Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/21487/ Java: 64bit/jdk-9.0.4 -XX:+UseCompressedOops -XX:+UseSerialGC
2 tests failed. FAILED: org.apache.solr.cloud.ForceLeaderTest.testReplicasInLowerTerms Error Message: Doc with id=5 not found in http://127.0.0.1:37185/forceleader_lower_terms_collection due to: Path not found: /id; rsp={doc=null} Stack Trace: java.lang.AssertionError: Doc with id=5 not found in http://127.0.0.1:37185/forceleader_lower_terms_collection due to: Path not found: /id; rsp={doc=null} at __randomizedtesting.SeedInfo.seed([EEDDBCA603505B6C:60E99CE5C3F7FB18]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.apache.solr.cloud.HttpPartitionTest.assertDocExists(HttpPartitionTest.java:699) at org.apache.solr.cloud.HttpPartitionTest.assertDocsExistInAllReplicas(HttpPartitionTest.java:644) at org.apache.solr.cloud.ForceLeaderTest.bringBackOldLeaderAndSendDoc(ForceLeaderTest.java:520) at org.apache.solr.cloud.ForceLeaderTest.testReplicasInLowerTerms(ForceLeaderTest.java:158) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:993) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.base/java.lang.Thread.run(Thread.java:844) FAILED: org.apache.solr.cloud.autoscaling.ComputePlanActionTest.testNodeWithMultipleReplicasLost Error Message: The operations computed by ComputePlanAction should not be null SolrClientNodeStateProvider.DEBUG{AFTER_ACTION=[compute_plan, null], BEFORE_ACTION=[compute_plan, null]} Stack Trace: java.lang.AssertionError: The operations computed by ComputePlanAction should not be null SolrClientNodeStateProvider.DEBUG{AFTER_ACTION=[compute_plan, null], BEFORE_ACTION=[compute_plan, null]} at __randomizedtesting.SeedInfo.seed([EEDDBCA603505B6C:DE1D5D248B22BA30]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertNotNull(Assert.java:526) at org.apache.solr.cloud.autoscaling.ComputePlanActionTest.testNodeWithMultipleReplicasLost(ComputePlanActionTest.java:291) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.base/java.lang.Thread.run(Thread.java:844) Build Log: [...truncated 12206 lines...] [junit4] Suite: org.apache.solr.cloud.autoscaling.ComputePlanActionTest [junit4] 2> 82609 INFO (SUITE-ComputePlanActionTest-seed#[EEDDBCA603505B6C]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ComputePlanActionTest_EEDDBCA603505B6C-001/init-core-data-001 [junit4] 2> 82610 INFO (SUITE-ComputePlanActionTest-seed#[EEDDBCA603505B6C]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 82610 INFO (SUITE-ComputePlanActionTest-seed#[EEDDBCA603505B6C]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0) [junit4] 2> 82612 INFO (SUITE-ComputePlanActionTest-seed#[EEDDBCA603505B6C]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ComputePlanActionTest_EEDDBCA603505B6C-001/tempDir-001 [junit4] 2> 82612 INFO (SUITE-ComputePlanActionTest-seed#[EEDDBCA603505B6C]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 82612 INFO (Thread-229) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 82612 INFO (Thread-229) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 82614 ERROR (Thread-229) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes [junit4] 2> 82712 INFO (SUITE-ComputePlanActionTest-seed#[EEDDBCA603505B6C]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:41675 [junit4] 2> 82717 INFO (zkConnectionManagerCallback-148-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 82731 INFO (jetty-launcher-145-thread-1) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 82744 INFO (jetty-launcher-145-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 82744 INFO (jetty-launcher-145-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 82744 INFO (jetty-launcher-145-thread-1) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 82745 INFO (jetty-launcher-145-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3ce3d512{/solr,null,AVAILABLE} [junit4] 2> 82747 INFO (jetty-launcher-145-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5fd1d27e{SSL,[ssl, http/1.1]}{127.0.0.1:35341} [junit4] 2> 82747 INFO (jetty-launcher-145-thread-1) [ ] o.e.j.s.Server Started @84616ms [junit4] 2> 82747 INFO (jetty-launcher-145-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=35341} [junit4] 2> 82747 ERROR (jetty-launcher-145-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 82748 INFO (jetty-launcher-145-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 82748 INFO (jetty-launcher-145-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 82748 INFO (jetty-launcher-145-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 82748 INFO (jetty-launcher-145-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-18T19:55:48.813159Z [junit4] 2> 82757 INFO (zkConnectionManagerCallback-150-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 82758 INFO (jetty-launcher-145-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 82770 INFO (jetty-launcher-145-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41675/solr [junit4] 2> 82785 INFO (zkConnectionManagerCallback-154-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 82837 INFO (zkConnectionManagerCallback-156-thread-1-processing-n:127.0.0.1:35341_solr) [n:127.0.0.1:35341_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 82919 INFO (jetty-launcher-145-thread-1) [n:127.0.0.1:35341_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 82920 INFO (jetty-launcher-145-thread-1) [n:127.0.0.1:35341_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:35341_solr [junit4] 2> 82920 INFO (jetty-launcher-145-thread-1) [n:127.0.0.1:35341_solr ] o.a.s.c.Overseer Overseer (id=72172079473754115-127.0.0.1:35341_solr-n_0000000000) starting [junit4] 2> 82942 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 82961 INFO (jetty-launcher-145-thread-1) [n:127.0.0.1:35341_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35341_solr [junit4] 2> 82974 INFO (zkCallback-155-thread-1-processing-n:127.0.0.1:35341_solr) [n:127.0.0.1:35341_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 82995 DEBUG (OverseerAutoScalingTriggerThread-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger [junit4] 2> 82996 DEBUG (OverseerAutoScalingTriggerThread-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1 [junit4] 2> 82997 DEBUG (OverseerAutoScalingTriggerThread-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:35341_solr] [junit4] 2> 82997 DEBUG (OverseerAutoScalingTriggerThread-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion -1 [junit4] 2> 82997 DEBUG (OverseerAutoScalingTriggerThread-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1 [junit4] 2> 83017 DEBUG (OverseerAutoScalingTriggerThread-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 83021 DEBUG (OverseerAutoScalingTriggerThread-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1 [junit4] 2> 83022 DEBUG (ScheduledTrigger-453-thread-1) [n:127.0.0.1:35341_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 [junit4] 2> 83043 INFO (jetty-launcher-145-thread-1) [n:127.0.0.1:35341_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35341.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@55f6af8c [junit4] 2> 83043 INFO (jetty-launcher-145-thread-1) [n:127.0.0.1:35341_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35341.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@55f6af8c [junit4] 2> 83043 INFO (jetty-launcher-145-thread-1) [n:127.0.0.1:35341_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35341.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@55f6af8c [junit4] 2> 83044 INFO (jetty-launcher-145-thread-1) [n:127.0.0.1:35341_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ComputePlanActionTest_EEDDBCA603505B6C-001/tempDir-001/node1/. [junit4] 2> 83062 INFO (zkConnectionManagerCallback-160-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 83082 INFO (zkConnectionManagerCallback-164-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 83083 INFO (SUITE-ComputePlanActionTest-seed#[EEDDBCA603505B6C]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 83085 INFO (SUITE-ComputePlanActionTest-seed#[EEDDBCA603505B6C]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41675/solr ready [junit4] 2> 83093 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[EEDDBCA603505B6C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testNodeAdded [junit4] 2> 83094 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[EEDDBCA603505B6C]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 83105 DEBUG (zkCallback-155-thread-1-processing-n:127.0.0.1:35341_solr) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2 [junit4] 2> 83111 DEBUG (OverseerAutoScalingTriggerThread-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2 [junit4] 2> 83113 DEBUG (OverseerAutoScalingTriggerThread-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 83114 DEBUG (OverseerAutoScalingTriggerThread-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 83114 DEBUG (OverseerAutoScalingTriggerThread-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2 [junit4] 2> 83160 INFO (qtp1931108059-845) [n:127.0.0.1:35341_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 83161 DEBUG (qtp1931108059-850) [n:127.0.0.1:35341_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 83162 INFO (qtp1931108059-850) [n:127.0.0.1:35341_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=26 [junit4] 2> 83162 DEBUG (zkCallback-155-thread-1-processing-n:127.0.0.1:35341_solr) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3 [junit4] 2> 83162 DEBUG (OverseerAutoScalingTriggerThread-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3 [junit4] 2> 83163 DEBUG (OverseerAutoScalingTriggerThread-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 83163 DEBUG (OverseerAutoScalingTriggerThread-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 83163 DEBUG (OverseerAutoScalingTriggerThread-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3 [junit4] 2> 83166 INFO (qtp1931108059-849) [n:127.0.0.1:35341_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 83167 DEBUG (qtp1931108059-848) [n:127.0.0.1:35341_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 83167 INFO (qtp1931108059-848) [n:127.0.0.1:35341_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=4 [junit4] 2> 83168 DEBUG (zkCallback-155-thread-1-processing-n:127.0.0.1:35341_solr) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 4 [junit4] 2> 83168 DEBUG (OverseerAutoScalingTriggerThread-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 4 [junit4] 2> 83168 DEBUG (OverseerAutoScalingTriggerThread-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 83168 DEBUG (OverseerAutoScalingTriggerThread-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 83168 DEBUG (OverseerAutoScalingTriggerThread-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVersion 4 [junit4] 2> 83176 INFO (qtp1931108059-850) [n:127.0.0.1:35341_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 83177 DEBUG (qtp1931108059-845) [n:127.0.0.1:35341_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 83177 INFO (qtp1931108059-845) [n:127.0.0.1:35341_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=8 [junit4] 2> 83178 DEBUG (zkCallback-155-thread-1-processing-n:127.0.0.1:35341_solr) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 5 [junit4] 2> 83179 DEBUG (zkCallback-155-thread-1-processing-n:127.0.0.1:35341_solr) [n:127.0.0.1:35341_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:35341_solr] [junit4] 2> 83179 DEBUG (zkCallback-155-thread-1-processing-n:127.0.0.1:35341_solr) [n:127.0.0.1:35341_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=1, actions=[{name=compute_plan, class=solr.ComputePlanAction}, {name=test, class=org.apache.solr.cloud.autoscaling.ComputePlanActionTest$AssertingTriggerAction}], enabled=true} [junit4] 2> 83179 DEBUG (OverseerAutoScalingTriggerThread-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 5 [junit4] 2> 83181 INFO (qtp1931108059-849) [n:127.0.0.1:35341_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 83182 DEBUG (ScheduledTrigger-453-thread-1) [n:127.0.0.1:35341_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 83182 DEBUG (ScheduledTrigger-453-thread-1) [n:127.0.0.1:35341_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1 [junit4] 2> 83182 DEBUG (qtp1931108059-848) [n:127.0.0.1:35341_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 83183 INFO (qtp1931108059-848) [n:127.0.0.1:35341_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=3 [junit4] 2> 83183 DEBUG (zkCallback-155-thread-1-processing-n:127.0.0.1:35341_solr) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 6 [junit4] 2> 83183 DEBUG (zkCallback-155-thread-1-processing-n:127.0.0.1:35341_solr) [n:127.0.0.1:35341_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:35341_solr] [junit4] 2> 83183 DEBUG (zkCallback-155-thread-1-processing-n:127.0.0.1:35341_solr) [n:127.0.0.1:35341_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=1, actions=[{name=compute_plan, class=solr.ComputePlanAction}, {name=test, class=org.apache.solr.cloud.autoscaling.ComputePlanActionTest$AssertingTriggerAction}], enabled=true} [junit4] 2> 83184 INFO (qtp1931108059-845) [n:127.0.0.1:35341_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=2&collection.configName=conf&name=testNodeAdded&nrtReplicas=2&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 83197 DEBUG (OverseerAutoScalingTriggerThread-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 83197 DEBUG (OverseerAutoScalingTriggerThread-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6, lastZnodeVersion 5 [junit4] 2> 83198 DEBUG (OverseerAutoScalingTriggerThread-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 6 [junit4] 2> 83198 DEBUG (OverseerAutoScalingTriggerThread-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 83198 DEBUG (OverseerAutoScalingTriggerThread-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6, lastZnodeVersion 6 [junit4] 2> 83205 INFO (OverseerThreadFactory-455-thread-1-processing-n:127.0.0.1:35341_solr) [n:127.0.0.1:35341_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testNodeAdded [junit4] 2> 83206 WARN (OverseerThreadFactory-455-thread-1-processing-n:127.0.0.1:35341_solr) [n:127.0.0.1:35341_solr ] o.a.s.c.a.c.CreateCollectionCmd Specified number of replicas of 2 on collection testNodeAdded is higher than the number of Solr instances currently live or live and part of your createNodeSet(1). It's unusual to run two replica of the same slice on the same Solr-instance. [junit4] 2> 83212 INFO (qtp1931108059-850) [n:127.0.0.1:35341_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 83222 INFO (OverseerThreadFactory-455-thread-1-processing-n:127.0.0.1:35341_solr) [n:127.0.0.1:35341_solr ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 1746909725 sessionWrapper.createTime 1746909715463142, this.sessionWrapper.createTime 1746909715463142 [junit4] 2> 83230 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "name":"testNodeAdded", [junit4] 2> "fromApi":"true", [junit4] 2> "replicationFactor":"2", [junit4] 2> "collection.configName":"conf", [junit4] 2> "numShards":"1", [junit4] 2> "nrtReplicas":"2", [junit4] 2> "stateFormat":"2", [junit4] 2> "operation":"create"} current state version: 0 [junit4] 2> 83230 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: testNodeAdded [junit4] 2> 83230 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/testNodeAdded/state.json [junit4] 2> 83328 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"testNodeAdded", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"testNodeAdded_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:35341/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} current state version: 0 [junit4] 2> 83328 INFO (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"testNodeAdded", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"testNodeAdded_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:35341/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 83329 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{}} [junit4] 2> 83329 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{"core_node3":{ [junit4] 2> "core":"testNodeAdded_shard1_replica_n1", [junit4] 2> "base_url":"https://127.0.0.1:35341/solr";, [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}}} [junit4] 2> 83330 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, message = { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"testNodeAdded", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"testNodeAdded_shard1_replica_n2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:35341/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} current state version: 0 [junit4] 2> 83330 INFO (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"testNodeAdded", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"testNodeAdded_shard1_replica_n2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:35341/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 83331 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{"core_node3":{ [junit4] 2> "core":"testNodeAdded_shard1_replica_n1", [junit4] 2> "base_url":"https://127.0.0.1:35341/solr";, [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}}} [junit4] 2> 83331 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{ [junit4] 2> "core_node3":{ [junit4] 2> "core":"testNodeAdded_shard1_replica_n1", [junit4] 2> "base_url":"https://127.0.0.1:35341/solr";, [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}, [junit4] 2> "core_node4":{ [junit4] 2> "core":"testNodeAdded_shard1_replica_n2", [junit4] 2> "base_url":"https://127.0.0.1:35341/solr";, [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}}} [junit4] 2> 83432 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testNodeAdded/state.json version: 0 [junit4] 2> 83537 INFO (qtp1931108059-850) [n:127.0.0.1:35341_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=testNodeAdded_shard1_replica_n1&action=CREATE&numShards=1&collection=testNodeAdded&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 83537 INFO (qtp1931108059-844) [n:127.0.0.1:35341_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=testNodeAdded_shard1_replica_n2&action=CREATE&numShards=1&collection=testNodeAdded&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 83537 INFO (qtp1931108059-850) [n:127.0.0.1:35341_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 83541 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"testNodeAdded_shard1_replica_n1", [junit4] 2> "core_node_name":"core_node3", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:35341/solr";, [junit4] 2> "node_name":"127.0.0.1:35341_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"testNodeAdded", [junit4] 2> "type":"NRT", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 83541 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"testNodeAdded_shard1_replica_n1", [junit4] 2> "core_node_name":"core_node3", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:35341/solr";, [junit4] 2> "node_name":"127.0.0.1:35341_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"testNodeAdded", [junit4] 2> "type":"NRT", [junit4] 2> "operation":"state"} [junit4] 2> 83541 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node3:{"core":"testNodeAdded_shard1_replica_n1","base_url":"https://127.0.0.1:35341/solr","node_name":"127.0.0.1:35341_solr","state":"down","type":"NRT"} [junit4] 2> 83541 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(testNodeAdded//collections/testNodeAdded/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> "shards":{"shard1":{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{ [junit4] 2> "core_node3":{ [junit4] 2> "core":"testNodeAdded_shard1_replica_n1", [junit4] 2> "base_url":"https://127.0.0.1:35341/solr";, [junit4] 2> "node_name":"127.0.0.1:35341_solr", [junit4] 2> "state":"down", [junit4] 2> "type":"NRT"}, [junit4] 2> "core_node4":{ [junit4] 2> "core":"testNodeAdded_shard1_replica_n2", [junit4] 2> "base_url":"https://127.0.0.1:35341/solr";, [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}}}}} [junit4] 2> 83544 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, message = { [junit4] 2> "core":"testNodeAdded_shard1_replica_n2", [junit4] 2> "core_node_name":"core_node4", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:35341/solr";, [junit4] 2> "node_name":"127.0.0.1:35341_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"testNodeAdded", [junit4] 2> "type":"NRT", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 83544 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"testNodeAdded_shard1_replica_n2", [junit4] 2> "core_node_name":"core_node4", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:35341/solr";, [junit4] 2> "node_name":"127.0.0.1:35341_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"testNodeAdded", [junit4] 2> "type":"NRT", [junit4] 2> "operation":"state"} [junit4] 2> 83544 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node4:{"core":"testNodeAdded_shard1_replica_n2","base_url":"https://127.0.0.1:35341/solr","node_name":"127.0.0.1:35341_solr","state":"down","type":"NRT"} [junit4] 2> 83544 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(testNodeAdded//collections/testNodeAdded/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> "shards":{"shard1":{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{ [junit4] 2> "core_node3":{ [junit4] 2> "core":"testNodeAdded_shard1_replica_n1", [junit4] 2> "base_url":"https://127.0.0.1:35341/solr";, [junit4] 2> "node_name":"127.0.0.1:35341_solr", [junit4] 2> "state":"down", [junit4] 2> "type":"NRT"}, [junit4] 2> "core_node4":{ [junit4] 2> "core":"testNodeAdded_shard1_replica_n2", [junit4] 2> "base_url":"https://127.0.0.1:35341/solr";, [junit4] 2> "node_name":"127.0.0.1:35341_solr", [junit4] 2> "state":"down", [junit4] 2> "type":"NRT"}}}}} [junit4] 2> 83644 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testNodeAdded/state.json version: 1 [junit4] 2> 83645 INFO (zkCallback-155-thread-1-processing-n:127.0.0.1:35341_solr) [n:127.0.0.1:35341_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has occurred - updating... (live nodes size: [1]) [junit4] 2> 84183 DEBUG (ScheduledTrigger-453-thread-2) [n:127.0.0.1:35341_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 84183 DEBUG (ScheduledTrigger-453-thread-2) [n:127.0.0.1:35341_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1 [junit4] 2> 84558 INFO (qtp1931108059-850) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 84562 INFO (qtp1931108059-844) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 84571 INFO (qtp1931108059-844) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] o.a.s.s.IndexSchema [testNodeAdded_shard1_replica_n2] Schema name=minimal [junit4] 2> 84573 INFO (qtp1931108059-844) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 84573 INFO (qtp1931108059-844) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'testNodeAdded_shard1_replica_n2' using configuration from collection testNodeAdded, trusted=true [junit4] 2> 84573 INFO (qtp1931108059-850) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.s.IndexSchema [testNodeAdded_shard1_replica_n1] Schema name=minimal [junit4] 2> 84578 INFO (qtp1931108059-850) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 84578 INFO (qtp1931108059-850) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'testNodeAdded_shard1_replica_n1' using configuration from collection testNodeAdded, trusted=true [junit4] 2> 84586 INFO (qtp1931108059-844) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35341.solr.core.testNodeAdded.shard1.replica_n2' (registry 'solr.core.testNodeAdded.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@55f6af8c [junit4] 2> 84586 INFO (qtp1931108059-844) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 84587 INFO (qtp1931108059-844) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] o.a.s.c.SolrCore [[testNodeAdded_shard1_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ComputePlanActionTest_EEDDBCA603505B6C-001/tempDir-001/node1/testNodeAdded_shard1_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ComputePlanActionTest_EEDDBCA603505B6C-001/tempDir-001/node1/./testNodeAdded_shard1_replica_n2/data/] [junit4] 2> 84589 INFO (qtp1931108059-850) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35341.solr.core.testNodeAdded.shard1.replica_n1' (registry 'solr.core.testNodeAdded.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@55f6af8c [junit4] 2> 84590 INFO (qtp1931108059-850) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 84590 INFO (qtp1931108059-850) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.c.SolrCore [[testNodeAdded_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ComputePlanActionTest_EEDDBCA603505B6C-001/tempDir-001/node1/testNodeAdded_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ComputePlanActionTest_EEDDBCA603505B6C-001/tempDir-001/node1/./testNodeAdded_shard1_replica_n1/data/] [junit4] 2> 84738 INFO (qtp1931108059-850) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 84738 INFO (qtp1931108059-850) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 84739 INFO (qtp1931108059-850) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 84739 INFO (qtp1931108059-850) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 84741 INFO (qtp1931108059-850) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@164c7e86[testNodeAdded_shard1_replica_n1] main] [junit4] 2> 84741 INFO (qtp1931108059-850) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 84742 INFO (qtp1931108059-850) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 84742 INFO (qtp1931108059-850) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 84743 INFO (qtp1931108059-850) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1592769905487249408 [junit4] 2> 84745 INFO (searcherExecutor-459-thread-1-processing-n:127.0.0.1:35341_solr x:testNodeAdded_shard1_replica_n1 s:shard1 c:testNodeAdded r:core_node3) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.c.SolrCore [testNodeAdded_shard1_replica_n1] Registered new searcher Searcher@164c7e86[testNodeAdded_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 84745 INFO (qtp1931108059-844) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 84745 INFO (qtp1931108059-844) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 84747 INFO (qtp1931108059-844) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 84747 INFO (qtp1931108059-844) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 84749 INFO (qtp1931108059-844) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@5f3c9d0a[testNodeAdded_shard1_replica_n2] main] [junit4] 2> 84765 INFO (qtp1931108059-844) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 84765 INFO (qtp1931108059-844) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 84766 INFO (qtp1931108059-844) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 84772 INFO (searcherExecutor-458-thread-1-processing-n:127.0.0.1:35341_solr x:testNodeAdded_shard1_replica_n2 s:shard1 c:testNodeAdded r:core_node4) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] o.a.s.c.SolrCore [testNodeAdded_shard1_replica_n2] Registered new searcher Searcher@5f3c9d0a[testNodeAdded_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 84772 INFO (qtp1931108059-844) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1592769905517658112 [junit4] 2> 84795 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"testNodeAdded"} current state version: 0 [junit4] 2> 84795 INFO (qtp1931108059-850) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=2 found=1 timeoutin=9999ms [junit4] 2> 84895 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testNodeAdded/state.json version: 2 [junit4] 2> 84896 INFO (zkCallback-155-thread-2-processing-n:127.0.0.1:35341_solr) [n:127.0.0.1:35341_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has occurred - updating... (live nodes size: [1]) [junit4] 2> 85183 DEBUG (ScheduledTrigger-453-thread-2) [n:127.0.0.1:35341_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 85183 DEBUG (ScheduledTrigger-453-thread-2) [n:127.0.0.1:35341_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1 [junit4] 2> 85295 INFO (qtp1931108059-850) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 85295 INFO (qtp1931108059-850) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 85295 INFO (qtp1931108059-850) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:35341/solr/testNodeAdded_shard1_replica_n1/ [junit4] 2> 85299 INFO (qtp1931108059-850) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.u.PeerSync PeerSync: core=testNodeAdded_shard1_replica_n1 url=https://127.0.0.1:35341/solr START replicas=[https://127.0.0.1:35341/solr/testNodeAdded_shard1_replica_n2/] nUpdates=100 [junit4] 2> 85308 INFO (qtp1931108059-848) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] o.a.s.c.S.Request [testNodeAdded_shard1_replica_n2] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 85601 INFO (qtp1931108059-850) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.u.PeerSync PeerSync: core=testNodeAdded_shard1_replica_n1 url=https://127.0.0.1:35341/solr DONE. We have no versions. sync failed. [junit4] 2> 85601 INFO (qtp1931108059-850) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate [junit4] 2> 85601 INFO (qtp1931108059-850) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway [junit4] 2> 85602 INFO (qtp1931108059-850) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 85604 INFO (qtp1931108059-850) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:35341/solr/testNodeAdded_shard1_replica_n1/ shard1 [junit4] 2> 85604 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"testNodeAdded", [junit4] 2> "base_url":"https://127.0.0.1:35341/solr";, [junit4] 2> "core":"testNodeAdded_shard1_replica_n1", [junit4] 2> "state":"active"} current state version: 0 [junit4] 2> 85706 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testNodeAdded/state.json version: 3 [junit4] 2> 85707 INFO (zkCallback-155-thread-2-processing-n:127.0.0.1:35341_solr) [n:127.0.0.1:35341_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has occurred - updating... (live nodes size: [1]) [junit4] 2> 85754 INFO (qtp1931108059-850) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 85755 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"testNodeAdded_shard1_replica_n1", [junit4] 2> "core_node_name":"core_node3", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:35341/solr";, [junit4] 2> "node_name":"127.0.0.1:35341_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"testNodeAdded", [junit4] 2> "type":"NRT", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 85755 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"testNodeAdded_shard1_replica_n1", [junit4] 2> "core_node_name":"core_node3", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:35341/solr";, [junit4] 2> "node_name":"127.0.0.1:35341_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"testNodeAdded", [junit4] 2> "type":"NRT", [junit4] 2> "operation":"state"} [junit4] 2> 85756 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node3:{"core":"testNodeAdded_shard1_replica_n1","base_url":"https://127.0.0.1:35341/solr","node_name":"127.0.0.1:35341_solr","state":"active","type":"NRT","leader":"true"} [junit4] 2> 85756 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(testNodeAdded//collections/testNodeAdded/state.json/4)={ [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> "shards":{"shard1":{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{ [junit4] 2> "core_node3":{ [junit4] 2> "core":"testNodeAdded_shard1_replica_n1", [junit4] 2> "base_url":"https://127.0.0.1:35341/solr";, [junit4] 2> "node_name":"127.0.0.1:35341_solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT", [junit4] 2> "leader":"true"}, [junit4] 2> "core_node4":{ [junit4] 2> "core":"testNodeAdded_shard1_replica_n2", [junit4] 2> "base_url":"https://127.0.0.1:35341/solr";, [junit4] 2> "node_name":"127.0.0.1:35341_solr", [junit4] 2> "state":"down", [junit4] 2> "type":"NRT"}}}}} [junit4] 2> 85756 INFO (qtp1931108059-850) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=testNodeAdded_shard1_replica_n1&action=CREATE&numShards=1&collection=testNodeAdded&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2218 [junit4] 2> 85807 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, message = { [junit4] 2> "core":"testNodeAdded_shard1_replica_n2", [junit4] 2> "core_node_name":"core_node4", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:35341/solr";, [junit4] 2> "node_name":"127.0.0.1:35341_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"testNodeAdded", [junit4] 2> "type":"NRT", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 85807 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"testNodeAdded_shard1_replica_n2", [junit4] 2> "core_node_name":"core_node4", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:35341/solr";, [junit4] 2> "node_name":"127.0.0.1:35341_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"testNodeAdded", [junit4] 2> "type":"NRT", [junit4] 2> "operation":"state"} [junit4] 2> 85807 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node4:{"core":"testNodeAdded_shard1_replica_n2","base_url":"https://127.0.0.1:35341/solr","node_name":"127.0.0.1:35341_solr","state":"active","type":"NRT"} [junit4] 2> 85807 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(testNodeAdded//collections/testNodeAdded/state.json/4)={ [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> "shards":{"shard1":{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{ [junit4] 2> "core_node3":{ [junit4] 2> "core":"testNodeAdded_shard1_replica_n1", [junit4] 2> "base_url":"https://127.0.0.1:35341/solr";, [junit4] 2> "node_name":"127.0.0.1:35341_solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT", [junit4] 2> "leader":"true"}, [junit4] 2> "core_node4":{ [junit4] 2> "core":"testNodeAdded_shard1_replica_n2", [junit4] 2> "base_url":"https://127.0.0.1:35341/solr";, [junit4] 2> "node_name":"127.0.0.1:35341_solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT"}}}}} [junit4] 2> 85807 INFO (qtp1931108059-844) [n:127.0.0.1:35341_solr c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=testNodeAdded_shard1_replica_n2&action=CREATE&numShards=1&collection=testNodeAdded&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2270 [junit4] 2> 85811 INFO (qtp1931108059-845) [n:127.0.0.1:35341_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas [junit4] 2> 85907 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testNodeAdded/state.json version: 4 [junit4] 2> 85908 INFO (zkCallback-155-thread-2-processing-n:127.0.0.1:35341_solr) [n:127.0.0.1:35341_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has occurred - updating... (live nodes size: [1]) [junit4] 2> 86184 DEBUG (ScheduledTrigger-453-thread-2) [n:127.0.0.1:35341_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 86184 DEBUG (ScheduledTrigger-453-thread-2) [n:127.0.0.1:35341_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1 [junit4] 2> 86811 INFO (qtp1931108059-845) [n:127.0.0.1:35341_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=2&collection.configName=conf&name=testNodeAdded&nrtReplicas=2&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=3627 [junit4] 2> 86820 INFO (qtp1931108059-849) [n:127.0.0.1:35341_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.testNodeAdded.shard1.replica_n1:INDEX.sizeInBytes&key=solr.core.testNodeAdded.shard1.replica_n2:INDEX.sizeInBytes} status=0 QTime=1 [junit4] 2> 86822 INFO (qtp1931108059-844) [n:127.0.0.1:35341_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1 [junit4] 2> 86824 DEBUG (qtp1931108059-889) [n:127.0.0.1:35341_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 86825 INFO (qtp1931108059-889) [n:127.0.0.1:35341_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=10 [junit4] 2> 86826 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[EEDDBCA603505B6C]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 86829 DEBUG (zkCallback-155-thread-2-processing-n:127.0.0.1:35341_solr) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 7 [junit4] 2> 86829 DEBUG (zkCallback-155-thread-2-processing-n:127.0.0.1:35341_solr) [n:127.0.0.1:35341_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:35341_solr] [junit4] 2> 86829 DEBUG (zkCallback-155-thread-2-processing-n:127.0.0.1:35341_solr) [n:127.0.0.1:35341_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=1, actions=[{name=compute_plan, class=solr.ComputePlanAction}, {name=test, class=org.apache.solr.cloud.autoscaling.ComputePlanActionTest$AssertingTriggerAction}], enabled=true} [junit4] 2> 86829 DEBUG (OverseerAutoScalingTriggerThread-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 7 [junit4] 2> 86829 DEBUG (OverseerAutoScalingTriggerThread-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 86830 DEBUG (OverseerAutoScalingTriggerThread-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 7, lastZnodeVersion 7 [junit4] 2> 86849 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[EEDDBCA603505B6C]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 86849 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[EEDDBCA603505B6C]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 86849 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[EEDDBCA603505B6C]) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 86850 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[EEDDBCA603505B6C]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@54210b81{/solr,null,AVAILABLE} [junit4] 2> 86851 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[EEDDBCA603505B6C]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7af578e7{SSL,[ssl, http/1.1]}{127.0.0.1:46135} [junit4] 2> 86851 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[EEDDBCA603505B6C]) [ ] o.e.j.s.Server Started @88719ms [junit4] 2> 86851 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[EEDDBCA603505B6C]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46135} [junit4] 2> 86851 ERROR (TEST-ComputePlanActionTest.testNodeAdded-seed#[EEDDBCA603505B6C]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 86851 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[EEDDBCA603505B6C]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 86851 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[EEDDBCA603505B6C]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 86851 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[EEDDBCA603505B6C]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 86851 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[EEDDBCA603505B6C]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-18T19:55:52.916836Z [junit4] 2> 86878 INFO (zkConnectionManagerCallback-167-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 86879 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[EEDDBCA603505B6C]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 86897 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[EEDDBCA603505B6C]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41675/solr [junit4] 2> 86906 INFO (zkConnectionManagerCallback-171-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 86909 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x100681fb7bc0007, likely client has closed socket [junit4] 2> 86911 INFO (zkConnectionManagerCallback-173-thread-1-processing-n:127.0.0.1:46135_solr) [n:127.0.0.1:46135_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 86915 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[EEDDBCA603505B6C]) [n:127.0.0.1:46135_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 86916 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[EEDDBCA603505B6C]) [n:127.0.0.1:46135_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 86926 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[EEDDBCA603505B6C]) [n:127.0.0.1:46135_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46135_solr [junit4] 2> 86926 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:46135_solr"} current state version: 0 [junit4] 2> 86926 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:46135_solr [junit4] 2> 86927 INFO (zkCallback-163-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 86927 INFO (zkCallback-155-thread-2-processing-n:127.0.0.1:35341_solr) [n:127.0.0.1:35341_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 86927 INFO (zkCallback-163-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 86951 INFO (zkCallback-172-thread-1-processing-n:127.0.0.1:46135_solr) [n:127.0.0.1:46135_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 86984 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[EEDDBCA603505B6C]) [n:127.0.0.1:46135_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46135.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@55f6af8c [junit4] 2> 86995 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[EEDDBCA603505B6C]) [n:127.0.0.1:46135_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46135.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@55f6af8c [junit4] 2> 86996 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[EEDDBCA603505B6C]) [n:127.0.0.1:46135_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46135.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@55f6af8c [junit4] 2> 86997 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[EEDDBCA603505B6C]) [n:127.0.0.1:46135_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ComputePlanActionTest_EEDDBCA603505B6C-001/tempDir-001/node2/. [junit4] 2> 87184 DEBUG (ScheduledTrigger-453-thread-2) [n:127.0.0.1:35341_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 87184 DEBUG (ScheduledTrigger-453-thread-2) [n:127.0.0.1:35341_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2 [junit4] 2> 87184 DEBUG (ScheduledTrigger-453-thread-2) [n:127.0.0.1:35341_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:46135_solr at time 1746913686860284 [junit4] 2> 87207 INFO (OverseerCollectionConfigSetProcessor-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 88185 DEBUG (ScheduledTrigger-453-thread-2) [n:127.0.0.1:35341_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 88185 DEBUG (ScheduledTrigger-453-thread-2) [n:127.0.0.1:35341_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2 [junit4] 2> 88185 DEBUG (ScheduledTrigger-453-thread-2) [n:127.0.0.1:35341_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger firing registered processor for nodes: [127.0.0.1:46135_solr] added at times [1746913686860284], now=1746914687642007 [junit4] 2> 88185 DEBUG (ScheduledTrigger-453-thread-2) [n:127.0.0.1:35341_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { [junit4] 2> "id":"634cf09c231fcTea5anpgr84o0f5ecz3swnm3r2", [junit4] 2> "source":"node_added_trigger", [junit4] 2> "eventTime":1746913686860284, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[1746913686860284], [junit4] 2> "nodeNames":["127.0.0.1:46135_solr"]}} [junit4] 2> 88185 DEBUG (ScheduledTrigger-453-thread-2) [n:127.0.0.1:35341_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: [node_added_trigger] [junit4] 2> 88186 INFO (ScheduledTrigger-453-thread-2) [n:127.0.0.1:35341_solr ] o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling logging. [junit4] 2> 88186 DEBUG (AutoscalingActionExecutor-454-thread-1-processing-n:127.0.0.1:35341_solr) [n:127.0.0.1:35341_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions for { [junit4] 2> "id":"634cf09c231fcTea5anpgr84o0f5ecz3swnm3r2", [junit4] 2> "source":"node_added_trigger", [junit4] 2> "eventTime":1746913686860284, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[1746913686860284], [junit4] 2> "_enqueue_time_":1746914688057104, [junit4] 2> "nodeNames":["127.0.0.1:46135_solr"]}} [junit4] 2> 88187 DEBUG (AutoscalingActionExecutor-454-thread-1-processing-n:127.0.0.1:35341_solr) [n:127.0.0.1:35341_solr ] o.a.s.c.a.ComputePlanAction -- processing event: { [junit4] 2> "id":"634cf09c231fcTea5anpgr84o0f5ecz3swnm3r2", [junit4] 2> "source":"node_added_trigger", [junit4] 2> "eventTime":1746913686860284, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[1746913686860284], [junit4] 2> "_enqueue_time_":1746914688057104, [junit4] 2> "nodeNames":["127.0.0.1:46135_solr"]}} with context properties: {BEFORE_ACTION=[compute_plan]} [junit4] 2> 88188 INFO (qtp1931108059-849) [n:127.0.0.1:35341_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.testNodeAdded.shard1.replica_n1:INDEX.sizeInBytes&key=solr.core.testNodeAdded.shard1.replica_n2:INDEX.sizeInBytes} status=0 QTime=0 [junit4] 2> 88191 INFO (qtp1931108059-889) [n:127.0.0.1:35341_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1 [junit4] 2> 88207 INFO (qtp1620109829-904) [n:127.0.0.1:46135_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 88210 INFO (AutoscalingActionExecutor-454-thread-1-processing-n:127.0.0.1:35341_solr) [n:127.0.0.1:35341_solr ] o.a.s.c.a.ComputePlanAction Computed Plan: action=MOVEREPLICA&collection=testNodeAdded&targetNode=127.0.0.1:46135_solr&inPlaceMove=true&replica=core_node3 [junit4] 2> 88210 INFO (AutoscalingActionExecutor-454-thread-1-processing-n:127.0.0.1:35341_solr) [n:127.0.0.1:35341_solr ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 1746914713 sessionWrapper.createTime 1746914710122330, this.sessionWrapper.createTime 1746914710122330 [junit4] 2> 88211 DEBUG (TEST-ComputePlanActionTest.testNodeAdded-seed#[EEDDBCA603505B6C]) [ ] o.a.s.c.a.ComputePlanActionTest -------------_ FINAL STATE -------------- [junit4] 2> 88211 DEBUG (AutoscalingActionExecutor-454-thread-1-processing-n:127.0.0.1:35341_solr) [n:127.0.0.1:35341_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger after 5000ms [junit4] 2> 88211 DEBUG (AutoscalingActionExecutor-454-thread-1-processing-n:127.0.0.1:35341_solr) [n:127.0.0.1:35341_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 24 ms for event id=634cf09c231fcTea5anpgr84o0f5ecz3swnm3r2 [junit4] 2> 88213 INFO (qtp1931108059-849) [n:127.0.0.1:35341_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1 [junit4] 2> 88213 DEBUG (TEST-ComputePlanActionTest.testNodeAdded-seed#[EEDDBCA603505B6C]) [ ] o.a.s.c.a.ComputePlanActionTest * Node values: 127.0.0.1:35341_solr [junit4] 2> { [junit4] 2> "node":"127.0.0.1:35341_solr", [junit4] 2> "cores":2, [junit4] 2> "port":"35341", [junit4] 2> "host":"127.0.0.1", [junit4] 2> "ip_1":"1", [junit4] 2> "ip_3":"0", [junit4] 2> "ip_2":"0", [junit4] 2> "ip_4":"127", [junit4] 2> "freedisk":212.70637893676758} [junit4] 2> 88217 INFO (qtp1620109829-906) [n:127.0.0.1:46135_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 88217 DEBUG (TEST-ComputePlanActionTest.testNodeAdded-seed#[EEDDBCA603505B6C]) [ ] o.a.s.c.a.ComputePlanActionTest * Node values: 127.0.0.1:46135_solr [junit4] 2> { [junit4] 2> "node":"127.0.0.1:46135_solr", [junit4] 2> "cores":0, [junit4] 2> "port":"46135", [junit4] 2> "host":"127.0.0.1", [junit4] 2> "ip_1":"1", [junit4] 2> "ip_3":"0", [junit4] 2> "ip_2":"0", [junit4] 2> "ip_4":"127", [junit4] 2> "freedisk":212.70637893676758} [junit4] 2> 88218 DEBUG (TEST-ComputePlanActionTest.testNodeAdded-seed#[EEDDBCA603505B6C]) [ ] o.a.s.c.a.ComputePlanActionTest * Live nodes: [127.0.0.1:35341_solr, 127.0.0.1:46135_solr] [junit4] 2> 88218 DEBUG (TEST-ComputePlanActionTest.testNodeAdded-seed#[EEDDBCA603505B6C]) [ ] o.a.s.c.a.ComputePlanActionTest * Collection testNodeAdded state: DocCollection(testNodeAdded//collections/testNodeAdded/state.json/5)={ [junit4] 2> "pullReplicas":"0", [junit4] 2> "replicationFactor":"2", [junit4] 2> "shards":{"shard1":{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{ [junit4] 2> "core_node3":{ [junit4] 2> "core":"testNodeAdded_shard1_replica_n1", [junit4] 2> "base_url":"https://127.0.0.1:35341/solr";, [junit4] 2> "node_name":"127.0.0.1:35341_solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT", [junit4] 2> "leader":"true"}, [junit4] 2> "core_node4":{ [junit4] 2> "core":"testNodeAdded_shard1_replica_n2", [junit4] 2> "base_url":"https://127.0.0.1:35341/solr";, [junit4] 2> "node_name":"127.0.0.1:35341_solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT"}}}}, [junit4] 2> "router":{"name":"compositeId"}, [junit4] 2> "maxShardsPerNode":"1", [junit4] 2> "autoAddReplicas":"false", [junit4] 2> "nrtReplicas":"2", [junit4] 2> "tlogReplicas":"0"} [junit4] 2> 88218 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[EEDDBCA603505B6C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testNodeAdded [junit4] 2> 88227 INFO (TEST-ComputePlanActionTest.testSelectedCollections-seed#[EEDDBCA603505B6C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSelectedCollections [junit4] 2> 88227 DEBUG (zkCallback-155-thread-2-processing-n:127.0.0.1:35341_solr) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 8 [junit4] 2> 88227 DEBUG (OverseerAutoScalingTriggerThread-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 8 [junit4] 2> 88228 DEBUG (OverseerAutoScalingTriggerThread-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 88228 DEBUG (OverseerAutoScalingTriggerThread-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 88228 INFO (TEST-ComputePlanActionTest.testSelectedCollections-seed#[EEDDBCA603505B6C]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@7af578e7{SSL,[ssl, http/1.1]}{127.0.0.1:0} [junit4] 2> 88228 DEBUG (OverseerAutoScalingTriggerThread-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 8, lastZnodeVersion 8 [junit4] 2> 88229 INFO (TEST-ComputePlanActionTest.testSelectedCollections-seed#[EEDDBCA603505B6C]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=950307307 [junit4] 2> 88229 INFO (TEST-ComputePlanActionTest.testSelectedCollections-seed#[EEDDBCA603505B6C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 88229 INFO (TEST-ComputePlanActionTest.testSelectedCollections-seed#[EEDDBCA603505B6C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@9c3a7e0: rootName = solr_46135, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@3b0b5403 [junit4] 2> 88237 INFO (TEST-ComputePlanActionTest.testSelectedCollections-seed#[EEDDBCA603505B6C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 88237 INFO (TEST-ComputePlanActionTest.testSelectedCollections-seed#[EEDDBCA603505B6C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@40f3ccd8: rootName = solr_46135, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@b624d3b [junit4] 2> 88242 INFO (TEST-ComputePlanActionTest.testSelectedCollections-seed#[EEDDBCA603505B6C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 88242 INFO (TEST-ComputePlanActionTest.testSelectedCollections-seed#[EEDDBCA603505B6C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@60032ebf: rootName = solr_46135, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@20816998 [junit4] 2> 88244 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:46135_solr"} current state version: 0 [junit4] 2> 88244 DEBUG (OverseerStateUpdate-72172079473754115-127.0.0.1:35341_solr-n_0000000000) [n:127.0.0.1:35341_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:46135_solr [junit4] 2> 88244 INFO (TEST-ComputePlanActionTest.testSelectedCollections-seed#[EEDDBCA603505B6C]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:46135_solr [junit4] 2> 88245 INFO (zkCallback-163-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 88245 INFO (zkCallback-155-thread-2-processing-n:127.0.0.1:35341_solr) [n:127.0.0.1:35341_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 88245 INFO (zkCallback-163-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 88245 INFO (TEST-ComputePlanActionTest.testSelectedCollections-seed#[EEDDBCA603505B6C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 88246 INFO (zkCallback-172-thread-1-processing-n:127.0.0.1:46135_solr) [n:127.0.0.1:46135_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 88246 INFO (TEST-ComputePlanActionTest.testSelectedCollections-seed#[EEDDBCA603505B6C]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 88247 INFO (TEST-ComputePlanActionTest.testSelectedCollections-seed#[EEDDBCA603505B6C]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@54210b81{/solr,null,UNAVAILABLE} [junit4] 2> 88247 INFO (TEST-ComputePlanActionTest.testSelectedCollections-seed#[EEDDBCA603505B6C]) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 88247 INFO (TEST-ComputePlanActionTest.testSelectedCollections-seed#[EEDDBCA603505B6C]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 88248 INFO (qtp1931108059-845) [n:127.0.0.1:35341_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=testNodeAdded&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 88251 INFO (OverseerThreadFactory-455-thread-2-processing-n:127.0.0.1:35341_solr) [n:127.0.0.1:35341_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true, asyncId=null [junit [...truncated too long message...] A603505B6C]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7d9c7b56{/,null,UNAVAILABLE} [junit4] 2> 1827415 INFO (TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[EEDDBCA603505B6C]) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 1827416 INFO (TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[EEDDBCA603505B6C]) [ ] o.a.s.c.ChaosMonkey monkey: stop jetty! 46803 [junit4] 2> 1827416 ERROR (TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[EEDDBCA603505B6C]) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes [junit4] 2> 1827416 INFO (TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[EEDDBCA603505B6C]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:40331 40331 [junit4] 2> 1832927 INFO (Thread-3841) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:40331 40331 [junit4] 2> 1832928 WARN (Thread-3841) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 6 /solr/aliases.json [junit4] 2> 5 /solr/collections/forceleader_last_published/terms/shard1 [junit4] 2> 5 /solr/collections/collection1/terms/shard2 [junit4] 2> 4 /solr/security.json [junit4] 2> 4 /solr/configs/conf1 [junit4] 2> 2 /solr/collections/collection1/terms/shard1 [junit4] 2> 2 /solr/collections/control_collection/terms/shard1 [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 6 /solr/clusterprops.json [junit4] 2> 6 /solr/clusterstate.json [junit4] 2> 4 /solr/autoscaling.json [junit4] 2> 3 /solr/collections/forceleader_last_published/state.json [junit4] 2> 3 /solr/collections/collection1/state.json [junit4] 2> 2 /solr/collections/forceleader_last_published/leader_elect/shard1/election/72172189566173193-core_node62-n_0000000000 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 6 /solr/live_nodes [junit4] 2> 6 /solr/collections [junit4] 2> 4 /solr/overseer/queue [junit4] 2> 4 /solr/autoscaling/events/.auto_add_replicas [junit4] 2> 4 /solr/overseer/queue-work [junit4] 2> 4 /solr/overseer/collection-queue-work [junit4] 2> [junit4] 2> 1832928 WARN (TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[EEDDBCA603505B6C]) [ ] o.a.s.c.SocketProxy Closing 5 connections to: http://127.0.0.1:46803/, target: http://127.0.0.1:39359/ [junit4] 2> 1832928 WARN (TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[EEDDBCA603505B6C]) [ ] o.a.s.c.SocketProxy Closing 4 connections to: http://127.0.0.1:34533/, target: http://127.0.0.1:38273/ [junit4] 2> 1832928 WARN (TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[EEDDBCA603505B6C]) [ ] o.a.s.c.SocketProxy Closing 1 connections to: http://127.0.0.1:38595/, target: http://127.0.0.1:43771/ [junit4] 2> 1832928 WARN (TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[EEDDBCA603505B6C]) [ ] o.a.s.c.SocketProxy Closing 12 connections to: http://127.0.0.1:36597/, target: http://127.0.0.1:45429/ [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ForceLeaderTest_EEDDBCA603505B6C-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {range_facet_l_dv=Lucene50(blocksize=128), multiDefault=PostingsFormat(name=LuceneVarGapDocFreqInterval), a_t=PostingsFormat(name=LuceneVarGapDocFreqInterval), intDefault=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), id_i1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), id=Lucene50(blocksize=128), range_facet_i_dv=PostingsFormat(name=LuceneVarGapDocFreqInterval), text=PostingsFormat(name=LuceneVarGapFixedInterval), range_facet_l=PostingsFormat(name=LuceneVarGapDocFreqInterval), timestamp=PostingsFormat(name=LuceneVarGapDocFreqInterval)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Direct), _version_=DocValuesFormat(name=Memory), intDefault=DocValuesFormat(name=Memory), id_i1=DocValuesFormat(name=Memory), range_facet_i_dv=DocValuesFormat(name=Lucene70), intDvoDefault=DocValuesFormat(name=Direct), range_facet_l=DocValuesFormat(name=Lucene70), timestamp=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=1696, maxMBSortInHeap=5.004990774801847, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@7a68e4dc), locale=eu-ES, timezone=Europe/Zagreb [junit4] 2> NOTE: Linux 4.13.0-32-generic amd64/Oracle Corporation 9.0.4 (64-bit)/cpus=8,threads=1,free=129134672,total=518979584 [junit4] 2> NOTE: All tests run in this JVM: [LegacyCloudClusterPropTest, AnalyticsQueryTest, DateFieldTest, LukeRequestHandlerTest, HttpTriggerListenerTest, TestReloadDeadlock, MoreLikeThisHandlerTest, DistributedFacetPivotSmallTest, ResponseLogComponentTest, AnalysisAfterCoreReloadTest, TestInfoStreamLogging, SolrJmxReporterTest, TestRealTimeGet, BitVectorTest, TestChildDocTransformer, PropertiesRequestHandlerTest, TestFieldResource, DistributedFacetPivotLargeTest, SolrMetricManagerTest, ComputePlanActionTest, ReplaceNodeNoTargetTest, TestValueSourceCache, TestRandomRequestDistribution, TestCloudPivotFacet, DistributedSpellCheckComponentTest, BasicDistributedZk2Test, ReturnFieldsTest, TestFaceting, TestConfigSets, SpatialHeatmapFacetsTest, PreAnalyzedFieldTest, SolrGangliaReporterTest, TestQueryTypes, UniqFieldsUpdateProcessorFactoryTest, ZkFailoverTest, TestConfigSetsAPIExclusivity, AtomicUpdateProcessorFactoryTest, DocExpirationUpdateProcessorFactoryTest, BlockCacheTest, TestGenericDistributedQueue, CoreSorterTest, CollectionsAPISolrJTest, TestSQLHandlerNonCloud, TestSolrCoreProperties, TestImpersonationWithHadoopAuth, TestCodecSupport, TestSmileRequest, HdfsDirectoryTest, MultiTermTest, DocValuesMultiTest, TestUninvertingReader, SearchHandlerTest, ClusterStateUpdateTest, SpellCheckComponentTest, PreAnalyzedUpdateProcessorTest, TestCoreContainer, RestartWhileUpdatingTest, CollectionsAPIDistributedZkTest, TestCollationFieldDocValues, ZkStateReaderTest, TestManagedSchemaAPI, DateMathParserTest, AliasIntegrationTest, TestLegacyField, CopyFieldTest, URLClassifyProcessorTest, SpatialFilterTest, TestManagedStopFilterFactory, TestNumericRangeQuery64, TestDistributedStatsComponentCardinality, HdfsChaosMonkeySafeLeaderTest, ClassificationUpdateProcessorIntegrationTest, NotRequiredUniqueKeyTest, TestFileDictionaryLookup, TestPerFieldSimilarityWithDefaultOverride, TestFieldSortValues, TestUtils, TestDFRSimilarityFactory, TestComplexPhraseLeadingWildcard, HttpPartitionTest, TestAtomicUpdateErrorCases, DistribCursorPagingTest, SuggesterTSTTest, DeleteInactiveReplicaTest, CacheHeaderTest, TestCloudDeleteByQuery, TestQuerySenderListener, TestSubQueryTransformerDistrib, HLLSerializationTest, SearchHandlerTest, CdcrUpdateLogTest, SolrShardReporterTest, TestPullReplica, PingRequestHandlerTest, TestJsonRequest, UnloadDistributedZkTest, CoreMergeIndexesAdminHandlerTest, TestClassicSimilarityFactory, TestDistribIDF, TestFieldCacheSortRandom, TestSolrConfigHandler, TestRandomDVFaceting, TestMacroExpander, TestRawTransformer, TestSuggestSpellingConverter, SolrCloudExampleTest, TestUpdate, TestSolrDeletionPolicy2, TestAnalyzedSuggestions, TestFiltering, TestJmxIntegration, TestLegacyTerms, DirectSolrSpellCheckerTest, RuleEngineTest, TestDistributedMissingSort, TestTolerantSearch, ConnectionManagerTest, NoCacheHeaderTest, DistributedFacetSimpleRefinementLongTailTest, HdfsCollectionsAPIDistributedZkTest, DistanceFunctionTest, TestHashQParserPlugin, TestNestedDocsSort, SaslZkACLProviderTest, LeaderFailoverAfterPartitionTest, TestJavabinTupleStreamParser, TestMinMaxOnMultiValuedField, SolrCmdDistributorTest, QueryElevationComponentTest, ConvertedLegacyTest, TestFunctionQuery, TestLazyCores, TestBadConfig, ShowFileRequestHandlerTest, TestCoreDiscovery, CoreAdminHandlerTest, TestFoldingMultitermQuery, DocValuesTest, SuggesterTest, PolyFieldTest, WordBreakSolrSpellCheckerTest, SolrCoreCheckLockOnStartupTest, StatelessScriptUpdateProcessorFactoryTest, TestRemoteStreaming, TestSolrDeletionPolicy1, SolrInfoBeanTest, XsltUpdateRequestHandlerTest, DisMaxRequestHandlerTest, TestWriterPerf, TestReversedWildcardFilterFactory, DocumentAnalysisRequestHandlerTest, FileBasedSpellCheckerTest, TermsComponentTest, XmlUpdateRequestHandlerTest, DocumentBuilderTest, PathHierarchyTokenizerFactoryTest, TermVectorComponentTest, TestIndexingPerformance, RequiredFieldsTest, FieldAnalysisRequestHandlerTest, TestSolrQueryParser, IndexSchemaRuntimeFieldTest, RegexBoostProcessorTest, SolrPluginUtilsTest, TestCollationField, TestCSVResponseWriter, MBeansHandlerTest, QueryParsingTest, TestPHPSerializedResponseWriter, CSVRequestHandlerTest, TestComponentsName, TestLFUCache, UpdateParamsTest, BadComponentTest, TestStressRecovery, SampleTest, TestBinaryField, NumericFieldsTest, MinimalSchemaTest, TestElisionMultitermQuery, OutputWriterTest, TestPostingsSolrHighlighter, DirectSolrConnectionTest, TestLuceneMatchVersion, TestPhraseSuggestions, SpellPossibilityIteratorTest, TestXIncludeConfig, EchoParamsTest, TestSweetSpotSimilarityFactory, TestPerFieldSimilarity, TestLMDirichletSimilarityFactory, TestFastWriter, OpenExchangeRatesOrgProviderTest, TestSolrJ, SliceStateTest, UUIDFieldTest, SolrTestCaseJ4Test, TestCrossCoreJoin, TestSimpleTrackingShardHandler, ActionThrottleTest, AssignBackwardCompatibilityTest, CleanupOldIndexTest, ConcurrentCreateRoutedAliasTest, CreateRoutedAliasTest, DeleteReplicaTest, DistributedVersionInfoTest, ForceLeaderTest] [junit4] Completed [447/778 (2!)] on J2 in 160.30s, 4 tests, 1 failure, 1 skipped <<< FAILURES! [...truncated 42301 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org
