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 2020/10/25 18:58:30 UTC
[JENKINS] Lucene-Solr-8.x-Linux (64bit/jdk-15) - Build # 4808 -
Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Linux/4808/
Java: 64bit/jdk-15 -XX:+UseCompressedOops -XX:+UseSerialGC
2 tests failed.
FAILED: org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth.testDelegationTokenRenew
Error Message:
expected:<200> but was:<403>
Stack Trace:
java.lang.AssertionError: expected:<200> but was:<403>
at __randomizedtesting.SeedInfo.seed([B1965FEDB215DB36:860DABF38AD90692]:0)
at org.junit.Assert.fail(Assert.java:89)
at org.junit.Assert.failNotEquals(Assert.java:835)
at org.junit.Assert.assertEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:633)
at org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth.renewDelegationToken(TestDelegationWithHadoopAuth.java:119)
at org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth.verifyDelegationTokenRenew(TestDelegationWithHadoopAuth.java:306)
at org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth.testDelegationTokenRenew(TestDelegationWithHadoopAuth.java:324)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:64)
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:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
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 org.junit.rules.RunRules.evaluate(RunRules.java:20)
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:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
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 org.junit.rules.RunRules.evaluate(RunRules.java:20)
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:832)
FAILED: org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth.testDelegationTokenRenew
Error Message:
expected:<200> but was:<403>
Stack Trace:
java.lang.AssertionError: expected:<200> but was:<403>
at __randomizedtesting.SeedInfo.seed([B1965FEDB215DB36:860DABF38AD90692]:0)
at org.junit.Assert.fail(Assert.java:89)
at org.junit.Assert.failNotEquals(Assert.java:835)
at org.junit.Assert.assertEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:633)
at org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth.renewDelegationToken(TestDelegationWithHadoopAuth.java:119)
at org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth.verifyDelegationTokenRenew(TestDelegationWithHadoopAuth.java:306)
at org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth.testDelegationTokenRenew(TestDelegationWithHadoopAuth.java:324)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:64)
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:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
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 org.junit.rules.RunRules.evaluate(RunRules.java:20)
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:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
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 org.junit.rules.RunRules.evaluate(RunRules.java:20)
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:832)
Build Log:
[...truncated 13639 lines...]
[junit4] Suite: org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth
[junit4] 2> 47600 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/server/solr/configsets/_default/conf'
[junit4] 2> 47600 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 47601 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.security.hadoop.TestDelegationWithHadoopAuth_B1965FEDB215DB36-001/data-dir-5-001
[junit4] 2> 47601 WARN (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=7 numCloses=7
[junit4] 2> 47601 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 47603 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 47639 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.security.hadoop.TestDelegationWithHadoopAuth_B1965FEDB215DB36-001/tempDir-001
[junit4] 2> 47640 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 47643 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 47644 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 47645 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 47743 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 41053
[junit4] 2> 47743 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:41053
[junit4] 2> 47743 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:41053
[junit4] 2> 47743 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 41053
[junit4] 2> 47749 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 47761 INFO (zkConnectionManagerCallback-367-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 47771 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 47808 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 47821 INFO (zkConnectionManagerCallback-369-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 47822 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 47841 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 47853 INFO (zkConnectionManagerCallback-371-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 47854 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 48000 WARN (jetty-launcher-372-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 48000 INFO (jetty-launcher-372-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 48000 INFO (jetty-launcher-372-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 48000 INFO (jetty-launcher-372-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 15+36-1562
[junit4] 2> 48003 WARN (jetty-launcher-372-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 48003 INFO (jetty-launcher-372-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 48003 INFO (jetty-launcher-372-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 48003 INFO (jetty-launcher-372-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 15+36-1562
[junit4] 2> 48030 INFO (jetty-launcher-372-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 48030 INFO (jetty-launcher-372-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 48030 INFO (jetty-launcher-372-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 48031 INFO (jetty-launcher-372-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@808e40e{/solr,null,AVAILABLE}
[junit4] 2> 48042 INFO (jetty-launcher-372-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6e407906{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:37873}
[junit4] 2> 48042 INFO (jetty-launcher-372-thread-1) [ ] o.e.j.s.Server Started @48049ms
[junit4] 2> 48042 INFO (jetty-launcher-372-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 48042 INFO (jetty-launcher-372-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 48042 INFO (jetty-launcher-372-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=37873}
[junit4] 2> 48042 INFO (jetty-launcher-372-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 48042 ERROR (jetty-launcher-372-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 48042 INFO (jetty-launcher-372-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 48042 INFO (jetty-launcher-372-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 48043 INFO (jetty-launcher-372-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 48043 INFO (jetty-launcher-372-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr
[junit4] 2> 48043 INFO (jetty-launcher-372-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-10-25T18:30:55.713180066Z
[junit4] 2> 48046 INFO (jetty-launcher-372-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@57b14afe{/solr,null,AVAILABLE}
[junit4] 2> 48050 INFO (jetty-launcher-372-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 48050 INFO (jetty-launcher-372-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@47da24dd{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:37261}
[junit4] 2> 48051 INFO (jetty-launcher-372-thread-2) [ ] o.e.j.s.Server Started @48057ms
[junit4] 2> 48051 INFO (jetty-launcher-372-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=37261}
[junit4] 2> 48052 ERROR (jetty-launcher-372-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 48052 INFO (jetty-launcher-372-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 48052 INFO (jetty-launcher-372-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 48052 INFO (jetty-launcher-372-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 48052 INFO (jetty-launcher-372-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr
[junit4] 2> 48052 INFO (jetty-launcher-372-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-10-25T18:30:55.722293929Z
[junit4] 2> 48053 INFO (jetty-launcher-372-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 48053 INFO (zkConnectionManagerCallback-374-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 48053 INFO (jetty-launcher-372-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 48058 INFO (jetty-launcher-372-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 48058 INFO (zkConnectionManagerCallback-376-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 48058 INFO (jetty-launcher-372-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 48059 INFO (jetty-launcher-372-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 48063 INFO (jetty-launcher-372-thread-2) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 48068 WARN (jetty-launcher-372-thread-2-SendThread(127.0.0.1:41053)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10005c5d67c0004.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10005c5d67c0004, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x10005c5d67c0004, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 48070 INFO (jetty-launcher-372-thread-1) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 48234 INFO (jetty-launcher-372-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 48238 WARN (jetty-launcher-372-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5c8286a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 48238 WARN (jetty-launcher-372-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5c8286a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 48245 WARN (jetty-launcher-372-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7279d3c2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 48245 WARN (jetty-launcher-372-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7279d3c2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 48246 INFO (jetty-launcher-372-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41053/solr
[junit4] 2> 48248 INFO (jetty-launcher-372-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 48249 INFO (zkConnectionManagerCallback-387-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 48249 INFO (jetty-launcher-372-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 48353 INFO (jetty-launcher-372-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 48355 WARN (jetty-launcher-372-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@721d42f3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 48355 WARN (jetty-launcher-372-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@721d42f3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 48358 WARN (jetty-launcher-372-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@40100c4c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 48359 WARN (jetty-launcher-372-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@40100c4c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 48360 INFO (jetty-launcher-372-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41053/solr
[junit4] 2> 48360 INFO (jetty-launcher-372-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 48362 INFO (zkConnectionManagerCallback-398-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 48362 INFO (jetty-launcher-372-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 48370 INFO (jetty-launcher-372-thread-2) [n:127.0.0.1:37261_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 48371 INFO (zkConnectionManagerCallback-400-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 48371 INFO (jetty-launcher-372-thread-2) [n:127.0.0.1:37261_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 48460 INFO (jetty-launcher-372-thread-2) [n:127.0.0.1:37261_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:37261_solr
[junit4] 2> 48460 INFO (jetty-launcher-372-thread-2) [n:127.0.0.1:37261_solr ] o.a.s.c.Overseer Overseer (id=72063941303074823-127.0.0.1:37261_solr-n_0000000000) starting
[junit4] 2> 48464 INFO (OverseerStateUpdate-72063941303074823-127.0.0.1:37261_solr-n_0000000000) [n:127.0.0.1:37261_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:37261_solr
[junit4] 2> 48474 INFO (jetty-launcher-372-thread-2) [n:127.0.0.1:37261_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37261_solr
[junit4] 2> 48476 INFO (jetty-launcher-372-thread-2) [n:127.0.0.1:37261_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 48476 INFO (jetty-launcher-372-thread-2) [n:127.0.0.1:37261_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: org.apache.solr.security.HadoopAuthPlugin
[junit4] 2> 48477 INFO (jetty-launcher-372-thread-1) [n:127.0.0.1:37873_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 48479 INFO (zkCallback-399-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 48480 INFO (jetty-launcher-372-thread-2) [n:127.0.0.1:37261_solr ] o.a.s.s.HadoopAuthPlugin Params: {delegation-token.token-kind=solr-dt, cookie.domain=127.0.0.1, zk-dt-secret-manager.znodeWorkingPath=solr/security/zkdtsm, signer.secret.provider.zookeeper.path=/token, type=simple, zk-dt-secret-manager.enable=true, signer.secret.provider=zookeeper, delegation-token.json-mapper.AUTO_CLOSE_TARGET=false}
[junit4] 2> 48481 INFO (zkConnectionManagerCallback-409-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 48482 INFO (jetty-launcher-372-thread-1) [n:127.0.0.1:37873_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 48487 INFO (jetty-launcher-372-thread-1) [n:127.0.0.1:37873_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 48491 INFO (jetty-launcher-372-thread-1) [n:127.0.0.1:37873_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:37873_solr as DOWN
[junit4] 2> 48498 INFO (jetty-launcher-372-thread-1) [n:127.0.0.1:37873_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 48499 INFO (jetty-launcher-372-thread-1) [n:127.0.0.1:37873_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37873_solr
[junit4] 2> 48499 INFO (zkCallback-399-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 48500 INFO (jetty-launcher-372-thread-1) [n:127.0.0.1:37873_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 48501 INFO (jetty-launcher-372-thread-1) [n:127.0.0.1:37873_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: org.apache.solr.security.HadoopAuthPlugin
[junit4] 2> 48501 INFO (jetty-launcher-372-thread-1) [n:127.0.0.1:37873_solr ] o.a.s.s.HadoopAuthPlugin Params: {delegation-token.token-kind=solr-dt, cookie.domain=127.0.0.1, zk-dt-secret-manager.znodeWorkingPath=solr/security/zkdtsm, signer.secret.provider.zookeeper.path=/token, type=simple, zk-dt-secret-manager.enable=true, signer.secret.provider=zookeeper, delegation-token.json-mapper.AUTO_CLOSE_TARGET=false}
[junit4] 2> 48514 INFO (zkCallback-408-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 48525 INFO (jetty-launcher-372-thread-2) [n:127.0.0.1:37261_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting
[junit4] 2> 48525 INFO (jetty-launcher-372-thread-1) [n:127.0.0.1:37873_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting
[junit4] 2> 48538 INFO (jetty-launcher-372-thread-2-EventThread) [ ] o.a.c.f.s.ConnectionStateManager State change: CONNECTED
[junit4] 2> 48538 INFO (jetty-launcher-372-thread-1-EventThread) [ ] o.a.c.f.s.ConnectionStateManager State change: CONNECTED
[junit4] 2> 48612 WARN (jetty-launcher-372-thread-1) [n:127.0.0.1:37873_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> 48612 WARN (jetty-launcher-372-thread-2) [n:127.0.0.1:37261_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> 48612 WARN (jetty-launcher-372-thread-1) [n:127.0.0.1:37873_solr ] o.a.s.c.CoreContainer Solr authentication is enabled, but SSL is off. Consider enabling SSL to protect user credentials and data with encryption.
[junit4] 2> 48612 WARN (jetty-launcher-372-thread-2) [n:127.0.0.1:37261_solr ] o.a.s.c.CoreContainer Solr authentication is enabled, but SSL is off. Consider enabling SSL to protect user credentials and data with encryption.
[junit4] 2> 48630 INFO (jetty-launcher-372-thread-2) [n:127.0.0.1:37261_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.security.hadoop.TestDelegationWithHadoopAuth_B1965FEDB215DB36-001/tempDir-001/node2
[junit4] 2> 48630 INFO (jetty-launcher-372-thread-1) [n:127.0.0.1:37873_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.security.hadoop.TestDelegationWithHadoopAuth_B1965FEDB215DB36-001/tempDir-001/node1
[junit4] 2> 48658 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 48658 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 48670 INFO (zkConnectionManagerCallback-423-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 48670 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 48675 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 48676 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41053/solr ready
[junit4] 2> 48703 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[B1965FEDB215DB36]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenVerify
[junit4] 2> 48853 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 48859 INFO (zkConnectionManagerCallback-428-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 48859 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 48860 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 48861 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41053/solr ready
[junit4] 2> 48925 INFO (qtp136364434-1313) [n:127.0.0.1:37261_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADZm9vA2ZvbwCKAXVhBxjVigF1hROc1QEEFOlABQswSNMwQWKRedWFXXI3x9-8B3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 48926 INFO (qtp136364434-1313) [n:127.0.0.1:37261_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADZm9vA2ZvbwCKAXVhBxjVigF1hROc1QEEFOlABQswSNMwQWKRedWFXXI3x9-8B3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 49030 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 49032 INFO (zkConnectionManagerCallback-433-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 49032 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 49034 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 49035 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41053/solr ready
[junit4] 2> 49173 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 49177 INFO (zkConnectionManagerCallback-438-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 49178 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 49179 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 49180 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41053/solr ready
[junit4] 2> 49208 INFO (qtp136364434-1313) [n:127.0.0.1:37261_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADZm9vA2ZvbwCKAXVhBxjVigF1hROc1QEEFOlABQswSNMwQWKRedWFXXI3x9-8B3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 49208 INFO (qtp136364434-1313) [n:127.0.0.1:37261_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADZm9vA2ZvbwCKAXVhBxjVigF1hROc1QEEFOlABQswSNMwQWKRedWFXXI3x9-8B3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 49342 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[B1965FEDB215DB36]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenVerify
[junit4] 2> 49365 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancelFail-seed#[B1965FEDB215DB36]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenCancelFail
[junit4] 2> 49403 ERROR (qtp136364434-1315) [ ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_2]
[junit4] 2> 49408 ERROR (qtp565877114-1306) [ ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_2]
[junit4] 2> 49435 ERROR (qtp136364434-1315) [ ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_3]
[junit4] 2> 49437 ERROR (qtp565877114-1306) [ ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_3]
[junit4] 2> 49439 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancelFail-seed#[B1965FEDB215DB36]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenCancelFail
[junit4] 2> 49457 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationOtherOp-seed#[B1965FEDB215DB36]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationOtherOp
[junit4] 2> 49472 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationOtherOp-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 49473 INFO (zkConnectionManagerCallback-443-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 49473 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationOtherOp-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 49474 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationOtherOp-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 49477 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationOtherOp-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41053/solr ready
[junit4] 2> 49494 INFO (qtp136364434-1313) [n:127.0.0.1:37261_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params op=someSolrOperation&action=LIST&user.name=foo&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 49494 INFO (qtp136364434-1313) [n:127.0.0.1:37261_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={op=someSolrOperation&action=LIST&user.name=foo&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 49599 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationOtherOp-seed#[B1965FEDB215DB36]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationOtherOp
[junit4] 2> 49622 INFO (TEST-TestDelegationWithHadoopAuth.testZNodePaths-seed#[B1965FEDB215DB36]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testZNodePaths
[junit4] 2> 49633 INFO (TEST-TestDelegationWithHadoopAuth.testZNodePaths-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 49642 INFO (zkConnectionManagerCallback-445-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 49642 INFO (TEST-TestDelegationWithHadoopAuth.testZNodePaths-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 49744 INFO (TEST-TestDelegationWithHadoopAuth.testZNodePaths-seed#[B1965FEDB215DB36]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testZNodePaths
[junit4] 2> 49762 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenSolrClient-seed#[B1965FEDB215DB36]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenSolrClient
[junit4] 2> 49818 INFO (qtp565877114-1308) [n:127.0.0.1:37873_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADZm9vA2ZvbwCKAXVhBxyaigF1hROgmgUEFFhQqbgpXXjAAWeQ_VH8of-aI3-3B3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 49818 INFO (qtp565877114-1308) [n:127.0.0.1:37873_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADZm9vA2ZvbwCKAXVhBxyaigF1hROgmgUEFFhQqbgpXXjAAWeQ_VH8of-aI3-3B3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 49820 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenSolrClient-seed#[B1965FEDB215DB36]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenSolrClient
[junit4] 2> 49836 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[B1965FEDB215DB36]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenCancel
[junit4] 2> 49867 ERROR (qtp565877114-1308) [n:127.0.0.1:37873_solr ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_6]
[junit4] 2> 49944 ERROR (qtp136364434-1315) [ ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_7]
[junit4] 2> 50006 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[B1965FEDB215DB36]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenCancel
[junit4] 2> 50043 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenewFail-seed#[B1965FEDB215DB36]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenRenewFail
[junit4] 2> 50049 WARN (qtp565877114-1306) [ ] o.a.h.s.a.s.AuthenticationFilter Authentication exception: org.apache.hadoop.security.AccessControlException: bar tries to renew a token (solr-dt owner=foo, renewer=foo, realUser=, issueDate=1603650657714, maxDate=1604255457714, sequenceNumber=8, masterKeyId=4) with non-matching renewer foo
[junit4] 2> 50049 WARN (qtp565877114-1306) [ ] o.a.s.s.HadoopAuthPlugin Could not find org.apache.solr.security.authentication.requestcontinues
[junit4] 2> 50051 WARN (qtp136364434-1313) [n:127.0.0.1:37261_solr ] o.a.h.s.a.s.AuthenticationFilter Authentication exception: org.apache.hadoop.security.AccessControlException: bar tries to renew a token (solr-dt owner=foo, renewer=foo, realUser=, issueDate=1603650657714, maxDate=1604255457714, sequenceNumber=8, masterKeyId=4) with non-matching renewer foo
[junit4] 2> 50052 WARN (qtp136364434-1313) [n:127.0.0.1:37261_solr ] o.a.s.s.HadoopAuthPlugin Could not find org.apache.solr.security.authentication.requestcontinues
[junit4] 2> 50068 WARN (qtp565877114-1308) [n:127.0.0.1:37873_solr ] o.a.h.s.a.s.AuthenticationFilter AuthenticationToken ignored: Unauthorized access
[junit4] 2> 50072 WARN (qtp565877114-1306) [ ] o.a.h.s.a.s.AuthenticationFilter AuthenticationToken ignored: Unauthorized access
[junit4] 2> 50073 WARN (qtp565877114-1306) [ ] o.a.h.s.a.s.AuthenticationFilter Authentication exception: org.apache.hadoop.security.AccessControlException: notRenewUser tries to renew a token (solr-dt owner=foo, renewer=renewUser, realUser=, issueDate=1603650657738, maxDate=1604255457738, sequenceNumber=9, masterKeyId=4) with non-matching renewer renewUser
[junit4] 2> 50073 WARN (qtp565877114-1306) [ ] o.a.s.s.HadoopAuthPlugin Could not find org.apache.solr.security.authentication.requestcontinues
[junit4] 2> 50074 WARN (qtp136364434-1315) [ ] o.a.h.s.a.s.AuthenticationFilter AuthenticationToken ignored: Unauthorized access
[junit4] 2> 50075 WARN (qtp136364434-1315) [ ] o.a.h.s.a.s.AuthenticationFilter Authentication exception: org.apache.hadoop.security.AccessControlException: notRenewUser tries to renew a token (solr-dt owner=foo, renewer=renewUser, realUser=, issueDate=1603650657738, maxDate=1604255457738, sequenceNumber=9, masterKeyId=4) with non-matching renewer renewUser
[junit4] 2> 50075 WARN (qtp136364434-1315) [ ] o.a.s.s.HadoopAuthPlugin Could not find org.apache.solr.security.authentication.requestcontinues
[junit4] 2> 50078 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenewFail-seed#[B1965FEDB215DB36]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenRenewFail
[junit4] 2> 50099 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenew-seed#[B1965FEDB215DB36]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenRenew
[junit4] 2> 50101 WARN (qtp565877114-1308) [n:127.0.0.1:37873_solr ] o.a.h.s.a.s.AuthenticationFilter AuthenticationToken ignored: Unauthorized access
[junit4] 2> 50105 WARN (qtp565877114-1306) [ ] o.a.h.s.a.s.AuthenticationFilter AuthenticationToken ignored: Unauthorized access
[junit4] 2> 50106 WARN (qtp565877114-1306) [ ] o.a.h.s.a.s.AuthenticationFilter Authentication exception: org.apache.hadoop.security.AccessControlException: foo is trying to renew a token (solr-dt owner=foo, renewer=foo, realUser=, issueDate=1603650657771, maxDate=1604255457771, sequenceNumber=10, masterKeyId=4) with wrong password
[junit4] 2> 50106 WARN (qtp565877114-1306) [ ] o.a.s.s.HadoopAuthPlugin Could not find org.apache.solr.security.authentication.requestcontinues
[junit4] 2> 50108 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenew-seed#[B1965FEDB215DB36]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenRenew
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDelegationWithHadoopAuth -Dtests.method=testDelegationTokenRenew -Dtests.seed=B1965FEDB215DB36 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ru-KZ -Dtests.timezone=America/Indiana/Petersburg -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 0.03s J2 | TestDelegationWithHadoopAuth.testDelegationTokenRenew <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<200> but was:<403>
[junit4] > at __randomizedtesting.SeedInfo.seed([B1965FEDB215DB36:860DABF38AD90692]:0)
[junit4] > at org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth.renewDelegationToken(TestDelegationWithHadoopAuth.java:119)
[junit4] > at org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth.verifyDelegationTokenRenew(TestDelegationWithHadoopAuth.java:306)
[junit4] > at org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth.testDelegationTokenRenew(TestDelegationWithHadoopAuth.java:324)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:64)
[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:832)
[junit4] 2> 50113 WARN (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker-SendThread(127.0.0.1:41053)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10005c5d67c000b.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10005c5d67c000b, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x10005c5d67c000b, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 50222 INFO (jetty-closer-446-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1727388481
[junit4] 2> 50222 INFO (jetty-closer-446-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:37873_solr
[junit4] 2> 50223 INFO (jetty-closer-446-thread-2) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=168211773
[junit4] 2> 50223 INFO (jetty-closer-446-thread-2) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:37261_solr
[junit4] 2> 50229 INFO (jetty-closer-446-thread-1) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 50229 INFO (jetty-closer-446-thread-1) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:37873_solr as DOWN
[junit4] 2> 50229 INFO (jetty-closer-446-thread-2) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 50229 INFO (jetty-closer-446-thread-2) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:37261_solr as DOWN
[junit4] 2> 50266 INFO (jetty-closer-446-thread-2) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 50273 INFO (jetty-closer-446-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 50273 INFO (jetty-closer-446-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 50273 INFO (jetty-closer-446-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 50274 INFO (jetty-closer-446-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 50282 INFO (jetty-closer-446-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 50282 INFO (jetty-closer-446-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 50282 INFO (jetty-closer-446-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 50282 INFO (jetty-closer-446-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 50283 INFO (zkCallback-399-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 50285 INFO (closeThreadPool-456-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72063941303074823-127.0.0.1:37261_solr-n_0000000000) closing
[junit4] 2> 50286 INFO (OverseerStateUpdate-72063941303074823-127.0.0.1:37261_solr-n_0000000000) [n:127.0.0.1:37261_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:37261_solr
[junit4] 2> 50286 INFO (OverseerAutoScalingTriggerThread-72063941303074823-127.0.0.1:37261_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 50297 INFO (closeThreadPool-456-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72063941303074823-127.0.0.1:37261_solr-n_0000000000) closing
[junit4] 2> 50297 WARN (jetty-launcher-372-thread-2-SendThread(127.0.0.1:41053)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10005c5d67c0007.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10005c5d67c0007, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x10005c5d67c0007, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 50383 ERROR (Thread[Thread-582,5,TGRP-TestDelegationWithHadoopAuth]) [ ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted
[junit4] 2> 50386 INFO (Curator-Framework-0) [ ] o.a.c.f.i.CuratorFrameworkImpl backgroundOperationsLoop exiting
[junit4] 2> 50390 WARN (jetty-launcher-372-thread-1-SendThread(127.0.0.1:41053)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10005c5d67c0009.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10005c5d67c0009, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x10005c5d67c0009, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 50485 INFO (jetty-closer-446-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72063941303074823-127.0.0.1:37261_solr-n_0000000000) closing
[junit4] 2> 50486 ERROR (Thread[Thread-581,5,TGRP-TestDelegationWithHadoopAuth]) [ ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted
[junit4] 2> 50486 INFO (Curator-Framework-0) [ ] o.a.c.f.i.CuratorFrameworkImpl backgroundOperationsLoop exiting
[junit4] 2> 50585 INFO (jetty-closer-446-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@6e407906{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:0}
[junit4] 2> 50585 INFO (jetty-closer-446-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@808e40e{/solr,null,UNAVAILABLE}
[junit4] 2> 50585 INFO (jetty-closer-446-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 50686 INFO (jetty-closer-446-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@47da24dd{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:0}
[junit4] 2> 50686 INFO (jetty-closer-446-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@57b14afe{/solr,null,UNAVAILABLE}
[junit4] 2> 50686 INFO (jetty-closer-446-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 50687 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 50893 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 7 /solr/aliases.json
[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> 22 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMSeqNumRoot
[junit4] 2> 11 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMKeyIdRoot
[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_2
[junit4] 2> 4 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_4
[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_10
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 30 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot
[junit4] 2> 11 /solr/live_nodes
[junit4] 2> 7 /solr/collections
[junit4] 2> 6 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot
[junit4] 2> 4 /solr/overseer/queue
[junit4] 2>
[junit4] 2> 50895 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:41053
[junit4] 2> 50895 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:41053
[junit4] 2> 50895 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 41053
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.security.hadoop.TestDelegationWithHadoopAuth_B1965FEDB215DB36-001
[junit4] 2> Oct 25, 2020 6:30:58 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(Lucene87): {}, docValues:{}, maxPointsInLeafNode=540, maxMBSortInHeap=7.400971913314515, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=ru-KZ, timezone=America/Indiana/Petersburg
[junit4] 2> NOTE: Linux 5.4.0-52-generic amd64/Oracle Corporation 15 (64-bit)/cpus=16,threads=1,free=330096952,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [TemplateUpdateProcessorTest, TestHdfsCloudBackupRestore, URLClassifyProcessorTest, SuggesterWFSTTest, TestDelegationWithHadoopAuth]
[junit4] Completed [35/921 (1!)] on J2 in 3.78s, 8 tests, 1 failure <<< FAILURES!
[...truncated 47889 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Linux/4808/consoleText
[repro] Revision: 13500076d5a9781ac8b636664148945bbfefd8cb
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC"
[repro] Repro line: ant test -Dtestcase=TestDelegationWithHadoopAuth -Dtests.method=testDelegationTokenRenew -Dtests.seed=B1965FEDB215DB36 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ru-KZ -Dtests.timezone=America/Indiana/Petersburg -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] TestDelegationWithHadoopAuth
[repro] ant compile-test
[...truncated 3455 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestDelegationWithHadoopAuth" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=B1965FEDB215DB36 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ru-KZ -Dtests.timezone=America/Indiana/Petersburg -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 34 lines...]
[junit4] Suite: org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth
[junit4] 2> 2226 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/server/solr/configsets/_default/conf'
[junit4] 2> 2234 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 2268 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J0/temp/solr.security.hadoop.TestDelegationWithHadoopAuth_B1965FEDB215DB36-001/data-dir-1-001
[junit4] 2> 2330 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 2359 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.e.j.u.log Logging initialized @2369ms to org.eclipse.jetty.util.log.Slf4jLog
[junit4] 2> 2363 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 2652 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J0/temp/solr.security.hadoop.TestDelegationWithHadoopAuth_B1965FEDB215DB36-001/tempDir-001
[junit4] 2> 2662 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2681 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 2681 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2725 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 2780 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 41277
[junit4] 2> 2780 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:41277
[junit4] 2> 2781 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:41277
[junit4] 2> 2781 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 41277
[junit4] 2> 2836 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2887 INFO (zkConnectionManagerCallback-3-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2887 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2914 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2924 INFO (zkConnectionManagerCallback-5-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2924 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2931 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2938 INFO (zkConnectionManagerCallback-7-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2938 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3219 WARN (jetty-launcher-8-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 3219 WARN (jetty-launcher-8-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 3220 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 3220 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 3220 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 3220 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 3223 INFO (jetty-launcher-8-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 15+36-1562
[junit4] 2> 3223 INFO (jetty-launcher-8-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 15+36-1562
[junit4] 2> 3248 INFO (jetty-launcher-8-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 3248 INFO (jetty-launcher-8-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 3250 INFO (jetty-launcher-8-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 3255 INFO (jetty-launcher-8-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 3255 INFO (jetty-launcher-8-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 3256 INFO (jetty-launcher-8-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 3263 INFO (jetty-launcher-8-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4f23b84f{/solr,null,AVAILABLE}
[junit4] 2> 3263 INFO (jetty-launcher-8-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6306763{/solr,null,AVAILABLE}
[junit4] 2> 3304 INFO (jetty-launcher-8-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7177e6d6{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:41449}
[junit4] 2> 3304 INFO (jetty-launcher-8-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@21556449{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:33025}
[junit4] 2> 3304 INFO (jetty-launcher-8-thread-2) [ ] o.e.j.s.Server Started @3318ms
[junit4] 2> 3304 INFO (jetty-launcher-8-thread-1) [ ] o.e.j.s.Server Started @3318ms
[junit4] 2> 3305 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=41449}
[junit4] 2> 3305 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33025}
[junit4] 2> 3327 ERROR (jetty-launcher-8-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 3327 ERROR (jetty-launcher-8-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 3328 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 3328 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 3328 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 3328 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 3332 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 3332 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 3333 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr
[junit4] 2> 3333 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr
[junit4] 2> 3333 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-10-25T18:58:16.069481628Z
[junit4] 2> 3333 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-10-25T18:58:16.069578063Z
[junit4] 2> 3335 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3335 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3359 INFO (zkConnectionManagerCallback-10-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3360 INFO (zkConnectionManagerCallback-12-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3360 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3360 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3372 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 3375 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 3443 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 3444 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 4059 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 4060 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 4108 WARN (jetty-launcher-8-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@570c4314[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4109 WARN (jetty-launcher-8-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@570c4314[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4119 WARN (jetty-launcher-8-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5f62bb85[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4119 WARN (jetty-launcher-8-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5f62bb85[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4241 WARN (jetty-launcher-8-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@120d9eef[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4241 WARN (jetty-launcher-8-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@120d9eef[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4243 WARN (jetty-launcher-8-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@35844b67[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4244 WARN (jetty-launcher-8-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@35844b67[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4351 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41277/solr
[junit4] 2> 4351 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41277/solr
[junit4] 2> 4362 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4362 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4364 INFO (zkConnectionManagerCallback-33-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4364 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4369 INFO (zkConnectionManagerCallback-34-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4369 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4370 WARN (jetty-launcher-8-thread-1-SendThread(127.0.0.1:41277)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10005dedd5a0005.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10005dedd5a0005, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x10005dedd5a0005, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 4479 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:33025_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4483 INFO (zkConnectionManagerCallback-36-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4484 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:33025_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4577 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:41449_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4592 INFO (zkConnectionManagerCallback-40-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4592 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:41449_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4697 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:41449_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:41449_solr
[junit4] 2> 4700 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:41449_solr ] o.a.s.c.Overseer Overseer (id=72064048792469512-127.0.0.1:41449_solr-n_0000000000) starting
[junit4] 2> 4820 INFO (OverseerStateUpdate-72064048792469512-127.0.0.1:41449_solr-n_0000000000) [n:127.0.0.1:41449_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:41449_solr
[junit4] 2> 4835 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:41449_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41449_solr
[junit4] 2> 4854 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:33025_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4864 INFO (zkCallback-39-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4867 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:33025_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:33025_solr as DOWN
[junit4] 2> 4870 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:41449_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 4873 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:33025_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 4873 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:33025_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33025_solr
[junit4] 2> 4878 INFO (zkCallback-39-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 4881 INFO (zkCallback-35-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 4884 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:33025_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 4885 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:41449_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: org.apache.solr.security.HadoopAuthPlugin
[junit4] 2> 4885 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:33025_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: org.apache.solr.security.HadoopAuthPlugin
[junit4] 2> 4893 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:41449_solr ] o.a.s.s.HadoopAuthPlugin Params: {delegation-token.token-kind=solr-dt, cookie.domain=127.0.0.1, zk-dt-secret-manager.znodeWorkingPath=solr/security/zkdtsm, signer.secret.provider.zookeeper.path=/token, type=simple, zk-dt-secret-manager.enable=true, signer.secret.provider=zookeeper, delegation-token.json-mapper.AUTO_CLOSE_TARGET=false}
[junit4] 2> 4896 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:33025_solr ] o.a.s.s.HadoopAuthPlugin Params: {delegation-token.token-kind=solr-dt, cookie.domain=127.0.0.1, zk-dt-secret-manager.znodeWorkingPath=solr/security/zkdtsm, signer.secret.provider.zookeeper.path=/token, type=simple, zk-dt-secret-manager.enable=true, signer.secret.provider=zookeeper, delegation-token.json-mapper.AUTO_CLOSE_TARGET=false}
[junit4] 2> 4994 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:33025_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting
[junit4] 2> 4994 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:41449_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting
[junit4] 2> 5033 INFO (jetty-launcher-8-thread-2-EventThread) [ ] o.a.c.f.s.ConnectionStateManager State change: CONNECTED
[junit4] 2> 5055 INFO (jetty-launcher-8-thread-1-EventThread) [ ] o.a.c.f.s.ConnectionStateManager State change: CONNECTED
[junit4] 2> 5272 WARN (jetty-launcher-8-thread-2) [n:127.0.0.1:41449_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> 5273 WARN (jetty-launcher-8-thread-2) [n:127.0.0.1:41449_solr ] o.a.s.c.CoreContainer Solr authentication is enabled, but SSL is off. Consider enabling SSL to protect user credentials and data with encryption.
[junit4] 2> 5273 WARN (jetty-launcher-8-thread-1) [n:127.0.0.1:33025_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> 5278 WARN (jetty-launcher-8-thread-1) [n:127.0.0.1:33025_solr ] o.a.s.c.CoreContainer Solr authentication is enabled, but SSL is off. Consider enabling SSL to protect user credentials and data with encryption.
[junit4] 2> 5450 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:41449_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J0/temp/solr.security.hadoop.TestDelegationWithHadoopAuth_B1965FEDB215DB36-001/tempDir-001/node2
[junit4] 2> 5450 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:33025_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J0/temp/solr.security.hadoop.TestDelegationWithHadoopAuth_B1965FEDB215DB36-001/tempDir-001/node1
[junit4] 2> 5519 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 5544 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 5566 INFO (zkConnectionManagerCallback-59-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 5567 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 5569 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 5571 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41277/solr ready
[junit4] 2> 5646 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[B1965FEDB215DB36]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenVerify
[junit4] 2> 6291 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 6300 INFO (zkConnectionManagerCallback-64-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6301 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 6306 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 6308 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41277/solr ready
[junit4] 2> 6399 INFO (qtp1538585044-167) [n:127.0.0.1:33025_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADZm9vA2ZvbwCKAXVhICjAigF1hSyswAEEFO8ppPypHOvCbjNKHNY2V3kBFxtwB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 6400 INFO (qtp1538585044-167) [n:127.0.0.1:33025_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADZm9vA2ZvbwCKAXVhICjAigF1hSyswAEEFO8ppPypHOvCbjNKHNY2V3kBFxtwB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=20
[junit4] 2> 6417 WARN (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[B1965FEDB215DB36]-SendThread(127.0.0.1:41277)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10005dedd5a000c.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10005dedd5a000c, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x10005dedd5a000c, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 6528 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 6531 INFO (zkConnectionManagerCallback-69-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6531 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 6533 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 6536 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41277/solr ready
[junit4] 2> 6702 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 6704 INFO (zkConnectionManagerCallback-74-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6704 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 6706 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 6717 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41277/solr ready
[junit4] 2> 6765 INFO (qtp1538585044-167) [n:127.0.0.1:33025_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADZm9vA2ZvbwCKAXVhICjAigF1hSyswAEEFO8ppPypHOvCbjNKHNY2V3kBFxtwB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 6765 INFO (qtp1538585044-167) [n:127.0.0.1:33025_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADZm9vA2ZvbwCKAXVhICjAigF1hSyswAEEFO8ppPypHOvCbjNKHNY2V3kBFxtwB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 6947 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[B1965FEDB215DB36]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenVerify
[junit4] OK 1.38s J0 | TestDelegationWithHadoopAuth.testDelegationTokenVerify
[junit4] 2> 7018 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancelFail-seed#[B1965FEDB215DB36]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenCancelFail
[junit4] 2> 7078 ERROR (qtp1257415845-181) [ ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_2]
[junit4] 2> 7083 ERROR (qtp1538585044-165) [ ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_2]
[junit4] 2> 7112 ERROR (qtp1257415845-181) [ ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_3]
[junit4] 2> 7115 ERROR (qtp1538585044-165) [ ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_3]
[junit4] 2> 7117 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancelFail-seed#[B1965FEDB215DB36]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenCancelFail
[junit4] OK 0.15s J0 | TestDelegationWithHadoopAuth.testDelegationTokenCancelFail
[junit4] 2> 7157 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationOtherOp-seed#[B1965FEDB215DB36]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationOtherOp
[junit4] 2> 7160 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationOtherOp-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 7161 INFO (zkConnectionManagerCallback-79-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 7162 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationOtherOp-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 7163 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationOtherOp-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 7166 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationOtherOp-seed#[B1965FEDB215DB36]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41277/solr ready
[junit4] 2> 7202 INFO (qtp15385
[...truncated too long message...]
7947 INFO (jetty-closer-82-thread-1) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 7948 INFO (jetty-closer-82-thread-1) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:33025_solr as DOWN
[junit4] 2> 7952 INFO (closeThreadPool-89-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72064048792469512-127.0.0.1:41449_solr-n_0000000000) closing
[junit4] 2> 7952 INFO (jetty-closer-82-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 7952 INFO (jetty-closer-82-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 7953 INFO (jetty-closer-82-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 7953 INFO (jetty-closer-82-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 7954 INFO (zkCallback-35-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 8054 INFO (jetty-closer-82-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72064048792469512-127.0.0.1:41449_solr-n_0000000000) closing
[junit4] 2> 8055 ERROR (Thread[Thread-23,5,TGRP-TestDelegationWithHadoopAuth]) [ ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted
[junit4] 2> 8056 INFO (Curator-Framework-0) [ ] o.a.c.f.i.CuratorFrameworkImpl backgroundOperationsLoop exiting
[junit4] 2> 8154 ERROR (Thread[Thread-21,5,TGRP-TestDelegationWithHadoopAuth]) [ ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted
[junit4] 2> 8155 INFO (Curator-Framework-0) [ ] o.a.c.f.i.CuratorFrameworkImpl backgroundOperationsLoop exiting
[junit4] 2> 8256 INFO (jetty-closer-82-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@7177e6d6{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:0}
[junit4] 2> 8257 INFO (jetty-closer-82-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6306763{/solr,null,UNAVAILABLE}
[junit4] 2> 8257 INFO (jetty-closer-82-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 8358 INFO (jetty-closer-82-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@21556449{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:0}
[junit4] 2> 8358 INFO (jetty-closer-82-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4f23b84f{/solr,null,UNAVAILABLE}
[junit4] 2> 8358 INFO (jetty-closer-82-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 8358 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 8562 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 7 /solr/aliases.json
[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> 22 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMSeqNumRoot
[junit4] 2> 11 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMKeyIdRoot
[junit4] 2> 7 /solr/clusterprops.json
[junit4] 2> 7 /solr/clusterstate.json
[junit4] 2> 4 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_1
[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/ZKDTSMMasterKeyRoot/DK_2
[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/ZKDTSMMasterKeyRoot/DK_4
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_4
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_10
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 30 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot
[junit4] 2> 11 /solr/live_nodes
[junit4] 2> 8 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot
[junit4] 2> 7 /solr/collections
[junit4] 2> 4 /solr/overseer/queue
[junit4] 2>
[junit4] 2> 8565 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:41277
[junit4] 2> 8565 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:41277
[junit4] 2> 8565 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[B1965FEDB215DB36]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 41277
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J0/temp/solr.security.hadoop.TestDelegationWithHadoopAuth_B1965FEDB215DB36-001
[junit4] 2> Oct 25, 2020 6:58:21 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(Lucene87): {}, docValues:{}, maxPointsInLeafNode=540, maxMBSortInHeap=7.400971913314515, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=ru-KZ, timezone=America/Indiana/Petersburg
[junit4] 2> NOTE: Linux 5.4.0-52-generic amd64/Oracle Corporation 15 (64-bit)/cpus=16,threads=1,free=413820520,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [TestDelegationWithHadoopAuth]
[junit4] Completed [1/5 (1!)] on J0 in 8.24s, 8 tests, 1 failure <<< FAILURES!
[...truncated 57 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-8.x-Linux/lucene/common-build.xml:1599: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-8.x-Linux/lucene/common-build.xml:1126: There were test failures: 5 suites, 40 tests, 1 failure [seed: B1965FEDB215DB36]
Total time: 12 seconds
[repro] Setting last failure code to 256
[repro] Failures w/original seeds:
[repro] 1/5 failed: org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth
[repro] Exiting with code 256
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Archiving artifacts
[Java] Sleeping for 5 seconds due to JENKINS-32191...
[Java] Parsing console log (workspace: '/home/jenkins/workspace/Lucene-Solr-8.x-Linux')
[Java] Successfully parsed console log
[Java] -> found 112 issues (skipped 103 duplicates)
[Java] Post processing issues on 'Master' with source code encoding 'UTF-8'
[Java] Creating SCM blamer to obtain author and commit information for affected files
[Java] Creating SCM miner to obtain statistics for affected repository files
[Java] Resolving file names for all issues in source directory '/home/jenkins/workspace/Lucene-Solr-8.x-Linux'
[Java] -> resolved paths in source directory (53 found, 0 not found)
[Java] Resolving module names from module definitions (build.xml, pom.xml, or Manifest.mf files)
[Java] -> resolved module names for 112 issues
[Java] Resolving package names (or namespaces) by parsing the affected files
[Java] -> resolved package names of 53 affected files
[Java] No filter has been set, publishing all 112 issues
[Java] Creating fingerprints for all affected code blocks to track issues over different builds
[Java] -> created fingerprints for 112 issues (skipped 0 issues)
[Java] Copying affected files to Jenkins' build folder '/home/jenkins/jobs/Lucene-Solr-8.x-Linux/builds/4808/files-with-issues'
[Java] -> 53 copied, 0 not in workspace, 0 not-found, 0 with I/O error
[Java] Using reference build 'Lucene-Solr-8.x-Linux #4807' to compute new, fixed, and outstanding issues
[Java] Issues delta (vs. reference build): outstanding: 112, new: 0, fixed: 0
[Java] No quality gates have been set - skipping
[Java] Health report is disabled - skipping
[Java] Created analysis result for 112 issues (found 0 new issues, fixed 0 issues)
[Java] Attaching ResultAction with ID 'java' to run 'Lucene-Solr-8.x-Linux #4808'.
[Checks API] No suitable checks publisher found.
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Recording test results
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[Checks API] No suitable checks publisher found.
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
[Checks API] No suitable checks publisher found.
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2