Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.5-MacOSX/60/
Java: 64bit/jdk-14 -XX:-UseCompressedOops -XX:+UseG1GC
2 tests failed.
FAILED: org.apache.solr.cloud.SystemCollectionCompatTest.testBackCompat
Error Message:
re-indexing warning not found
Stack Trace:
java.lang.AssertionError: re-indexing warning not found
at
__randomizedtesting.SeedInfo.seed([22A3F8A7030506DC:52565B0E63CDAFAA]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at
org.apache.solr.cloud.SystemCollectionCompatTest.testBackCompat(SystemCollectionCompatTest.java:215)
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:1750)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.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:947)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.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:832)
FAILED:
org.apache.solr.cloud.autoscaling.ComputePlanActionTest.testSelectedCollections
Error Message:
Trigger was not fired even after 5 seconds
Stack Trace:
java.lang.AssertionError: Trigger was not fired even after 5 seconds
at
__randomizedtesting.SeedInfo.seed([22A3F8A7030506DC:180D1D7E3D61DFB2]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at
org.apache.solr.cloud.autoscaling.ComputePlanActionTest.testSelectedCollections(ComputePlanActionTest.java:507)
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:1750)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.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:947)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.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:832)
Build Log:
[...truncated 14230 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.ComputePlanActionTest
[junit4] 2> 815245 INFO
(SUITE-ComputePlanActionTest-seed#[22A3F8A7030506DC]-worker) [ ]
o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to
test-framework derived value of
'/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/server/solr/configsets/_default/conf'
[junit4] 2> 815247 INFO
(SUITE-ComputePlanActionTest-seed#[22A3F8A7030506DC]-worker) [ ]
o.a.s.SolrTestCaseJ4 Created dataDir:
/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.autoscaling.ComputePlanActionTest_22A3F8A7030506DC-001/data-dir-64-001
[junit4] 2> 815247 WARN
(SUITE-ComputePlanActionTest-seed#[22A3F8A7030506DC]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 815247 INFO
(SUITE-ComputePlanActionTest-seed#[22A3F8A7030506DC]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 815247 INFO
(SUITE-ComputePlanActionTest-seed#[22A3F8A7030506DC]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0,
clientAuth=0.0/0.0) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 815247 INFO
(SUITE-ComputePlanActionTest-seed#[22A3F8A7030506DC]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 815248 INFO
(SUITE-ComputePlanActionTest-seed#[22A3F8A7030506DC]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in
/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.autoscaling.ComputePlanActionTest_22A3F8A7030506DC-001/tempDir-001
[junit4] 2> 815248 INFO
(SUITE-ComputePlanActionTest-seed#[22A3F8A7030506DC]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 815249 INFO (ZkTestServer Run Thread) [ ]
o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 815249 INFO (ZkTestServer Run Thread) [ ]
o.a.s.c.ZkTestServer Starting server
[junit4] 2> 815353 INFO
(SUITE-ComputePlanActionTest-seed#[22A3F8A7030506DC]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:52699
[junit4] 2> 815353 INFO
(SUITE-ComputePlanActionTest-seed#[22A3F8A7030506DC]-worker) [ ]
o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:52699
[junit4] 2> 815353 INFO
(SUITE-ComputePlanActionTest-seed#[22A3F8A7030506DC]-worker) [ ]
o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:52699
[junit4] 2> 815353 INFO
(SUITE-ComputePlanActionTest-seed#[22A3F8A7030506DC]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1 52699
[junit4] 2> 815357 INFO
(SUITE-ComputePlanActionTest-seed#[22A3F8A7030506DC]-worker) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 815363 INFO (zkConnectionManagerCallback-3776-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 815363 INFO
(SUITE-ComputePlanActionTest-seed#[22A3F8A7030506DC]-worker) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 815369 INFO
(SUITE-ComputePlanActionTest-seed#[22A3F8A7030506DC]-worker) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 815374 INFO (zkConnectionManagerCallback-3778-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 815375 INFO
(SUITE-ComputePlanActionTest-seed#[22A3F8A7030506DC]-worker) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 815376 INFO
(SUITE-ComputePlanActionTest-seed#[22A3F8A7030506DC]-worker) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 815380 INFO (zkConnectionManagerCallback-3780-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 815380 INFO
(SUITE-ComputePlanActionTest-seed#[22A3F8A7030506DC]-worker) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 815490 WARN (jetty-launcher-3781-thread-1) [ ]
o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content,
break even is size 23
[junit4] 2> 815490 INFO (jetty-launcher-3781-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 815490 INFO (jetty-launcher-3781-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 815490 INFO (jetty-launcher-3781-thread-1) [ ]
o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git:
363d5f2df3a8a28de40604320230664b9c793c16; jvm 14+36
[junit4] 2> 815493 INFO (jetty-launcher-3781-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 815493 INFO (jetty-launcher-3781-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 815493 INFO (jetty-launcher-3781-thread-1) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 815493 INFO (jetty-launcher-3781-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@69c3f93a{/solr,null,AVAILABLE}
[junit4] 2> 815494 INFO (jetty-launcher-3781-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@59b72286{HTTP/1.1,[http/1.1,
h2c]}{127.0.0.1:52704}
[junit4] 2> 815494 INFO (jetty-launcher-3781-thread-1) [ ]
o.e.j.s.Server Started @815572ms
[junit4] 2> 815494 INFO (jetty-launcher-3781-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=52704}
[junit4] 2> 815494 ERROR (jetty-launcher-3781-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 815494 INFO (jetty-launcher-3781-thread-1) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 815494 INFO (jetty-launcher-3781-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.5.1
[junit4] 2> 815494 INFO (jetty-launcher-3781-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 815494 INFO (jetty-launcher-3781-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir:
/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr
[junit4] 2> 815494 INFO (jetty-launcher-3781-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2020-04-14T03:36:24.970936Z
[junit4] 2> 815496 INFO (jetty-launcher-3781-thread-1) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 815500 INFO (zkConnectionManagerCallback-3783-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 815500 INFO (jetty-launcher-3781-thread-1) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 815501 INFO (jetty-launcher-3781-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 815734 INFO (jetty-launcher-3781-thread-1) [ ]
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 815735 WARN (jetty-launcher-3781-thread-1) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@7c8477ab[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 815735 WARN (jetty-launcher-3781-thread-1) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@7c8477ab[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 815738 WARN (jetty-launcher-3781-thread-1) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@6c02beb4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 815738 WARN (jetty-launcher-3781-thread-1) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@6c02beb4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 815739 INFO (jetty-launcher-3781-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52699/solr
[junit4] 2> 815747 INFO (jetty-launcher-3781-thread-1) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 815749 INFO (zkConnectionManagerCallback-3790-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 815751 INFO (jetty-launcher-3781-thread-1) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 815868 INFO (jetty-launcher-3781-thread-1)
[n:127.0.0.1:52704_solr ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 815871 INFO (zkConnectionManagerCallback-3792-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 815872 INFO (jetty-launcher-3781-thread-1)
[n:127.0.0.1:52704_solr ] o.a.s.c.c.ConnectionManager Client is connected
to ZooKeeper
[junit4] 2> 816238 INFO (jetty-launcher-3781-thread-1)
[n:127.0.0.1:52704_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:52704_solr
[junit4] 2> 816241 INFO (jetty-launcher-3781-thread-1)
[n:127.0.0.1:52704_solr ] o.a.s.c.Overseer Overseer
(id=72157482958651397-127.0.0.1:52704_solr-n_0000000000) starting
[junit4] 2> 816266 INFO
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:52704_solr
[junit4] 2> 816269 DEBUG
(OverseerAutoScalingTriggerThread-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 816269 INFO (jetty-launcher-3781-thread-1)
[n:127.0.0.1:52704_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:52704_solr
[junit4] 2> 816283 DEBUG
(OverseerAutoScalingTriggerThread-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with
znode version 1
[junit4] 2> 816283 DEBUG
(OverseerAutoScalingTriggerThread-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1,
lastZnodeVersion 0
[junit4] 2> 816283 DEBUG
(OverseerAutoScalingTriggerThread-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 1
[junit4] 2> 816302 INFO (zkCallback-3791-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 816361 INFO (jetty-launcher-3781-thread-1)
[n:127.0.0.1:52704_solr ] o.a.s.p.PackageLoader /packages.json updated to
version -1
[junit4] 2> 816368 WARN (jetty-launcher-3781-thread-1)
[n:127.0.0.1:52704_solr ] o.a.s.c.CoreContainer Not all security plugins
configured! authentication=disabled authorization=disabled. Solr is only as
secure as you make it. Consider configuring authentication/authorization before
exposing Solr to users internal or external. See
https://s.apache.org/solrsecurity for more info
[junit4] 2> 816378 DEBUG
(OverseerAutoScalingTriggerThread-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial
livenodes: [127.0.0.1:52704_solr]
[junit4] 2> 816383 DEBUG
(OverseerAutoScalingTriggerThread-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost /
nodeAdded markers
[junit4] 2> 816386 DEBUG (ScheduledTrigger-2368-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1 and last live nodes: 1
[junit4] 2> 816387 DEBUG
(OverseerAutoScalingTriggerThread-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1,
lastZnodeVersion 1
[junit4] 2> 816412 INFO (jetty-launcher-3781-thread-1)
[n:127.0.0.1:52704_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 816443 INFO (jetty-launcher-3781-thread-1)
[n:127.0.0.1:52704_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_52704.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7abd9fe5
[junit4] 2> 816461 INFO (jetty-launcher-3781-thread-1)
[n:127.0.0.1:52704_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_52704.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7abd9fe5
[junit4] 2> 816461 INFO (jetty-launcher-3781-thread-1)
[n:127.0.0.1:52704_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_52704.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7abd9fe5
[junit4] 2> 816462 INFO (jetty-launcher-3781-thread-1)
[n:127.0.0.1:52704_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.autoscaling.ComputePlanActionTest_22A3F8A7030506DC-001/tempDir-001/node1/.
[junit4] 2> 816479 INFO
(SUITE-ComputePlanActionTest-seed#[22A3F8A7030506DC]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=1
[junit4] 2> 816480 INFO
(SUITE-ComputePlanActionTest-seed#[22A3F8A7030506DC]-worker) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 816485 INFO (zkConnectionManagerCallback-3801-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 816485 INFO
(SUITE-ComputePlanActionTest-seed#[22A3F8A7030506DC]-worker) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 816494 INFO
(SUITE-ComputePlanActionTest-seed#[22A3F8A7030506DC]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 816499 INFO
(SUITE-ComputePlanActionTest-seed#[22A3F8A7030506DC]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:52699/solr ready
[junit4] 2> 816553 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testNodeAdded
[junit4] 2> 816555 DEBUG (zkCallback-3791-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
2
[junit4] 2> 816555 DEBUG
(OverseerAutoScalingTriggerThread-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 2
[junit4] 2> 816561 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 816564 DEBUG
(OverseerAutoScalingTriggerThread-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost /
nodeAdded markers
[junit4] 2> 816571 DEBUG
(OverseerAutoScalingTriggerThread-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2,
lastZnodeVersion 2
[junit4] 2> 816587 INFO (qtp1467008536-9744) [n:127.0.0.1:52704_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> 816589 DEBUG (qtp1467008536-9742) [n:127.0.0.1:52704_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 816590 INFO (qtp1467008536-9742) [n:127.0.0.1:52704_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=15
[junit4] 2> 816590 DEBUG (zkCallback-3791-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
3
[junit4] 2> 816591 DEBUG
(OverseerAutoScalingTriggerThread-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 3
[junit4] 2> 816591 DEBUG
(OverseerAutoScalingTriggerThread-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost /
nodeAdded markers
[junit4] 2> 816592 DEBUG
(OverseerAutoScalingTriggerThread-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3,
lastZnodeVersion 3
[junit4] 2> 816597 INFO (qtp1467008536-9743) [n:127.0.0.1:52704_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> 816597 DEBUG (qtp1467008536-9741) [n:127.0.0.1:52704_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 816599 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=7
[junit4] 2> 816600 DEBUG (zkCallback-3791-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
4
[junit4] 2> 816600 DEBUG
(OverseerAutoScalingTriggerThread-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 4
[junit4] 2> 816600 DEBUG
(OverseerAutoScalingTriggerThread-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost /
nodeAdded markers
[junit4] 2> 816603 DEBUG
(OverseerAutoScalingTriggerThread-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4,
lastZnodeVersion 4
[junit4] 2> 816617 INFO (qtp1467008536-9742) [n:127.0.0.1:52704_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=1
[junit4] 2> 816618 DEBUG (qtp1467008536-9744) [n:127.0.0.1:52704_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 816621 INFO (qtp1467008536-9744) [n:127.0.0.1:52704_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=15
[junit4] 2> 816623 DEBUG (zkCallback-3791-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
5
[junit4] 2> 816623 DEBUG
(OverseerAutoScalingTriggerThread-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 5
[junit4] 2> 816630 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_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> 816631 DEBUG (qtp1467008536-9743) [n:127.0.0.1:52704_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 816634 INFO (qtp1467008536-9743) [n:127.0.0.1:52704_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=11
[junit4] 2> 816636 INFO (qtp1467008536-9742) [n:127.0.0.1:52704_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
collection.configName=conf&maxShardsPerNode=2&name=testNodeAdded&nrtReplicas=2&action=CREATE&numShards=1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 816637 DEBUG (zkCallback-3791-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
6
[junit4] 2> 816644 DEBUG
(OverseerAutoScalingTriggerThread-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger -
Initial livenodes: [127.0.0.1:52704_solr]
[junit4] 2> 816644 DEBUG
(OverseerAutoScalingTriggerThread-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[ ] 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> 816645 DEBUG
(OverseerAutoScalingTriggerThread-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost /
nodeAdded markers
[junit4] 2> 816649 DEBUG
(OverseerAutoScalingTriggerThread-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6,
lastZnodeVersion 5
[junit4] 2> 816649 DEBUG
(OverseerAutoScalingTriggerThread-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 6
[junit4] 2> 816650 DEBUG (ScheduledTrigger-2368-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 816650 DEBUG (ScheduledTrigger-2368-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 816652 DEBUG
(OverseerAutoScalingTriggerThread-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost /
nodeAdded markers
[junit4] 2> 816655 DEBUG
(OverseerAutoScalingTriggerThread-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6,
lastZnodeVersion 6
[junit4] 2> 816668 INFO
(OverseerThreadFactory-2370-thread-1-processing-n:127.0.0.1:52704_solr)
[n:127.0.0.1:52704_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection
testNodeAdded
[junit4] 2> 816716 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "name":"testNodeAdded",
[junit4] 2> "fromApi":"true",
[junit4] 2> "collection.configName":"conf",
[junit4] 2> "numShards":"1",
[junit4] 2> "maxShardsPerNode":"2",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 816716 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_solr ] o.a.s.c.o.ClusterStateMutator building a new
cName: testNodeAdded
[junit4] 2> 816719 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_solr ] o.a.s.c.o.ZkStateWriter going to
create_collection /collections/testNodeAdded/state.json
[junit4] 2> 816829 WARN
(OverseerThreadFactory-2370-thread-1-processing-n:127.0.0.1:52704_solr)
[n:127.0.0.1:52704_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> 816847 INFO (qtp1467008536-9744) [n:127.0.0.1:52704_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=1
[junit4] 2> 816877 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_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":"http://127.0.0.1:52704/solr";,
[junit4] 2> "node_name":"127.0.0.1:52704_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 816877 INFO
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_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":"http://127.0.0.1:52704/solr";,
[junit4] 2> "node_name":"127.0.0.1:52704_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 816881 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 816881 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_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":"http://127.0.0.1:52704/solr";,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:52704_solr",
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 816888 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_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":"http://127.0.0.1:52704/solr";,
[junit4] 2> "node_name":"127.0.0.1:52704_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 816888 INFO
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_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":"http://127.0.0.1:52704/solr";,
[junit4] 2> "node_name":"127.0.0.1:52704_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 816893 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_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":"http://127.0.0.1:52704/solr";,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:52704_solr",
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 816893 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_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":"http://127.0.0.1:52704/solr";,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:52704_solr",
[junit4] 2> "type":"NRT"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "base_url":"http://127.0.0.1:52704/solr";,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:52704_solr",
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 816996 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_solr ] o.a.s.c.o.ZkStateWriter going to
update_collection /collections/testNodeAdded/state.json version: 0
[junit4] 2> 817109 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_solr
x:testNodeAdded_shard1_replica_n1 ] 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> 817110 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_solr
x:testNodeAdded_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault
Allocating transient cache for 2147483647 transient cores
[junit4] 2> 817111 INFO (qtp1467008536-9744) [n:127.0.0.1:52704_solr
x:testNodeAdded_shard1_replica_n2 ] 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> 817131 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_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":"http://127.0.0.1:52704/solr";,
[junit4] 2> "node_name":"127.0.0.1:52704_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 817131 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_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":"http://127.0.0.1:52704/solr";,
[junit4] 2> "node_name":"127.0.0.1:52704_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 817131 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_solr ] o.a.s.c.o.ReplicaMutator Will update state for
replica: core_node3:{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:52704/solr";,
[junit4] 2> "node_name":"127.0.0.1:52704_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}
[junit4] 2> 817131 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_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":"2",
[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":"http://127.0.0.1:52704/solr";,
[junit4] 2> "node_name":"127.0.0.1:52704_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "base_url":"http://127.0.0.1:52704/solr";,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:52704_solr",
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 817145 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_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":"http://127.0.0.1:52704/solr";,
[junit4] 2> "node_name":"127.0.0.1:52704_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 817145 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_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":"http://127.0.0.1:52704/solr";,
[junit4] 2> "node_name":"127.0.0.1:52704_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 817145 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_solr ] o.a.s.c.o.ReplicaMutator Will update state for
replica: core_node4:{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "base_url":"http://127.0.0.1:52704/solr";,
[junit4] 2> "node_name":"127.0.0.1:52704_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}
[junit4] 2> 817145 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_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":"2",
[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":"http://127.0.0.1:52704/solr";,
[junit4] 2> "node_name":"127.0.0.1:52704_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "base_url":"http://127.0.0.1:52704/solr";,
[junit4] 2> "node_name":"127.0.0.1:52704_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}}}}
[junit4] 2> 817170 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1 ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.5.1
[junit4] 2> 817172 INFO (qtp1467008536-9744) [n:127.0.0.1:52704_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2 ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.5.1
[junit4] 2> 817198 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_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> 817201 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_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> 817201 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_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 configset conf, trusted=true
[junit4] 2> 817201 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1 ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_52704.solr.core.testNodeAdded.shard1.replica_n1' (registry
'solr.core.testNodeAdded.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7abd9fe5
[junit4] 2> 817202 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_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
[/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.autoscaling.ComputePlanActionTest_22A3F8A7030506DC-001/tempDir-001/node1/testNodeAdded_shard1_replica_n1],
dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.autoscaling.ComputePlanActionTest_22A3F8A7030506DC-001/tempDir-001/node1/./testNodeAdded_shard1_replica_n1/data/]
[junit4] 2> 817203 INFO (qtp1467008536-9744) [n:127.0.0.1:52704_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> 817205 INFO (qtp1467008536-9744) [n:127.0.0.1:52704_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> 817205 INFO (qtp1467008536-9744) [n:127.0.0.1:52704_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 configset conf, trusted=true
[junit4] 2> 817205 INFO (qtp1467008536-9744) [n:127.0.0.1:52704_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2 ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_52704.solr.core.testNodeAdded.shard1.replica_n2' (registry
'solr.core.testNodeAdded.shard1.replica_n2') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7abd9fe5
[junit4] 2> 817206 INFO (qtp1467008536-9744) [n:127.0.0.1:52704_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
[/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.autoscaling.ComputePlanActionTest_22A3F8A7030506DC-001/tempDir-001/node1/testNodeAdded_shard1_replica_n2],
dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.autoscaling.ComputePlanActionTest_22A3F8A7030506DC-001/tempDir-001/node1/./testNodeAdded_shard1_replica_n2/data/]
[junit4] 2> 817248 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_solr ] o.a.s.c.o.ZkStateWriter going to
update_collection /collections/testNodeAdded/state.json version: 1
[junit4] 2> 817317 INFO (qtp1467008536-9744) [n:127.0.0.1:52704_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> 817317 INFO (qtp1467008536-9744) [n:127.0.0.1:52704_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> 817348 INFO (qtp1467008536-9744) [n:127.0.0.1:52704_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2 ]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 817355 INFO (qtp1467008536-9744) [n:127.0.0.1:52704_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2 ]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 817362 INFO (qtp1467008536-9744) [n:127.0.0.1:52704_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2 ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@4b7fa283[testNodeAdded_shard1_replica_n2] main]
[junit4] 2> 817367 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_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> 817367 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_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> 817371 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1 ]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 817372 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1 ]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 817385 INFO (qtp1467008536-9744) [n:127.0.0.1:52704_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> 817392 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1 ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@2ee0f3f5[testNodeAdded_shard1_replica_n1] main]
[junit4] 2> 817392 INFO (qtp1467008536-9744) [n:127.0.0.1:52704_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> 817393 INFO (qtp1467008536-9744) [n:127.0.0.1:52704_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> 817393 INFO (qtp1467008536-9744) [n:127.0.0.1:52704_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 1663917502621548544
[junit4] 2> 817412 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_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> 817441 INFO
(searcherExecutor-2376-thread-1-processing-n:127.0.0.1:52704_solr
x:testNodeAdded_shard1_replica_n2 c:testNodeAdded s:shard1 r:core_node4)
[n:127.0.0.1:52704_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@4b7fa283[testNodeAdded_shard1_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 817445 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_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> 817445 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_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> 817445 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_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 1663917502676074496
[junit4] 2> 817449 INFO
(searcherExecutor-2375-thread-1-processing-n:127.0.0.1:52704_solr
x:testNodeAdded_shard1_replica_n1 c:testNodeAdded s:shard1 r:core_node3)
[n:127.0.0.1:52704_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@2ee0f3f5[testNodeAdded_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 817490 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1 ]
o.a.s.c.ZkShardTerms Successful update of terms at
/collections/testNodeAdded/terms/shard1 to Terms{values={core_node3=0},
version=0}
[junit4] 2> 817492 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1 ]
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created
/collections/testNodeAdded/leaders/shard1
[junit4] 2> 817494 INFO (qtp1467008536-9744) [n:127.0.0.1:52704_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2 ]
o.a.s.c.ZkShardTerms Successful update of terms at
/collections/testNodeAdded/terms/shard1 to Terms{values={core_node3=0,
core_node4=0}, version=1}
[junit4] 2> 817496 INFO (qtp1467008536-9744) [n:127.0.0.1:52704_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2 ]
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created
/collections/testNodeAdded/leaders/shard1
[junit4] 2> 817537 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_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> 817537 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_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> 817537 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_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> 817537 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1 ]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:52704/solr/testNodeAdded_shard1_replica_n1/
[junit4] 2> 817542 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_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=http://127.0.0.1:52704/solr START
replicas=[http://127.0.0.1:52704/solr/testNodeAdded_shard1_replica_n2/]
nUpdates=100
[junit4] 2> 817543 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_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=http://127.0.0.1:52704/solr DONE. We have no versions. sync failed.
[junit4] 2> 817546 INFO (qtp1467008536-9743) [n:127.0.0.1:52704_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=1
[junit4] 2> 817547 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_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> 817547 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_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> 817547 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1 ]
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node
/collections/testNodeAdded/leaders/shard1/leader after winning as
/collections/testNodeAdded/leader_elect/shard1/election/72157482958651397-core_node3-n_0000000000
[junit4] 2> 817568 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1 ]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:52704/solr/testNodeAdded_shard1_replica_n1/ shard1
[junit4] 2> 817570 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_solr ] o.a.s.c.Overseer processMessage: queueSize: 2,
message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "base_url":"http://127.0.0.1:52704/solr";,
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "state":"active"} current state version: 0
[junit4] 2> 817657 DEBUG (ScheduledTrigger-2368-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 817658 DEBUG (ScheduledTrigger-2368-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 817674 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_solr ] o.a.s.c.o.ZkStateWriter going to
update_collection /collections/testNodeAdded/state.json version: 2
[junit4] 2> 817676 INFO (zkCallback-3791-thread-1) [ ]
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> 817676 INFO (zkCallback-3791-thread-2) [ ]
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> 817677 INFO (zkCallback-3791-thread-3) [ ]
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> 817683 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_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> 817696 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_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":"http://127.0.0.1:52704/solr";,
[junit4] 2> "node_name":"127.0.0.1:52704_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 817697 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_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":"http://127.0.0.1:52704/solr";,
[junit4] 2> "node_name":"127.0.0.1:52704_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 817697 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_solr ] o.a.s.c.o.ReplicaMutator Will update state for
replica: core_node3:{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:52704/solr";,
[junit4] 2> "node_name":"127.0.0.1:52704_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "leader":"true"}
[junit4] 2> 817697 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_solr ] o.a.s.c.o.ReplicaMutator Collection is now:
DocCollection(testNodeAdded//collections/testNodeAdded/state.json/3)={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"2",
[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":"http://127.0.0.1:52704/solr";,
[junit4] 2> "node_name":"127.0.0.1:52704_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "leader":"true"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "base_url":"http://127.0.0.1:52704/solr";,
[junit4] 2> "node_name":"127.0.0.1:52704_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}}}}
[junit4] 2> 817699 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_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=590
[junit4] 2> 817801 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_solr ] o.a.s.c.o.ZkStateWriter going to
update_collection /collections/testNodeAdded/state.json version: 3
[junit4] 2> 817802 INFO (zkCallback-3791-thread-3) [ ]
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> 817802 INFO (zkCallback-3791-thread-1) [ ]
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> 817802 INFO (zkCallback-3791-thread-2) [ ]
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> 817802 INFO (zkCallback-3791-thread-4) [ ]
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> 818548 INFO (qtp1467008536-9744) [n:127.0.0.1:52704_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=1436
[junit4] 2> 818550 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "core_node_name":"core_node4",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:52704/solr";,
[junit4] 2> "node_name":"127.0.0.1:52704_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 818550 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_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":"http://127.0.0.1:52704/solr";,
[junit4] 2> "node_name":"127.0.0.1:52704_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 818550 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_solr ] o.a.s.c.o.ReplicaMutator Will update state for
replica: core_node4:{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "base_url":"http://127.0.0.1:52704/solr";,
[junit4] 2> "node_name":"127.0.0.1:52704_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}
[junit4] 2> 818550 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_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":"2",
[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":"http://127.0.0.1:52704/solr";,
[junit4] 2> "node_name":"127.0.0.1:52704_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "leader":"true"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "base_url":"http://127.0.0.1:52704/solr";,
[junit4] 2> "node_name":"127.0.0.1:52704_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}}}}
[junit4] 2> 818552 INFO (qtp1467008536-9742) [n:127.0.0.1:52704_solr
] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
45 seconds. Check all shard replicas
[junit4] 2> 818652 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_solr ] o.a.s.c.o.ZkStateWriter going to
update_collection /collections/testNodeAdded/state.json version: 4
[junit4] 2> 818653 INFO (zkCallback-3791-thread-2) [ ]
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> 818653 INFO (zkCallback-3791-thread-4) [ ]
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> 818653 INFO (zkCallback-3791-thread-3) [ ]
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> 818653 INFO (zkCallback-3791-thread-1) [ ]
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> 818655 INFO (qtp1467008536-9742) [n:127.0.0.1:52704_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={collection.configName=conf&maxShardsPerNode=2&name=testNodeAdded&nrtReplicas=2&action=CREATE&numShards=1&wt=javabin&version=2}
status=0 QTime=2019
[junit4] 2> 818657 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC]) [ ]
o.a.s.c.SolrCloudTestCase waitForState (testNodeAdded): Timed out waiting for
replicas of new collection to be active
[junit4] 2> 818662 DEBUG (ScheduledTrigger-2368-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 818662 DEBUG (ScheduledTrigger-2368-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 818676 INFO
(OverseerCollectionConfigSetProcessor-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_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> 818690 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_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=8
[junit4] 2> 818694 INFO (qtp1467008536-9744) [n:127.0.0.1:52704_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> 818695 DEBUG (qtp1467008536-9743) [n:127.0.0.1:52704_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 818697 INFO (qtp1467008536-9743) [n:127.0.0.1:52704_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=20
[junit4] 2> 818698 WARN
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC]) [ ]
o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content,
break even is size 23
[junit4] 2> 818698 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC]) [ ]
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 818698 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC]) [ ]
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 818698 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC]) [ ]
o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git:
363d5f2df3a8a28de40604320230664b9c793c16; jvm 14+36
[junit4] 2> 818701 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 818701 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 818701 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC]) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 818701 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@3c9c47d8{/solr,null,AVAILABLE}
[junit4] 2> 818702 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC]) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@3ec0c5ba{HTTP/1.1,[http/1.1,
h2c]}{127.0.0.1:52721}
[junit4] 2> 818703 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC]) [ ]
o.e.j.s.Server Started @818780ms
[junit4] 2> 818703 DEBUG (zkCallback-3791-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
7
[junit4] 2> 818706 DEBUG
(OverseerAutoScalingTriggerThread-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 7
[junit4] 2> 818703 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=52721}
[junit4] 2> 818707 ERROR
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 818707 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC]) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 818707 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.5.1
[junit4] 2> 818707 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 818707 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir:
/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr
[junit4] 2> 818707 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2020-04-14T03:36:28.183744Z
[junit4] 2> 818717 DEBUG
(OverseerAutoScalingTriggerThread-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost /
nodeAdded markers
[junit4] 2> 818720 DEBUG
(OverseerAutoScalingTriggerThread-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 7,
lastZnodeVersion 7
[junit4] 2> 818722 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 818723 INFO (zkConnectionManagerCallback-3807-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 818723 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 818725 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC]) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 819100 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC]) [ ]
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 819104 WARN
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC]) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@61ea2544[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 819104 WARN
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC]) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@61ea2544[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 819112 WARN
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC]) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@68715501[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 819112 WARN
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC]) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@68715501[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 819115 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52699/solr
[junit4] 2> 819119 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 819122 INFO (zkConnectionManagerCallback-3814-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 819122 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 819234 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC])
[n:127.0.0.1:52721_solr ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 819236 INFO (zkConnectionManagerCallback-3816-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 819242 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC])
[n:127.0.0.1:52721_solr ] o.a.s.c.c.ConnectionManager Client is connected
to ZooKeeper
[junit4] 2> 819289 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC])
[n:127.0.0.1:52721_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 819337 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC])
[n:127.0.0.1:52721_solr ] o.a.s.c.ZkController Publish
node=127.0.0.1:52721_solr as DOWN
[junit4] 2> 819348 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC])
[n:127.0.0.1:52721_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 819349 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC])
[n:127.0.0.1:52721_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:52721_solr
[junit4] 2> 819356 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:52721_solr"} current state version: 0
[junit4] 2> 819356 DEBUG
(OverseerStateUpdate-72157482958651397-127.0.0.1:52704_solr-n_0000000000)
[n:127.0.0.1:52704_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for
node: 127.0.0.1:52721_solr
[junit4] 2> 819366 INFO (zkCallback-3800-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 819366 INFO (zkCallback-3791-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 819366 INFO (zkCallback-3800-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 819370 INFO (zkCallback-3815-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 819390 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC])
[n:127.0.0.1:52721_solr ] o.a.s.p.PackageLoader /packages.json updated to
version -1
[junit4] 2> 819393 WARN
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC])
[n:127.0.0.1:52721_solr ] o.a.s.c.CoreContainer Not all security plugins
configured! authentication=disabled authorization=disabled. Solr is only as
secure as you make it. Consider configuring authentication/authorization before
exposing Solr to users internal or external. See
https://s.apache.org/solrsecurity for more info
[junit4] 2> 819440 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC])
[n:127.0.0.1:52721_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 819491 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC])
[n:127.0.0.1:52721_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_52721.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7abd9fe5
[junit4] 2> 819507 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC])
[n:127.0.0.1:52721_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_52721.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7abd9fe5
[junit4] 2> 819508 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC])
[n:127.0.0.1:52721_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_52721.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7abd9fe5
[junit4] 2> 819508 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[22A3F8A7030506DC])
[n:127.0.0.1:52721_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.autoscaling.ComputePlanActionTest_22A3F8A7030506DC-001/tempDir-001/node2/.
[junit4] 2> 819664 DEBUG (ScheduledTrigger-2368-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 819664 DEBUG (ScheduledTrigger-2368-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 819664 DEBUG (ScheduledTrigger-2368-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:52721_solr at time
1524188383317704
[junit4] 2> 820680 DEBUG (ScheduledTrigger-2368-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 820680 DEBUG (ScheduledTrigger-2368-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 820680 DEBUG (ScheduledTrigger-2368-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger firing
registered processor for nodes: [127.0.0.1:52721_solr] added at times
[1524188383317704], now=1524189399443987
[junit4] 2> 820680 DEBUG (ScheduledTrigger-2368-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
[junit4] 2> "id":"56a3dc3288ec8T7m2mac4f6bfyj0xbkm6wg7fcm",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":1524188383317704,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[1524188383317704],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "nodeNames":["127.0.0.1:52721_solr"],
[junit4] 2> "replicaType":"NRT"}}
[junit4] 2> 820683 DEBUG (ScheduledTrigger-2368-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers Pausing all triggers: [node_added_trigger]
[junit4] 2> 820699 DEBUG (ScheduledTrigger-2368-thread-2) [ ]
o.a.s.c.a.SystemLogListener Collection .system missing, skip sending event {
[junit4] 2> "id":"56a3dc3288ec8T7m2mac4f6bfyj0xbkm6wg7fcm",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":1524188383317704,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[1524188383317704],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":1524189403327818,
[junit4] 2> "nodeNames":["127.0.0.1:52721_solr"],
[junit4] 2> "replicaType":"NRT"}}
[junit4] 2> 820701 DEBUG (ScheduledTrigger-2368-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Removing new node from tracking: 127.0.0.1:52721_solr
[junit4] 2> 820702 DEBUG (AutoscalingActionExecutor-2369-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -- processing actions for {
[junit4] 2> "id":"56a3dc3288ec8T7m2mac4f6bfyj0xbkm6wg7fcm",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":1524188383317704,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[1524188383317704],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":1524189403327818,
[junit4] 2> "nodeNames":["127.0.0.1:52721_solr"],
[junit4] 2> "replicaType":"NRT"}}
[junit4] 2> 820702 DEBUG (AutoscalingActionExecutor-2369-thread-1) [ ]
o.a.s.c.a.ComputePlanAction -- processing event: {
[junit4] 2> "id":"56a3dc3288ec8T7m2mac4f6bfyj0xbkm6wg7fcm",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":1524188383317704,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[1524188383317704],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":1524189403327818,
[junit4] 2> "nodeNames":["127.0.0.1:52721_solr"],
[junit4] 2> "replicaType":"NRT"}} with context properties:
{BEFORE_ACTION=[compute_plan]}
[junit4] 2> 820709 INFO (qtp1467008536-9742) [n:127.0.0.1:52704_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> 820716 INFO (qtp1467008536-9741) [n:127.0.0.1:52704_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=1
[junit4] 2> 820725 INFO (qtp228683874-9798) [n:127.0.0.1:52721_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=
[...truncated too long message...]
ard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Core core_node3 was interrupted
waiting for schema version 2 to propagate to 1 replicas for collection .system
[junit4] 2> 1850628 INFO (qtp115184546-25468) [n:127.0.0.1:61884_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ]
o.a.s.s.ManagedIndexSchema Took 200766.0ms for 1 replicas to apply schema
update version 2 for collection .system
[junit4] 2> 1850628 INFO (qtp115184546-25468) [n:127.0.0.1:61884_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request
[.system_shard1_replica_n1] webapp=/solr path=/schema
params={wt=javabin&version=2} status=0 QTime=200965
[junit4] 2> 1850628 INFO (qtp115184546-25468) [n:127.0.0.1:61884_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore
[.system_shard1_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@4b799400
[junit4] 2> 1850628 WARN (qtp115184546-25468) [n:127.0.0.1:61884_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ]
o.a.s.m.SolrMetricManager Interrupted while trying to obtain lock to modify
reporters registry: solr.core..system.shard1.replica_n1
[junit4] 2> 1850628 INFO (qtp115184546-25468) [n:127.0.0.1:61884_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection..system.shard1.leader, tag=SolrCore@4b799400
[junit4] 2> 1850629 INFO (qtp115184546-25468) [n:127.0.0.1:61884_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ]
o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() .
[junit4] 2> 1850629 INFO (qtp115184546-25468) [n:127.0.0.1:61884_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@73292f47 commitCommandVersion:0
[junit4] 2> 1850630 INFO
(httpUriRequest-57690-thread-1-processing-x:.system_shard1_replica_n1
r:core_node3 n:127.0.0.1:61884_solr
http:////127.0.0.1:61885//solr//.system_shard1_replica_n2 c:.system s:shard1)
[n:127.0.0.1:61884_solr c:.system s:shard1 r:core_node3
x:.system_shard1_replica_n1 ] o.a.h.i.e.RetryExec I/O exception
(java.net.SocketException) caught when processing request to
{}->http://127.0.0.1:61885: Socket closed
[junit4] 2> 1850630 INFO
(httpUriRequest-57690-thread-1-processing-x:.system_shard1_replica_n1
r:core_node3 n:127.0.0.1:61884_solr
http:////127.0.0.1:61885//solr//.system_shard1_replica_n2 c:.system s:shard1)
[n:127.0.0.1:61884_solr c:.system s:shard1 r:core_node3
x:.system_shard1_replica_n1 ] o.a.h.i.e.RetryExec Retrying request to
{}->http://127.0.0.1:61885
[junit4] 2> 1850633 INFO
(SUITE-SystemCollectionCompatTest-seed#[22A3F8A7030506DC]-worker) [ ]
o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1850858 WARN (ZkTestServer Run Thread) [ ]
o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 9 /solr/configs/.system/managed-schema
[junit4] 2> 8 /solr/clusterprops.json
[junit4] 2> 8 /solr/aliases.json
[junit4] 2> 8 /solr/collections/.system/terms/shard1
[junit4] 2> 6 /solr/configs/.system
[junit4] 2> 5 /solr/collections/.system/collectionprops.json
[junit4] 2> 4 /solr/collections/.system/state.json
[junit4] 2> 2 /solr/packages.json
[junit4] 2> 2 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 30 /solr/collections/.system/state.json
[junit4] 2> 8 /solr/clusterstate.json
[junit4] 2> 5 /solr/aliases.json
[junit4] 2> 3 /solr/configs/.system/managed-schema
[junit4] 2> 2 /solr/autoscaling.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 25 /solr/overseer/queue
[junit4] 2> 16 /solr/collections
[junit4] 2> 16 /solr/overseer/collection-queue-work
[junit4] 2> 14 /solr/live_nodes
[junit4] 2> 2 /solr/autoscaling/events/.scheduled_maintenance
[junit4] 2> 2 /solr/autoscaling/events/.auto_add_replicas
[junit4] 2> 2 /solr/overseer/queue-work
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J2/temp/solr.cloud.SystemCollectionCompatTest_22A3F8A7030506DC-001
[junit4] 2> 1850865 INFO
(SUITE-SystemCollectionCompatTest-seed#[22A3F8A7030506DC]-worker) [ ]
o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:61878
[junit4] 2> 1850865 INFO
(SUITE-SystemCollectionCompatTest-seed#[22A3F8A7030506DC]-worker) [ ]
o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:61878
[junit4] 2> 1850865 INFO
(SUITE-SystemCollectionCompatTest-seed#[22A3F8A7030506DC]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1 61878
[junit4] 2> Apr 14, 2020 3:53:40 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Lucene84,
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@64e0e33a),
locale=ps-AF, timezone=Asia/Ho_Chi_Minh
[junit4] 2> NOTE: Mac OS X 10.14.6 x86_64/AdoptOpenJDK 14
(64-bit)/cpus=6,threads=1,free=202555312,total=371195904
[junit4] 2> NOTE: All tests run in this JVM: [DistanceFunctionTest,
TestSchemaManager, CdcrBootstrapTest, TestReload, TestSmileRequest,
BJQFilterAccessibleTest, TestHdfsCloudBackupRestore,
TestRetrieveFieldsOptimizer, TestRebalanceLeaders, DeleteStatusTest,
TestGraphTermsQParserPlugin, HdfsChaosMonkeySafeLeaderTest,
TemplateUpdateProcessorTest, WrapperMergePolicyFactoryTest,
TestReplicationHandlerDiskOverFlow, JWTVerificationkeyResolverTest,
ChaosMonkeySafeLeaderWithPullReplicasTest, SegmentsInfoRequestHandlerTest,
MetricUtilsTest, TestManagedStopFilterFactory, TestSolrFieldCacheBean,
TestJmxIntegration, TestFastLRUCache, TestTolerantUpdateProcessorCloud,
NodeMarkersRegistrationTest, TestLMJelinekMercerSimilarityFactory,
TestWriterPerf, BasicDistributedZkTest, DebugComponentTest,
TestStressCloudBlindAtomicUpdates, TransactionLogTest, HttpTriggerListenerTest,
GraphQueryTest, SuggesterTest, DistributedUpdateProcessorTest,
CoreAdminCreateDiscoverTest, MinimalSchemaTest,
DistributedFacetPivotLongTailTest, TestSurroundQueryParser,
SpatialRPTFieldTypeTest, TestFieldCollectionResource,
TestClassicSimilarityFactory, CollectionsAPIAsyncDistributedZkTest,
TestStressLucene, TestAddFieldRealTimeGet, TestZkChroot, TestComponentsName,
ZookeeperStatusHandlerTest, TestSimTriggerIntegration,
SpellingQueryConverterTest, TestSimLargeCluster, SpellCheckComponentTest,
TestCloudSchemaless, TestLuceneMatchVersion, RAMDirectoryFactoryTest,
SortByFunctionTest, RandomizedTaggerTest, TestSchemaResource,
TestBinaryResponseWriter, TestLRUCache, AutoAddReplicasPlanActionTest,
TestSolrConfigHandlerCloud, TestSolrCloudWithHadoopAuthPlugin,
CdcrWithNodesRestartsTest, SimplePostToolTest, ImplicitSnitchTest,
RecoveryAfterSoftCommitTest, DistributedQueryComponentCustomSortTest,
TestDynamicLoadingUrl, TestLeaderElectionZkExpiry, QueryElevationComponentTest,
SolrMetricManagerTest, CursorMarkTest, TestCrossCoreJoin, ZkCLITest,
MetricTriggerTest, FullHLLTest, TestFaceting, PeerSyncWithBufferUpdatesTest,
TestPackages, AtomicUpdateProcessorFactoryTest, NoCacheHeaderTest,
TestCloudJSONFacetSKG, SearchRateTriggerIntegrationTest,
TestQuerySenderNoQuery, SSLMigrationTest, ActionThrottleTest, TestJoin,
TestRandomFaceting, TestSolrCoreProperties, TestSolrTestCaseJ4,
PathHierarchyTokenizerFactoryTest, TokenizerChainTest,
TestEmbeddedSolrServerAdminHandler, TestEmbeddedSolrServerConstructors,
TestEmbeddedSolrServerSchemaAPI, CloudExitableDirectoryReaderTest,
CollectionsAPISolrJTest, LeaderElectionTest, LeaderTragicEventTest,
LeaderVoteWaitTimeoutTest, OverseerCollectionConfigSetProcessorTest,
OverseerModifyCollectionTest, OverseerStatusTest, ReplaceNodeNoTargetTest,
ReplaceNodeTest, SystemCollectionCompatTest]
[junit4] Completed [582/900 (2!)] on J2 in 204.70s, 1 test, 1 failure <<<
FAILURES!
[...truncated 45863 lines...]
[repro] Jenkins log URL:
https://jenkins.thetaphi.de/job/Lucene-Solr-8.5-MacOSX/60/consoleText
[repro] Revision: edb9fc409398f2c3446883f9f80595c884d245d0
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseG1GC"
[repro] Repro line: ant test -Dtestcase=ComputePlanActionTest
-Dtests.method=testSelectedCollections -Dtests.seed=22A3F8A7030506DC
-Dtests.slow=true -Dtests.locale=sv-FI -Dtests.timezone=Europe/Moscow
-Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[repro] Repro line: ant test -Dtestcase=SystemCollectionCompatTest
-Dtests.method=testBackCompat -Dtests.seed=22A3F8A7030506DC -Dtests.slow=true
-Dtests.locale=ps-AF -Dtests.timezone=Asia/Ho_Chi_Minh -Dtests.asserts=true
-Dtests.file.encoding=US-ASCII
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] SystemCollectionCompatTest
[repro] ComputePlanActionTest
[repro] ant compile-test
[...truncated 3445 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=10
-Dtests.class="*.SystemCollectionCompatTest|*.ComputePlanActionTest"
-Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseG1GC"
-Dtests.seed=22A3F8A7030506DC -Dtests.slow=true -Dtests.locale=ps-AF
-Dtests.timezone=Asia/Ho_Chi_Minh -Dtests.asserts=true
-Dtests.file.encoding=US-ASCII
[...truncated 141 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.cloud.SystemCollectionCompatTest
[repro] 0/5 failed: org.apache.solr.cloud.autoscaling.ComputePlanActionTest
[repro] Exiting with code 0
[...truncated 73 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
