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/10/26 23:31:51 UTC
[JENKINS] Lucene » Lucene-Solr-NightlyTests-8.10 - Build # 49 - Unstable!
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-NightlyTests-8.10/49/
1 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard
Error Message:
Stack Trace:
java.lang.AssertionError
at __randomizedtesting.SeedInfo.seed([695B8EE0117F8DD:4637381929D4A88B]:0)
at org.junit.Assert.fail(Assert.java:87)
at org.junit.Assert.assertTrue(Assert.java:42)
at org.junit.Assert.assertTrue(Assert.java:53)
at org.apache.solr.cloud.autoscaling.ComputePlanActionTest.nodeAddedTriggerWithAddReplicaPreferredOp(ComputePlanActionTest.java:701)
at org.apache.solr.cloud.autoscaling.ComputePlanActionTest.nodeAddedTriggerWithAddReplicaPreferredOp(ComputePlanActionTest.java:665)
at org.apache.solr.cloud.autoscaling.ComputePlanActionTest.nodeAddedTriggerWithAddReplicaPreferredOp(ComputePlanActionTest.java:637)
at org.apache.solr.cloud.autoscaling.ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard(ComputePlanActionTest.java:586)
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 13667 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.ComputePlanActionTest
[junit4] 2> 682577 INFO (SUITE-ComputePlanActionTest-seed#[695B8EE0117F8DD]-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-NightlyTests-8.10/checkout/solr/server/solr/configsets/_default/conf'
[junit4] 2> 682578 INFO (SUITE-ComputePlanActionTest-seed#[695B8EE0117F8DD]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.10/checkout/solr/build/solr-core/test/J3/temp/solr.cloud.autoscaling.ComputePlanActionTest_695B8EE0117F8DD-001/data-dir-23-001
[junit4] 2> 682578 WARN (SUITE-ComputePlanActionTest-seed#[695B8EE0117F8DD]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=9 numCloses=9
[junit4] 2> 682578 INFO (SUITE-ComputePlanActionTest-seed#[695B8EE0117F8DD]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 682580 INFO (SUITE-ComputePlanActionTest-seed#[695B8EE0117F8DD]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 682580 INFO (SUITE-ComputePlanActionTest-seed#[695B8EE0117F8DD]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 682580 INFO (SUITE-ComputePlanActionTest-seed#[695B8EE0117F8DD]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.10/checkout/solr/build/solr-core/test/J3/temp/solr.cloud.autoscaling.ComputePlanActionTest_695B8EE0117F8DD-001/tempDir-001
[junit4] 2> 682581 INFO (SUITE-ComputePlanActionTest-seed#[695B8EE0117F8DD]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 682581 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 682581 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 682582 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 682681 INFO (SUITE-ComputePlanActionTest-seed#[695B8EE0117F8DD]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 43233
[junit4] 2> 682681 INFO (SUITE-ComputePlanActionTest-seed#[695B8EE0117F8DD]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:43233
[junit4] 2> 682681 INFO (SUITE-ComputePlanActionTest-seed#[695B8EE0117F8DD]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:43233
[junit4] 2> 682681 INFO (SUITE-ComputePlanActionTest-seed#[695B8EE0117F8DD]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 43233
[junit4] 2> 682686 INFO (SUITE-ComputePlanActionTest-seed#[695B8EE0117F8DD]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 682694 INFO (zkConnectionManagerCallback-3579-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 682695 INFO (SUITE-ComputePlanActionTest-seed#[695B8EE0117F8DD]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 682704 INFO (SUITE-ComputePlanActionTest-seed#[695B8EE0117F8DD]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 682710 INFO (zkConnectionManagerCallback-3581-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 682710 INFO (SUITE-ComputePlanActionTest-seed#[695B8EE0117F8DD]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 682715 INFO (SUITE-ComputePlanActionTest-seed#[695B8EE0117F8DD]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 682716 INFO (zkConnectionManagerCallback-3583-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 682716 INFO (SUITE-ComputePlanActionTest-seed#[695B8EE0117F8DD]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 682717 WARN (SUITE-ComputePlanActionTest-seed#[695B8EE0117F8DD]-worker-SendThread(127.0.0.1:43233)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x108758172f30002.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x108758172f30002, 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 0x108758172f30002, 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> 682819 INFO (jetty-launcher-3584-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 682819 INFO (jetty-launcher-3584-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 682819 INFO (jetty-launcher-3584-thread-1) [ ] o.e.j.s.Server jetty-9.4.44.v20210927; built: 2021-09-27T23:02:44.612Z; git: 8da83308eeca865e495e53ef315a249d63ba9332; jvm 1.8.0_291-b10
[junit4] 2> 682826 INFO (jetty-launcher-3584-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 682826 INFO (jetty-launcher-3584-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 682826 INFO (jetty-launcher-3584-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 682826 INFO (jetty-launcher-3584-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@48007090{/solr,null,AVAILABLE}
[junit4] 2> 682827 INFO (jetty-launcher-3584-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7b00a73a{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:45449}
[junit4] 2> 682827 INFO (jetty-launcher-3584-thread-1) [ ] o.e.j.s.Server Started @682866ms
[junit4] 2> 682827 INFO (jetty-launcher-3584-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:43233/solr, hostPort=45449}
[junit4] 2> 682827 ERROR (jetty-launcher-3584-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 682827 INFO (jetty-launcher-3584-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 682827 INFO (jetty-launcher-3584-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.10.2
[junit4] 2> 682827 INFO (jetty-launcher-3584-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 682827 INFO (jetty-launcher-3584-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.10/checkout/solr
[junit4] 2> 682827 INFO (jetty-launcher-3584-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-10-26T21:49:22.995Z
[junit4] 2> 682828 INFO (jetty-launcher-3584-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 682829 INFO (zkConnectionManagerCallback-3586-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 682829 INFO (jetty-launcher-3584-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 682830 INFO (jetty-launcher-3584-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 683798 INFO (jetty-launcher-3584-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 683799 WARN (jetty-launcher-3584-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2355518f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 683799 WARN (jetty-launcher-3584-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2355518f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 683802 WARN (jetty-launcher-3584-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@60da9402[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 683802 WARN (jetty-launcher-3584-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@60da9402[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 683803 INFO (jetty-launcher-3584-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43233/solr
[junit4] 2> 683806 INFO (jetty-launcher-3584-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 683809 INFO (zkConnectionManagerCallback-3597-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 683810 INFO (jetty-launcher-3584-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 683918 INFO (jetty-launcher-3584-thread-1) [n:127.0.0.1:45449_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 683918 INFO (zkConnectionManagerCallback-3599-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 683918 INFO (jetty-launcher-3584-thread-1) [n:127.0.0.1:45449_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 683984 WARN (jetty-launcher-3584-thread-1) [n:127.0.0.1:45449_solr ] o.a.s.c.ZkController Contents of zookeeper /security.json are world-readable; consider setting up ACLs as described in https://solr.apache.org/guide/zookeeper-access-control.html
[junit4] 2> 683989 INFO (jetty-launcher-3584-thread-1) [n:127.0.0.1:45449_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:45449_solr
[junit4] 2> 683989 INFO (jetty-launcher-3584-thread-1) [n:127.0.0.1:45449_solr ] o.a.s.c.Overseer Overseer (id=74438592691372037-127.0.0.1:45449_solr-n_0000000000) starting
[junit4] 2> 683994 INFO (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:45449_solr
[junit4] 2> 683998 INFO (jetty-launcher-3584-thread-1) [n:127.0.0.1:45449_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45449_solr
[junit4] 2> 684000 INFO (jetty-launcher-3584-thread-1) [n:127.0.0.1:45449_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 684000 WARN (jetty-launcher-3584-thread-1) [n:127.0.0.1:45449_solr ] 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> 684004 INFO (zkCallback-3598-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 684010 DEBUG (OverseerAutoScalingTriggerThread-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 684011 DEBUG (OverseerAutoScalingTriggerThread-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 684012 DEBUG (OverseerAutoScalingTriggerThread-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 684012 DEBUG (OverseerAutoScalingTriggerThread-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 684017 DEBUG (OverseerAutoScalingTriggerThread-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:45449_solr]
[junit4] 2> 684024 DEBUG (ScheduledTrigger-3604-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 684024 DEBUG (OverseerAutoScalingTriggerThread-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 684025 DEBUG (OverseerAutoScalingTriggerThread-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 684032 INFO (jetty-launcher-3584-thread-1) [n:127.0.0.1:45449_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 684068 INFO (jetty-launcher-3584-thread-1) [n:127.0.0.1:45449_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45449.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52f44408
[junit4] 2> 684077 INFO (jetty-launcher-3584-thread-1) [n:127.0.0.1:45449_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45449.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52f44408
[junit4] 2> 684077 INFO (jetty-launcher-3584-thread-1) [n:127.0.0.1:45449_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45449.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52f44408
[junit4] 2> 684078 INFO (jetty-launcher-3584-thread-1) [n:127.0.0.1:45449_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.10/checkout/solr/build/solr-core/test/J3/temp/solr.cloud.autoscaling.ComputePlanActionTest_695B8EE0117F8DD-001/tempDir-001/node1
[junit4] 2> 684091 INFO (SUITE-ComputePlanActionTest-seed#[695B8EE0117F8DD]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=1
[junit4] 2> 684091 INFO (SUITE-ComputePlanActionTest-seed#[695B8EE0117F8DD]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 684094 INFO (zkConnectionManagerCallback-3616-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 684094 INFO (SUITE-ComputePlanActionTest-seed#[695B8EE0117F8DD]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 684095 INFO (SUITE-ComputePlanActionTest-seed#[695B8EE0117F8DD]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 684096 INFO (SUITE-ComputePlanActionTest-seed#[695B8EE0117F8DD]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43233/solr ready
[junit4] 2> 684115 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSelectedCollectionsByPolicy
[junit4] 2> 684115 DEBUG (zkCallback-3598-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 684116 DEBUG (OverseerAutoScalingTriggerThread-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 684117 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 684118 DEBUG (OverseerAutoScalingTriggerThread-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 684119 DEBUG (OverseerAutoScalingTriggerThread-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 684142 INFO (qtp1975998450-14536) [n:127.0.0.1:45449_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 2147483647 cores with initial capacity of 1024
[junit4] 2> 684142 INFO (qtp1975998450-14536) [n:127.0.0.1:45449_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 684151 DEBUG (qtp1975998450-14534) [n:127.0.0.1:45449_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 684154 INFO (qtp1975998450-14534) [n:127.0.0.1:45449_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=33
[junit4] 2> 684155 DEBUG (zkCallback-3598-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3
[junit4] 2> 684155 DEBUG (OverseerAutoScalingTriggerThread-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 684155 DEBUG (OverseerAutoScalingTriggerThread-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 684156 DEBUG (OverseerAutoScalingTriggerThread-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 684157 INFO (qtp1975998450-14533) [n:127.0.0.1:45449_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 684157 DEBUG (qtp1975998450-14537) [n:127.0.0.1:45449_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 684158 INFO (qtp1975998450-14537) [n:127.0.0.1:45449_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 684159 DEBUG (zkCallback-3598-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 4
[junit4] 2> 684159 DEBUG (OverseerAutoScalingTriggerThread-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 4
[junit4] 2> 684159 DEBUG (OverseerAutoScalingTriggerThread-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 684159 DEBUG (OverseerAutoScalingTriggerThread-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVersion 4
[junit4] 2> 684167 INFO (qtp1975998450-14536) [n:127.0.0.1:45449_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 684168 DEBUG (qtp1975998450-14535) [n:127.0.0.1:45449_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 684170 INFO (qtp1975998450-14535) [n:127.0.0.1:45449_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=8
[junit4] 2> 684170 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.c.a.ComputePlanActionTest Found number of jetties: 1
[junit4] 2> 684171 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 684171 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 684171 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.e.j.s.Server jetty-9.4.44.v20210927; built: 2021-09-27T23:02:44.612Z; git: 8da83308eeca865e495e53ef315a249d63ba9332; jvm 1.8.0_291-b10
[junit4] 2> 684171 DEBUG (zkCallback-3598-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 5
[junit4] 2> 684171 DEBUG (OverseerAutoScalingTriggerThread-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 5
[junit4] 2> 684171 DEBUG (OverseerAutoScalingTriggerThread-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 684173 DEBUG (OverseerAutoScalingTriggerThread-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5, lastZnodeVersion 5
[junit4] 2> 684182 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 684182 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 684189 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 684189 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@232e93db{/solr,null,AVAILABLE}
[junit4] 2> 684189 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@456c15a6{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:40813}
[junit4] 2> 684189 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.e.j.s.Server Started @684229ms
[junit4] 2> 684189 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:43233/solr, hostPort=40813}
[junit4] 2> 684190 ERROR (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 684191 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 684191 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.10.2
[junit4] 2> 684191 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 684191 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.10/checkout/solr
[junit4] 2> 684191 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-10-26T21:49:24.359Z
[junit4] 2> 684201 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 684206 INFO (zkConnectionManagerCallback-3620-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 684206 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 684207 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 685308 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 685309 WARN (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3d7c6cac[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 685309 WARN (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3d7c6cac[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 685316 WARN (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@59f4b55a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 685316 WARN (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@59f4b55a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 685317 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43233/solr
[junit4] 2> 685318 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 685323 INFO (zkConnectionManagerCallback-3631-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 685323 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 685326 WARN (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]-SendThread(127.0.0.1:43233)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x108758172f30008.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x108758172f30008, 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 0x108758172f30008, 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> 685430 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:40813_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 685433 INFO (zkConnectionManagerCallback-3633-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 685433 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:40813_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 685436 WARN (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:40813_solr ] o.a.s.c.ZkController Contents of zookeeper /security.json are world-readable; consider setting up ACLs as described in https://solr.apache.org/guide/zookeeper-access-control.html
[junit4] 2> 685438 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:40813_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 685440 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:40813_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:40813_solr as DOWN
[junit4] 2> 685441 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:40813_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 2147483647 cores with initial capacity of 1024
[junit4] 2> 685441 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:40813_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40813_solr
[junit4] 2> 685441 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "node_name":"127.0.0.1:40813_solr",
[junit4] 2> "base_url":"http://127.0.0.1:40813/solr",
[junit4] 2> "operation":"downnode"} current state version: 0
[junit4] 2> 685442 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:40813_solr
[junit4] 2> 685442 INFO (zkCallback-3598-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 685442 INFO (zkCallback-3615-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 685442 INFO (zkCallback-3615-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 685443 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:40813_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 685444 WARN (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:40813_solr ] 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> 685454 INFO (zkCallback-3632-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 685640 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:40813_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 685680 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:40813_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40813.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52f44408
[junit4] 2> 685692 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:40813_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40813.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52f44408
[junit4] 2> 685692 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:40813_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40813.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52f44408
[junit4] 2> 685693 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:40813_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.10/checkout/solr/build/solr-core/test/J3/temp/solr.cloud.autoscaling.ComputePlanActionTest_695B8EE0117F8DD-001/tempDir-001/node2
[junit4] 2> 685710 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 685710 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 685710 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.e.j.s.Server jetty-9.4.44.v20210927; built: 2021-09-27T23:02:44.612Z; git: 8da83308eeca865e495e53ef315a249d63ba9332; jvm 1.8.0_291-b10
[junit4] 2> 685714 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 685714 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 685714 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 685715 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@79d2b2c4{/solr,null,AVAILABLE}
[junit4] 2> 685715 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7ca98bca{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:35443}
[junit4] 2> 685715 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.e.j.s.Server Started @685754ms
[junit4] 2> 685715 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:43233/solr, hostPort=35443}
[junit4] 2> 685715 ERROR (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 685715 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 685715 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.10.2
[junit4] 2> 685715 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 685715 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.10/checkout/solr
[junit4] 2> 685715 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-10-26T21:49:25.883Z
[junit4] 2> 685716 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 685724 INFO (zkConnectionManagerCallback-3643-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 685724 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 685725 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 685730 WARN (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]-SendThread(127.0.0.1:43233)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x108758172f3000a.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x108758172f3000a, 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 0x108758172f3000a, 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> 686430 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 686431 WARN (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@241cc0bf[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 686431 WARN (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@241cc0bf[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 686436 WARN (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3b405151[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 686436 WARN (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3b405151[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 686437 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43233/solr
[junit4] 2> 686442 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 686447 INFO (zkConnectionManagerCallback-3654-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 686447 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 686638 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:35443_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 686642 INFO (zkConnectionManagerCallback-3656-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 686642 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:35443_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 686655 WARN (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:35443_solr ] o.a.s.c.ZkController Contents of zookeeper /security.json are world-readable; consider setting up ACLs as described in https://solr.apache.org/guide/zookeeper-access-control.html
[junit4] 2> 686656 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:35443_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 686665 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:35443_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:35443_solr as DOWN
[junit4] 2> 686669 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:35443_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 2147483647 cores with initial capacity of 1024
[junit4] 2> 686669 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:35443_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35443_solr
[junit4] 2> 686670 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "node_name":"127.0.0.1:35443_solr",
[junit4] 2> "base_url":"http://127.0.0.1:35443/solr",
[junit4] 2> "operation":"downnode"} current state version: 0
[junit4] 2> 686670 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:35443_solr
[junit4] 2> 686671 INFO (zkCallback-3632-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 686671 INFO (zkCallback-3615-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 686671 INFO (zkCallback-3615-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 686671 INFO (zkCallback-3598-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 686673 INFO (zkCallback-3655-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 686674 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:35443_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 686674 WARN (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:35443_solr ] 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> 686701 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:35443_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 686721 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:35443_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35443.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52f44408
[junit4] 2> 686731 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:35443_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35443.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52f44408
[junit4] 2> 686731 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:35443_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35443.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52f44408
[junit4] 2> 686733 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:35443_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.10/checkout/solr/build/solr-core/test/J3/temp/solr.cloud.autoscaling.ComputePlanActionTest_695B8EE0117F8DD-001/tempDir-001/node3
[junit4] 2> 686754 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 686754 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 686754 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.e.j.s.Server jetty-9.4.44.v20210927; built: 2021-09-27T23:02:44.612Z; git: 8da83308eeca865e495e53ef315a249d63ba9332; jvm 1.8.0_291-b10
[junit4] 2> 686755 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 686755 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 686755 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 686755 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@24dee66c{/solr,null,AVAILABLE}
[junit4] 2> 686756 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6c433f85{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:42697}
[junit4] 2> 686756 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.e.j.s.Server Started @686795ms
[junit4] 2> 686756 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:43233/solr, hostPort=42697}
[junit4] 2> 686756 ERROR (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 686756 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 686756 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.10.2
[junit4] 2> 686756 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 686756 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.10/checkout/solr
[junit4] 2> 686756 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-10-26T21:49:26.924Z
[junit4] 2> 686757 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 686758 INFO (zkConnectionManagerCallback-3666-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 686758 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 686759 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 687181 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 687330 WARN (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@263ffd4e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 687330 WARN (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@263ffd4e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 687337 WARN (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5aa12328[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 687337 WARN (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5aa12328[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 687338 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43233/solr
[junit4] 2> 687341 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 687343 INFO (zkConnectionManagerCallback-3677-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 687343 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 687447 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:42697_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 687447 INFO (zkConnectionManagerCallback-3679-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 687447 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:42697_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 687452 WARN (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:42697_solr ] o.a.s.c.ZkController Contents of zookeeper /security.json are world-readable; consider setting up ACLs as described in https://solr.apache.org/guide/zookeeper-access-control.html
[junit4] 2> 687453 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:42697_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 687456 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:42697_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:42697_solr as DOWN
[junit4] 2> 687457 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:42697_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 2147483647 cores with initial capacity of 1024
[junit4] 2> 687457 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:42697_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42697_solr
[junit4] 2> 687458 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "node_name":"127.0.0.1:42697_solr",
[junit4] 2> "base_url":"http://127.0.0.1:42697/solr",
[junit4] 2> "operation":"downnode"} current state version: 0
[junit4] 2> 687458 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:42697_solr
[junit4] 2> 687458 INFO (zkCallback-3615-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 687458 INFO (zkCallback-3632-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 687458 INFO (zkCallback-3598-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 687458 INFO (zkCallback-3655-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 687458 INFO (zkCallback-3615-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 687459 INFO (zkCallback-3678-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 687460 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:42697_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 687460 WARN (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:42697_solr ] 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> 687483 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:42697_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 687505 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:42697_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42697.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52f44408
[junit4] 2> 687520 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:42697_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42697.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52f44408
[junit4] 2> 687523 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:42697_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42697.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52f44408
[junit4] 2> 687525 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [n:127.0.0.1:42697_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.10/checkout/solr/build/solr-core/test/J3/temp/solr.cloud.autoscaling.ComputePlanActionTest_695B8EE0117F8DD-001/tempDir-001/node4
[junit4] 2> 687549 INFO (TEST-ComputePlanActionTest.testSelectedCollectionsByPolicy-seed#[695B8EE0117F8DD]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=4
[junit4] 2> 687554 INFO (qtp1653001802-14605) [n:127.0.0.1:35443_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 687556 INFO (qtp153661115-14575) [n:127.0.0.1:40813_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 687563 INFO (qtp620290168-14629) [n:127.0.0.1:42697_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 687564 INFO (qtp1975998450-14533) [n:127.0.0.1:45449_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 687564 DEBUG (qtp1653001802-14602) [n:127.0.0.1:35443_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 687565 INFO (qtp1653001802-14602) [n:127.0.0.1:35443_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=14
[junit4] 2> 687566 DEBUG (zkCallback-3598-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 6
[junit4] 2> 687567 DEBUG (OverseerAutoScalingTriggerThread-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 6
[junit4] 2> 687569 DEBUG (OverseerAutoScalingTriggerThread-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger node_lost_trigger - Initial livenodes: [127.0.0.1:35443_solr, 127.0.0.1:40813_solr, 127.0.0.1:42697_solr, 127.0.0.1:45449_solr]
[junit4] 2> 687570 INFO (OverseerThreadFactory-3606-thread-1-processing-n:127.0.0.1:45449_solr) [n:127.0.0.1:45449_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testSelected1
[junit4] 2> 687571 DEBUG (OverseerAutoScalingTriggerThread-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 687571 DEBUG (OverseerAutoScalingTriggerThread-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6, lastZnodeVersion 6
[junit4] 2> 687572 DEBUG (ScheduledTrigger-3604-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 4 and last live nodes: 4
[junit4] 2> 687576 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "name":"testSelected1",
[junit4] 2> "fromApi":"true",
[junit4] 2> "collection.configName":"conf",
[junit4] 2> "numShards":"2",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "policy":"search",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 687576 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: testSelected1
[junit4] 2> 687576 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/testSelected1/state.json
[junit4] 2> 687691 INFO (qtp1653001802-14603) [n:127.0.0.1:35443_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 687693 INFO (qtp153661115-14576) [n:127.0.0.1:40813_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 687694 INFO (qtp620290168-14631) [n:127.0.0.1:42697_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 687696 INFO (qtp1975998450-14537) [n:127.0.0.1:45449_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 687711 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"testSelected1_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:45449_solr",
[junit4] 2> "base_url":"http://127.0.0.1:45449/solr",
[junit4] 2> "collection":"testSelected1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 687711 INFO (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"testSelected1_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:45449_solr",
[junit4] 2> "base_url":"http://127.0.0.1:45449/solr",
[junit4] 2> "collection":"testSelected1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 687712 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-ffffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 687712 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-ffffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node3":{
[junit4] 2> "core":"testSelected1_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:45449_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:45449/solr"}}}
[junit4] 2> 687713 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
[junit4] 2> "core":"testSelected1_shard1_replica_n2",
[junit4] 2> "node_name":"127.0.0.1:42697_solr",
[junit4] 2> "base_url":"http://127.0.0.1:42697/solr",
[junit4] 2> "collection":"testSelected1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 687713 INFO (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"testSelected1_shard1_replica_n2",
[junit4] 2> "node_name":"127.0.0.1:42697_solr",
[junit4] 2> "base_url":"http://127.0.0.1:42697/solr",
[junit4] 2> "collection":"testSelected1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 687714 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-ffffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node3":{
[junit4] 2> "core":"testSelected1_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:45449_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:45449/solr"}}}
[junit4] 2> 687714 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-ffffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testSelected1_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:45449_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:45449/solr"},
[junit4] 2> "core_node5":{
[junit4] 2> "core":"testSelected1_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:42697_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:42697/solr"}}}
[junit4] 2> 687715 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = {
[junit4] 2> "core":"testSelected1_shard2_replica_n4",
[junit4] 2> "node_name":"127.0.0.1:40813_solr",
[junit4] 2> "base_url":"http://127.0.0.1:40813/solr",
[junit4] 2> "collection":"testSelected1",
[junit4] 2> "shard":"shard2",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 687715 INFO (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"testSelected1_shard2_replica_n4",
[junit4] 2> "node_name":"127.0.0.1:40813_solr",
[junit4] 2> "base_url":"http://127.0.0.1:40813/solr",
[junit4] 2> "collection":"testSelected1",
[junit4] 2> "shard":"shard2",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 687716 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.o.SliceMutator Old Slice: shard2:{
[junit4] 2> "range":"0-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 687716 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.o.SliceMutator New Slice: shard2:{
[junit4] 2> "range":"0-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node7":{
[junit4] 2> "core":"testSelected1_shard2_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:40813_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:40813/solr"}}}
[junit4] 2> 687716 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.Overseer processMessage: queueSize: 4, message = {
[junit4] 2> "core":"testSelected1_shard2_replica_n6",
[junit4] 2> "node_name":"127.0.0.1:35443_solr",
[junit4] 2> "base_url":"http://127.0.0.1:35443/solr",
[junit4] 2> "collection":"testSelected1",
[junit4] 2> "shard":"shard2",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 687716 INFO (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"testSelected1_shard2_replica_n6",
[junit4] 2> "node_name":"127.0.0.1:35443_solr",
[junit4] 2> "base_url":"http://127.0.0.1:35443/solr",
[junit4] 2> "collection":"testSelected1",
[junit4] 2> "shard":"shard2",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 687717 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.o.SliceMutator Old Slice: shard2:{
[junit4] 2> "range":"0-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node7":{
[junit4] 2> "core":"testSelected1_shard2_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:40813_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:40813/solr"}}}
[junit4] 2> 687717 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.o.SliceMutator New Slice: shard2:{
[junit4] 2> "range":"0-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node7":{
[junit4] 2> "core":"testSelected1_shard2_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:40813_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:40813/solr"},
[junit4] 2> "core_node8":{
[junit4] 2> "core":"testSelected1_shard2_replica_n6",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:35443_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:35443/solr"}}}
[junit4] 2> 687818 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testSelected1/state.json version: 0
[junit4] 2> 687919 INFO (qtp1975998450-14536) [n:127.0.0.1:45449_solr x:testSelected1_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=testSelected1_shard1_replica_n1&action=CREATE&numShards=2&collection=testSelected1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 687919 INFO (qtp1653001802-14605) [n:127.0.0.1:35443_solr x:testSelected1_shard2_replica_n6 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=testSelected1_shard2_replica_n6&action=CREATE&numShards=2&collection=testSelected1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 687919 INFO (qtp620290168-14632) [n:127.0.0.1:42697_solr x:testSelected1_shard1_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=testSelected1_shard1_replica_n2&action=CREATE&numShards=2&collection=testSelected1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 687921 INFO (qtp153661115-14578) [n:127.0.0.1:40813_solr x:testSelected1_shard2_replica_n4 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=testSelected1_shard2_replica_n4&action=CREATE&numShards=2&collection=testSelected1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 687929 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = {
[junit4] 2> "core":"testSelected1_shard1_replica_n2",
[junit4] 2> "core_node_name":"core_node5",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:42697_solr",
[junit4] 2> "base_url":"http://127.0.0.1:42697/solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testSelected1",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 687929 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "core":"testSelected1_shard1_replica_n2",
[junit4] 2> "core_node_name":"core_node5",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:42697_solr",
[junit4] 2> "base_url":"http://127.0.0.1:42697/solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testSelected1",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 687929 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node5:{
[junit4] 2> "core":"testSelected1_shard1_replica_n2",
[junit4] 2> "node_name":"127.0.0.1:42697_solr",
[junit4] 2> "base_url":"http://127.0.0.1:42697/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}
[junit4] 2> 687929 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(testSelected1//collections/testSelected1/state.json/1 )={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "policy":"search",
[junit4] 2> "shards":{
[junit4] 2> "shard1":{
[junit4] 2> "range":"80000000-ffffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testSelected1_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:45449_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:45449/solr"},
[junit4] 2> "core_node5":{
[junit4] 2> "core":"testSelected1_shard1_replica_n2",
[junit4] 2> "node_name":"127.0.0.1:42697_solr",
[junit4] 2> "base_url":"http://127.0.0.1:42697/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}},
[junit4] 2> "shard2":{
[junit4] 2> "range":"0-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node7":{
[junit4] 2> "core":"testSelected1_shard2_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:40813_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:40813/solr"},
[junit4] 2> "core_node8":{
[junit4] 2> "core":"testSelected1_shard2_replica_n6",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:35443_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:35443/solr"}}}}}
[junit4] 2> 687929 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = {
[junit4] 2> "core":"testSelected1_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node3",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:45449_solr",
[junit4] 2> "base_url":"http://127.0.0.1:45449/solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testSelected1",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 687929 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "core":"testSelected1_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node3",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:45449_solr",
[junit4] 2> "base_url":"http://127.0.0.1:45449/solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testSelected1",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 687929 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node3:{
[junit4] 2> "core":"testSelected1_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:45449_solr",
[junit4] 2> "base_url":"http://127.0.0.1:45449/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}
[junit4] 2> 687929 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(testSelected1//collections/testSelected1/state.json/1 )={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "policy":"search",
[junit4] 2> "shards":{
[junit4] 2> "shard1":{
[junit4] 2> "range":"80000000-ffffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testSelected1_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:45449_solr",
[junit4] 2> "base_url":"http://127.0.0.1:45449/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"},
[junit4] 2> "core_node5":{
[junit4] 2> "core":"testSelected1_shard1_replica_n2",
[junit4] 2> "node_name":"127.0.0.1:42697_solr",
[junit4] 2> "base_url":"http://127.0.0.1:42697/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}},
[junit4] 2> "shard2":{
[junit4] 2> "range":"0-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node7":{
[junit4] 2> "core":"testSelected1_shard2_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:40813_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:40813/solr"},
[junit4] 2> "core_node8":{
[junit4] 2> "core":"testSelected1_shard2_replica_n6",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:35443_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:35443/solr"}}}}}
[junit4] 2> 687929 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = {
[junit4] 2> "core":"testSelected1_shard2_replica_n6",
[junit4] 2> "core_node_name":"core_node8",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:35443_solr",
[junit4] 2> "base_url":"http://127.0.0.1:35443/solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testSelected1",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 687929 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "core":"testSelected1_shard2_replica_n6",
[junit4] 2> "core_node_name":"core_node8",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:35443_solr",
[junit4] 2> "base_url":"http://127.0.0.1:35443/solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testSelected1",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 687929 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node8:{
[junit4] 2> "core":"testSelected1_shard2_replica_n6",
[junit4] 2> "node_name":"127.0.0.1:35443_solr",
[junit4] 2> "base_url":"http://127.0.0.1:35443/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}
[junit4] 2> 687930 DEBUG (OverseerStateUpdate-74438592691372037-127.0.0.1:45449_solr-n_0000000000) [n:127.0.0.1:45449_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(testSelected1//collections/testSelected1/s
[...truncated too long message...]
gerWithAddReplicaPreferredOp_2Shard_1/terms/shard2
[junit4] 2> 11 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_2Shard_3/terms/shard2
[junit4] 2> 11 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_2Shard_4/terms/shard1
[junit4] 2> 11 /solr/collections/testSelected3/terms/shard1
[junit4] 2> 11 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_2Shard_3/state.json
[junit4] 2> 11 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_2Shard_1/terms/shard1
[junit4] 2> 11 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_2Shard_2/terms/shard1
[junit4] 2> 11 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_2Shard_2/terms/shard2
[junit4] 2> 11 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_2Shard_2/state.json
[junit4] 2> 11 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_2Shard_0/state.json
[junit4] 2> 10 /solr/collections/testSelected1/terms/shard2
[junit4] 2> 10 /solr/collections/testSelected2/terms/shard2
[junit4] 2> 10 /solr/collections/testSelected3/terms/shard2
[junit4] 2> 10 /solr/collections/testNodeWithMultipleReplicasLost/terms/shard2
[junit4] 2> 10 /solr/collections/testNodeWithMultipleReplicasLost/terms/shard1
[junit4] 2> 9 /solr/collections/testSelected3/state.json
[junit4] 2> 8 /solr/collections/testSelected1/state.json
[junit4] 2> 8 /solr/collections/testSelected2/state.json
[junit4] 2> 6 /solr/collections/testNodeAdded/state.json
[junit4] 2> 6 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_2Shard_0/terms/shard2
[junit4] 2> 6 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOpReplicaType_1Shard_2/terms/shard1
[junit4] 2> 6 /solr/collections/testNodeLostTriggerWithDeleteNodePreferredOp_0/terms/shard1
[junit4] 2> 6 /solr/collections/testNodeLostTriggerWithDeleteNodePreferredOp_0/terms/shard2
[junit4] 2> 6 /solr/collections/testNodeAdded/terms/shard1
[junit4] 2> 6 /solr/collections/testNodeLostTriggerWithDeleteNodePreferredOp_0/state.json
[junit4] 2> 5 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOpReplicaType_1Shard_1/terms/shard1
[junit4] 2> 5 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOpReplicaType_1Shard_3/terms/shard1
[junit4] 2> 5 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOpReplicaType_1Shard_4/terms/shard1
[junit4] 2> 5 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_2Shard_0/terms/shard1
[junit4] 2> 4 /solr/collections/testNodeWithMultipleReplicasLost/state.json
[junit4] 2> 2 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOpReplicaType_1Shard_0/terms/shard1
[junit4] 2> 2 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0/terms/shard1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 121 /solr/collections/testSelected1/state.json
[junit4] 2> 109 /solr/collections/testSelected2/state.json
[junit4] 2> 108 /solr/collections/testSelected3/state.json
[junit4] 2> 98 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_2Shard_3/state.json
[junit4] 2> 81 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_2Shard_4/state.json
[junit4] 2> 71 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_2Shard_2/state.json
[junit4] 2> 69 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_2Shard_1/state.json
[junit4] 2> 63 /solr/aliases.json
[junit4] 2> 57 /solr/collections/testNodeWithMultipleReplicasLost/state.json
[junit4] 2> 50 /solr/autoscaling.json
[junit4] 2> 42 /solr/collections/testNodeLostTriggerWithDeleteNodePreferredOp_0/state.json
[junit4] 2> 34 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOpReplicaType_1Shard_4/state.json
[junit4] 2> 29 /solr/clusterstate.json
[junit4] 2> 27 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_2Shard_0/state.json
[junit4] 2> 24 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOpReplicaType_1Shard_2/state.json
[junit4] 2> 24 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOpReplicaType_1Shard_3/state.json
[junit4] 2> 24 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOpReplicaType_1Shard_1/state.json
[junit4] 2> 22 /solr/collections/testNodeAdded/state.json
[junit4] 2> 16 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOpReplicaType_1Shard_0/state.json
[junit4] 2> 14 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0/state.json
[junit4] 2> 5 /solr/overseer_elect/election/74438592691372037-127.0.0.1:45449_solr-n_0000000000
[junit4] 2> 4 /solr/overseer_elect/election/74438592691372059-127.0.0.1:34111_solr-n_0000000007
[junit4] 2> 3 /solr/overseer_elect/election/74438592691372077-127.0.0.1:42369_solr-n_0000000013
[junit4] 2> 2 /solr/overseer_elect/election/74438592691372071-127.0.0.1:35195_solr-n_0000000011
[junit4] 2> 2 /solr/overseer_elect/election/74438592691372041-127.0.0.1:40813_solr-n_0000000001
[junit4] 2> 2 /solr/collections/testNodeWithMultipleReplicasLost/leader_elect/shard2/election/74438592691372071-core_node9-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 655 /solr/overseer/queue
[junit4] 2> 298 /solr/live_nodes
[junit4] 2> 173 /solr/collections
[junit4] 2> 163 /solr/overseer/collection-queue-work
[junit4] 2> 37 /solr/collections/testSelected3/state.json
[junit4] 2> 36 /solr/collections/testSelected1/state.json
[junit4] 2> 36 /solr/collections/testSelected2/state.json
[junit4] 2> 25 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_2Shard_4/state.json
[junit4] 2> 25 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_2Shard_3/state.json
[junit4] 2> 24 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_2Shard_2/state.json
[junit4] 2> 23 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_2Shard_1/state.json
[junit4] 2> 22 /solr/collections/testNodeWithMultipleReplicasLost/state.json
[junit4] 2> 13 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_2Shard_0/state.json
[junit4] 2> 13 /solr/collections/testNodeLostTriggerWithDeleteNodePreferredOp_0/state.json
[junit4] 2> 12 /solr/autoscaling/events/node_added_trigger
[junit4] 2> 9 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOpReplicaType_1Shard_1/state.json
[junit4] 2> 9 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOpReplicaType_1Shard_2/state.json
[junit4] 2> 9 /solr/autoscaling/events/node_lost_trigger
[junit4] 2> 9 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOpReplicaType_1Shard_3/state.json
[junit4] 2> 9 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOpReplicaType_1Shard_4/state.json
[junit4] 2> 7 /solr/collections/testNodeAdded/state.json
[junit4] 2> 6 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0/state.json
[junit4] 2> 5 /solr/overseer/queue-work
[junit4] 2> 5 /solr/autoscaling/events/.auto_add_replicas
[junit4] 2> 5 /solr/autoscaling/events/.scheduled_maintenance
[junit4] 2> 5 /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOpReplicaType_1Shard_0/state.json
[junit4] 2>
[junit4] 2> 823418 INFO (SUITE-ComputePlanActionTest-seed#[695B8EE0117F8DD]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:43233
[junit4] 2> 823418 INFO (SUITE-ComputePlanActionTest-seed#[695B8EE0117F8DD]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:43233
[junit4] 2> 823418 INFO (SUITE-ComputePlanActionTest-seed#[695B8EE0117F8DD]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 43233
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.10/checkout/solr/build/solr-core/test/J3/temp/solr.cloud.autoscaling.ComputePlanActionTest_695B8EE0117F8DD-001
[junit4] 2> Oct 26, 2021 9:51:43 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=FastCompressingStoredFieldsData(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST, chunkSize=30248, maxDocsPerChunk=768, blockShift=11), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST, chunkSize=30248, maxDocsPerChunk=768, blockSize=11)), sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=ar-DZ, timezone=Africa/Libreville
[junit4] 2> NOTE: Linux 5.4.0-45-generic amd64/Oracle Corporation 1.8.0_291 (64-bit)/cpus=4,threads=1,free=150881824,total=477626368
[junit4] 2> NOTE: All tests run in this JVM: [TestDeprecatedFilters, SystemLogListenerTest, TestZkAclsWithHadoopAuth, TestBlendedInfixSuggestions, DirectoryFactoryTest, TestCloudPhrasesIdentificationComponent, TestFoldingMultitermQuery, RecoveryAfterSoftCommitTest, AddReplicaTest, TestPivotHelperCode, TestPostingsSolrHighlighter, SearchHandlerTest, TestSolr4Spatial, SolrLogPostToolTest, AutoscalingHistoryHandlerTest, HdfsChaosMonkeyNothingIsSafeTest, TestDistribIDF, SuggesterTest, TestDynamicLoading, TestSimGenericDistributedQueue, SharedFSAutoReplicaFailoverTest, TestEmbeddedSolrServerSchemaAPI, CdcrOpsAndBoundariesTest, TestCloudJSONFacetSKG, AnalyticsMergeStrategyTest, ComputePlanActionTest]
[junit4] Completed [142/948 (1!)] on J3 in 141.86s, 10 tests, 1 failure, 1 skipped <<< FAILURES!
[...truncated 56911 lines...]
[JENKINS] Lucene » Lucene-Solr-NightlyTests-8.10 - Build # 51 - Still Unstable!
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-NightlyTests-8.10/51/
3 tests failed.
FAILED: org.apache.solr.cloud.api.collections.ShardSplitTest.testSplitWithChaosMonkey
Error Message:
Address already in use
Stack Trace:
java.net.BindException: Address already in use
at __randomizedtesting.SeedInfo.seed([886F07A64CFC3F52:348D4770DFA94D6]:0)
at sun.nio.ch.Net.bind0(Native Method)
at sun.nio.ch.Net.bind(Net.java:438)
at sun.nio.ch.Net.bind(Net.java:430)
at sun.nio.ch.ServerSocketChannelImpl.bind(ServerSocketChannelImpl.java:225)
at sun.nio.ch.ServerSocketAdaptor.bind(ServerSocketAdaptor.java:74)
at org.eclipse.jetty.server.ServerConnector.openAcceptChannel(ServerConnector.java:344)
at org.eclipse.jetty.server.ServerConnector.open(ServerConnector.java:310)
at org.eclipse.jetty.server.AbstractNetworkConnector.doStart(AbstractNetworkConnector.java:80)
at org.eclipse.jetty.server.ServerConnector.doStart(ServerConnector.java:234)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.eclipse.jetty.server.Server.doStart(Server.java:401)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:73)
at org.apache.solr.client.solrj.embedded.JettySolrRunner.retryOnPortBindFailure(JettySolrRunner.java:566)
at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:504)
at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:472)
at org.apache.solr.cloud.api.collections.ShardSplitTest.testSplitWithChaosMonkey(ShardSplitTest.java:506)
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:1084)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1055)
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)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.api.collections.ShardSplitTest
Error Message:
10 threads leaked from SUITE scope at org.apache.solr.cloud.api.collections.ShardSplitTest: 1) Thread[id=35514, name=Session-HouseKeeper-66335620-1, state=TIMED_WAITING, group=TGRP-ShardSplitTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 2) Thread[id=35509, name=qtp1382726623-35509, state=TIMED_WAITING, group=TGRP-ShardSplitTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:382) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.idleJobPoll(QueuedThreadPool.java:974) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1018) at java.lang.Thread.run(Thread.java:748) 3) Thread[id=35510, name=qtp1382726623-35510, state=TIMED_WAITING, group=TGRP-ShardSplitTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:382) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.idleJobPoll(QueuedThreadPool.java:974) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1018) at java.lang.Thread.run(Thread.java:748) 4) Thread[id=35507, name=qtp1382726623-35507, state=RUNNABLE, group=TGRP-ShardSplitTest] at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method) at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269) at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101) at org.eclipse.jetty.io.ManagedSelector.nioSelect(ManagedSelector.java:183) at org.eclipse.jetty.io.ManagedSelector.select(ManagedSelector.java:190) at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.select(ManagedSelector.java:606) at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:543) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produceTask(EatWhatYouKill.java:362) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:186) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:137) at org.eclipse.jetty.io.ManagedSelector$$Lambda$95/528301441.run(Unknown Source) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034) at java.lang.Thread.run(Thread.java:748) 5) Thread[id=35508, name=qtp1382726623-35508-acceptor-0@1758f646-ServerConnector@5655354a{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:42699}, state=RUNNABLE, group=TGRP-ShardSplitTest] at sun.nio.ch.ServerSocketChannelImpl.accept0(Native Method) at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:424) at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:252) at org.eclipse.jetty.server.ServerConnector.accept(ServerConnector.java:388) at org.eclipse.jetty.server.AbstractConnector$Acceptor.run(AbstractConnector.java:702) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034) at java.lang.Thread.run(Thread.java:748) 6) Thread[id=35511, name=qtp1382726623-35511, state=TIMED_WAITING, group=TGRP-ShardSplitTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:382) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.idleJobPoll(QueuedThreadPool.java:974) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1018) at java.lang.Thread.run(Thread.java:748) 7) Thread[id=35512, name=qtp1382726623-35512, state=TIMED_WAITING, group=TGRP-ShardSplitTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:382) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.idleJobPoll(QueuedThreadPool.java:974) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1018) at java.lang.Thread.run(Thread.java:748) 8) Thread[id=35506, name=qtp1382726623-35506, state=RUNNABLE, group=TGRP-ShardSplitTest] at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method) at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269) at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101) at org.eclipse.jetty.io.ManagedSelector.nioSelect(ManagedSelector.java:183) at org.eclipse.jetty.io.ManagedSelector.select(ManagedSelector.java:190) at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.select(ManagedSelector.java:606) at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:543) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produceTask(EatWhatYouKill.java:362) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:186) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:137) at org.eclipse.jetty.io.ManagedSelector$$Lambda$95/528301441.run(Unknown Source) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034) at java.lang.Thread.run(Thread.java:748) 9) Thread[id=35599, name=Connector-Scheduler-5655354a-1, state=WAITING, group=TGRP-ShardSplitTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1081) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 10) Thread[id=35513, name=qtp1382726623-35513, state=TIMED_WAITING, group=TGRP-ShardSplitTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:382) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.idleJobPoll(QueuedThreadPool.java:974) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1018) at java.lang.Thread.run(Thread.java:748)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 10 threads leaked from SUITE scope at org.apache.solr.cloud.api.collections.ShardSplitTest:
1) Thread[id=35514, name=Session-HouseKeeper-66335620-1, state=TIMED_WAITING, group=TGRP-ShardSplitTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
2) Thread[id=35509, name=qtp1382726623-35509, state=TIMED_WAITING, group=TGRP-ShardSplitTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:382)
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.idleJobPoll(QueuedThreadPool.java:974)
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1018)
at java.lang.Thread.run(Thread.java:748)
3) Thread[id=35510, name=qtp1382726623-35510, state=TIMED_WAITING, group=TGRP-ShardSplitTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:382)
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.idleJobPoll(QueuedThreadPool.java:974)
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1018)
at java.lang.Thread.run(Thread.java:748)
4) Thread[id=35507, name=qtp1382726623-35507, state=RUNNABLE, group=TGRP-ShardSplitTest]
at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101)
at org.eclipse.jetty.io.ManagedSelector.nioSelect(ManagedSelector.java:183)
at org.eclipse.jetty.io.ManagedSelector.select(ManagedSelector.java:190)
at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.select(ManagedSelector.java:606)
at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:543)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produceTask(EatWhatYouKill.java:362)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:186)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:137)
at org.eclipse.jetty.io.ManagedSelector$$Lambda$95/528301441.run(Unknown Source)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883)
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034)
at java.lang.Thread.run(Thread.java:748)
5) Thread[id=35508, name=qtp1382726623-35508-acceptor-0@1758f646-ServerConnector@5655354a{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:42699}, state=RUNNABLE, group=TGRP-ShardSplitTest]
at sun.nio.ch.ServerSocketChannelImpl.accept0(Native Method)
at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:424)
at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:252)
at org.eclipse.jetty.server.ServerConnector.accept(ServerConnector.java:388)
at org.eclipse.jetty.server.AbstractConnector$Acceptor.run(AbstractConnector.java:702)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883)
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034)
at java.lang.Thread.run(Thread.java:748)
6) Thread[id=35511, name=qtp1382726623-35511, state=TIMED_WAITING, group=TGRP-ShardSplitTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:382)
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.idleJobPoll(QueuedThreadPool.java:974)
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1018)
at java.lang.Thread.run(Thread.java:748)
7) Thread[id=35512, name=qtp1382726623-35512, state=TIMED_WAITING, group=TGRP-ShardSplitTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:382)
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.idleJobPoll(QueuedThreadPool.java:974)
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1018)
at java.lang.Thread.run(Thread.java:748)
8) Thread[id=35506, name=qtp1382726623-35506, state=RUNNABLE, group=TGRP-ShardSplitTest]
at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101)
at org.eclipse.jetty.io.ManagedSelector.nioSelect(ManagedSelector.java:183)
at org.eclipse.jetty.io.ManagedSelector.select(ManagedSelector.java:190)
at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.select(ManagedSelector.java:606)
at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:543)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produceTask(EatWhatYouKill.java:362)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:186)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:137)
at org.eclipse.jetty.io.ManagedSelector$$Lambda$95/528301441.run(Unknown Source)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883)
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034)
at java.lang.Thread.run(Thread.java:748)
9) Thread[id=35599, name=Connector-Scheduler-5655354a-1, state=WAITING, group=TGRP-ShardSplitTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1081)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
10) Thread[id=35513, name=qtp1382726623-35513, state=TIMED_WAITING, group=TGRP-ShardSplitTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:382)
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.idleJobPoll(QueuedThreadPool.java:974)
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1018)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([886F07A64CFC3F52]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.api.collections.ShardSplitTest
Error Message:
There are still zombie threads that couldn't be terminated: 1) Thread[id=35506, name=qtp1382726623-35506, state=RUNNABLE, group=TGRP-ShardSplitTest] at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method) at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269) at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101) at org.eclipse.jetty.io.ManagedSelector.nioSelect(ManagedSelector.java:183) at org.eclipse.jetty.io.ManagedSelector.select(ManagedSelector.java:190) at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.select(ManagedSelector.java:606) at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:543) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produceTask(EatWhatYouKill.java:362) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:186) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:137) at org.eclipse.jetty.io.ManagedSelector$$Lambda$95/528301441.run(Unknown Source) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034) at java.lang.Thread.run(Thread.java:748) 2) Thread[id=35599, name=Connector-Scheduler-5655354a-1, state=WAITING, group=TGRP-ShardSplitTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1081) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 3) Thread[id=35514, name=Session-HouseKeeper-66335620-1, state=TIMED_WAITING, group=TGRP-ShardSplitTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 4) Thread[id=35509, name=qtp1382726623-35509, state=TIMED_WAITING, group=TGRP-ShardSplitTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:382) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.idleJobPoll(QueuedThreadPool.java:974) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1018) at java.lang.Thread.run(Thread.java:748) 5) Thread[id=35510, name=qtp1382726623-35510, state=TIMED_WAITING, group=TGRP-ShardSplitTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:382) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.idleJobPoll(QueuedThreadPool.java:974) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1018) at java.lang.Thread.run(Thread.java:748) 6) Thread[id=35507, name=qtp1382726623-35507, state=RUNNABLE, group=TGRP-ShardSplitTest] at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method) at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269) at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101) at org.eclipse.jetty.io.ManagedSelector.nioSelect(ManagedSelector.java:183) at org.eclipse.jetty.io.ManagedSelector.select(ManagedSelector.java:190) at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.select(ManagedSelector.java:606) at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:543) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produceTask(EatWhatYouKill.java:362) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:186) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:137) at org.eclipse.jetty.io.ManagedSelector$$Lambda$95/528301441.run(Unknown Source) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034) at java.lang.Thread.run(Thread.java:748) 7) Thread[id=35508, name=qtp1382726623-35508, state=TIMED_WAITING, group=TGRP-ShardSplitTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:382) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.idleJobPoll(QueuedThreadPool.java:974) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1018) at java.lang.Thread.run(Thread.java:748) 8) Thread[id=35511, name=qtp1382726623-35511, state=TIMED_WAITING, group=TGRP-ShardSplitTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:382) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.idleJobPoll(QueuedThreadPool.java:974) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1018) at java.lang.Thread.run(Thread.java:748) 9) Thread[id=35512, name=qtp1382726623-35512, state=TIMED_WAITING, group=TGRP-ShardSplitTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:382) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.idleJobPoll(QueuedThreadPool.java:974) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1018) at java.lang.Thread.run(Thread.java:748) 10) Thread[id=35513, name=qtp1382726623-35513, state=TIMED_WAITING, group=TGRP-ShardSplitTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:382) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.idleJobPoll(QueuedThreadPool.java:974) at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1018) at java.lang.Thread.run(Thread.java:748)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
1) Thread[id=35506, name=qtp1382726623-35506, state=RUNNABLE, group=TGRP-ShardSplitTest]
at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101)
at org.eclipse.jetty.io.ManagedSelector.nioSelect(ManagedSelector.java:183)
at org.eclipse.jetty.io.ManagedSelector.select(ManagedSelector.java:190)
at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.select(ManagedSelector.java:606)
at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:543)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produceTask(EatWhatYouKill.java:362)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:186)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:137)
at org.eclipse.jetty.io.ManagedSelector$$Lambda$95/528301441.run(Unknown Source)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883)
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034)
at java.lang.Thread.run(Thread.java:748)
2) Thread[id=35599, name=Connector-Scheduler-5655354a-1, state=WAITING, group=TGRP-ShardSplitTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1081)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
3) Thread[id=35514, name=Session-HouseKeeper-66335620-1, state=TIMED_WAITING, group=TGRP-ShardSplitTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093)
at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
4) Thread[id=35509, name=qtp1382726623-35509, state=TIMED_WAITING, group=TGRP-ShardSplitTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:382)
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.idleJobPoll(QueuedThreadPool.java:974)
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1018)
at java.lang.Thread.run(Thread.java:748)
5) Thread[id=35510, name=qtp1382726623-35510, state=TIMED_WAITING, group=TGRP-ShardSplitTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:382)
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.idleJobPoll(QueuedThreadPool.java:974)
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1018)
at java.lang.Thread.run(Thread.java:748)
6) Thread[id=35507, name=qtp1382726623-35507, state=RUNNABLE, group=TGRP-ShardSplitTest]
at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101)
at org.eclipse.jetty.io.ManagedSelector.nioSelect(ManagedSelector.java:183)
at org.eclipse.jetty.io.ManagedSelector.select(ManagedSelector.java:190)
at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.select(ManagedSelector.java:606)
at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:543)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produceTask(EatWhatYouKill.java:362)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:186)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:137)
at org.eclipse.jetty.io.ManagedSelector$$Lambda$95/528301441.run(Unknown Source)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883)
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034)
at java.lang.Thread.run(Thread.java:748)
7) Thread[id=35508, name=qtp1382726623-35508, state=TIMED_WAITING, group=TGRP-ShardSplitTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:382)
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.idleJobPoll(QueuedThreadPool.java:974)
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1018)
at java.lang.Thread.run(Thread.java:748)
8) Thread[id=35511, name=qtp1382726623-35511, state=TIMED_WAITING, group=TGRP-ShardSplitTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:382)
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.idleJobPoll(QueuedThreadPool.java:974)
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1018)
at java.lang.Thread.run(Thread.java:748)
9) Thread[id=35512, name=qtp1382726623-35512, state=TIMED_WAITING, group=TGRP-ShardSplitTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:382)
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.idleJobPoll(QueuedThreadPool.java:974)
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1018)
at java.lang.Thread.run(Thread.java:748)
10) Thread[id=35513, name=qtp1382726623-35513, state=TIMED_WAITING, group=TGRP-ShardSplitTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:382)
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.idleJobPoll(QueuedThreadPool.java:974)
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1018)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([886F07A64CFC3F52]:0)
Build Log:
[...truncated 15087 lines...]
[junit4] Suite: org.apache.solr.cloud.api.collections.ShardSplitTest
[junit4] 2> 2989677 INFO (SUITE-ShardSplitTest-seed#[886F07A64CFC3F52]-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-NightlyTests-8.10/checkout/solr/server/solr/configsets/_default/conf'
[junit4] 2> 2989677 INFO (SUITE-ShardSplitTest-seed#[886F07A64CFC3F52]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 2989678 INFO (SUITE-ShardSplitTest-seed#[886F07A64CFC3F52]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.10/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.ShardSplitTest_886F07A64CFC3F52-001/data-dir-138-001
[junit4] 2> 2989678 INFO (SUITE-ShardSplitTest-seed#[886F07A64CFC3F52]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 2989679 INFO (SUITE-ShardSplitTest-seed#[886F07A64CFC3F52]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-5776)
[junit4] 2> 2989679 INFO (SUITE-ShardSplitTest-seed#[886F07A64CFC3F52]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /_/w
[junit4] 2> 2989690 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2989691 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 2989691 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2989691 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 2989791 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.ZkTestServer start zk server on port: 39711
[junit4] 2> 2989791 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:39711
[junit4] 2> 2989791 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:39711
[junit4] 2> 2989791 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 39711
[junit4] 2> 2989792 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2989794 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2989799 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:None path:null
[junit4] 2> 2989800 DEBUG (zkConnectionManagerCallback-15648-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@67bf64e name: ZooKeeperConnection Watcher:127.0.0.1:39711 got event WatchedEvent state:SyncConnected type:None path:null path: null type: None
[junit4] 2> 2989800 INFO (zkConnectionManagerCallback-15648-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2989800 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2989800 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2989801 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 2989805 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2989807 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2989810 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:None path:null
[junit4] 2> 2989810 DEBUG (zkConnectionManagerCallback-15650-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7b04c09 name: ZooKeeperConnection Watcher:127.0.0.1:39711/solr got event WatchedEvent state:SyncConnected type:None path:null path: null type: None
[junit4] 2> 2989810 INFO (zkConnectionManagerCallback-15650-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2989810 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2989810 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2989811 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterprops.json
[junit4] 2> 2989811 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.10/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 2989811 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 2989812 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.10/checkout/solr/core/src/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml
[junit4] 2> 2989812 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
[junit4] 2> 2989813 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.10/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2989813 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2989814 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.10/checkout/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 2989814 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
[junit4] 2> 2989815 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.10/checkout/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 2989815 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
[junit4] 2> 2989818 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.10/checkout/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 2989818 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
[junit4] 2> 2989822 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.10/checkout/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 2989822 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 2989824 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.10/checkout/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 2989824 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 2989827 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.10/checkout/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2989827 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2989828 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.10/checkout/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 2989828 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 2989829 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.10/checkout/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 2989829 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
[junit4] 2> 2989830 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise
[junit4] 2> 2989948 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 2989948 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 2989948 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.e.j.s.Server jetty-9.4.44.v20210927; built: 2021-09-27T23:02:44.612Z; git: 8da83308eeca865e495e53ef315a249d63ba9332; jvm 1.8.0_291-b10
[junit4] 2> 2989956 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2989956 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2989956 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2989960 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@676408e6{/_/w,null,AVAILABLE}
[junit4] 2> 2989960 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2f8c09ee{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:42799}
[junit4] 2> 2989960 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.e.j.s.Server Started @2989992ms
[junit4] 2> 2989960 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.10/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.ShardSplitTest_886F07A64CFC3F52-001/tempDir-001/control/data, replicaType=NRT, hostContext=/_/w, hostPort=42799, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.10/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.ShardSplitTest_886F07A64CFC3F52-001/control-001/cores}
[junit4] 2> 2989960 ERROR (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2989960 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2989960 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.10.2
[junit4] 2> 2989960 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2989960 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.10/checkout/solr
[junit4] 2> 2989960 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-10-29T21:49:56.403Z
[junit4] 2> 2989961 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2989961 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2989962 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:None path:null
[junit4] 2> 2989963 DEBUG (zkConnectionManagerCallback-15652-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@289dbb6d name: ZooKeeperConnection Watcher:127.0.0.1:39711/solr got event WatchedEvent state:SyncConnected type:None path:null path: null type: None
[junit4] 2> 2989963 INFO (zkConnectionManagerCallback-15652-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2989963 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2989963 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2989964 WARN (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]-SendThread(127.0.0.1:39711)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102278e40390002.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102278e40390002, 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 0x102278e40390002, 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> 2990065 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 2990065 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 2990065 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.10/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.ShardSplitTest_886F07A64CFC3F52-001/control-001/solr.xml
[junit4] 2> 2990068 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2990068 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2990070 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@13d1d178, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2991228 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2991229 WARN (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@366ee528[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2991229 WARN (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@366ee528[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2991232 WARN (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@60c4bd8a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2991232 WARN (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@60c4bd8a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2991234 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39711/solr
[junit4] 2> 2991234 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2991236 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2991238 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:None path:null
[junit4] 2> 2991239 DEBUG (zkConnectionManagerCallback-15663-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7164e14 name: ZooKeeperConnection Watcher:127.0.0.1:39711 got event WatchedEvent state:SyncConnected type:None path:null path: null type: None
[junit4] 2> 2991239 INFO (zkConnectionManagerCallback-15663-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2991239 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2991239 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2991240 WARN (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]-SendThread(127.0.0.1:39711)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102278e40390003.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102278e40390003, 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 0x102278e40390003, 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> 2991340 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 2991341 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2991342 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:None path:null
[junit4] 2> 2991342 DEBUG (zkConnectionManagerCallback-15665-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@bbf670e name: ZooKeeperConnection Watcher:127.0.0.1:39711/solr got event WatchedEvent state:SyncConnected type:None path:null path: null type: None
[junit4] 2> 2991342 INFO (zkConnectionManagerCallback-15665-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2991342 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2991342 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 2991343 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 2991344 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 2991345 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /overseer/async_ids
[junit4] 2> 2991346 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 2991346 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 2991347 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /autoscaling/events
[junit4] 2> 2991347 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /autoscaling/triggerState
[junit4] 2> 2991348 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /autoscaling/nodeAdded
[junit4] 2> 2991349 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /autoscaling/nodeLost
[junit4] 2> 2991349 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 2991350 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /autoscaling.json
[junit4] 2> 2991350 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 2991351 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/protwords.txt
[junit4] 2> 2991352 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/managed-schema
[junit4] 2> 2991354 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/solrconfig.xml
[junit4] 2> 2991355 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/synonyms.txt
[junit4] 2> 2991356 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_it.txt
[junit4] 2> 2991357 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_cz.txt
[junit4] 2> 2991358 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_fr.txt
[junit4] 2> 2991360 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_no.txt
[junit4] 2> 2991361 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/contractions_ca.txt
[junit4] 2> 2991362 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_sv.txt
[junit4] 2> 2991363 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_ca.txt
[junit4] 2> 2991364 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_hu.txt
[junit4] 2> 2991365 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_gl.txt
[junit4] 2> 2991366 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_bg.txt
[junit4] 2> 2991367 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_th.txt
[junit4] 2> 2991369 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_ga.txt
[junit4] 2> 2991370 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_da.txt
[junit4] 2> 2991371 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_et.txt
[junit4] 2> 2991372 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/userdict_ja.txt
[junit4] 2> 2991373 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/contractions_ga.txt
[junit4] 2> 2991374 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_id.txt
[junit4] 2> 2991375 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_tr.txt
[junit4] 2> 2991377 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_ja.txt
[junit4] 2> 2991378 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_ar.txt
[junit4] 2> 2991379 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_ru.txt
[junit4] 2> 2991380 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/hyphenations_ga.txt
[junit4] 2> 2991381 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_de.txt
[junit4] 2> 2991382 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_en.txt
[junit4] 2> 2991383 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stemdict_nl.txt
[junit4] 2> 2991385 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_ro.txt
[junit4] 2> 2991386 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_hy.txt
[junit4] 2> 2991388 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_eu.txt
[junit4] 2> 2991389 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_hi.txt
[junit4] 2> 2991390 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stoptags_ja.txt
[junit4] 2> 2991392 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/contractions_fr.txt
[junit4] 2> 2991393 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_fi.txt
[junit4] 2> 2991394 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_pt.txt
[junit4] 2> 2991397 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_lv.txt
[junit4] 2> 2991398 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_el.txt
[junit4] 2> 2991399 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/contractions_it.txt
[junit4] 2> 2991400 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_nl.txt
[junit4] 2> 2991401 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_es.txt
[junit4] 2> 2991402 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_fa.txt
[junit4] 2> 2991404 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/stopwords.txt
[junit4] 2> 2991405 WARN (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.ZkController Contents of zookeeper /security.json are world-readable; consider setting up ACLs as described in https://solr.apache.org/guide/zookeeper-access-control.html
[junit4] 2> 2991405 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 2991405 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {legacyCloud=true}
[junit4] 2> 2991406 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> []
[junit4] 2> 2991406 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [2] total [2]
[junit4] 2> 2991407 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.ZkStateReader$AliasesManager Checking ZK for most up to date Aliases /aliases.json
[junit4] 2> 2991407 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.ZkStateReader$AliasesManager Aliases: cmp=-1, new definition is: Aliases{collectionAliases={}, collectionAliasProperties={}, zNodeVersion=0}
[junit4] 2> 2991408 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 2991409 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 2991410 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:42799__%2Fw
[junit4] 2> 2991410 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 2991410 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.Overseer Overseer (id=72664035907665924-127.0.0.1:42799__%2Fw-n_0000000000) starting
[junit4] 2> 2991411 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 2991411 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 2991413 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 2991421 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42799__%2Fw
[junit4] 2> 2991421 DEBUG (OverseerCollectionConfigSetProcessor-72664035907665924-127.0.0.1:42799__%2Fw-n_0000000000) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
[junit4] 2> 2991421 INFO (OverseerStateUpdate-72664035907665924-127.0.0.1:42799__%2Fw-n_0000000000) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:42799__%2Fw
[junit4] 2> 2991422 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes
[junit4] 2> 2991422 DEBUG (zkCallback-15664-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0])
[junit4] 2> 2991423 INFO (OverseerStateUpdate-72664035907665924-127.0.0.1:42799__%2Fw-n_0000000000) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2991423 DEBUG (OverseerStateUpdate-72664035907665924-127.0.0.1:42799__%2Fw-n_0000000000) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> [127.0.0.1:42799__%2Fw]
[junit4] 2> 2991423 DEBUG (OverseerCollectionConfigSetProcessor-72664035907665924-127.0.0.1:42799__%2Fw-n_0000000000) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 0 #Completed tasks: 0
[junit4] 2> 2991423 DEBUG (OverseerCollectionConfigSetProcessor-72664035907665924-127.0.0.1:42799__%2Fw-n_0000000000) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.OverseerTaskProcessor RunningTasks: []
[junit4] 2> 2991423 DEBUG (OverseerCollectionConfigSetProcessor-72664035907665924-127.0.0.1:42799__%2Fw-n_0000000000) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.OverseerTaskProcessor BlockedTasks: []
[junit4] 2> 2991423 DEBUG (OverseerCollectionConfigSetProcessor-72664035907665924-127.0.0.1:42799__%2Fw-n_0000000000) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.OverseerTaskProcessor CompletedTasks: []
[junit4] 2> 2991423 INFO (OverseerCollectionConfigSetProcessor-72664035907665924-127.0.0.1:42799__%2Fw-n_0000000000) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: []
[junit4] 2> 2991423 DEBUG (OverseerStateUpdate-72664035907665924-127.0.0.1:42799__%2Fw-n_0000000000) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [2] total [2]
[junit4] 2> 2991424 DEBUG (zkCallback-15664-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:42799__%2Fw] -> [127.0.0.1:42799__%2Fw]
[junit4] 2> 2991424 DEBUG (OverseerAutoScalingTriggerThread-72664035907665924-127.0.0.1:42799__%2Fw-n_0000000000) [ ] o.a.s.c.c.SolrZkClient makePath: /autoscaling/events/.scheduled_maintenance
[junit4] 2> 2991424 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 2991425 WARN (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] 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> 2991434 DEBUG (OverseerAutoScalingTriggerThread-72664035907665924-127.0.0.1:42799__%2Fw-n_0000000000) [ ] o.a.s.c.c.SolrZkClient makePath: /autoscaling/events/.auto_add_replicas
[junit4] 2> 2991461 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2991488 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@13d1d178
[junit4] 2> 2991499 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@13d1d178
[junit4] 2> 2991499 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@13d1d178
[junit4] 2> 2991500 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [n:127.0.0.1:42799__%2Fw ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.10/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.ShardSplitTest_886F07A64CFC3F52-001/control-001/cores
[junit4] 2> 2991517 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2991517 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2991518 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:None path:null
[junit4] 2> 2991520 DEBUG (zkConnectionManagerCallback-15682-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2a208740 name: ZooKeeperConnection Watcher:127.0.0.1:39711/solr got event WatchedEvent state:SyncConnected type:None path:null path: null type: None
[junit4] 2> 2991520 INFO (zkConnectionManagerCallback-15682-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2991520 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2991520 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2991520 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F52]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 2991521 DEBUG (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[886F07A64CFC3F5
[...truncated too long message...]
> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:186)
[junit4] > at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173)
[junit4] > at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:137)
[junit4] > at org.eclipse.jetty.io.ManagedSelector$$Lambda$95/528301441.run(Unknown Source)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > 2) Thread[id=35599, name=Connector-Scheduler-5655354a-1, state=WAITING, group=TGRP-ShardSplitTest]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1081)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > 3) Thread[id=35514, name=Session-HouseKeeper-66335620-1, state=TIMED_WAITING, group=TGRP-ShardSplitTest]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > 4) Thread[id=35509, name=qtp1382726623-35509, state=TIMED_WAITING, group=TGRP-ShardSplitTest]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
[junit4] > at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:382)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.idleJobPoll(QueuedThreadPool.java:974)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1018)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > 5) Thread[id=35510, name=qtp1382726623-35510, state=TIMED_WAITING, group=TGRP-ShardSplitTest]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
[junit4] > at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:382)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.idleJobPoll(QueuedThreadPool.java:974)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1018)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > 6) Thread[id=35507, name=qtp1382726623-35507, state=RUNNABLE, group=TGRP-ShardSplitTest]
[junit4] > at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
[junit4] > at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
[junit4] > at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
[junit4] > at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
[junit4] > at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
[junit4] > at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101)
[junit4] > at org.eclipse.jetty.io.ManagedSelector.nioSelect(ManagedSelector.java:183)
[junit4] > at org.eclipse.jetty.io.ManagedSelector.select(ManagedSelector.java:190)
[junit4] > at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.select(ManagedSelector.java:606)
[junit4] > at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:543)
[junit4] > at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produceTask(EatWhatYouKill.java:362)
[junit4] > at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:186)
[junit4] > at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173)
[junit4] > at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:137)
[junit4] > at org.eclipse.jetty.io.ManagedSelector$$Lambda$95/528301441.run(Unknown Source)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > 7) Thread[id=35508, name=qtp1382726623-35508, state=TIMED_WAITING, group=TGRP-ShardSplitTest]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
[junit4] > at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:382)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.idleJobPoll(QueuedThreadPool.java:974)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1018)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > 8) Thread[id=35511, name=qtp1382726623-35511, state=TIMED_WAITING, group=TGRP-ShardSplitTest]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
[junit4] > at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:382)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.idleJobPoll(QueuedThreadPool.java:974)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1018)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > 9) Thread[id=35512, name=qtp1382726623-35512, state=TIMED_WAITING, group=TGRP-ShardSplitTest]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
[junit4] > at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:382)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.idleJobPoll(QueuedThreadPool.java:974)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1018)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > 10) Thread[id=35513, name=qtp1382726623-35513, state=TIMED_WAITING, group=TGRP-ShardSplitTest]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
[junit4] > at org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:382)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.idleJobPoll(QueuedThreadPool.java:974)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1018)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > at __randomizedtesting.SeedInfo.seed([886F07A64CFC3F52]:0)
[junit4] Completed [561/948 (1!)] on J0 in 587.71s, 11 tests, 3 errors <<< FAILURES!
[...truncated 55438 lines...]
[JENKINS] Lucene » Lucene-Solr-NightlyTests-8.10 - Build # 50 - Still Unstable!
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: ${BUILD_URL}
${FAILED_TESTS}
Build Log:
${BUILD_LOG_MULTILINE_REGEX,regex="(?x: \
\
(?:.*\\[javac\\]\\s++(?![1-9]\\d*\\s+error).*\\r?\\n)*+.*\\[javac\\]\\s+[1-9]\\d*\\s+error.*\\r?\\n \
\
|.*\\[junit4\\]\\s+Suite:.*+\\s++ \
(?:.*\\[junit4\\]\\s++(?!Suite:)(?!Completed).*\\r?\\n)*+ \
.*\\[junit4\\]\\s++Completed\\s+.*<<<\\s*FAILURES!\\r?\\n \
\
|.*\\[junit4\\]\\s+JVM\\s+J\\d+:\\s+std(?:out|err)\\s+was\\s+not\\s+empty.*+\\s++ \
(?:.*\\[junit4\\]\\s++(?!JVM\\s+\\d+:\\s+std)(?!\\<<<\\s+JVM\\s+J\\d+:\\s+EOF).*\\r?\\n)*+ \
.*\\[junit4\\]\\s++<<<\\s+JVM\\s+J\\d+:\\s+EOF.*\\r?\\n \
\
|.*rat-sources:.*\\r?\\n \
(?:\\s*+\\[echo\\]\\s*\\r?\\n|\\s*+\\[echo\\]\\s++(?![1-9]\\d*\\s+Unknown\\s+License)\\S.*\\r?\\n)*+ \
\\s*+\\[echo\\]\\s+[1-9]\\d*\\s+Unknown\\s+License.*\\r?\\n \
(?:\\s*+\\[echo\\].*\\r?\\n)*+ \
\
|(?:.*\\r?\\n){2}.*\\[licenses\\]\\s+MISSING\\s+sha1(?:.*\\r?\\n){2} \
\
|.*check-licenses:.*\\r?\\n\\s*\\[echo\\].*\\r?\\n \
\\s*\\[licenses\\]\\s+(?:MISSING\\s+LICENSE|CHECKSUM\\s+FAILED).*\\r?\\n \
(?:\\s*+\\[licenses\\].*\\r?\\n)++ \
\
|(?:.*\\[javadoc\\]\\s++(?![1-9]\\d*\\s+(?:error|warning)).+\\r?\\n)*+ \
.*\\[javadoc\\]\\s+[1-9]\\d*\\s+(?:error|warning).*\\r?\\n \
\
|.*javadocs-lint:.*\\r?\\n(?:.*\\[exec\\].*\\r?\\n)*+ \
\
|.*check.*:.*\\r?\\n \
(?:\\s*+\\[forbidden-apis\\]\\s*\\r?\\n \
|\\s*+\\[forbidden-apis\\]\\s++ \
(?!Scanned\\s+\\d+\\s+(?:\\(and\\s+\\d+\\s+related\\)\\s+)?class\\s+file\\(s\\))\\S.*\\r?\n)*+ \
\\s*+\\[forbidden-apis\\]\\s++ \
Scanned\\s+\\d+\\s+(?:\\(and\\s+\\d+\\s+related\\)\\s+)?class\\s+file\\(s\\).*[1-9]\\d*\\s+error\\(s\\)\\.\\r?\\n \
\
|\\S+:\\s*\\r?\\n \
(?:(?!\\S+:\\s*\\r?\\n)(?!.*:\\s+exec\\s+returned:\\s+[^0]).*\\r?\\n)*+ \
.*:\\s+exec\\s+returned:\\s+[^0](?s:.*+) \
\
|.*\\[echo\\]\\s+Building\\s+.*\\r?\\n \
\\s*ivy-fail:\\s*(?:\\r?\\n)+ \
\\s*ivy-configure:\\s*(?:\\r?\\n)+ \
\\s*\\[ivy:configure\\].*(?:\\r?\\n)+ \
.*resolve:.*(?:\\r?\\n)+ \
(?:\\s*\\[ivy:retrieve\\].*\\r?\\n)* \
\\s*\\[ivy:retrieve\\]\\s+:::*\\s+(?:ERRORS|FAILED\\s+DOWNLOADS).*\\r?\\n \
(?:\\s*\\[ivy:retrieve\\].*\\r?\\n)* \
\
|.*-ecj-javadoc-lint-(?:src|tests):.*\\r?\\n \
(?:\\s*\\[mkdir].*\\r?\\n)? \
(?:\\s*\\[ecj-lint\\].*\\r?\\n)* \
\\s*\\[ecj-lint\\].*\\([1-9]\\d*\\s+error.*\\r?\\n \
\
|.*check-lib-versions:.*\\r?\\n \
(?:\\s*\\[echo\\].*\\r?\\n)? \
(?:\\s*\\[libversions\\].*\\r?\\n)* \
\\s*\\[libversions\\].*[1-9]\\d*\\s+error.*\\r?\\n \
\
|.*-validate-source-patterns:.*\\r?\\n \
(?:\\s*\\[source-patterns\\].*\\r?\\n)+ \
\
|.*\\r?\\n.*Full\\s+thread\\s+dump(?s:.*+) \
\
|.*(?:FATAL|ERROR):(?s:.*+) \
\
|.*\\#\\s+A\\s+fatal\\s+error\\s+has\\s+been\\s+detected\\s+by\\s+the\\s+Java\\s+Runtime\\s+Environment:(?s:.*+) \
\
|.*\\#\\s+There\\s+is\\s+insufficient\\s+memory\\s+for\\s+the\\s+Java\\s+Runtime\\s+Environment\\s+to\\s+continue\\.(?s:.*+) \
\
|.*BUILD\\s+FAILED(?s:.*+) \
)"}