Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/442/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED:
org.apache.solr.cloud.autoscaling.ComputePlanActionTest.testNodeWithMultipleReplicasLost
Error Message:
The operations computed by ComputePlanAction should not be null
SolrClientNodeStateProvider.DEBUG{AFTER_ACTION=[compute_plan, null],
BEFORE_ACTION=[compute_plan, null]}
Stack Trace:
java.lang.AssertionError: The operations computed by ComputePlanAction should
not be null SolrClientNodeStateProvider.DEBUG{AFTER_ACTION=[compute_plan,
null], BEFORE_ACTION=[compute_plan, null]}
at
__randomizedtesting.SeedInfo.seed([DE82DCAA4E527116:EE423D28C620904A]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNotNull(Assert.java:526)
at
org.apache.solr.cloud.autoscaling.ComputePlanActionTest.testNodeWithMultipleReplicasLost(ComputePlanActionTest.java:291)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
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.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 13987 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.ComputePlanActionTest
[junit4] 2> 4231881 INFO
(SUITE-ComputePlanActionTest-seed#[DE82DCAA4E527116]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ComputePlanActionTest_DE82DCAA4E527116-001/init-core-data-001
[junit4] 2> 4231881 WARN
(SUITE-ComputePlanActionTest-seed#[DE82DCAA4E527116]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=15 numCloses=15
[junit4] 2> 4231882 INFO
(SUITE-ComputePlanActionTest-seed#[DE82DCAA4E527116]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 4231883 INFO
(SUITE-ComputePlanActionTest-seed#[DE82DCAA4E527116]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 4231883 INFO
(SUITE-ComputePlanActionTest-seed#[DE82DCAA4E527116]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ComputePlanActionTest_DE82DCAA4E527116-001/tempDir-001
[junit4] 2> 4231883 INFO
(SUITE-ComputePlanActionTest-seed#[DE82DCAA4E527116]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 4231883 INFO (Thread-7018) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 4231884 INFO (Thread-7018) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 4231885 ERROR (Thread-7018) [ ] o.a.z.s.ZooKeeperServer
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action
on ERROR or SHUTDOWN server state changes
[junit4] 2> 4231983 INFO
(SUITE-ComputePlanActionTest-seed#[DE82DCAA4E527116]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:55212
[junit4] 2> 4231989 INFO (zkConnectionManagerCallback-5869-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4231999 INFO (jetty-launcher-5866-thread-1) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T22:27:37+01:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 4232000 INFO (jetty-launcher-5866-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4232000 INFO (jetty-launcher-5866-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4232000 INFO (jetty-launcher-5866-thread-1) [ ]
o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 4232000 INFO (jetty-launcher-5866-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@5062bc1b{/solr,null,AVAILABLE}
[junit4] 2> 4232001 INFO (jetty-launcher-5866-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@4570ac40{HTTP/1.1,[http/1.1]}{127.0.0.1:40448}
[junit4] 2> 4232001 INFO (jetty-launcher-5866-thread-1) [ ]
o.e.j.s.Server Started @4240948ms
[junit4] 2> 4232001 INFO (jetty-launcher-5866-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=40448}
[junit4] 2> 4232001 ERROR (jetty-launcher-5866-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 4232001 INFO (jetty-launcher-5866-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.3.0
[junit4] 2> 4232001 INFO (jetty-launcher-5866-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4232001 INFO (jetty-launcher-5866-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4232001 INFO (jetty-launcher-5866-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-15T00:50:04.385Z
[junit4] 2> 4232003 INFO (zkConnectionManagerCallback-5871-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4232004 INFO (jetty-launcher-5866-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 4232011 INFO (jetty-launcher-5866-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55212/solr
[junit4] 2> 4232013 INFO (zkConnectionManagerCallback-5875-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4232016 INFO
(zkConnectionManagerCallback-5877-thread-1-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4232139 INFO (jetty-launcher-5866-thread-1)
[n:127.0.0.1:40448_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 4232140 INFO (jetty-launcher-5866-thread-1)
[n:127.0.0.1:40448_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:40448_solr
[junit4] 2> 4232141 INFO (jetty-launcher-5866-thread-1)
[n:127.0.0.1:40448_solr ] o.a.s.c.Overseer Overseer
(id=72199968907722755-127.0.0.1:40448_solr-n_0000000000) starting
[junit4] 2> 4232150 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 4232150 INFO (jetty-launcher-5866-thread-1)
[n:127.0.0.1:40448_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:40448_solr
[junit4] 2> 4232150 DEBUG
(OverseerAutoScalingTriggerThread-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread Adding
.autoAddReplicas trigger
[junit4] 2> 4232153 DEBUG
(OverseerAutoScalingTriggerThread-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 1
[junit4] 2> 4232154 INFO
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 4232154 DEBUG
(OverseerAutoScalingTriggerThread-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:40448_solr]
[junit4] 2> 4232154 DEBUG
(OverseerAutoScalingTriggerThread-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion -1
[junit4] 2> 4232154 DEBUG
(OverseerAutoScalingTriggerThread-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 1
[junit4] 2> 4232157 DEBUG
(OverseerAutoScalingTriggerThread-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 4232157 DEBUG
(OverseerAutoScalingTriggerThread-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 4232158 DEBUG (ScheduledTrigger-10320-thread-1)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 1
[junit4] 2> 4232250 INFO (jetty-launcher-5866-thread-1)
[n:127.0.0.1:40448_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40448.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7ec71af8
[junit4] 2> 4232255 INFO (jetty-launcher-5866-thread-1)
[n:127.0.0.1:40448_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40448.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7ec71af8
[junit4] 2> 4232255 INFO (jetty-launcher-5866-thread-1)
[n:127.0.0.1:40448_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40448.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7ec71af8
[junit4] 2> 4232256 INFO (jetty-launcher-5866-thread-1)
[n:127.0.0.1:40448_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ComputePlanActionTest_DE82DCAA4E527116-001/tempDir-001/node1/.
[junit4] 2> 4232268 INFO (zkConnectionManagerCallback-5881-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4232272 INFO (zkConnectionManagerCallback-5885-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4232275 INFO
(SUITE-ComputePlanActionTest-seed#[DE82DCAA4E527116]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4232276 INFO
(SUITE-ComputePlanActionTest-seed#[DE82DCAA4E527116]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55212/solr ready
[junit4] 2> 4232351 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[DE82DCAA4E527116]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testNodeAdded
[junit4] 2> 4232352 DEBUG
(zkCallback-5876-thread-1-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 2
[junit4] 2> 4232352 DEBUG
(OverseerAutoScalingTriggerThread-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 2
[junit4] 2> 4232353 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[DE82DCAA4E527116]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4232354 DEBUG
(OverseerAutoScalingTriggerThread-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 4232355 DEBUG
(OverseerAutoScalingTriggerThread-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 4232355 DEBUG
(OverseerAutoScalingTriggerThread-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 4232359 INFO (qtp1713158116-29498) [n:127.0.0.1:40448_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> 4232359 DEBUG (qtp1713158116-29494) [n:127.0.0.1:40448_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 4232360 INFO (qtp1713158116-29494) [n:127.0.0.1:40448_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 4232360 DEBUG
(zkCallback-5876-thread-1-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 3
[junit4] 2> 4232361 DEBUG
(OverseerAutoScalingTriggerThread-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 3
[junit4] 2> 4232361 DEBUG
(OverseerAutoScalingTriggerThread-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 4232361 DEBUG
(OverseerAutoScalingTriggerThread-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 4232362 DEBUG
(OverseerAutoScalingTriggerThread-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 4232362 INFO (qtp1713158116-29498) [n:127.0.0.1:40448_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> 4232362 DEBUG (qtp1713158116-29496) [n:127.0.0.1:40448_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 4232363 INFO (qtp1713158116-29496) [n:127.0.0.1:40448_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 4232363 DEBUG
(zkCallback-5876-thread-1-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 4
[junit4] 2> 4232364 DEBUG
(OverseerAutoScalingTriggerThread-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 4
[junit4] 2> 4232364 DEBUG
(OverseerAutoScalingTriggerThread-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 4232364 DEBUG
(OverseerAutoScalingTriggerThread-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 4232365 DEBUG
(OverseerAutoScalingTriggerThread-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 4, lastZnodeVersion 4
[junit4] 2> 4232366 INFO (qtp1713158116-29496) [n:127.0.0.1:40448_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> 4232366 DEBUG (qtp1713158116-29499) [n:127.0.0.1:40448_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 4232367 INFO (qtp1713158116-29499) [n:127.0.0.1:40448_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 4232367 DEBUG
(zkCallback-5876-thread-1-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 5
[junit4] 2> 4232370 DEBUG
(zkCallback-5876-thread-1-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:40448_solr]
[junit4] 2> 4232370 DEBUG
(zkCallback-5876-thread-1-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=1,
actions=[{name=compute_plan, class=solr.ComputePlanAction}, {name=test,
class=org.apache.solr.cloud.autoscaling.ComputePlanActionTest$AssertingTriggerAction}],
enabled=true}
[junit4] 2> 4232370 DEBUG
(OverseerAutoScalingTriggerThread-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 5
[junit4] 2> 4232371 INFO (qtp1713158116-29499) [n:127.0.0.1:40448_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> 4232371 DEBUG (qtp1713158116-29498) [n:127.0.0.1:40448_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 4232372 INFO (qtp1713158116-29498) [n:127.0.0.1:40448_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 4232372 INFO (qtp1713158116-29496) [n:127.0.0.1:40448_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=2&collection.configName=conf&name=testNodeAdded&nrtReplicas=2&action=CREATE&numShards=1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 4232372 DEBUG
(zkCallback-5876-thread-1-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 6
[junit4] 2> 4232373 DEBUG
(zkCallback-5876-thread-1-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:40448_solr]
[junit4] 2> 4232373 DEBUG
(zkCallback-5876-thread-1-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=1,
actions=[{name=compute_plan, class=solr.ComputePlanAction}, {name=test,
class=org.apache.solr.cloud.autoscaling.ComputePlanActionTest$AssertingTriggerAction}],
enabled=true}
[junit4] 2> 4232374 DEBUG
(OverseerAutoScalingTriggerThread-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 4232374 DEBUG
(OverseerAutoScalingTriggerThread-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 6, lastZnodeVersion 5
[junit4] 2> 4232374 DEBUG
(OverseerAutoScalingTriggerThread-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 6
[junit4] 2> 4232375 DEBUG (ScheduledTrigger-10320-thread-1)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 4232375 DEBUG (ScheduledTrigger-10320-thread-1)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 4232375 DEBUG
(OverseerAutoScalingTriggerThread-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 4232375 INFO
(OverseerThreadFactory-10322-thread-1-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection
testNodeAdded
[junit4] 2> 4232375 DEBUG
(OverseerAutoScalingTriggerThread-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 6, lastZnodeVersion 6
[junit4] 2> 4232376 WARN
(OverseerThreadFactory-10322-thread-1-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_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> 4232378 INFO (qtp1713158116-29498) [n:127.0.0.1:40448_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> 4232378 INFO
(OverseerThreadFactory-10322-thread-1-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.s.c.a.PolicyHelper returnSession,
curr-time 2172468600 sessionWrapper.createTime 2172468600286434,
this.sessionWrapper.createTime 2172468600286434
[junit4] 2> 4232386 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "name":"testNodeAdded",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"conf",
[junit4] 2> "numShards":"1",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 4232386 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.o.ClusterStateMutator building a new
cName: testNodeAdded
[junit4] 2> 4232386 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.o.ZkStateWriter going to create_collection
/collections/testNodeAdded/state.json
[junit4] 2> 4232491 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_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:40448/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 4232492 INFO
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_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:40448/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 4232494 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 4232494 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_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:40448/solr";,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 4232495 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_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:40448/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 4232495 INFO
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_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:40448/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 4232497 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_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:40448/solr";,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 4232497 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_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:40448/solr";,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "base_url":"http://127.0.0.1:40448/solr";,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 4232597 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeAdded/state.json version: 0
[junit4] 2> 4232698 INFO (qtp1713158116-29498) [n:127.0.0.1:40448_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=testNodeAdded_shard1_replica_n2&action=CREATE&numShards=1&collection=testNodeAdded&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 4232698 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=testNodeAdded_shard1_replica_n1&action=CREATE&numShards=1&collection=testNodeAdded&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 4232699 INFO (qtp1713158116-29498) [n:127.0.0.1:40448_solr
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 4232703 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_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:40448/solr";,
[junit4] 2> "node_name":"127.0.0.1:40448_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 4232703 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_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:40448/solr";,
[junit4] 2> "node_name":"127.0.0.1:40448_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"}
[junit4] 2> 4232703 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.o.ReplicaMutator Will update state for
replica:
core_node4:{"core":"testNodeAdded_shard1_replica_n2","base_url":"http://127.0.0.1:40448/solr","node_name":"127.0.0.1:40448_solr","state":"down","type":"NRT"}
[junit4] 2> 4232703 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.o.ReplicaMutator Collection is now:
DocCollection(testNodeAdded//collections/testNodeAdded/state.json/1)={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:40448/solr";,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "base_url":"http://127.0.0.1:40448/solr";,
[junit4] 2> "node_name":"127.0.0.1:40448_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 4232703 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.Overseer processMessage: queueSize: 2,
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:40448/solr";,
[junit4] 2> "node_name":"127.0.0.1:40448_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 4232703 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_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:40448/solr";,
[junit4] 2> "node_name":"127.0.0.1:40448_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"}
[junit4] 2> 4232703 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.o.ReplicaMutator Will update state for
replica:
core_node3:{"core":"testNodeAdded_shard1_replica_n1","base_url":"http://127.0.0.1:40448/solr","node_name":"127.0.0.1:40448_solr","state":"down","type":"NRT"}
[junit4] 2> 4232703 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.o.ReplicaMutator Collection is now:
DocCollection(testNodeAdded//collections/testNodeAdded/state.json/1)={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:40448/solr";,
[junit4] 2> "node_name":"127.0.0.1:40448_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "base_url":"http://127.0.0.1:40448/solr";,
[junit4] 2> "node_name":"127.0.0.1:40448_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 4232804 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeAdded/state.json version: 1
[junit4] 2> 4232805 INFO
(zkCallback-5876-thread-2-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 4232805 INFO
(zkCallback-5876-thread-1-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 4233382 DEBUG (ScheduledTrigger-10320-thread-2)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 4233382 DEBUG (ScheduledTrigger-10320-thread-2)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 4233715 INFO (qtp1713158116-29498) [n:127.0.0.1:40448_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 4233715 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 4233733 INFO (qtp1713158116-29498) [n:127.0.0.1:40448_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> 4233734 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_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> 4233735 INFO (qtp1713158116-29498) [n:127.0.0.1:40448_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> 4233735 INFO (qtp1713158116-29498) [n:127.0.0.1:40448_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.CoreContainer Creating SolrCore 'testNodeAdded_shard1_replica_n2' using
configuration from collection testNodeAdded, trusted=true
[junit4] 2> 4233736 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_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> 4233736 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.c.CoreContainer Creating SolrCore 'testNodeAdded_shard1_replica_n1' using
configuration from collection testNodeAdded, trusted=true
[junit4] 2> 4233753 INFO (qtp1713158116-29498) [n:127.0.0.1:40448_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40448.solr.core.testNodeAdded.shard1.replica_n2' (registry
'solr.core.testNodeAdded.shard1.replica_n2') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7ec71af8
[junit4] 2> 4233753 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40448.solr.core.testNodeAdded.shard1.replica_n1' (registry
'solr.core.testNodeAdded.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7ec71af8
[junit4] 2> 4233753 INFO (qtp1713158116-29498) [n:127.0.0.1:40448_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 4233753 INFO (qtp1713158116-29498) [n:127.0.0.1:40448_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
[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ComputePlanActionTest_DE82DCAA4E527116-001/tempDir-001/node1/testNodeAdded_shard1_replica_n2],
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ComputePlanActionTest_DE82DCAA4E527116-001/tempDir-001/node1/./testNodeAdded_shard1_replica_n2/data/]
[junit4] 2> 4233753 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 4233753 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_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
[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ComputePlanActionTest_DE82DCAA4E527116-001/tempDir-001/node1/testNodeAdded_shard1_replica_n1],
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ComputePlanActionTest_DE82DCAA4E527116-001/tempDir-001/node1/./testNodeAdded_shard1_replica_n1/data/]
[junit4] 2> 4233806 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_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> 4233806 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_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> 4233807 INFO (qtp1713158116-29498) [n:127.0.0.1:40448_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> 4233807 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 4233807 INFO (qtp1713158116-29498) [n:127.0.0.1:40448_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> 4233807 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 4233808 INFO (qtp1713158116-29498) [n:127.0.0.1:40448_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 4233808 INFO (qtp1713158116-29498) [n:127.0.0.1:40448_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 4233808 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@1108045[testNodeAdded_shard1_replica_n1] main]
[junit4] 2> 4233809 INFO (qtp1713158116-29498) [n:127.0.0.1:40448_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@8606eb8[testNodeAdded_shard1_replica_n2] main]
[junit4] 2> 4233810 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_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> 4233810 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_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> 4233811 INFO (qtp1713158116-29498) [n:127.0.0.1:40448_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> 4233811 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_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> 4233811 INFO (qtp1713158116-29498) [n:127.0.0.1:40448_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> 4233811 INFO (qtp1713158116-29498) [n:127.0.0.1:40448_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> 4233811 INFO
(searcherExecutor-10326-thread-1-processing-n:127.0.0.1:40448_solr
x:testNodeAdded_shard1_replica_n1 s:shard1 c:testNodeAdded r:core_node3)
[n:127.0.0.1:40448_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@1108045[testNodeAdded_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4233811 INFO (qtp1713158116-29498) [n:127.0.0.1:40448_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 1592426030636728320
[junit4] 2> 4233811 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_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 1592426030636728320
[junit4] 2> 4233812 INFO
(searcherExecutor-10325-thread-1-processing-n:127.0.0.1:40448_solr
x:testNodeAdded_shard1_replica_n2 s:shard1 c:testNodeAdded r:core_node4)
[n:127.0.0.1:40448_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@8606eb8[testNodeAdded_shard1_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4233817 INFO (qtp1713158116-29498) [n:127.0.0.1:40448_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.ZkShardTerms Failed to save terms, version is not match, retrying
[junit4] 2> 4233823 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_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> 4233823 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_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> 4233823 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_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> 4233823 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_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:40448/solr/testNodeAdded_shard1_replica_n1/
[junit4] 2> 4233824 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_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:40448/solr START
replicas=[http://127.0.0.1:40448/solr/testNodeAdded_shard1_replica_n2/]
nUpdates=100
[junit4] 2> 4233825 INFO (qtp1713158116-29497) [n:127.0.0.1:40448_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.S.Request [testNodeAdded_shard1_replica_n2] webapp=/solr path=/get
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 4233925 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeAdded/state.json version: 2
[junit4] 2> 4233926 INFO
(zkCallback-5876-thread-3-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 4233926 INFO
(zkCallback-5876-thread-1-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 4234126 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_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:40448/solr DONE. We have no versions. sync failed.
[junit4] 2> 4234126 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_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> 4234126 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_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> 4234126 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 4234134 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_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:40448/solr/testNodeAdded_shard1_replica_n1/ shard1
[junit4] 2> 4234135 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "base_url":"http://127.0.0.1:40448/solr";,
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "state":"active"} current state version: 0
[junit4] 2> 4234236 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeAdded/state.json version: 3
[junit4] 2> 4234236 INFO
(zkCallback-5876-thread-3-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 4234236 INFO
(zkCallback-5876-thread-1-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 4234285 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_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> 4234288 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_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:40448/solr";,
[junit4] 2> "node_name":"127.0.0.1:40448_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 4234288 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_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:40448/solr";,
[junit4] 2> "node_name":"127.0.0.1:40448_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"}
[junit4] 2> 4234288 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.o.ReplicaMutator Will update state for
replica:
core_node3:{"core":"testNodeAdded_shard1_replica_n1","base_url":"http://127.0.0.1:40448/solr","node_name":"127.0.0.1:40448_solr","state":"active","type":"NRT","leader":"true"}
[junit4] 2> 4234288 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.o.ReplicaMutator Collection is now:
DocCollection(testNodeAdded//collections/testNodeAdded/state.json/4)={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:40448/solr";,
[junit4] 2> "node_name":"127.0.0.1:40448_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "base_url":"http://127.0.0.1:40448/solr";,
[junit4] 2> "node_name":"127.0.0.1:40448_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 4234288 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_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=1590
[junit4] 2> 4234385 DEBUG (ScheduledTrigger-10320-thread-2)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 4234385 DEBUG (ScheduledTrigger-10320-thread-2)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 4234389 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeAdded/state.json version: 4
[junit4] 2> 4234389 INFO
(zkCallback-5876-thread-1-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 4234389 INFO
(zkCallback-5876-thread-3-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 4234829 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_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:40448/solr";,
[junit4] 2> "node_name":"127.0.0.1:40448_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 4234829 INFO (qtp1713158116-29498) [n:127.0.0.1:40448_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=2132
[junit4] 2> 4234829 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_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:40448/solr";,
[junit4] 2> "node_name":"127.0.0.1:40448_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"}
[junit4] 2> 4234830 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.o.ReplicaMutator Will update state for
replica:
core_node4:{"core":"testNodeAdded_shard1_replica_n2","base_url":"http://127.0.0.1:40448/solr","node_name":"127.0.0.1:40448_solr","state":"active","type":"NRT"}
[junit4] 2> 4234830 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.o.ReplicaMutator Collection is now:
DocCollection(testNodeAdded//collections/testNodeAdded/state.json/5)={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:40448/solr";,
[junit4] 2> "node_name":"127.0.0.1:40448_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "base_url":"http://127.0.0.1:40448/solr";,
[junit4] 2> "node_name":"127.0.0.1:40448_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 4234834 INFO (qtp1713158116-29496) [n:127.0.0.1:40448_solr
] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 4234931 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeAdded/state.json version: 5
[junit4] 2> 4234932 INFO
(zkCallback-5876-thread-1-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 4234932 INFO
(zkCallback-5876-thread-3-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 4235386 DEBUG (ScheduledTrigger-10320-thread-2)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 4235386 DEBUG (ScheduledTrigger-10320-thread-2)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 4235835 INFO (qtp1713158116-29496) [n:127.0.0.1:40448_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=2&collection.configName=conf&name=testNodeAdded&nrtReplicas=2&action=CREATE&numShards=1&wt=javabin&version=2}
status=0 QTime=3463
[junit4] 2> 4235843 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_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=2
[junit4] 2> 4235845 INFO (qtp1713158116-29497) [n:127.0.0.1:40448_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> 4235845 DEBUG (qtp1713158116-29499) [n:127.0.0.1:40448_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 4235846 INFO (qtp1713158116-29499) [n:127.0.0.1:40448_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=7
[junit4] 2> 4235847 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[DE82DCAA4E527116]) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T22:27:37+01:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 4235847 DEBUG
(zkCallback-5876-thread-3-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 7
[junit4] 2> 4235847 DEBUG
(zkCallback-5876-thread-3-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:40448_solr]
[junit4] 2> 4235847 DEBUG
(zkCallback-5876-thread-3-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=1,
actions=[{name=compute_plan, class=solr.ComputePlanAction}, {name=test,
class=org.apache.solr.cloud.autoscaling.ComputePlanActionTest$AssertingTriggerAction}],
enabled=true}
[junit4] 2> 4235847 DEBUG
(OverseerAutoScalingTriggerThread-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 7
[junit4] 2> 4235848 DEBUG
(OverseerAutoScalingTriggerThread-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 4235848 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[DE82DCAA4E527116]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4235848 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[DE82DCAA4E527116]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4235848 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[DE82DCAA4E527116]) [ ]
o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 4235848 DEBUG
(OverseerAutoScalingTriggerThread-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 7, lastZnodeVersion 7
[junit4] 2> 4235848 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[DE82DCAA4E527116]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@44a70946{/solr,null,AVAILABLE}
[junit4] 2> 4235849 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[DE82DCAA4E527116]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@306bc862{HTTP/1.1,[http/1.1]}{127.0.0.1:64740}
[junit4] 2> 4235849 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[DE82DCAA4E527116]) [ ]
o.e.j.s.Server Started @4244797ms
[junit4] 2> 4235849 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[DE82DCAA4E527116]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=64740}
[junit4] 2> 4235849 ERROR
(TEST-ComputePlanActionTest.testNodeAdded-seed#[DE82DCAA4E527116]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 4235849 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[DE82DCAA4E527116]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.3.0
[junit4] 2> 4235849 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[DE82DCAA4E527116]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4235849 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[DE82DCAA4E527116]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4235849 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[DE82DCAA4E527116]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-15T00:50:08.233Z
[junit4] 2> 4235851 INFO (zkConnectionManagerCallback-5888-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4235852 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[DE82DCAA4E527116]) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 4235858 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[DE82DCAA4E527116]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55212/solr
[junit4] 2> 4235860 INFO (zkConnectionManagerCallback-5892-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4235863 INFO
(zkConnectionManagerCallback-5894-thread-1-processing-n:127.0.0.1:64740_solr)
[n:127.0.0.1:64740_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4235873 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[DE82DCAA4E527116])
[n:127.0.0.1:64740_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 4235876 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[DE82DCAA4E527116])
[n:127.0.0.1:64740_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 4235879 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[DE82DCAA4E527116])
[n:127.0.0.1:64740_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:64740_solr
[junit4] 2> 4235880 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:64740_solr"} current state version: 0
[junit4] 2> 4235880 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for
node: 127.0.0.1:64740_solr
[junit4] 2> 4235881 INFO
(zkCallback-5876-thread-3-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 4235881 INFO (zkCallback-5884-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 4235881 INFO (zkCallback-5884-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 4235883 INFO
(zkCallback-5893-thread-1-processing-n:127.0.0.1:64740_solr)
[n:127.0.0.1:64740_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 4235968 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[DE82DCAA4E527116])
[n:127.0.0.1:64740_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_64740.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7ec71af8
[junit4] 2> 4235974 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[DE82DCAA4E527116])
[n:127.0.0.1:64740_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_64740.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7ec71af8
[junit4] 2> 4235974 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[DE82DCAA4E527116])
[n:127.0.0.1:64740_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_64740.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7ec71af8
[junit4] 2> 4235975 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[DE82DCAA4E527116])
[n:127.0.0.1:64740_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ComputePlanActionTest_DE82DCAA4E527116-001/tempDir-001/node2/.
[junit4] 2> 4236387 DEBUG (ScheduledTrigger-10320-thread-2)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 4236387 DEBUG (ScheduledTrigger-10320-thread-2)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 4236387 DEBUG (ScheduledTrigger-10320-thread-2)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node:
127.0.0.1:64740_solr at time 2172472610873919
[junit4] 2> 4236388 INFO
(OverseerCollectionConfigSetProcessor-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_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> 4237389 DEBUG (ScheduledTrigger-10320-thread-2)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 4237389 DEBUG (ScheduledTrigger-10320-thread-2)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 4237390 DEBUG (ScheduledTrigger-10320-thread-2)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_trigger firing registered processor for nodes:
[127.0.0.1:64740_solr] added at times [2172472610873919], now=2172473613615169
[junit4] 2> 4237390 DEBUG (ScheduledTrigger-10320-thread-2)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown
inactive - processing event: {
[junit4] 2> "id":"7b7da3241623fTa2jak3hxi1wi92eqetql55nvk",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":2172472610873919,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[2172472610873919],
[junit4] 2> "nodeNames":["127.0.0.1:64740_solr"]}}
[junit4] 2> 4237392 DEBUG (ScheduledTrigger-10320-thread-2)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers:
[node_added_trigger]
[junit4] 2> 4237393 INFO (ScheduledTrigger-10320-thread-2)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.SystemLogListener Collection .system
does not exist, disabling logging.
[junit4] 2> 4237394 DEBUG
(AutoscalingActionExecutor-10321-thread-1-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions
for {
[junit4] 2> "id":"7b7da3241623fTa2jak3hxi1wi92eqetql55nvk",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":2172472610873919,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[2172472610873919],
[junit4] 2> "_enqueue_time_":2172473615876613,
[junit4] 2> "nodeNames":["127.0.0.1:64740_solr"]}}
[junit4] 2> 4237394 DEBUG
(AutoscalingActionExecutor-10321-thread-1-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.ComputePlanAction -- processing event: {
[junit4] 2> "id":"7b7da3241623fTa2jak3hxi1wi92eqetql55nvk",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":2172472610873919,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[2172472610873919],
[junit4] 2> "_enqueue_time_":2172473615876613,
[junit4] 2> "nodeNames":["127.0.0.1:64740_solr"]}} with context
properties: {BEFORE_ACTION=[compute_plan]}
[junit4] 2> 4237395 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={wt=javabin&version=2&key=solr.core.testNodeAdded.shard1.replica_n1:INDEX.sizeInBytes&key=solr.core.testNodeAdded.shard1.replica_n2:INDEX.sizeInBytes}
status=0 QTime=0
[junit4] 2> 4237397 INFO (qtp1713158116-29499) [n:127.0.0.1:40448_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> 4237399 INFO (qtp1069046786-29548) [n:127.0.0.1:64740_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> 4237400 INFO
(AutoscalingActionExecutor-10321-thread-1-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.ComputePlanAction Computed Plan:
action=MOVEREPLICA&collection=testNodeAdded&targetNode=127.0.0.1:64740_solr&inPlaceMove=true&replica=core_node3
[junit4] 2> 4237400 INFO
(AutoscalingActionExecutor-10321-thread-1-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.s.c.a.PolicyHelper returnSession,
curr-time 2172473623 sessionWrapper.createTime 2172473623397162,
this.sessionWrapper.createTime 2172473623397162
[junit4] 2> 4237400 DEBUG
(TEST-ComputePlanActionTest.testNodeAdded-seed#[DE82DCAA4E527116]) [ ]
o.a.s.c.a.ComputePlanActionTest -------------_ FINAL STATE --------------
[junit4] 2> 4237401 DEBUG
(AutoscalingActionExecutor-10321-thread-1-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger:
node_added_trigger after 5000ms
[junit4] 2> 4237401 DEBUG
(AutoscalingActionExecutor-10321-thread-1-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 7
ms for event id=7b7da3241623fTa2jak3hxi1wi92eqetql55nvk
[junit4] 2> 4237403 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_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> 4237403 DEBUG
(TEST-ComputePlanActionTest.testNodeAdded-seed#[DE82DCAA4E527116]) [ ]
o.a.s.c.a.ComputePlanActionTest * Node values: 127.0.0.1:40448_solr
[junit4] 2> {
[junit4] 2> "node":"127.0.0.1:40448_solr",
[junit4] 2> "cores":2,
[junit4] 2> "port":"40448",
[junit4] 2> "host":"127.0.0.1",
[junit4] 2> "ip_1":"1",
[junit4] 2> "ip_3":"0",
[junit4] 2> "ip_2":"0",
[junit4] 2> "ip_4":"127",
[junit4] 2> "freedisk":12.538081169128418}
[junit4] 2> 4237404 INFO (qtp1069046786-29550) [n:127.0.0.1:64740_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> 4237405 DEBUG
(TEST-ComputePlanActionTest.testNodeAdded-seed#[DE82DCAA4E527116]) [ ]
o.a.s.c.a.ComputePlanActionTest * Node values: 127.0.0.1:64740_solr
[junit4] 2> {
[junit4] 2> "node":"127.0.0.1:64740_solr",
[junit4] 2> "cores":0,
[junit4] 2> "port":"64740",
[junit4] 2> "host":"127.0.0.1",
[junit4] 2> "ip_1":"1",
[junit4] 2> "ip_3":"0",
[junit4] 2> "ip_2":"0",
[junit4] 2> "ip_4":"127",
[junit4] 2> "freedisk":12.538081169128418}
[junit4] 2> 4237405 DEBUG
(TEST-ComputePlanActionTest.testNodeAdded-seed#[DE82DCAA4E527116]) [ ]
o.a.s.c.a.ComputePlanActionTest * Live nodes: [127.0.0.1:40448_solr,
127.0.0.1:64740_solr]
[junit4] 2> 4237405 DEBUG
(TEST-ComputePlanActionTest.testNodeAdded-seed#[DE82DCAA4E527116]) [ ]
o.a.s.c.a.ComputePlanActionTest * Collection testNodeAdded state:
DocCollection(testNodeAdded//collections/testNodeAdded/state.json/6)={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:40448/solr";,
[junit4] 2> "node_name":"127.0.0.1:40448_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "base_url":"http://127.0.0.1:40448/solr";,
[junit4] 2> "node_name":"127.0.0.1:40448_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT"}}}},
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "tlogReplicas":"0"}
[junit4] 2> 4237405 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[DE82DCAA4E527116]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testNodeAdded
[junit4] 2> 4237546 INFO
(TEST-ComputePlanActionTest.testSelectedCollections-seed#[DE82DCAA4E527116]) [
] o.a.s.SolrTestCaseJ4 ###Starting testSelectedCollections
[junit4] 2> 4237548 DEBUG
(zkCallback-5876-thread-3-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 8
[junit4] 2> 4237548 DEBUG
(OverseerAutoScalingTriggerThread-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 8
[junit4] 2> 4237549 INFO
(TEST-ComputePlanActionTest.testSelectedCollections-seed#[DE82DCAA4E527116]) [
] o.e.j.s.AbstractConnector Stopped
ServerConnector@306bc862{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 4237549 INFO
(TEST-ComputePlanActionTest.testSelectedCollections-seed#[DE82DCAA4E527116]) [
] o.a.s.c.CoreContainer Shutting down CoreContainer instance=931138866
[junit4] 2> 4237549 INFO
(TEST-ComputePlanActionTest.testSelectedCollections-seed#[DE82DCAA4E527116]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 4237549 INFO
(TEST-ComputePlanActionTest.testSelectedCollections-seed#[DE82DCAA4E527116]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@42fd89b1: rootName =
solr_64740, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@60b0e55b
[junit4] 2> 4237550 DEBUG
(OverseerAutoScalingTriggerThread-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 4237550 DEBUG
(OverseerAutoScalingTriggerThread-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 4237551 DEBUG
(OverseerAutoScalingTriggerThread-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 8, lastZnodeVersion 8
[junit4] 2> 4237554 INFO
(TEST-ComputePlanActionTest.testSelectedCollections-seed#[DE82DCAA4E527116]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 4237554 INFO
(TEST-ComputePlanActionTest.testSelectedCollections-seed#[DE82DCAA4E527116]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@6585170b: rootName =
solr_64740, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@6c12e94d
[junit4] 2> 4237557 INFO
(TEST-ComputePlanActionTest.testSelectedCollections-seed#[DE82DCAA4E527116]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 4237557 INFO
(TEST-ComputePlanActionTest.testSelectedCollections-seed#[DE82DCAA4E527116]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@4e83674b: rootName =
solr_64740, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@f521675
[junit4] 2> 4237559 INFO
(TEST-ComputePlanActionTest.testSelectedCollections-seed#[DE82DCAA4E527116]) [
] o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:64740_solr
[junit4] 2> 4237560 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:64740_solr"} current state version: 0
[junit4] 2> 4237560 DEBUG
(OverseerStateUpdate-72199968907722755-127.0.0.1:40448_solr-n_0000000000)
[n:127.0.0.1:40448_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for
node: 127.0.0.1:64740_solr
[junit4] 2> 4237561 INFO
(TEST-ComputePlanActionTest.testSelectedCollections-seed#[DE82DCAA4E527116]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.cluster, tag=null
[junit4] 2> 4237561 INFO (zkCallback-5884-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 4237561 INFO (zkCallback-5884-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 4237561 INFO
(zkCallback-5893-thread-1-processing-n:127.0.0.1:64740_solr)
[n:127.0.0.1:64740_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (1)
[junit4] 2> 4237561 INFO
(zkCallback-5876-thread-3-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (1)
[junit4] 2> 4237561 INFO
(TEST-ComputePlanActionTest.testSelectedCollections-seed#[DE82DCAA4E527116]) [
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 4237562 INFO
(TEST-ComputePlanActionTest.testSelectedCollections-seed#[DE82DCAA4E527116]) [
] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@44a70946{/solr,null,UNAVAILABLE}
[junit4] 2> 4237562 INFO
(TEST-ComputePlanActionTest.testSelectedCollections-seed#[DE82DCAA4E527116]) [
] o.e.j.s.session Stopped scavenging
[junit4] 2> 4237566 INFO
(TEST-ComputePlanActionTest.testSelectedCollections-seed#[DE82DCAA4E527116]) [
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4237568 INFO (qtp1713158116-29499) [n:127.0.0.1:40448_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params
name=testNodeAdded&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 4237571 INFO
(OverseerThreadFactory-10322-thread-2-processing-n:127.0.0.1:40448_solr)
[n:127.0.0.1:40448_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler
Executing Collection Cmd :
action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 4237572 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_solr
] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.testNodeAdded.shard1.replica_n1, tag=null
[junit4] 2> 4237572 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_solr
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@33a78a21: rootName =
solr_40448, domain = solr.core.testNodeAdded.shard1.replica_n1, service url =
null, agent id = null] for registry solr.core.testNodeAdded.shard1.replica_n1 /
com.codahale.metrics.MetricRegistry@3a9365e7
[junit4] 2> 4237579 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_solr
] o.a.s.c.SolrCore [testNodeAdded_shard1_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@60b5e571
[junit4] 2> 4237579 INFO (qtp1713158116-29497) [n:127.0.0.1:40448_solr
] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.testNodeAdded.shard1.replica_n2, tag=null
[junit4] 2> 4237579 INFO (qtp1713158116-29497) [n:127.0.0.1:40448_solr
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@6a193c18: rootName =
solr_40448, domain = solr.core.testNodeAdded.shard1.replica_n2, service url =
null, agent id = null] for registry solr.core.testNodeAdded.shard1.replica_n2 /
com.codahale.metrics.MetricRegistry@5908c35a
[junit4] 2> 4237586 INFO (qtp1713158116-29497) [n:127.0.0.1:40448_solr
] o.a.s.c.SolrCore [testNodeAdded_shard1_replica_n2] CLOSING SolrCore
org.apache.solr.core.SolrCore@6a60115c
[junit4] 2> 4237586 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_solr
] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.testNodeAdded.shard1.replica_n1, tag=1622533489
[junit4] 2> 4237586 INFO (qtp1713158116-29500) [n:127.0.0.1:40448_
[...truncated too long message...]
thread-1) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been
closed explicitly
[junit4] 2> 4275681 INFO (jetty-closer-5867-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@f23e58d{/solr,null,UNAVAILABLE}
[junit4] 2> 4275685 INFO (jetty-closer-5867-thread-1) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 4275687 ERROR
(SUITE-ComputePlanActionTest-seed#[DE82DCAA4E527116]-worker) [ ]
o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper
server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 4275688 INFO
(SUITE-ComputePlanActionTest-seed#[DE82DCAA4E527116]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:55212 55212
[junit4] 2> 4282334 INFO (Thread-7018) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:55212 55212
[junit4] 2> 4282358 WARN (Thread-7018) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 15 /solr/aliases.json
[junit4] 2> 15 /solr/clusterprops.json
[junit4] 2> 11 /solr/configs/conf
[junit4] 2> 10 /solr/security.json
[junit4] 2> 9
/solr/collections/testNodeWithMultipleReplicasLost/terms/shard2
[junit4] 2> 9
/solr/collections/testNodeWithMultipleReplicasLost/terms/shard1
[junit4] 2> 6 /solr/collections/testSelected2/terms/shard1
[junit4] 2> 5 /solr/collections/testNodeLost/terms/shard1
[junit4] 2> 5 /solr/collections/testNodeAdded/terms/shard1
[junit4] 2> 5 /solr/collections/testSelected1/terms/shard2
[junit4] 2> 5 /solr/collections/testSelected1/terms/shard1
[junit4] 2> 5 /solr/collections/testSelected2/terms/shard2
[junit4] 2> 5 /solr/collections/testSelected3/terms/shard2
[junit4] 2> 5 /solr/collections/testSelected3/terms/shard1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 38 /solr/collections/testSelected2/state.json
[junit4] 2> 30 /solr/collections/testSelected1/state.json
[junit4] 2> 30 /solr/collections/testSelected3/state.json
[junit4] 2> 29
/solr/collections/testNodeWithMultipleReplicasLost/state.json
[junit4] 2> 16 /solr/collections/testNodeLost/state.json
[junit4] 2> 15 /solr/clusterstate.json
[junit4] 2> 8 /solr/collections/testNodeAdded/state.json
[junit4] 2> 3
/solr/overseer_elect/election/72199968907722773-127.0.0.1:34216_solr-n_0000000005
[junit4] 2> 3
/solr/overseer_elect/election/72199968907722755-127.0.0.1:40448_solr-n_0000000000
[junit4] 2> 3 /solr/autoscaling.json
[junit4] 2> 2
/solr/collections/testNodeWithMultipleReplicasLost/leader_elect/shard2/election/72199968907722779-core_node12-n_0000000000
[junit4] 2> 2
/solr/overseer_elect/election/72199968907722766-127.0.0.1:49802_solr-n_0000000003
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 63 /solr/live_nodes
[junit4] 2> 44 /solr/collections
[junit4] 2> 3 /solr/overseer/queue
[junit4] 2> 3 /solr/autoscaling/events/.auto_add_replicas
[junit4] 2> 3 /solr/overseer/collection-queue-work
[junit4] 2> 3 /solr/overseer/queue-work
[junit4] 2> 2 /solr/autoscaling/events/node_lost_trigger
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ComputePlanActionTest_DE82DCAA4E527116-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70),
sim=RandomSimilarity(queryNorm=false): {}, locale=sl, timezone=America/Panama
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_152
(64-bit)/cpus=3,threads=1,free=200602968,total=517996544
[junit4] 2> NOTE: All tests run in this JVM: [CSVRequestHandlerTest,
TestStressCloudBlindAtomicUpdates, StressHdfsTest, TestCoreBackup,
HdfsUnloadDistributedZkTest, TestClusterProperties,
HdfsRestartWhileUpdatingTest, URLClassifyProcessorTest,
TestManagedStopFilterFactory, SortByFunctionTest, ResponseHeaderTest,
TestExportWriter, ResponseLogComponentTest, ZkStateReaderTest,
TestLegacyFieldReuse, ZkFailoverTest, TestUpdate, TestCloudPivotFacet,
AtomicUpdateProcessorFactoryTest, TestSubQueryTransformerDistrib,
TestAuthenticationFramework, TestBlendedInfixSuggestions,
HttpTriggerListenerTest, TestStressVersions, HdfsDirectoryFactoryTest,
TestHdfsUpdateLog, TestGraphTermsQParserPlugin, SystemInfoHandlerTest,
JvmMetricsTest, TestInPlaceUpdatesStandalone, TestExpandComponent,
SuggesterWFSTTest, TestFieldCacheVsDocValues, TestMaxScoreQueryParser,
FullSolrCloudDistribCmdsTest, TestTriggerIntegration, AutoScalingHandlerTest,
DistributedIntervalFacetingTest, TestDistribIDF, TestFieldCache,
TestManagedSynonymGraphFilterFactory, TestPHPSerializedResponseWriter,
HdfsRecoverLeaseTest, BasicDistributedZk2Test, RestartWhileUpdatingTest,
DistributedTermsComponentTest, TestPKIAuthenticationPlugin,
TestReversedWildcardFilterFactory, TestSSLRandomization, SchemaApiFailureTest,
DebugComponentTest, DocExpirationUpdateProcessorFactoryTest, TestOrdValues,
TestConfigSetImmutable, BlockCacheTest, TestNodeAddedTrigger,
AliasIntegrationTest, TestDistributedMissingSort, TestExtendedDismaxParser,
DefaultValueUpdateProcessorTest, ConfigureRecoveryStrategyTest,
TestIntervalFaceting, ShowFileRequestHandlerTest, TestStressRecovery,
SimpleFacetsTest, MinimalSchemaTest, DistributedQueryComponentOptimizationTest,
TestRestoreCore, TestXmlQParser, TestWordDelimiterFilterFactory,
ClusterStateTest, TestManagedResourceStorage, HdfsBasicDistributedZkTest,
PrimUtilsTest, SegmentsInfoRequestHandlerTest, TestDistributedGrouping,
TestRebalanceLeaders, TestQuerySenderListener, ShardSplitTest,
JavabinLoaderTest, CdcrRequestHandlerTest, HLLSerializationTest,
ConcurrentCreateRoutedAliasTest, DeleteLastCustomShardedReplicaTest,
TestSchemaSimilarityResource, DistributedQueueTest, HighlighterConfigTest,
BadComponentTest, V2ApiIntegrationTest, TestUseDocValuesAsStored2,
LeaderInitiatedRecoveryOnShardRestartTest, TestPivotHelperCode,
TestRandomDVFaceting, TestNestedDocsSort, BufferStoreTest,
ParsingFieldUpdateProcessorsTest, ChaosMonkeyNothingIsSafeTest,
BasicAuthIntegrationTest, SolrGangliaReporterTest,
HdfsChaosMonkeySafeLeaderTest, TestCollapseQParserPlugin,
TestClassicSimilarityFactory, SuggestComponentTest, RequestHandlersTest,
TestImpersonationWithHadoopAuth, ReplicaListTransformerTest,
TestDistribDocBasedVersion, CollectionsAPIDistributedZkTest,
AddBlockUpdateTest, TestLuceneMatchVersion, JsonLoaderTest,
DistribDocExpirationUpdateProcessorTest, TestCloudRecovery, TimeZoneUtilsTest,
DistanceFunctionTest, TestHdfsCloudBackupRestore, TestRTGBase,
TestAtomicUpdateErrorCases, TestCollectionAPI, TestFuzzyAnalyzedSuggestions,
TestLMDirichletSimilarityFactory, PeerSyncWithIndexFingerprintCachingTest,
ClassificationUpdateProcessorFactoryTest, ConnectionManagerTest,
RemoteQueryErrorTest, TestJsonFacets, TestTolerantUpdateProcessorCloud,
SpellCheckCollatorTest, HdfsThreadLeakTest, UpdateRequestProcessorFactoryTest,
SuggesterTest, BlockJoinFacetRandomTest, TestValueSourceCache,
TestMultiWordSynonyms, TestLeaderElectionZkExpiry, VersionInfoTest,
TestGenericDistributedQueue, EchoParamsTest, ClassificationUpdateProcessorTest,
TestLRUCache, LIRRollingUpdatesTest, TestCodecSupport,
CollectionStateFormat2Test, ExecutePlanActionTest, TestSolrQueryParser,
CurrencyFieldTypeTest, CollectionTooManyReplicasTest,
AutoscalingHistoryHandlerTest, SolrSlf4jReporterTest,
TestSolrCloudWithKerberosAlt, SpellPossibilityIteratorTest, TestBulkSchemaAPI,
SimplePostToolTest, ReturnFieldsTest, CircularListTest, TestSchemaNameResource,
TestSimpleQParserPlugin, TestDocBasedVersionConstraints,
DistributedFacetPivotWhiteBoxTest, ManagedSchemaRoundRobinCloudTest,
TestWriterPerf, TestRTimerTree, DeleteReplicaTest, ExternalFileFieldSortTest,
SolrCloudExampleTest, ChaosMonkeySafeLeaderWithPullReplicasTest,
TestSerializedLuceneMatchVersion, TestExclusionRuleCollectionAccess,
UpdateParamsTest, SolrCoreCheckLockOnStartupTest,
CdcrReplicationDistributedZkTest, AssignTest, TestSolrDeletionPolicy2,
TestStressReorder, SpatialRPTFieldTypeTest, DocValuesNotIndexedTest,
DistanceUnitsTest, ReplaceNodeTest, BadIndexSchemaTest, ResponseBuilderTest,
SolrCoreMetricManagerTest, TestCloudInspectUtil, TestSolr4Spatial2,
TestPolicyCloud, TestFastWriter, SpellCheckCollatorWithCollapseTest,
TestSolrIndexConfig, TestDFRSimilarityFactory, TestSchemaManager,
TestSolrFieldCacheBean, ChaosMonkeySafeLeaderTest, UnloadDistributedZkTest,
SyncSliceTest, OpenCloseCoreStressTest, OverseerTest,
LeaderElectionIntegrationTest, ShardRoutingTest, ClusterStateUpdateTest,
TestRandomFaceting, LeaderElectionTest, ZkSolrClientTest, TestZkChroot,
ShardRoutingCustomTest, TestDistributedSearch, TestFaceting, TestRecovery,
TestHashPartitioner, DistributedSpellCheckComponentTest,
TermVectorComponentDistributedTest, ZkControllerTest, TestSort, TestFiltering,
BasicFunctionalityTest, DirectUpdateHandlerTest, TestBadConfig,
SoftAutoCommitTest, CloudExitableDirectoryReaderTest, ConfigSetsAPITest,
CreateRoutedAliasTest, DistributedVersionInfoTest,
LeaderFailoverAfterPartitionTest, MoveReplicaHDFSFailoverTest, MoveReplicaTest,
OverseerModifyCollectionTest, OverseerRolesTest, OverseerStatusTest,
PeerSyncReplicationTest, SSLMigrationTest, SharedFSAutoReplicaFailoverTest,
TestCloudDeleteByQuery, TestConfigSetsAPI, TestLockTree,
TestOnReconnectListenerSupport, TestRandomFlRTGCloud,
TestRandomRequestDistribution, TestShortCircuitedRequests,
TestSizeLimitedDistributedMap, TestSolrCloudWithSecureImpersonation,
TestStressInPlaceUpdates, CollectionsAPIAsyncDistributedZkTest,
TestReplicaProperties, ComputePlanActionTest]
[junit4] Completed [574/775 (1!)] on J1 in 50.55s, 4 tests, 1 failure <<<
FAILURES!
[...truncated 49189 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org
