You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2021/01/12 21:52:17 UTC
[JENKINS] Lucene » Lucene-Solr-Tests-8.8 - Build # 64 - Still Unstable!
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.8/64/
1 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([E6E823FA0BA8C61:39F57621987651C5]: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 sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
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 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.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 15028 lines...]
[junit4] Suite: org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth
[junit4] 2> 1555747 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[E6E823FA0BA8C61]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/server/solr/configsets/_default/conf'
[junit4] 2> 1555747 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[E6E823FA0BA8C61]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1555747 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[E6E823FA0BA8C61]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestDelegationWithHadoopAuth_E6E823FA0BA8C61-001/data-dir-118-001
[junit4] 2> 1555747 WARN (SUITE-TestDelegationWithHadoopAuth-seed#[E6E823FA0BA8C61]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 1555747 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[E6E823FA0BA8C61]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1555749 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[E6E823FA0BA8C61]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 1555749 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[E6E823FA0BA8C61]-worker) [ ] o.a.s.c.SolrCloudTestCase Using per-replica state
[junit4] 2> 1555751 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[E6E823FA0BA8C61]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestDelegationWithHadoopAuth_E6E823FA0BA8C61-001/tempDir-001
[junit4] 2> 1555751 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[E6E823FA0BA8C61]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1555752 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 1555752 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1555752 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 1555852 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[E6E823FA0BA8C61]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 32919
[junit4] 2> 1555852 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[E6E823FA0BA8C61]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:32919
[junit4] 2> 1555852 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[E6E823FA0BA8C61]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:32919
[junit4] 2> 1555852 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[E6E823FA0BA8C61]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 32919
[junit4] 2> 1555853 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[E6E823FA0BA8C61]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1555859 INFO (zkConnectionManagerCallback-18920-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1555859 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[E6E823FA0BA8C61]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1555866 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[E6E823FA0BA8C61]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1555867 INFO (zkConnectionManagerCallback-18922-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1555867 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[E6E823FA0BA8C61]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1555867 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[E6E823FA0BA8C61]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1555871 INFO (zkConnectionManagerCallback-18924-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1555871 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[E6E823FA0BA8C61]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1555974 INFO (jetty-launcher-18925-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1555974 INFO (jetty-launcher-18925-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1555975 INFO (jetty-launcher-18925-thread-2) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_252-b09
[junit4] 2> 1555975 INFO (jetty-launcher-18925-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1555975 INFO (jetty-launcher-18925-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1555975 INFO (jetty-launcher-18925-thread-1) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_252-b09
[junit4] 2> 1555976 INFO (jetty-launcher-18925-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1555976 INFO (jetty-launcher-18925-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1555976 INFO (jetty-launcher-18925-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1555977 INFO (jetty-launcher-18925-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1f9236e2{/solr,null,AVAILABLE}
[junit4] 2> 1555977 INFO (jetty-launcher-18925-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1555977 INFO (jetty-launcher-18925-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1555977 INFO (jetty-launcher-18925-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1555977 INFO (jetty-launcher-18925-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3b862b79{/solr,null,AVAILABLE}
[junit4] 2> 1555977 INFO (jetty-launcher-18925-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5430db95{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:35839}
[junit4] 2> 1555977 INFO (jetty-launcher-18925-thread-2) [ ] o.e.j.s.Server Started @1556005ms
[junit4] 2> 1555977 INFO (jetty-launcher-18925-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:32919/solr, hostPort=35839}
[junit4] 2> 1555977 ERROR (jetty-launcher-18925-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1555977 INFO (jetty-launcher-18925-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1555977 INFO (jetty-launcher-18925-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3ce7c5c0{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:45533}
[junit4] 2> 1555977 INFO (jetty-launcher-18925-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 1555977 INFO (jetty-launcher-18925-thread-1) [ ] o.e.j.s.Server Started @1556005ms
[junit4] 2> 1555978 INFO (jetty-launcher-18925-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1555978 INFO (jetty-launcher-18925-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:32919/solr, hostPort=45533}
[junit4] 2> 1555978 INFO (jetty-launcher-18925-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr
[junit4] 2> 1555978 INFO (jetty-launcher-18925-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-01-12T21:20:00.407Z
[junit4] 2> 1555978 ERROR (jetty-launcher-18925-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1555978 INFO (jetty-launcher-18925-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1555978 INFO (jetty-launcher-18925-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 1555978 INFO (jetty-launcher-18925-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1555978 INFO (jetty-launcher-18925-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr
[junit4] 2> 1555978 INFO (jetty-launcher-18925-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-01-12T21:20:00.407Z
[junit4] 2> 1555978 INFO (jetty-launcher-18925-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1555979 INFO (jetty-launcher-18925-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1555979 INFO (zkConnectionManagerCallback-18929-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1555979 INFO (jetty-launcher-18925-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1555979 INFO (zkConnectionManagerCallback-18927-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1555979 INFO (jetty-launcher-18925-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1555980 INFO (jetty-launcher-18925-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1555980 INFO (jetty-launcher-18925-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1555983 INFO (jetty-launcher-18925-thread-2) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 1555983 INFO (jetty-launcher-18925-thread-1) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 1555983 WARN (jetty-launcher-18925-thread-2-SendThread(127.0.0.1:32919)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102af6336f60003.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102af6336f60003, 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 0x102af6336f60003, 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> 1555983 WARN (jetty-launcher-18925-thread-1-SendThread(127.0.0.1:32919)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102af6336f60004.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102af6336f60004, 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 0x102af6336f60004, 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> 1556547 INFO (jetty-launcher-18925-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1556548 WARN (jetty-launcher-18925-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@459967f9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1556548 WARN (jetty-launcher-18925-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@459967f9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1556550 WARN (jetty-launcher-18925-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3071b53f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1556550 WARN (jetty-launcher-18925-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3071b53f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1556551 INFO (jetty-launcher-18925-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:32919/solr
[junit4] 2> 1556552 INFO (jetty-launcher-18925-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1556560 INFO (zkConnectionManagerCallback-18943-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1556560 INFO (jetty-launcher-18925-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1556664 INFO (jetty-launcher-18925-thread-2) [n:127.0.0.1:35839_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1556664 INFO (zkConnectionManagerCallback-18945-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1556664 INFO (jetty-launcher-18925-thread-2) [n:127.0.0.1:35839_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1556717 INFO (jetty-launcher-18925-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1556717 INFO (jetty-launcher-18925-thread-2) [n:127.0.0.1:35839_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:35839_solr
[junit4] 2> 1556718 INFO (jetty-launcher-18925-thread-2) [n:127.0.0.1:35839_solr ] o.a.s.c.Overseer Overseer (id=72813384650063878-127.0.0.1:35839_solr-n_0000000000) starting
[junit4] 2> 1556728 WARN (jetty-launcher-18925-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@491bf831[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1556728 WARN (jetty-launcher-18925-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@491bf831[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1556729 INFO (OverseerStateUpdate-72813384650063878-127.0.0.1:35839_solr-n_0000000000) [n:127.0.0.1:35839_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:35839_solr
[junit4] 2> 1556730 INFO (jetty-launcher-18925-thread-2) [n:127.0.0.1:35839_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35839_solr
[junit4] 2> 1556735 WARN (jetty-launcher-18925-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1e435f2b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1556735 WARN (jetty-launcher-18925-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1e435f2b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1556737 INFO (jetty-launcher-18925-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:32919/solr
[junit4] 2> 1556737 INFO (jetty-launcher-18925-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1556737 INFO (zkCallback-18944-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1556739 INFO (jetty-launcher-18925-thread-2) [n:127.0.0.1:35839_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1556740 INFO (jetty-launcher-18925-thread-2) [n:127.0.0.1:35839_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: org.apache.solr.security.HadoopAuthPlugin
[junit4] 2> 1556743 INFO (zkConnectionManagerCallback-18960-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1556743 INFO (jetty-launcher-18925-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1556753 INFO (jetty-launcher-18925-thread-2) [n:127.0.0.1:35839_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> 1556849 INFO (jetty-launcher-18925-thread-1) [n:127.0.0.1:45533_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1556849 INFO (zkConnectionManagerCallback-18962-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1556849 INFO (jetty-launcher-18925-thread-1) [n:127.0.0.1:45533_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1556852 INFO (jetty-launcher-18925-thread-1) [n:127.0.0.1:45533_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1556855 INFO (jetty-launcher-18925-thread-1) [n:127.0.0.1:45533_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:45533_solr as DOWN
[junit4] 2> 1556856 INFO (jetty-launcher-18925-thread-1) [n:127.0.0.1:45533_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1556856 INFO (jetty-launcher-18925-thread-1) [n:127.0.0.1:45533_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45533_solr
[junit4] 2> 1556857 INFO (zkCallback-18944-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1556857 INFO (jetty-launcher-18925-thread-1) [n:127.0.0.1:45533_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1556858 INFO (jetty-launcher-18925-thread-1) [n:127.0.0.1:45533_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: org.apache.solr.security.HadoopAuthPlugin
[junit4] 2> 1556858 INFO (jetty-launcher-18925-thread-1) [n:127.0.0.1:45533_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> 1556863 INFO (zkCallback-18961-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1556968 INFO (jetty-launcher-18925-thread-2) [n:127.0.0.1:35839_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting
[junit4] 2> 1556969 INFO (jetty-launcher-18925-thread-1) [n:127.0.0.1:45533_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting
[junit4] 2> 1557014 INFO (jetty-launcher-18925-thread-1-EventThread) [ ] o.a.c.f.s.ConnectionStateManager State change: CONNECTED
[junit4] 2> 1557015 INFO (jetty-launcher-18925-thread-2-EventThread) [ ] o.a.c.f.s.ConnectionStateManager State change: CONNECTED
[junit4] 2> 1557187 WARN (jetty-launcher-18925-thread-1) [n:127.0.0.1:45533_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> 1557187 WARN (jetty-launcher-18925-thread-1) [n:127.0.0.1:45533_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> 1557195 WARN (jetty-launcher-18925-thread-2) [n:127.0.0.1:35839_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> 1557195 WARN (jetty-launcher-18925-thread-2) [n:127.0.0.1:35839_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> 1557213 INFO (jetty-launcher-18925-thread-1) [n:127.0.0.1:45533_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestDelegationWithHadoopAuth_E6E823FA0BA8C61-001/tempDir-001/node1
[junit4] 2> 1557214 INFO (jetty-launcher-18925-thread-2) [n:127.0.0.1:35839_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestDelegationWithHadoopAuth_E6E823FA0BA8C61-001/tempDir-001/node2
[junit4] 2> 1557232 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[E6E823FA0BA8C61]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 1557233 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[E6E823FA0BA8C61]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1557234 INFO (zkConnectionManagerCallback-18976-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1557234 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[E6E823FA0BA8C61]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1557235 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[E6E823FA0BA8C61]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1557236 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[E6E823FA0BA8C61]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:32919/solr ready
[junit4] 2> 1557265 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenSolrClient-seed#[E6E823FA0BA8C61]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenSolrClient
[junit4] 2> 1557296 INFO (qtp840969716-30211) [n:127.0.0.1:45533_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADZm9vA2ZvbwCKAXb4eEqoigF3HITOqAEEFLmsLcN5ZlD8ZHajsKiiLGctTrrWB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1557299 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenSolrClient-seed#[E6E823FA0BA8C61]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenSolrClient
[junit4] 2> 1557321 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancelFail-seed#[E6E823FA0BA8C61]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenCancelFail
[junit4] 2> 1557340 ERROR (qtp1526148795-30203) [ ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_2]
[junit4] 2> 1557341 ERROR (qtp840969716-30212) [ ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_2]
[junit4] 2> 1557348 ERROR (qtp1526148795-30205) [ ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_3]
[junit4] 2> 1557349 ERROR (qtp840969716-30208) [ ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_3]
[junit4] 2> 1557350 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancelFail-seed#[E6E823FA0BA8C61]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenCancelFail
[junit4] 2> 1557373 INFO (TEST-TestDelegationWithHadoopAuth.testZNodePaths-seed#[E6E823FA0BA8C61]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testZNodePaths
[junit4] 2> 1557381 INFO (TEST-TestDelegationWithHadoopAuth.testZNodePaths-seed#[E6E823FA0BA8C61]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1557383 INFO (zkConnectionManagerCallback-18978-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1557383 INFO (TEST-TestDelegationWithHadoopAuth.testZNodePaths-seed#[E6E823FA0BA8C61]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1557384 WARN (TEST-TestDelegationWithHadoopAuth.testZNodePaths-seed#[E6E823FA0BA8C61]-SendThread(127.0.0.1:32919)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102af6336f6000c.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102af6336f6000c, 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 0x102af6336f6000c, 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> 1557485 INFO (TEST-TestDelegationWithHadoopAuth.testZNodePaths-seed#[E6E823FA0BA8C61]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testZNodePaths
[junit4] 2> 1557507 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[E6E823FA0BA8C61]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenCancel
[junit4] 2> 1557517 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[E6E823FA0BA8C61]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1557518 INFO (zkConnectionManagerCallback-18983-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1557518 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[E6E823FA0BA8C61]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1557519 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[E6E823FA0BA8C61]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1557519 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[E6E823FA0BA8C61]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:32919/solr ready
[junit4] 2> 1557521 ERROR (qtp1526148795-30201) [ ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_5]
[junit4] 2> 1557526 WARN (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[E6E823FA0BA8C61]-SendThread(127.0.0.1:32919)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102af6336f6000d.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102af6336f6000d, 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 0x102af6336f6000d, 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> 1557637 ERROR (qtp1526148795-30207) [ ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_6]
[junit4] 2> 1557641 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[E6E823FA0BA8C61]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1557641 INFO (zkConnectionManagerCallback-18988-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1557641 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[E6E823FA0BA8C61]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1557642 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[E6E823FA0BA8C61]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1557643 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[E6E823FA0BA8C61]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:32919/solr ready
[junit4] 2> 1557746 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[E6E823FA0BA8C61]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenCancel
[junit4] 2> 1557767 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenewFail-seed#[E6E823FA0BA8C61]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenRenewFail
[junit4] 2> 1557773 WARN (qtp840969716-30213) [ ] 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=1610486402197, maxDate=1611091202197, sequenceNumber=7, masterKeyId=4) with non-matching renewer foo
[junit4] 2> 1557773 WARN (qtp840969716-30213) [ ] o.a.s.s.HadoopAuthPlugin Could not find org.apache.solr.security.authentication.requestcontinues
[junit4] 2> 1557774 WARN (qtp1526148795-30203) [ ] 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=1610486402197, maxDate=1611091202197, sequenceNumber=7, masterKeyId=4) with non-matching renewer foo
[junit4] 2> 1557774 WARN (qtp1526148795-30203) [ ] o.a.s.s.HadoopAuthPlugin Could not find org.apache.solr.security.authentication.requestcontinues
[junit4] 2> 1557787 WARN (qtp840969716-30208) [ ] o.a.h.s.a.s.AuthenticationFilter AuthenticationToken ignored: Unauthorized access
[junit4] 2> 1557790 WARN (qtp840969716-30210) [ ] o.a.h.s.a.s.AuthenticationFilter AuthenticationToken ignored: Unauthorized access
[junit4] 2> 1557790 WARN (qtp840969716-30210) [ ] 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=1610486402216, maxDate=1611091202216, sequenceNumber=8, masterKeyId=4) with non-matching renewer renewUser
[junit4] 2> 1557790 WARN (qtp840969716-30210) [ ] o.a.s.s.HadoopAuthPlugin Could not find org.apache.solr.security.authentication.requestcontinues
[junit4] 2> 1557791 WARN (qtp1526148795-30204) [ ] o.a.h.s.a.s.AuthenticationFilter AuthenticationToken ignored: Unauthorized access
[junit4] 2> 1557791 WARN (qtp1526148795-30204) [ ] 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=1610486402216, maxDate=1611091202216, sequenceNumber=8, masterKeyId=4) with non-matching renewer renewUser
[junit4] 2> 1557791 WARN (qtp1526148795-30204) [ ] o.a.s.s.HadoopAuthPlugin Could not find org.apache.solr.security.authentication.requestcontinues
[junit4] 2> 1557792 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenewFail-seed#[E6E823FA0BA8C61]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenRenewFail
[junit4] 2> 1557812 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationOtherOp-seed#[E6E823FA0BA8C61]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationOtherOp
[junit4] 2> 1557814 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationOtherOp-seed#[E6E823FA0BA8C61]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1557815 INFO (zkConnectionManagerCallback-18993-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1557815 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationOtherOp-seed#[E6E823FA0BA8C61]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1557816 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationOtherOp-seed#[E6E823FA0BA8C61]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1557817 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationOtherOp-seed#[E6E823FA0BA8C61]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:32919/solr ready
[junit4] 2> 1557818 INFO (qtp1526148795-30207) [n:127.0.0.1:35839_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> 1557819 WARN (TEST-TestDelegationWithHadoopAuth.testDelegationOtherOp-seed#[E6E823FA0BA8C61]-SendThread(127.0.0.1:32919)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102af6336f6000f.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102af6336f6000f, 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 0x102af6336f6000f, 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> 1557920 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationOtherOp-seed#[E6E823FA0BA8C61]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationOtherOp
[junit4] 2> 1557942 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[E6E823FA0BA8C61]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenVerify
[junit4] 2> 1557943 WARN (qtp840969716-30211) [n:127.0.0.1:45533_solr ] o.a.h.s.a.s.AuthenticationFilter AuthenticationToken ignored: Unauthorized access
[junit4] 2> 1557947 INFO (qtp840969716-30213) [n:127.0.0.1:45533_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADZm9vA2ZvbwCKAXb4eE1EigF3HITRRAkEFM1pLZA6FWM0wlatVxF_9-teitvJB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1557950 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[E6E823FA0BA8C61]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1557951 INFO (zkConnectionManagerCallback-18998-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1557951 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[E6E823FA0BA8C61]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1557952 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[E6E823FA0BA8C61]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1557953 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[E6E823FA0BA8C61]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:32919/solr ready
[junit4] 2> 1557954 INFO (qtp1526148795-30205) [n:127.0.0.1:35839_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADZm9vA2ZvbwCKAXb4eE1EigF3HITRRAkEFM1pLZA6FWM0wlatVxF_9-teitvJB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1557954 WARN (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[E6E823FA0BA8C61]-SendThread(127.0.0.1:32919)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102af6336f60010.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102af6336f60010, 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 0x102af6336f60010, 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> 1558058 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[E6E823FA0BA8C61]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenVerify
[junit4] 2> 1558081 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenew-seed#[E6E823FA0BA8C61]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenRenew
[junit4] 2> 1558081 WARN (qtp840969716-30210) [ ] o.a.h.s.a.s.AuthenticationFilter AuthenticationToken ignored: Unauthorized access
[junit4] 2> 1558088 WARN (qtp840969716-30211) [n:127.0.0.1:45533_solr ] o.a.h.s.a.s.AuthenticationFilter AuthenticationToken ignored: Unauthorized access
[junit4] 2> 1558088 WARN (qtp840969716-30211) [n:127.0.0.1:45533_solr ] 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=1610486402510, maxDate=1611091202510, sequenceNumber=10, masterKeyId=4) with wrong password
[junit4] 2> 1558088 WARN (qtp840969716-30211) [n:127.0.0.1:45533_solr ] o.a.s.s.HadoopAuthPlugin Could not find org.apache.solr.security.authentication.requestcontinues
[junit4] 2> 1558090 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenew-seed#[E6E823FA0BA8C61]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenRenew
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDelegationWithHadoopAuth -Dtests.method=testDelegationTokenRenew -Dtests.seed=E6E823FA0BA8C61 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=ar-YE -Dtests.timezone=Asia/Dubai -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 0.04s J1 | TestDelegationWithHadoopAuth.testDelegationTokenRenew <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<200> but was:<403>
[junit4] > at __randomizedtesting.SeedInfo.seed([E6E823FA0BA8C61:39F57621987651C5]: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.lang.Thread.run(Thread.java:748)
[junit4] 2> 1558195 INFO (jetty-closer-18999-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1952831933
[junit4] 2> 1558195 INFO (jetty-closer-18999-thread-2) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=339715290
[junit4] 2> 1558195 INFO (jetty-closer-18999-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:45533_solr
[junit4] 2> 1558195 INFO (jetty-closer-18999-thread-2) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:35839_solr
[junit4] 2> 1558195 INFO (jetty-closer-18999-thread-2) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 1558195 INFO (jetty-closer-18999-thread-1) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 1558195 INFO (jetty-closer-18999-thread-2) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:35839_solr as DOWN
[junit4] 2> 1558195 INFO (jetty-closer-18999-thread-1) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:45533_solr as DOWN
[junit4] 2> 1558198 INFO (jetty-closer-18999-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 1558198 INFO (jetty-closer-18999-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 1558198 INFO (jetty-closer-18999-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 1558198 INFO (jetty-closer-18999-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 1558198 INFO (jetty-closer-18999-thread-2) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1558198 INFO (jetty-closer-18999-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 1558198 INFO (jetty-closer-18999-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 1558198 INFO (jetty-closer-18999-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 1558198 INFO (jetty-closer-18999-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 1558199 INFO (closeThreadPool-19009-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72813384650063878-127.0.0.1:35839_solr-n_0000000000) closing
[junit4] 2> 1558200 WARN (jetty-launcher-18925-thread-1-SendThread(127.0.0.1:32919)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102af6336f60008.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102af6336f60008, 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 0x102af6336f60008, 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> 1558201 INFO (OverseerAutoScalingTriggerThread-72813384650063878-127.0.0.1:35839_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 1558201 INFO (OverseerStateUpdate-72813384650063878-127.0.0.1:35839_solr-n_0000000000) [n:127.0.0.1:35839_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:35839_solr
[junit4] 2> 1558202 INFO (closeThreadPool-19009-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72813384650063878-127.0.0.1:35839_solr-n_0000000000) closing
[junit4] 2> 1558301 ERROR (Thread[Thread-4391,5,TGRP-TestDelegationWithHadoopAuth]) [ ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted
[junit4] 2> 1558310 INFO (Curator-Framework-0) [ ] o.a.c.f.i.CuratorFrameworkImpl backgroundOperationsLoop exiting
[junit4] 2> 1558401 INFO (jetty-closer-18999-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72813384650063878-127.0.0.1:35839_solr-n_0000000000) closing
[junit4] 2> 1558401 ERROR (Thread[Thread-4389,5,TGRP-TestDelegationWithHadoopAuth]) [ ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted
[junit4] 2> 1558402 INFO (Curator-Framework-0) [ ] o.a.c.f.i.CuratorFrameworkImpl backgroundOperationsLoop exiting
[junit4] 2> 1558503 INFO (jetty-closer-18999-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@3ce7c5c0{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1558503 INFO (jetty-closer-18999-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3b862b79{/solr,null,STOPPED}
[junit4] 2> 1558503 INFO (jetty-closer-18999-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1558603 INFO (jetty-closer-18999-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@5430db95{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1558604 INFO (jetty-closer-18999-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1f9236e2{/solr,null,STOPPED}
[junit4] 2> 1558604 INFO (jetty-closer-18999-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1558605 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[E6E823FA0BA8C61]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1558606 WARN (SUITE-TestDelegationWithHadoopAuth-seed#[E6E823FA0BA8C61]-worker-SendThread(127.0.0.1:32919)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102af6336f60000.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102af6336f60000, 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 0x102af6336f60000, 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> 1558707 WARN (SUITE-TestDelegationWithHadoopAuth-seed#[E6E823FA0BA8C61]-worker-SendThread(127.0.0.1:32919)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102af6336f60001.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102af6336f60001, 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 0x102af6336f60001, 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> 1558809 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> 7 /solr/clusterprops.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> 9 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMKeyIdRoot
[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> 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/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> 10 /solr/live_nodes
[junit4] 2> 7 /solr/collections
[junit4] 2> 7 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot
[junit4] 2> 4 /solr/overseer/queue
[junit4] 2>
[junit4] 2> 1558811 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[E6E823FA0BA8C61]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:32919
[junit4] 2> 1558811 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[E6E823FA0BA8C61]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:32919
[junit4] 2> 1558811 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[E6E823FA0BA8C61]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 32919
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestDelegationWithHadoopAuth_E6E823FA0BA8C61-001
[junit4] 2> Jan 12, 2021 9:20:03 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=930, maxMBSortInHeap=6.127501660884638, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=ar-YE, timezone=Asia/Dubai
[junit4] 2> NOTE: Linux 4.15.0-112-generic amd64/Oracle Corporation 1.8.0_252 (64-bit)/cpus=4,threads=1,free=190123624,total=525860864
[junit4] 2> NOTE: All tests run in this JVM: [TermVectorComponentDistributedTest, TestSimComputePlanAction, InfixSuggestersTest, IgnoreLargeDocumentProcessorFactoryTest, DocValuesMultiTest, ChaosMonkeyNothingIsSafeTest, TestExportWriter, OverseerCollectionConfigSetProcessorTest, TestCloudDeleteByQuery, TestCloudSchemaless, PingRequestHandlerTest, TestGeoJSONResponseWriter, SearchRateTriggerTest, TestReversedWildcardFilterFactory, TestSolr4Spatial, PackageManagerCLITest, ShardRoutingTest, TestIntervalFaceting, TestHdfsBackupRestoreCore, RoutingToNodesWithPropertiesTest, HdfsCollectionsAPIDistributedZkTest, TestConfigSetsAPIZkFailure, NodeLostTriggerIntegrationTest, TestManagedResourceStorage, TestCollapseQParserPlugin, TestRandomCollapseQParserPlugin, DeleteReplicaTest, CurrencyFieldTypeTest, ResourceLoaderTest, MetricsHistoryWithAuthIntegrationTest, TestClusterProperties, HttpPartitionOnCommitTest, TestDistributedSearch, TestPointFields, DocValuesNotIndexedTest, DocumentAnalysisRequestHandlerTest, JWTVerificationkeyResolverTest, PreAnalyzedUpdateProcessorTest, VMParamsZkACLAndCredentialsProvidersTest, ReindexCollectionTest, LeaderTragicEventTest, TestCSVLoader, CollectionsAPISolrJTest, OverseerSolrResponseUnsafeSerializationTest, TestSolrConfigHandler, UUIDFieldTest, BasicFunctionalityTest, SimplePostToolTest, CollectionsAPIDistributedZkTest, SimpleFacetsTest, TestEmbeddedSolrServerAdminHandler, TestDistributedMap, FileUtilsTest, TestFileDictionaryLookup, TestRebalanceLeaders, TestPerFieldSimilarityWithDefaultOverride, AtomicUpdatesTest, TestCollectionAPI, SchemaApiFailureTest, ClusterStateTest, TestTolerantUpdateProcessorRandomCloud, TestRTimerTree, TestSolrCoreProperties, TestHashPartitioner, DistribDocExpirationUpdateProcessorTest, TestSystemIdResolver, TestWriterPerf, HttpTriggerListenerTest, TestSlowCompositeReaderWrapper, CdcrVersionReplicationTest, TestCloudPseudoReturnFields, TestMaxScoreQueryParser, TestDocBasedVersionConstraints, AutoScalingHandlerTest, ZkStateWriterTest, HLLUtilTest, TestMiniSolrCloudClusterSSL, SolrRrdBackendFactoryTest, UpdateParamsTest, PreAnalyzedFieldTest, TestTrieFacet, SignatureUpdateProcessorFactoryTest, MoveReplicaHDFSTest, TestPseudoReturnFields, TestReplicaProperties, TestRecovery, TestNumericRangeQuery64, TestReqParamsAPI, TestSolrQueryResponse, SpellCheckCollatorWithCollapseTest, CloudReplicaSourceTest, TestInitQParser, TestSolrConfigHandlerCloud, TestQuerySenderNoQuery, TestCustomSort, StressHdfsTest, TestSearchPerf, TestDynamicLoading, SortSpecParsingTest, TestShortCircuitedRequests, TestMergePolicyConfig, ThreadDumpHandlerTest, TestDelegationWithHadoopAuth]
[junit4] Completed [509/923 (1!)] on J1 in 3.51s, 8 tests, 1 failure <<< FAILURES!
[...truncated 52096 lines...]
[JENKINS] Lucene » Lucene-Solr-Tests-8.8 - Build # 65 - Still Unstable!
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.8/65/
1 tests failed.
FAILED: org.apache.solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest.test
Error Message:
Timed out waiting for replica core_node16 (1610496395339) to replicate from leader core_node4 (0)
Stack Trace:
java.lang.AssertionError: Timed out waiting for replica core_node16 (1610496395339) to replicate from leader core_node4 (0)
at __randomizedtesting.SeedInfo.seed([1E879B73F3A7C86F:96D3A4A95D5BA597]:0)
at org.junit.Assert.fail(Assert.java:89)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForReplicationFromReplicas(AbstractFullDistribZkTestBase.java:2326)
at org.apache.solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest.test(ChaosMonkeySafeLeaderWithPullReplicasTest.java:212)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1090)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1061)
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 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.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 14535 lines...]
[junit4] Suite: org.apache.solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest
[junit4] 2> 948068 INFO (SUITE-ChaosMonkeySafeLeaderWithPullReplicasTest-seed#[1E879B73F3A7C86F]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/server/solr/configsets/_default/conf'
[junit4] 2> 948068 INFO (SUITE-ChaosMonkeySafeLeaderWithPullReplicasTest-seed#[1E879B73F3A7C86F]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/data-dir-116-001
[junit4] 2> 948068 WARN (SUITE-ChaosMonkeySafeLeaderWithPullReplicasTest-seed#[1E879B73F3A7C86F]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 948068 INFO (SUITE-ChaosMonkeySafeLeaderWithPullReplicasTest-seed#[1E879B73F3A7C86F]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 948070 INFO (SUITE-ChaosMonkeySafeLeaderWithPullReplicasTest-seed#[1E879B73F3A7C86F]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 948071 INFO (SUITE-ChaosMonkeySafeLeaderWithPullReplicasTest-seed#[1E879B73F3A7C86F]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 948071 INFO (SUITE-ChaosMonkeySafeLeaderWithPullReplicasTest-seed#[1E879B73F3A7C86F]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /l_bn/ei
[junit4] 2> 948074 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.ChaosMonkeySafeLeaderWithPullReplicasTest Starting ChaosMonkey test with 2 shards and 8 nodes
[junit4] 2> 948082 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 948083 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 948083 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 948083 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 948183 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.ZkTestServer start zk server on port: 34447
[junit4] 2> 948183 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:34447
[junit4] 2> 948183 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:34447
[junit4] 2> 948183 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 34447
[junit4] 2> 948190 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 948196 INFO (zkConnectionManagerCallback-8790-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 948196 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 948203 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 948204 INFO (zkConnectionManagerCallback-8792-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 948204 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 948205 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 948207 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/core/src/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml
[junit4] 2> 948209 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 948211 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 948213 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 948215 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 948216 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 948218 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 948219 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 948221 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 948222 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 948226 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 948230 INFO (zkConnectionManagerCallback-8796-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 948230 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 948333 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise
[junit4] 2> 948434 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 948434 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 948434 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_252-b09
[junit4] 2> 948436 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 948436 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 948436 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 948438 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@49499a47{/l_bn/ei,null,AVAILABLE}
[junit4] 2> 948439 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@41d457da{SSL, (ssl, http/1.1)}{127.0.0.1:43373}
[junit4] 2> 948439 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.e.j.s.Server Started @948465ms
[junit4] 2> 948439 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/tempDir-001/control/data, replicaType=NRT, hostContext=/l_bn/ei, hostPort=43373, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/control-001/cores}
[junit4] 2> 948439 ERROR (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 948439 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 948439 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 948439 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 948439 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr
[junit4] 2> 948439 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-01-13T00:05:39.269Z
[junit4] 2> 948440 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 948442 INFO (zkConnectionManagerCallback-8798-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 948442 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 948443 WARN (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]-SendThread(127.0.0.1:34447)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102affade250003.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102affade250003, 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 0x102affade250003, 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> 948543 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 948543 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/control-001/solr.xml
[junit4] 2> 948546 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 948546 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 948547 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1f1a4299, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 948762 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 948763 WARN (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 948764 WARN (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@51ba24de[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 948764 WARN (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@51ba24de[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 948766 WARN (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 948767 WARN (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@46fb4efa[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 948767 WARN (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@46fb4efa[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 948768 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34447/solr
[junit4] 2> 948770 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 948770 INFO (zkConnectionManagerCallback-8809-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 948770 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 948771 WARN (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]-SendThread(127.0.0.1:34447)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102affade250004.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102affade250004, 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 0x102affade250004, 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> 948872 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [n:127.0.0.1:43373_l_bn%2Fei ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 948873 INFO (zkConnectionManagerCallback-8811-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 948873 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [n:127.0.0.1:43373_l_bn%2Fei ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 948937 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [n:127.0.0.1:43373_l_bn%2Fei ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:43373_l_bn%2Fei
[junit4] 2> 948937 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [n:127.0.0.1:43373_l_bn%2Fei ] o.a.s.c.Overseer Overseer (id=72814035995000837-127.0.0.1:43373_l_bn%2Fei-n_0000000000) starting
[junit4] 2> 948942 INFO (OverseerStateUpdate-72814035995000837-127.0.0.1:43373_l_bn%2Fei-n_0000000000) [n:127.0.0.1:43373_l_bn%2Fei ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:43373_l_bn%2Fei
[junit4] 2> 948942 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [n:127.0.0.1:43373_l_bn%2Fei ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43373_l_bn%2Fei
[junit4] 2> 948943 INFO (OverseerStateUpdate-72814035995000837-127.0.0.1:43373_l_bn%2Fei-n_0000000000) [n:127.0.0.1:43373_l_bn%2Fei ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 948945 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [n:127.0.0.1:43373_l_bn%2Fei ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 948945 WARN (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [n:127.0.0.1:43373_l_bn%2Fei ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled 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> 948963 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [n:127.0.0.1:43373_l_bn%2Fei ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 948982 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [n:127.0.0.1:43373_l_bn%2Fei ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1f1a4299
[junit4] 2> 948989 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [n:127.0.0.1:43373_l_bn%2Fei ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1f1a4299
[junit4] 2> 948989 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [n:127.0.0.1:43373_l_bn%2Fei ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1f1a4299
[junit4] 2> 948990 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [n:127.0.0.1:43373_l_bn%2Fei ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/control-001/cores
[junit4] 2> 949001 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 949002 INFO (zkConnectionManagerCallback-8828-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 949002 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 949003 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 949004 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34447/solr ready
[junit4] 2> 949043 INFO (OverseerThreadFactory-8818-thread-1-processing-n:127.0.0.1:43373_l_bn%2Fei) [n:127.0.0.1:43373_l_bn%2Fei ] o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 949152 INFO (qtp59987609-14945) [n:127.0.0.1:43373_l_bn%2Fei x:control_collection_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 949152 INFO (qtp59987609-14945) [n:127.0.0.1:43373_l_bn%2Fei x:control_collection_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 950172 INFO (qtp59987609-14945) [n:127.0.0.1:43373_l_bn%2Fei c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.0
[junit4] 2> 950186 INFO (qtp59987609-14945) [n:127.0.0.1:43373_l_bn%2Fei c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 950190 INFO (qtp59987609-14945) [n:127.0.0.1:43373_l_bn%2Fei c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 950204 INFO (qtp59987609-14945) [n:127.0.0.1:43373_l_bn%2Fei c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1' using configuration from configset conf1, trusted=true
[junit4] 2> 950204 INFO (qtp59987609-14945) [n:127.0.0.1:43373_l_bn%2Fei c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.control_collection.shard1.replica_n1' (registry 'solr.core.control_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1f1a4299
[junit4] 2> 950204 INFO (qtp59987609-14945) [n:127.0.0.1:43373_l_bn%2Fei c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/control-001/cores/control_collection_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/control-001/cores/control_collection_shard1_replica_n1/data/]
[junit4] 2> 950207 INFO (qtp59987609-14945) [n:127.0.0.1:43373_l_bn%2Fei c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=48, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 950255 WARN (qtp59987609-14945) [n:127.0.0.1:43373_l_bn%2Fei c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 950338 INFO (qtp59987609-14945) [n:127.0.0.1:43373_l_bn%2Fei c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 950338 INFO (qtp59987609-14945) [n:127.0.0.1:43373_l_bn%2Fei c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 950340 INFO (qtp59987609-14945) [n:127.0.0.1:43373_l_bn%2Fei c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 950340 INFO (qtp59987609-14945) [n:127.0.0.1:43373_l_bn%2Fei c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 950341 INFO (qtp59987609-14945) [n:127.0.0.1:43373_l_bn%2Fei c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=31, maxMergeAtOnceExplicit=19, maxMergedSegmentMB=71.3583984375, floorSegmentMB=0.234375, forceMergeDeletesPctAllowed=13.63348714188631, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, deletesPctAllowed=39.16303861517446
[junit4] 2> 950356 INFO (qtp59987609-14945) [n:127.0.0.1:43373_l_bn%2Fei c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 950356 INFO (qtp59987609-14945) [n:127.0.0.1:43373_l_bn%2Fei c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 950357 INFO (qtp59987609-14945) [n:127.0.0.1:43373_l_bn%2Fei c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 950357 INFO (qtp59987609-14945) [n:127.0.0.1:43373_l_bn%2Fei c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1688727811456499712
[junit4] 2> 950360 INFO (searcherExecutor-8830-thread-1-processing-n:127.0.0.1:43373_l_bn%2Fei x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:43373_l_bn%2Fei c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 950360 INFO (qtp59987609-14945) [n:127.0.0.1:43373_l_bn%2Fei c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 950373 INFO (qtp59987609-14945) [n:127.0.0.1:43373_l_bn%2Fei c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/control_collection/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 950373 INFO (qtp59987609-14945) [n:127.0.0.1:43373_l_bn%2Fei c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/control_collection/leaders/shard1
[junit4] 2> 950376 INFO (qtp59987609-14945) [n:127.0.0.1:43373_l_bn%2Fei c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 950376 INFO (qtp59987609-14945) [n:127.0.0.1:43373_l_bn%2Fei c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 950376 INFO (qtp59987609-14945) [n:127.0.0.1:43373_l_bn%2Fei c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:43373/l_bn/ei/control_collection_shard1_replica_n1/
[junit4] 2> 950376 INFO (qtp59987609-14945) [n:127.0.0.1:43373_l_bn%2Fei c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 950377 INFO (qtp59987609-14945) [n:127.0.0.1:43373_l_bn%2Fei c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:43373/l_bn/ei/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 950377 INFO (qtp59987609-14945) [n:127.0.0.1:43373_l_bn%2Fei c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/72814035995000837-core_node2-n_0000000000
[junit4] 2> 950379 INFO (qtp59987609-14945) [n:127.0.0.1:43373_l_bn%2Fei c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:43373/l_bn/ei/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 950379 INFO (qtp59987609-14945) [n:127.0.0.1:43373_l_bn%2Fei c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 950480 INFO (zkCallback-8810-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 950481 INFO (zkCallback-8810-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 950481 INFO (qtp59987609-14945) [n:127.0.0.1:43373_l_bn%2Fei c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 950483 INFO (qtp59987609-14945) [n:127.0.0.1:43373_l_bn%2Fei c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 950485 INFO (qtp59987609-14945) [n:127.0.0.1:43373_l_bn%2Fei ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1333
[junit4] 2> 950486 INFO (qtp59987609-14947) [n:127.0.0.1:43373_l_bn%2Fei ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 950486 INFO (qtp59987609-14947) [n:127.0.0.1:43373_l_bn%2Fei ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 950584 INFO (zkCallback-8810-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 950584 INFO (zkCallback-8810-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 950584 INFO (zkCallback-8810-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 950585 INFO (qtp59987609-14947) [n:127.0.0.1:43373_l_bn%2Fei ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:43373_l_bn%252Fei&wt=javabin&version=2} status=0 QTime=1547
[junit4] 2> 950585 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Waiting to see 1 active replicas in collection: control_collection
[junit4] 2> 950586 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 950709 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 950712 INFO (zkConnectionManagerCallback-8839-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 950712 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 950713 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 950714 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34447/solr ready
[junit4] 2> 950714 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 950730 INFO (OverseerThreadFactory-8818-thread-2-processing-n:127.0.0.1:43373_l_bn%2Fei) [n:127.0.0.1:43373_l_bn%2Fei ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 950731 INFO (OverseerCollectionConfigSetProcessor-72814035995000837-127.0.0.1:43373_l_bn%2Fei-n_0000000000) [n:127.0.0.1:43373_l_bn%2Fei ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 950934 WARN (OverseerThreadFactory-8818-thread-2-processing-n:127.0.0.1:43373_l_bn%2Fei) [n:127.0.0.1:43373_l_bn%2Fei ] o.a.s.c.a.c.CreateCollectionCmd It is unusual to create a collection (collection1) without cores.
[junit4] 2> 950936 INFO (qtp59987609-14947) [n:127.0.0.1:43373_l_bn%2Fei ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 950936 INFO (qtp59987609-14947) [n:127.0.0.1:43373_l_bn%2Fei ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 950938 INFO (qtp59987609-14947) [n:127.0.0.1:43373_l_bn%2Fei ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=2&wt=javabin&version=2} status=0 QTime=221
[junit4] 2> 950938 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 950941 INFO (watches-8836-thread-1) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected: 2
[junit4] 2> 950941 INFO (watches-8836-thread-1) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 950943 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected: 2
[junit4] 2> 950943 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 950944 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected: 2
[junit4] 2> 950944 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 950947 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected: 2
[junit4] 2> 950947 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 950947 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating jetty instances pullReplicaCount=2 numOtherReplicas=4
[junit4] 2> 951046 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/shard-1-001 of type NRT for shard2
[junit4] 2> 951058 INFO (closeThreadPool-8840-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 951058 INFO (closeThreadPool-8840-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 951059 INFO (closeThreadPool-8840-thread-1) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_252-b09
[junit4] 2> 951154 INFO (closeThreadPool-8840-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 951154 INFO (closeThreadPool-8840-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 951154 INFO (closeThreadPool-8840-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 951166 INFO (closeThreadPool-8840-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7f813e90{/l_bn/ei,null,AVAILABLE}
[junit4] 2> 951169 INFO (closeThreadPool-8840-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@72cf08c6{SSL, (ssl, http/1.1)}{127.0.0.1:43885}
[junit4] 2> 951170 INFO (closeThreadPool-8840-thread-1) [ ] o.e.j.s.Server Started @951195ms
[junit4] 2> 951170 INFO (closeThreadPool-8840-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/l_bn/ei, hostPort=43885, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/shard-1-001/cores}
[junit4] 2> 951170 ERROR (closeThreadPool-8840-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 951170 INFO (closeThreadPool-8840-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 951170 INFO (closeThreadPool-8840-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 951170 INFO (closeThreadPool-8840-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 951170 INFO (closeThreadPool-8840-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr
[junit4] 2> 951170 INFO (closeThreadPool-8840-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-01-13T00:05:42Z
[junit4] 2> 951186 INFO (closeThreadPool-8840-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 951189 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/shard-2-001 of type NRT for shard1
[junit4] 2> 951189 INFO (zkConnectionManagerCallback-8842-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 951189 INFO (closeThreadPool-8840-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 951201 INFO (closeThreadPool-8840-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 951201 INFO (closeThreadPool-8840-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 951201 INFO (closeThreadPool-8840-thread-2) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_252-b09
[junit4] 2> 951279 INFO (closeThreadPool-8840-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 951279 INFO (closeThreadPool-8840-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 951279 INFO (closeThreadPool-8840-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 951283 INFO (closeThreadPool-8840-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5fcf2330{/l_bn/ei,null,AVAILABLE}
[junit4] 2> 951288 INFO (closeThreadPool-8840-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6b19117{SSL, (ssl, http/1.1)}{127.0.0.1:34813}
[junit4] 2> 951289 INFO (closeThreadPool-8840-thread-2) [ ] o.e.j.s.Server Started @951314ms
[junit4] 2> 951289 INFO (closeThreadPool-8840-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/tempDir-001/jetty2, solrconfig=solrconfig.xml, hostContext=/l_bn/ei, hostPort=34813, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/shard-2-001/cores}
[junit4] 2> 951289 ERROR (closeThreadPool-8840-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 951289 INFO (closeThreadPool-8840-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 951289 INFO (closeThreadPool-8840-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 951289 INFO (closeThreadPool-8840-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 951289 INFO (closeThreadPool-8840-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr
[junit4] 2> 951289 INFO (closeThreadPool-8840-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-01-13T00:05:42.119Z
[junit4] 2> 951307 INFO (closeThreadPool-8840-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 951307 INFO (closeThreadPool-8840-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 951307 INFO (closeThreadPool-8840-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/shard-1-001/solr.xml
[junit4] 2> 951310 INFO (closeThreadPool-8840-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 951310 INFO (closeThreadPool-8840-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 951311 INFO (closeThreadPool-8840-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1f1a4299, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 951325 INFO (zkConnectionManagerCallback-8844-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 951325 INFO (closeThreadPool-8840-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 951358 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/shard-3-001 of type NRT for shard2
[junit4] 2> 951374 INFO (closeThreadPool-8840-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 951374 INFO (closeThreadPool-8840-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 951374 INFO (closeThreadPool-8840-thread-3) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_252-b09
[junit4] 2> 951427 INFO (closeThreadPool-8840-thread-2) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 951427 INFO (closeThreadPool-8840-thread-2) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/shard-2-001/solr.xml
[junit4] 2> 951430 INFO (closeThreadPool-8840-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 951430 INFO (closeThreadPool-8840-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 951431 INFO (closeThreadPool-8840-thread-2) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1f1a4299, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 951450 INFO (closeThreadPool-8840-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 951450 INFO (closeThreadPool-8840-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 951450 INFO (closeThreadPool-8840-thread-3) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 951463 INFO (closeThreadPool-8840-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6d7b3ffb{/l_bn/ei,null,AVAILABLE}
[junit4] 2> 951463 INFO (closeThreadPool-8840-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@83f1dfb{SSL, (ssl, http/1.1)}{127.0.0.1:38777}
[junit4] 2> 951463 INFO (closeThreadPool-8840-thread-3) [ ] o.e.j.s.Server Started @951489ms
[junit4] 2> 951463 INFO (closeThreadPool-8840-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/tempDir-001/jetty3, solrconfig=solrconfig.xml, hostContext=/l_bn/ei, hostPort=38777, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/shard-3-001/cores}
[junit4] 2> 951464 ERROR (closeThreadPool-8840-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 951464 INFO (closeThreadPool-8840-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 951464 INFO (closeThreadPool-8840-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 951464 INFO (closeThreadPool-8840-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 951464 INFO (closeThreadPool-8840-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr
[junit4] 2> 951464 INFO (closeThreadPool-8840-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-01-13T00:05:42.294Z
[junit4] 2> 951468 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/shard-4-001 of type NRT for shard1
[junit4] 2> 951469 INFO (closeThreadPool-8840-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 951491 INFO (closeThreadPool-8840-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 951491 INFO (closeThreadPool-8840-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 951491 INFO (closeThreadPool-8840-thread-4) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_252-b09
[junit4] 2> 951507 INFO (zkConnectionManagerCallback-8852-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 951507 INFO (closeThreadPool-8840-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 951568 INFO (closeThreadPool-8840-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 951568 INFO (closeThreadPool-8840-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 951568 INFO (closeThreadPool-8840-thread-4) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 951568 INFO (closeThreadPool-8840-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5147246{/l_bn/ei,null,AVAILABLE}
[junit4] 2> 951569 INFO (closeThreadPool-8840-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@67266838{SSL, (ssl, http/1.1)}{127.0.0.1:37033}
[junit4] 2> 951569 INFO (closeThreadPool-8840-thread-4) [ ] o.e.j.s.Server Started @951595ms
[junit4] 2> 951569 INFO (closeThreadPool-8840-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/tempDir-001/jetty4, solrconfig=solrconfig.xml, hostContext=/l_bn/ei, hostPort=37033, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/shard-4-001/cores}
[junit4] 2> 951569 ERROR (closeThreadPool-8840-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 951569 INFO (closeThreadPool-8840-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 951574 INFO (closeThreadPool-8840-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 951574 INFO (closeThreadPool-8840-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 951574 INFO (closeThreadPool-8840-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr
[junit4] 2> 951574 INFO (closeThreadPool-8840-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-01-13T00:05:42.404Z
[junit4] 2> 951601 INFO (closeThreadPool-8840-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 951609 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 5 in directory /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/shard-5-001 of type PULL for shard2
[junit4] 2> 951625 INFO (closeThreadPool-8840-thread-5) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 951625 INFO (closeThreadPool-8840-thread-5) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 951625 INFO (closeThreadPool-8840-thread-5) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_252-b09
[junit4] 2> 951634 INFO (zkConnectionManagerCallback-8854-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 951634 INFO (closeThreadPool-8840-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 951649 WARN (closeThreadPool-8840-thread-4-SendThread(127.0.0.1:34447)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102affade25000b.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102affade25000b, 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 0x102affade25000b, 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> 951674 INFO (closeThreadPool-8840-thread-3) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 951674 INFO (closeThreadPool-8840-thread-3) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/shard-3-001/solr.xml
[junit4] 2> 951676 INFO (closeThreadPool-8840-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 951676 INFO (closeThreadPool-8840-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 951736 INFO (closeThreadPool-8840-thread-5) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 951736 INFO (closeThreadPool-8840-thread-5) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 951736 INFO (closeThreadPool-8840-thread-5) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 951737 INFO (closeThreadPool-8840-thread-3) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1f1a4299, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 951746 INFO (closeThreadPool-8840-thread-5) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@288395ad{/l_bn/ei,null,AVAILABLE}
[junit4] 2> 951747 INFO (closeThreadPool-8840-thread-5) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4722863f{SSL, (ssl, http/1.1)}{127.0.0.1:35549}
[junit4] 2> 951747 INFO (closeThreadPool-8840-thread-5) [ ] o.e.j.s.Server Started @951773ms
[junit4] 2> 951747 INFO (closeThreadPool-8840-thread-5) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/tempDir-001/jetty5, replicaType=PULL, solrconfig=solrconfig.xml, hostContext=/l_bn/ei, hostPort=35549, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/shard-5-001/cores}
[junit4] 2> 951747 ERROR (closeThreadPool-8840-thread-5) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 951747 INFO (closeThreadPool-8840-thread-5) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 951747 INFO (closeThreadPool-8840-thread-5) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 951747 INFO (closeThreadPool-8840-thread-5) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 951747 INFO (closeThreadPool-8840-thread-5) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr
[junit4] 2> 951747 INFO (closeThreadPool-8840-thread-5) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-01-13T00:05:42.577Z
[junit4] 2> 951749 INFO (closeThreadPool-8840-thread-4) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 951749 INFO (closeThreadPool-8840-thread-4) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/shard-4-001/solr.xml
[junit4] 2> 951751 INFO (closeThreadPool-8840-thread-4) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 951751 INFO (closeThreadPool-8840-thread-4) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 951752 INFO (closeThreadPool-8840-thread-4) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1f1a4299, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 951757 INFO (closeThreadPool-8840-thread-5) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 951784 INFO (zkConnectionManagerCallback-8859-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 951784 INFO (closeThreadPool-8840-thread-5) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 951788 WARN (closeThreadPool-8840-thread-5-SendThread(127.0.0.1:34447)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102affade25000c.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102affade25000c, 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 0x102affade25000c, 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> 951889 INFO (closeThreadPool-8840-thread-5) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 951889 INFO (closeThreadPool-8840-thread-5) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/shard-5-001/solr.xml
[junit4] 2> 951891 INFO (closeThreadPool-8840-thread-5) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 951891 INFO (closeThreadPool-8840-thread-5) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 951893 INFO (closeThreadPool-8840-thread-5) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1f1a4299, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 951922 INFO (closeThreadPool-8840-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 951924 WARN (closeThreadPool-8840-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 951924 WARN (closeThreadPool-8840-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4c56b385[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 951924 WARN (closeThreadPool-8840-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4c56b385[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 951946 WARN (closeThreadPool-8840-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 951970 WARN (closeThreadPool-8840-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4f6c714[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 951970 WARN (closeThreadPool-8840-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4f6c714[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 951971 INFO (closeThreadPool-8840-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34447/solr
[junit4] 2> 951972 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 6 in directory /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/shard-6-001 of type PULL for shard1
[junit4] 2> 952006 INFO (closeThreadPool-8840-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 952030 INFO (zkConnectionManagerCallback-8873-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 952030 INFO (closeThreadPool-8840-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 952031 WARN (closeThreadPool-8840-thread-1-SendThread(127.0.0.1:34447)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102affade25000d.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102affade25000d, 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 0x102affade25000d, 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> 952032 INFO (closeThreadPool-8840-thread-6) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 952032 INFO (closeThreadPool-8840-thread-6) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 952032 INFO (closeThreadPool-8840-thread-6) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_252-b09
[junit4] 2> 952076 INFO (closeThreadPool-8840-thread-6) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 952076 INFO (closeThreadPool-8840-thread-6) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 952076 INFO (closeThreadPool-8840-thread-6) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 952077 INFO (closeThreadPool-8840-thread-6) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@edcb3dd{/l_bn/ei,null,AVAILABLE}
[junit4] 2> 952077 INFO (closeThreadPool-8840-thread-6) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7849fe1a{SSL, (ssl, http/1.1)}{127.0.0.1:36191}
[junit4] 2> 952077 INFO (closeThreadPool-8840-thread-6) [ ] o.e.j.s.Server Started @952103ms
[junit4] 2> 952077 INFO (closeThreadPool-8840-thread-6) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/tempDir-001/jetty6, replicaType=PULL, solrconfig=solrconfig.xml, hostContext=/l_bn/ei, hostPort=36191, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/shard-6-001/cores}
[junit4] 2> 952078 ERROR (closeThreadPool-8840-thread-6) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 952090 INFO (closeThreadPool-8840-thread-6) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 952090 INFO (closeThreadPool-8840-thread-6) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 952090 INFO (closeThreadPool-8840-thread-6) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 952090 INFO (closeThreadPool-8840-thread-6) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr
[junit4] 2> 952090 INFO (closeThreadPool-8840-thread-6) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-01-13T00:05:42.920Z
[junit4] 2> 952095 INFO (closeThreadPool-8840-thread-6) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 952129 INFO (zkConnectionManagerCallback-8875-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 952138 INFO (closeThreadPool-8840-thread-6) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 952149 INFO (closeThreadPool-8840-thread-1) [n:127.0.0.1:43885_l_bn%2Fei ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 952162 INFO (zkConnectionManagerCallback-8877-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 952162 INFO (closeThreadPool-8840-thread-1) [n:127.0.0.1:43885_l_bn%2Fei ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 952165 INFO (closeThreadPool-8840-thread-1) [n:127.0.0.1:43885_l_bn%2Fei ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 952168 INFO (closeThreadPool-8840-thread-1) [n:127.0.0.1:43885_l_bn%2Fei ] o.a.s.c.ZkController Publish node=127.0.0.1:43885_l_bn%2Fei as DOWN
[junit4] 2> 952169 INFO (closeThreadPool-8840-thread-1) [n:127.0.0.1:43885_l_bn%2Fei ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 952169 INFO (closeThreadPool-8840-thread-1) [n:127.0.0.1:43885_l_bn%2Fei ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43885_l_bn%2Fei
[junit4] 2> 952170 INFO (zkCallback-8810-thread-4) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 952171 INFO (closeThreadPool-8840-thread-1) [n:127.0.0.1:43885_l_bn%2Fei ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 952172 WARN (closeThreadPool-8840-thread-1) [n:127.0.0.1:43885_l_bn%2Fei ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled 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> 952176 INFO (zkCallback-8838-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 952182 INFO (zkCallback-8876-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 952206 INFO (closeThreadPool-8840-thread-1) [n:127.0.0.1:43885_l_bn%2Fei ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 952225 INFO (closeThreadPool-8840-thread-1) [n:127.0.0.1:43885_l_bn%2Fei ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1f1a4299
[junit4] 2> 952250 INFO (closeThreadPool-8840-thread-6) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 952250 INFO (closeThreadPool-8840-thread-6) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/shard-6-001/solr.xml
[junit4] 2> 952253 INFO (closeThreadPool-8840-thread-6) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 952253 INFO (closeThreadPool-8840-thread-6) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 952254 INFO (closeThreadPool-8840-thread-6) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1f1a4299, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 952266 INFO (closeThreadPool-8840-thread-1) [n:127.0.0.1:43885_l_bn%2Fei ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1f1a4299
[junit4] 2> 952266 INFO (closeThreadPool-8840-thread-1) [n:127.0.0.1:43885_l_bn%2Fei ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1f1a4299
[junit4] 2> 952267 INFO (closeThreadPool-8840-thread-1) [n:127.0.0.1:43885_l_bn%2Fei ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/shard-1-001/cores
[junit4] 2> 952274 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 7 in directory /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/shard-7-001 of type PULL for shard2
[junit4] 2> 952307 INFO (closeThreadPool-8840-thread-7) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 952307 INFO (closeThreadPool-8840-thread-7) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 952307 INFO (closeThreadPool-8840-thread-7) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_252-b09
[junit4] 2> 952318 INFO (closeThreadPool-8840-thread-5) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 952319 WARN (closeThreadPool-8840-thread-5) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 952327 INFO (closeThreadPool-8840-thread-1) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:43885_l_bn%2Fei
[junit4] 2> 952426 WARN (closeThreadPool-8840-thread-5) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@39c7d29f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 952426 WARN (closeThreadPool-8840-thread-5) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@39c7d29f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 952448 INFO (closeThreadPool-8840-thread-7) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 952448 INFO (closeThreadPool-8840-thread-7) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 952449 INFO (closeThreadPool-8840-thread-7) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 952449 INFO (closeThreadPool-8840-thread-7) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@425dc430{/l_bn/ei,null,AVAILABLE}
[junit4] 2> 952451 WARN (closeThreadPool-8840-thread-5) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 952461 INFO (closeThreadPool-8840-thread-7) [ ] o.e.j.s.AbstractConnector Started ServerConnector@187a119b{SSL, (ssl, http/1.1)}{127.0.0.1:41645}
[junit4] 2> 952461 INFO (closeThreadPool-8840-thread-7) [ ] o.e.j.s.Server Started @952487ms
[junit4] 2> 952461 INFO (closeThreadPool-8840-thread-7) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/tempDir-001/jetty7, replicaType=PULL, solrconfig=solrconfig.xml, hostContext=/l_bn/ei, hostPort=41645, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/shard-7-001/cores}
[junit4] 2> 952461 ERROR (closeThreadPool-8840-thread-7) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 952461 INFO (closeThreadPool-8840-thread-7) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 952461 INFO (closeThreadPool-8840-thread-7) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 952461 INFO (closeThreadPool-8840-thread-7) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 952461 INFO (closeThreadPool-8840-thread-7) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr
[junit4] 2> 952461 INFO (closeThreadPool-8840-thread-7) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-01-13T00:05:43.291Z
[junit4] 2> 952468 WARN (closeThreadPool-8840-thread-5) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@43deb443[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 952468 WARN (closeThreadPool-8840-thread-5) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@43deb443[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 952469 INFO (closeThreadPool-8840-thread-5) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34447/solr
[junit4] 2> 952479 INFO (closeThreadPool-8840-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 952480 WARN (closeThreadPool-8840-thread-4) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 952490 INFO (closeThreadPool-8840-thread-7) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 952502 INFO (zkConnectionManagerCallback-8895-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 952502 INFO (closeThreadPool-8840-thread-7) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 952514 INFO (closeThreadPool-8840-thread-5) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 952521 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 8 in directory /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001/shard-8-001 of type PULL for shard1
[junit4] 2> 952522 WARN (closeThreadPool-8840-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6e8be26d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 952522 INFO (closeThreadPool-8840-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 952522 WARN (closeThreadPool-8840-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6e8be26d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 952522 INFO (closeThreadPool-8840-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 952522 INFO (closeThreadPool-8840-thread-1) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_252-b09
[junit4] 2> 952562 INFO (zkConnectionManagerCallback-8898-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 952562 INFO (closeThreadPool-8840-thread-5) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 952573 INFO (closeThreadPool-8840-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 952573 INFO (closeThreadPool-8840-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 952573 INFO (closeThreadPool-8840-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 952586 INFO (closeThreadPool-8840-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2c7ee8d3{/l_bn/ei,null,
[...truncated too long message...]
.ServletContextHandler@5147246{/l_bn/ei,null,STOPPED}
[junit4] 2> 1009578 INFO (closeThreadPool-9152-thread-10) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1009676 INFO (closeThreadPool-9152-thread-9) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@72cf08c6{SSL, (ssl, http/1.1)}{127.0.0.1:0}
[junit4] 2> 1009677 INFO (closeThreadPool-9152-thread-9) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7f813e90{/l_bn/ei,null,STOPPED}
[junit4] 2> 1009677 INFO (closeThreadPool-9152-thread-9) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1009777 INFO (closeThreadPool-9152-thread-11) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@187a119b{SSL, (ssl, http/1.1)}{127.0.0.1:0}
[junit4] 2> 1009777 INFO (closeThreadPool-9152-thread-11) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@425dc430{/l_bn/ei,null,STOPPED}
[junit4] 2> 1009778 INFO (closeThreadPool-9152-thread-11) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1009878 INFO (closeThreadPool-9152-thread-7) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@83f1dfb{SSL, (ssl, http/1.1)}{127.0.0.1:0}
[junit4] 2> 1009878 INFO (closeThreadPool-9152-thread-7) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6d7b3ffb{/l_bn/ei,null,STOPPED}
[junit4] 2> 1009878 INFO (closeThreadPool-9152-thread-7) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1009878 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1009980 WARN (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]-SendThread(127.0.0.1:34447)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102affade250001.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102affade250001, 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 0x102affade250001, 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> 1010082 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> 12 /solr/collections/collection1/terms/shard2
[junit4] 2> 12 /solr/aliases.json
[junit4] 2> 11 /solr/collections/collection1/terms/shard1
[junit4] 2> 10 /solr/packages.json
[junit4] 2> 10 /solr/security.json
[junit4] 2> 10 /solr/configs/conf1
[junit4] 2> 4 /solr/collections/collection1/collectionprops.json
[junit4] 2> 3 /solr/collections/control_collection/terms/shard1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 314 /solr/collections/collection1/state.json
[junit4] 2> 12 /solr/clusterprops.json
[junit4] 2> 12 /solr/collections/control_collection/state.json
[junit4] 2> 12 /solr/clusterstate.json
[junit4] 2> 2 /solr/overseer_elect/election/72814035995000847-127.0.0.1:43885_l_bn%2Fei-n_0000000001
[junit4] 2> 2 /solr/overseer_elect/election/72814035995000858-127.0.0.1:33439_l_bn%2Fei-n_0000000005
[junit4] 2> 2 /solr/overseer_elect/election/72814035995000855-127.0.0.1:38777_l_bn%2Fei-n_0000000004
[junit4] 2> 2 /solr/overseer_elect/election/72814035995000861-127.0.0.1:41645_l_bn%2Fei-n_0000000007
[junit4] 2> 2 /solr/overseer_elect/election/72814035995000853-127.0.0.1:37033_l_bn%2Fei-n_0000000003
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 119 /solr/live_nodes
[junit4] 2> 80 /solr/overseer/queue
[junit4] 2> 42 /solr/collections/collection1/state.json
[junit4] 2> 26 /solr/overseer/collection-queue-work
[junit4] 2> 12 /solr/collections
[junit4] 2> 4 /solr/collections/control_collection/state.json
[junit4] 2> 3 /solr/autoscaling/events/.auto_add_replicas
[junit4] 2>
[junit4] 2> 1010086 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:34447
[junit4] 2> 1010086 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:34447
[junit4] 2> 1010086 INFO (TEST-ChaosMonkeySafeLeaderWithPullReplicasTest.test-seed#[1E879B73F3A7C86F]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 34447
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ChaosMonkeySafeLeaderWithPullReplicasTest -Dtests.method=test -Dtests.seed=1E879B73F3A7C86F -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=ar-LY -Dtests.timezone=Africa/Lubumbashi -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 62.0s J0 | ChaosMonkeySafeLeaderWithPullReplicasTest.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: Timed out waiting for replica core_node16 (1610496395339) to replicate from leader core_node4 (0)
[junit4] > at __randomizedtesting.SeedInfo.seed([1E879B73F3A7C86F:96D3A4A95D5BA597]:0)
[junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForReplicationFromReplicas(AbstractFullDistribZkTestBase.java:2326)
[junit4] > at org.apache.solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest.test(ChaosMonkeySafeLeaderWithPullReplicasTest.java:212)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1090)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1061)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderWithPullReplicasTest_1E879B73F3A7C86F-001
[junit4] 2> Jan 13, 2021 12:06:41 AM 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=1654, maxMBSortInHeap=5.726363110381677, sim=Asserting(RandomSimilarity(queryNorm=true): {}), locale=ar-LY, timezone=Africa/Lubumbashi
[junit4] 2> NOTE: Linux 4.15.0-112-generic amd64/Oracle Corporation 1.8.0_252 (64-bit)/cpus=4,threads=1,free=67376968,total=524812288
[junit4] 2> NOTE: All tests run in this JVM: [SolrTestCaseJ4Test, TestDistributedStatsComponentCardinality, MaxScoreCollectorTest, TestComponentsName, TimeRoutedAliasUpdateProcessorTest, JvmMetricsTest, TestDocTermOrdsUninvertLimit, TestExtendedDismaxParser, TestConfigSets, TestHalfAndHalfDocValues, MoveReplicaHDFSFailoverTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, SoftAutoCommitTest, TestUniqueKeyFieldResource, SearchRateTriggerIntegrationTest, SplitShardTest, TestTrackingShardHandlerFactory, TestPerFieldSimilarity, ExternalFileFieldSortTest, SolrIndexSplitterTest, TestManagedResource, MetricsHistoryIntegrationTest, TestSolrIndexConfig, RandomizedTaggerTest, SpatialRPTFieldTypeTest, OverriddenZkACLAndCredentialsProvidersTest, MetricUtilsTest, ShardRoutingCustomTest, TriggerCooldownIntegrationTest, HdfsAutoAddReplicasIntegrationTest, RulesTest, AnalysisAfterCoreReloadTest, TestRTGBase, CustomCollectionTest, TestConfig, TestSystemCollAutoCreate, RequiredFieldsTest, TestReloadDeadlock, TestLockTree, TestFieldCacheSortRandom, OutputWriterTest, BasicAuthOnSingleNodeTest, TestSimpleTrackingShardHandler, TestSolrCloudSnapshots, TestSolrJacksonAnnotation, TestRetrieveFieldsOptimizer, JSONWriterTest, SuggestComponentTest, TestCloudSearcherWarming, ZkStateReaderTest, TestQuerySenderListener, TestOmitPositions, DocValuesMissingTest, OverseerSolrResponseTest, RegexBytesRefFilterTest, ZkFailoverTest, CoreAdminHandlerTest, MultiThreadedOCPTest, CSVRequestHandlerTest, AutoAddReplicasPlanActionTest, BadComponentTest, TestDefaultStatsCache, IndexSizeTriggerTest, MoreLikeThisHandlerTest, TestDistribDocBasedVersion, TestTermsQParserPlugin, HdfsSyncSliceTest, TestCaffeineCache, TestZkAclsWithHadoopAuth, TestCoreAdminApis, ShardsWhitelistTest, TestExclusionRuleCollectionAccess, TestHighlightDedupGrouping, HighlighterTest, TestLMDirichletSimilarityFactory, TestStressInPlaceUpdates, XmlInterpolationTest, SliceStateTest, TestTolerantUpdateProcessorCloud, SolrJmxReporterCloudTest, TestCloudPivotFacet, AtomicUpdateProcessorFactoryTest, TestWithCollection, DistributedFacetPivotWhiteBoxTest, DeleteNodeTest, CloudMLTQParserTest, DistributedQueryElevationComponentTest, QueryElevationComponentTest, TestPullReplica, RestoreTriggerStateTest, TestUnInvertedFieldException, BadCopyFieldTest, TestFaceting, TestPHPSerializedResponseWriter, TestUnifiedSolrHighlighterWithoutStoredId, TestLegacyFieldReuse, BufferStoreTest, CircularListTest, TestJsonRangeFacets, TimeZoneUtilsTest, TestSimpleTextCodec, TestSolrCoreParser, ChaosMonkeySafeLeaderWithPullReplicasTest]
[junit4] Completed [385/923 (1!)] on J0 in 63.01s, 1 test, 1 failure <<< FAILURES!
[...truncated 52573 lines...]