Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/20625/
Java: 64bit/jdk-9 -XX:+UseCompressedOops -XX:+UseG1GC --illegal-access=deny
1 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.ComputePlanActionTest.testNodeLost
Error Message:
The operations computed by ComputePlanAction should not be null
Stack Trace:
java.lang.AssertionError: The operations computed by ComputePlanAction should
not be null
at
__randomizedtesting.SeedInfo.seed([C60822F581C43AC:B3754CD1DBF6262A]: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.testNodeLost(ComputePlanActionTest.java:193)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
Build Log:
[...truncated 12601 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.ComputePlanActionTest
[junit4] 2> Creating dataDir:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ComputePlanActionTest_C60822F581C43AC-001/init-core-data-001
[junit4] 2> 1073473 WARN
(SUITE-ComputePlanActionTest-seed#[C60822F581C43AC]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=8 numCloses=8
[junit4] 2> 1073478 INFO
(SUITE-ComputePlanActionTest-seed#[C60822F581C43AC]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1073479 INFO
(SUITE-ComputePlanActionTest-seed#[C60822F581C43AC]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0,
clientAuth=0.0/0.0)
[junit4] 2> 1073479 INFO
(SUITE-ComputePlanActionTest-seed#[C60822F581C43AC]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1073479 INFO
(SUITE-ComputePlanActionTest-seed#[C60822F581C43AC]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ComputePlanActionTest_C60822F581C43AC-001/tempDir-001
[junit4] 2> 1073479 INFO
(SUITE-ComputePlanActionTest-seed#[C60822F581C43AC]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1073481 INFO (Thread-2699) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1073481 INFO (Thread-2699) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1073494 ERROR (Thread-2699) [ ] 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> 1073581 INFO
(SUITE-ComputePlanActionTest-seed#[C60822F581C43AC]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:37417
[junit4] 2> 1073594 INFO (jetty-launcher-2808-thread-1) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1073603 INFO (jetty-launcher-2808-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@22893812{/solr,null,AVAILABLE}
[junit4] 2> 1073605 INFO (jetty-launcher-2808-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@49178569{HTTP/1.1,[http/1.1]}{127.0.0.1:37149}
[junit4] 2> 1073605 INFO (jetty-launcher-2808-thread-1) [ ]
o.e.j.s.Server Started @1076263ms
[junit4] 2> 1073605 INFO (jetty-launcher-2808-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=37149}
[junit4] 2> 1073605 ERROR (jetty-launcher-2808-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1073605 INFO (jetty-launcher-2808-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 1073605 INFO (jetty-launcher-2808-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1073605 INFO (jetty-launcher-2808-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 1073605 INFO (jetty-launcher-2808-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-10-07T15:07:33.694802Z
[junit4] 2> 1073610 INFO (jetty-launcher-2808-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1073631 INFO (jetty-launcher-2808-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37417/solr
[junit4] 2> 1073702 INFO (jetty-launcher-2808-thread-1) [ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1073702 INFO (jetty-launcher-2808-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:37149_solr
[junit4] 2> 1073703 INFO (jetty-launcher-2808-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=98788235907629059-127.0.0.1:37149_solr-n_0000000000) starting
[junit4] 2> 1073707 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 1073707 INFO (jetty-launcher-2808-thread-1) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:37149_solr
[junit4] 2> 1073707 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger
[junit4] 2> 1073708 INFO
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1073708 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 1
[junit4] 2> 1073708 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:37149_solr]
[junit4] 2> 1073708 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1,
lastZnodeVersion -1
[junit4] 2> 1073708 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 1
[junit4] 2> 1073709 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 1073710 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1,
lastZnodeVersion 1
[junit4] 2> 1073710 DEBUG (ScheduledTrigger-3980-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: [127.0.0.1:37149_solr]
[junit4] 2> 1073739 INFO (jetty-launcher-2808-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37149.solr.node' (registry
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5b29d865
[junit4] 2> 1073745 INFO (jetty-launcher-2808-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37149.solr.jvm' (registry
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5b29d865
[junit4] 2> 1073745 INFO (jetty-launcher-2808-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37149.solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5b29d865
[junit4] 2> 1073745 INFO (jetty-launcher-2808-thread-1) [ ]
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ComputePlanActionTest_C60822F581C43AC-001/tempDir-001/node1/.
[junit4] 2> 1073754 INFO
(SUITE-ComputePlanActionTest-seed#[C60822F581C43AC]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1073755 INFO
(SUITE-ComputePlanActionTest-seed#[C60822F581C43AC]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37417/solr ready
[junit4] 2> 1073758 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C60822F581C43AC]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testNodeAdded
[junit4] 2> 1073759 DEBUG (zkCallback-2815-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
2
[junit4] 2> 1073759 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 2
[junit4] 2> 1073760 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 1073760 INFO (qtp2095776594-11091) [ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params
name=testNodeLost&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1073760 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 1073760 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2,
lastZnodeVersion 2
[junit4] 2> 1073761 INFO (OverseerThreadFactory-3982-thread-1) [ ]
o.a.s.c.OverseerCollectionMessageHandler Executing Collection Cmd :
action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 1073761 ERROR (OverseerThreadFactory-3982-thread-1) [ ]
o.a.s.c.OverseerCollectionMessageHandler Collection: testNodeLost operation:
delete failed:org.apache.solr.common.SolrException: Could not find collection :
testNodeLost
[junit4] 2> at
org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:111)
[junit4] 2> at
org.apache.solr.cloud.OverseerCollectionMessageHandler.collectionCmd(OverseerCollectionMessageHandler.java:798)
[junit4] 2> at
org.apache.solr.cloud.DeleteCollectionCmd.call(DeleteCollectionCmd.java:91)
[junit4] 2> at
org.apache.solr.cloud.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:229)
[junit4] 2> at
org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:464)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[junit4] 2> at
java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
[junit4] 2> at
java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2>
[junit4] 2> 1073762 INFO (qtp2095776594-11091) [ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=testNodeLost&action=DELETE&wt=javabin&version=2} status=400 QTime=1
[junit4] 2> 1073762 INFO (qtp2095776594-11092) [ ]
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> 1073763 INFO
(OverseerCollectionConfigSetProcessor-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] 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> 1073763 INFO (OverseerThreadFactory-3982-thread-2) [ ]
o.a.s.c.OverseerCollectionMessageHandler Executing Collection Cmd :
action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 1073763 ERROR (OverseerThreadFactory-3982-thread-2) [ ]
o.a.s.c.OverseerCollectionMessageHandler Collection: testNodeAdded operation:
delete failed:org.apache.solr.common.SolrException: Could not find collection :
testNodeAdded
[junit4] 2> at
org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:111)
[junit4] 2> at
org.apache.solr.cloud.OverseerCollectionMessageHandler.collectionCmd(OverseerCollectionMessageHandler.java:798)
[junit4] 2> at
org.apache.solr.cloud.DeleteCollectionCmd.call(DeleteCollectionCmd.java:91)
[junit4] 2> at
org.apache.solr.cloud.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:229)
[junit4] 2> at
org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:464)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[junit4] 2> at
java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
[junit4] 2> at
java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2>
[junit4] 2> 1073763 INFO (qtp2095776594-11092) [ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=testNodeAdded&action=DELETE&wt=javabin&version=2} status=400
QTime=1
[junit4] 2> 1073764 INFO (qtp2095776594-11093) [ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params
name=testNodeWithMultipleReplicasLost&action=DELETE&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 1073764 INFO
(OverseerCollectionConfigSetProcessor-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 1073764 INFO (OverseerThreadFactory-3982-thread-3) [ ]
o.a.s.c.OverseerCollectionMessageHandler Executing Collection Cmd :
action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 1073764 ERROR (OverseerThreadFactory-3982-thread-3) [ ]
o.a.s.c.OverseerCollectionMessageHandler Collection:
testNodeWithMultipleReplicasLost operation: delete
failed:org.apache.solr.common.SolrException: Could not find collection :
testNodeWithMultipleReplicasLost
[junit4] 2> at
org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:111)
[junit4] 2> at
org.apache.solr.cloud.OverseerCollectionMessageHandler.collectionCmd(OverseerCollectionMessageHandler.java:798)
[junit4] 2> at
org.apache.solr.cloud.DeleteCollectionCmd.call(DeleteCollectionCmd.java:91)
[junit4] 2> at
org.apache.solr.cloud.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:229)
[junit4] 2> at
org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:464)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[junit4] 2> at
java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
[junit4] 2> at
java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2>
[junit4] 2> 1073765 INFO (qtp2095776594-11093) [ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=testNodeWithMultipleReplicasLost&action=DELETE&wt=javabin&version=2}
status=400 QTime=1
[junit4] 2> 1073770 INFO (qtp2095776594-11086) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1073770 INFO (qtp2095776594-11086) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37417/solr ready
[junit4] 2> 1073771 INFO (qtp2095776594-11092) [ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1073771 DEBUG (qtp2095776594-11086) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1073772 INFO (qtp2095776594-11086) [ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=6
[junit4] 2> 1073772 DEBUG (zkCallback-2815-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
3
[junit4] 2> 1073772 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 3
[junit4] 2> 1073772 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 1073772 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 1073772 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3,
lastZnodeVersion 3
[junit4] 2> 1073774 INFO (qtp2095776594-11091) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1073774 INFO (qtp2095776594-11091) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37417/solr ready
[junit4] 2> 1073775 INFO (qtp2095776594-11093) [ ]
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> 1073775 DEBUG (qtp2095776594-11091) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1073775 INFO (qtp2095776594-11091) [ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 1073775 DEBUG (zkCallback-2815-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
4
[junit4] 2> 1073776 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 4
[junit4] 2> 1073776 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 1073776 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 1073776 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4,
lastZnodeVersion 4
[junit4] 2> 1073778 INFO (qtp2095776594-11087) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1073778 INFO (qtp2095776594-11087) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37417/solr ready
[junit4] 2> 1073778 INFO (qtp2095776594-11092) [ ]
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> 1073778 DEBUG (qtp2095776594-11087) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1073779 INFO (qtp2095776594-11087) [ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 1073779 DEBUG (zkCallback-2815-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
5
[junit4] 2> 1073781 INFO (qtp2095776594-11086) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1073781 INFO (qtp2095776594-11086) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37417/solr ready
[junit4] 2> 1073781 DEBUG (zkCallback-2815-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:37149_solr]
[junit4] 2> 1073781 DEBUG (zkCallback-2815-thread-1) [ ]
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> 1073781 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 5
[junit4] 2> 1073782 INFO (qtp2095776594-11093) [ ]
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> 1073782 DEBUG (qtp2095776594-11086) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1073782 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 1073782 INFO (qtp2095776594-11086) [ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 1073782 DEBUG (zkCallback-2815-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
6
[junit4] 2> 1073782 DEBUG (ScheduledTrigger-3980-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1073783 DEBUG (ScheduledTrigger-3980-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:37149_solr]
[junit4] 2> 1073783 INFO (qtp2095776594-11091) [ ]
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> 1073783 DEBUG (zkCallback-2815-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:37149_solr]
[junit4] 2> 1073783 DEBUG (zkCallback-2815-thread-1) [ ]
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> 1073783 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6,
lastZnodeVersion 5
[junit4] 2> 1073783 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 6
[junit4] 2> 1073783 INFO (OverseerThreadFactory-3982-thread-4) [ ]
o.a.s.c.CreateCollectionCmd Create collection testNodeAdded
[junit4] 2> 1073783 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 1073784 INFO
(OverseerCollectionConfigSetProcessor-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000004 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 1073784 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6,
lastZnodeVersion 6
[junit4] 2> 1073784 WARN (OverseerThreadFactory-3982-thread-4) [ ]
o.a.s.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> 1073785 INFO (qtp2095776594-11093) [ ]
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> 1073797 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] 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> 1073797 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] o.a.s.c.o.ClusterStateMutator building a new cName: testNodeAdded
[junit4] 2> 1073797 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] o.a.s.c.o.ZkStateWriter going to create_collection
/collections/testNodeAdded/state.json
[junit4] 2> 1073901 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] 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:37149/solr";,
[junit4] 2> "type":"NRT"} current state version: 0
[junit4] 2> 1073901 INFO
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] 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:37149/solr";,
[junit4] 2> "type":"NRT"}
[junit4] 2> 1073902 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 1073902 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] 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:37149/solr";,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 1073903 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] 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_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:37149/solr";,
[junit4] 2> "type":"NRT"} current state version: 0
[junit4] 2> 1073903 INFO
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] 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:37149/solr";,
[junit4] 2> "type":"NRT"}
[junit4] 2> 1073903 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] 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:37149/solr";,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 1073903 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] 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:37149/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:37149/solr";,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 1074005 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeAdded/state.json version: 0
[junit4] 2> 1074105 INFO (qtp2095776594-11086) [ ]
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> 1074105 INFO (qtp2095776594-11089) [ ]
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> 1074106 INFO (qtp2095776594-11086) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 1074108 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] 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:37149/solr";,
[junit4] 2> "node_name":"127.0.0.1:37149_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> 1074108 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] 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:37149/solr";,
[junit4] 2> "node_name":"127.0.0.1:37149_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> 1074108 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] 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:37149/solr","node_name":"127.0.0.1:37149_solr","state":"down","type":"NRT"}
[junit4] 2> 1074108 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] 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:37149/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:37149/solr";,
[junit4] 2> "node_name":"127.0.0.1:37149_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 1074109 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] 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:37149/solr";,
[junit4] 2> "node_name":"127.0.0.1:37149_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> 1074109 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] 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:37149/solr";,
[junit4] 2> "node_name":"127.0.0.1:37149_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> 1074109 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] 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:37149/solr","node_name":"127.0.0.1:37149_solr","state":"down","type":"NRT"}
[junit4] 2> 1074110 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] 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:37149/solr";,
[junit4] 2> "node_name":"127.0.0.1:37149_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:37149/solr";,
[junit4] 2> "node_name":"127.0.0.1:37149_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 1074211 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeAdded/state.json version: 1
[junit4] 2> 1074212 INFO (zkCallback-2815-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 1074212 INFO (zkCallback-2815-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 1074784 DEBUG (ScheduledTrigger-3980-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1074784 DEBUG (ScheduledTrigger-3980-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:37149_solr]
[junit4] 2> 1075126 INFO (qtp2095776594-11089) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1075126 INFO (qtp2095776594-11086) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1075132 INFO (qtp2095776594-11086) [ ] o.a.s.s.IndexSchema
[testNodeAdded_shard1_replica_n1] Schema name=minimal
[junit4] 2> 1075132 INFO (qtp2095776594-11089) [ ] o.a.s.s.IndexSchema
[testNodeAdded_shard1_replica_n2] Schema name=minimal
[junit4] 2> 1075134 INFO (qtp2095776594-11086) [ ] o.a.s.s.IndexSchema
Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1075134 INFO (qtp2095776594-11089) [ ] o.a.s.s.IndexSchema
Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1075134 INFO (qtp2095776594-11086) [ ]
o.a.s.c.CoreContainer Creating SolrCore 'testNodeAdded_shard1_replica_n1' using
configuration from collection testNodeAdded, trusted=true
[junit4] 2> 1075134 INFO (qtp2095776594-11089) [ ]
o.a.s.c.CoreContainer Creating SolrCore 'testNodeAdded_shard1_replica_n2' using
configuration from collection testNodeAdded, trusted=true
[junit4] 2> 1075136 INFO (qtp2095776594-11086) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37149.solr.core.testNodeAdded.shard1.replica_n1' (registry
'solr.core.testNodeAdded.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5b29d865
[junit4] 2> 1075136 INFO (qtp2095776594-11089) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37149.solr.core.testNodeAdded.shard1.replica_n2' (registry
'solr.core.testNodeAdded.shard1.replica_n2') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5b29d865
[junit4] 2> 1075136 INFO (qtp2095776594-11086) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1075136 INFO (qtp2095776594-11089) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1075136 INFO (qtp2095776594-11086) [ ] o.a.s.c.SolrCore
[[testNodeAdded_shard1_replica_n1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ComputePlanActionTest_C60822F581C43AC-001/tempDir-001/node1/testNodeAdded_shard1_replica_n1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ComputePlanActionTest_C60822F581C43AC-001/tempDir-001/node1/./testNodeAdded_shard1_replica_n1/data/]
[junit4] 2> 1075136 INFO (qtp2095776594-11089) [ ] o.a.s.c.SolrCore
[[testNodeAdded_shard1_replica_n2] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ComputePlanActionTest_C60822F581C43AC-001/tempDir-001/node1/testNodeAdded_shard1_replica_n2],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ComputePlanActionTest_C60822F581C43AC-001/tempDir-001/node1/./testNodeAdded_shard1_replica_n2/data/]
[junit4] 2> 1075171 INFO (qtp2095776594-11086) [ ]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1075171 INFO (qtp2095776594-11086) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1075171 INFO (qtp2095776594-11089) [ ]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1075171 INFO (qtp2095776594-11089) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1075171 INFO (qtp2095776594-11086) [ ]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1075171 INFO (qtp2095776594-11086) [ ]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1075171 INFO (qtp2095776594-11089) [ ]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1075171 INFO (qtp2095776594-11089) [ ]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1075172 INFO (qtp2095776594-11086) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@210ddd24[testNodeAdded_shard1_replica_n1] main]
[junit4] 2> 1075172 INFO (qtp2095776594-11089) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@181fc84c[testNodeAdded_shard1_replica_n2] main]
[junit4] 2> 1075173 INFO (qtp2095776594-11089) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 1075173 INFO (qtp2095776594-11086) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 1075173 INFO (qtp2095776594-11089) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1075173 INFO (qtp2095776594-11086) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1075173 INFO (qtp2095776594-11089) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1075173 INFO (qtp2095776594-11086) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1075174 INFO (searcherExecutor-3986-thread-1) [ ]
o.a.s.c.SolrCore [testNodeAdded_shard1_replica_n2] Registered new searcher
Searcher@181fc84c[testNodeAdded_shard1_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1075174 INFO (qtp2095776594-11089) [ ] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1580611776296255488
[junit4] 2> 1075174 INFO (searcherExecutor-3985-thread-1) [ ]
o.a.s.c.SolrCore [testNodeAdded_shard1_replica_n1] Registered new searcher
Searcher@210ddd24[testNodeAdded_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1075174 INFO (qtp2095776594-11086) [ ] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1580611776296255488
[junit4] 2> 1075176 INFO (qtp2095776594-11089) [ ]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1075177 INFO (qtp2095776594-11089) [ ]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1075177 INFO (qtp2095776594-11089) [ ]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:37149/solr/testNodeAdded_shard1_replica_n2/
[junit4] 2> 1075177 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] 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> 1075177 INFO (qtp2095776594-11089) [ ] o.a.s.u.PeerSync
PeerSync: core=testNodeAdded_shard1_replica_n2 url=http://127.0.0.1:37149/solr
START replicas=[http://127.0.0.1:37149/solr/testNodeAdded_shard1_replica_n1/]
nUpdates=100
[junit4] 2> 1075177 INFO (qtp2095776594-11093) [ ] o.a.s.c.S.Request
[testNodeAdded_shard1_replica_n1] webapp=/solr path=/get
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1075277 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeAdded/state.json version: 2
[junit4] 2> 1075278 INFO (zkCallback-2815-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 1075278 INFO (zkCallback-2815-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 1075477 INFO (qtp2095776594-11089) [ ] o.a.s.u.PeerSync
PeerSync: core=testNodeAdded_shard1_replica_n2 url=http://127.0.0.1:37149/solr
DONE. We have no versions. sync failed.
[junit4] 2> 1075477 INFO (qtp2095776594-11089) [ ]
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the
next candidate
[junit4] 2> 1075477 INFO (qtp2095776594-11089) [ ]
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> 1075477 INFO (qtp2095776594-11089) [ ]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 1075483 INFO (qtp2095776594-11089) [ ]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:37149/solr/testNodeAdded_shard1_replica_n2/ shard1
[junit4] 2> 1075484 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] 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:37149/solr";,
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2"} current state
version: 0
[junit4] 2> 1075586 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeAdded/state.json version: 3
[junit4] 2> 1075586 INFO (zkCallback-2815-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 1075586 INFO (zkCallback-2815-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 1075634 INFO (qtp2095776594-11089) [ ]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1075636 INFO (qtp2095776594-11089) [ ]
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=1530
[junit4] 2> 1075636 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] 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:37149/solr";,
[junit4] 2> "node_name":"127.0.0.1:37149_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> 1075636 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] 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:37149/solr";,
[junit4] 2> "node_name":"127.0.0.1:37149_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> 1075636 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] 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:37149/solr","node_name":"127.0.0.1:37149_solr","state":"active","type":"NRT","leader":"true"}
[junit4] 2> 1075636 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] 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:37149/solr";,
[junit4] 2> "node_name":"127.0.0.1:37149_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:37149/solr";,
[junit4] 2> "node_name":"127.0.0.1:37149_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true"}}}}}
[junit4] 2> 1075738 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeAdded/state.json version: 4
[junit4] 2> 1075738 INFO (zkCallback-2815-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 1075738 INFO (zkCallback-2815-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 1075784 DEBUG (ScheduledTrigger-3980-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1075784 DEBUG (ScheduledTrigger-3980-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:37149_solr]
[junit4] 2> 1076178 INFO (qtp2095776594-11086) [ ]
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=2072
[junit4] 2> 1076178 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] 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:37149/solr";,
[junit4] 2> "node_name":"127.0.0.1:37149_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> 1076178 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] 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:37149/solr";,
[junit4] 2> "node_name":"127.0.0.1:37149_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> 1076178 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] 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:37149/solr","node_name":"127.0.0.1:37149_solr","state":"active","type":"NRT"}
[junit4] 2> 1076179 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] 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:37149/solr";,
[junit4] 2> "node_name":"127.0.0.1:37149_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "base_url":"http://127.0.0.1:37149/solr";,
[junit4] 2> "node_name":"127.0.0.1:37149_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true"}}}}}
[junit4] 2> 1076180 INFO (qtp2095776594-11091) [ ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 1076281 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeAdded/state.json version: 5
[junit4] 2> 1076281 INFO (zkCallback-2815-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 1076281 INFO (zkCallback-2815-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 1076784 DEBUG (ScheduledTrigger-3980-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1076784 DEBUG (ScheduledTrigger-3980-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:37149_solr]
[junit4] 2> 1077180 INFO (qtp2095776594-11091) [ ]
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=3397
[junit4] 2> 1077187 INFO (qtp2095776594-11087) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1077188 INFO (qtp2095776594-11087) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37417/solr ready
[junit4] 2> 1077191 INFO (qtp2095776594-11144) [ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=1
[junit4] 2> 1077193 DEBUG (qtp2095776594-11087) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1077194 INFO (qtp2095776594-11087) [ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=11
[junit4] 2> 1077195 DEBUG (zkCallback-2815-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
7
[junit4] 2> 1077195 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C60822F581C43AC]) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1077196 DEBUG (zkCallback-2815-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:37149_solr]
[junit4] 2> 1077196 DEBUG (zkCallback-2815-thread-1) [ ]
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> 1077196 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 7
[junit4] 2> 1077196 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 1077197 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 7,
lastZnodeVersion 7
[junit4] 2> 1077198 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C60822F581C43AC]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@5d3c0c41{/solr,null,AVAILABLE}
[junit4] 2> 1077198 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C60822F581C43AC]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@564dc2fe{HTTP/1.1,[http/1.1]}{127.0.0.1:44225}
[junit4] 2> 1077198 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C60822F581C43AC]) [ ]
o.e.j.s.Server Started @1079857ms
[junit4] 2> 1077199 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C60822F581C43AC]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=44225}
[junit4] 2> 1077199 ERROR
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C60822F581C43AC]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1077200 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C60822F581C43AC]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 1077200 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C60822F581C43AC]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1077200 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C60822F581C43AC]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 1077200 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C60822F581C43AC]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-10-07T15:07:37.289310Z
[junit4] 2> 1077202 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C60822F581C43AC]) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1077211 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C60822F581C43AC]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37417/solr
[junit4] 2> 1077223 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C60822F581C43AC]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1077224 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C60822F581C43AC]) [ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1077226 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C60822F581C43AC]) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:44225_solr
[junit4] 2> 1077227 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:44225_solr"} current state version: 0
[junit4] 2> 1077227 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:44225_solr
[junit4] 2> 1077227 INFO (zkCallback-2815-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1077228 INFO (zkCallback-2820-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1077228 INFO (zkCallback-2841-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1077272 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C60822F581C43AC]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44225.solr.node' (registry
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5b29d865
[junit4] 2> 1077279 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C60822F581C43AC]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44225.solr.jvm' (registry
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5b29d865
[junit4] 2> 1077279 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C60822F581C43AC]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44225.solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5b29d865
[junit4] 2> 1077280 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C60822F581C43AC]) [ ]
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ComputePlanActionTest_C60822F581C43AC-001/tempDir-001/node2/.
[junit4] 2> 1077784 DEBUG (ScheduledTrigger-3980-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1077785 DEBUG (ScheduledTrigger-3980-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:37149_solr,
127.0.0.1:44225_solr]
[junit4] 2> 1077785 DEBUG (ScheduledTrigger-3980-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:44225_solr at time
1507388857874000000
[junit4] 2> 1077785 INFO
(OverseerCollectionConfigSetProcessor-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000006 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 1078786 DEBUG (ScheduledTrigger-3980-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1078786 DEBUG (ScheduledTrigger-3980-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:37149_solr,
127.0.0.1:44225_solr]
[junit4] 2> 1078786 DEBUG (ScheduledTrigger-3980-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger firing
registered processor for nodes: [127.0.0.1:44225_solr] added at times
[1507388857874000000]
[junit4] 2> 1078790 INFO (ScheduledTrigger-3980-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1078792 INFO (ScheduledTrigger-3980-thread-2) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37417/solr ready
[junit4] 2> 1078793 INFO (ScheduledTrigger-3980-thread-2) [ ]
o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling
logging.
[junit4] 2> 1078793 DEBUG (AutoscalingActionExecutor-3981-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -- processing actions for
NodeAddedEvent{id='14eb522e03f62880Tc1oze7k0agnw791ig8cjnb4zo',
source='node_added_trigger', eventTime=1507388857874000000,
properties={eventTimes=[1507388857874000000],
_enqueue_time_=1507388858875000000, nodeNames=[127.0.0.1:44225_solr]}}
[junit4] 2> 1078793 DEBUG (AutoscalingActionExecutor-3981-thread-1) [ ]
o.a.s.c.a.ComputePlanAction -- processing event:
NodeAddedEvent{id='14eb522e03f62880Tc1oze7k0agnw791ig8cjnb4zo',
source='node_added_trigger', eventTime=1507388857874000000,
properties={eventTimes=[1507388857874000000],
_enqueue_time_=1507388858875000000, nodeNames=[127.0.0.1:44225_solr]}} with
context properties: {BEFORE_ACTION=[compute_plan]}
[junit4] 2> 1078795 INFO (AutoscalingActionExecutor-3981-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1078796 INFO (AutoscalingActionExecutor-3981-thread-1) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37417/solr ready
[junit4] 2> 1078796 DEBUG (AutoscalingActionExecutor-3981-thread-1) [ ]
o.a.s.c.a.ComputePlanAction Cluster data provider: {nodeValues={},
liveNodes=[127.0.0.1:44225_solr, 127.0.0.1:37149_solr],
replicaInfo={127.0.0.1:37149_solr={testNodeAdded={shard1=[{core_node3={type=NRT}},
{core_node4={type=NRT}}]}}}}
[junit4] 2> 1078797 INFO (qtp935826393-11161) [ ] 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> 1078798 INFO (qtp2095776594-11093) [ ]
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> 1078798 DEBUG (AutoscalingActionExecutor-3981-thread-1) [ ]
o.a.s.c.a.ComputePlanAction Created suggester with targetNode:
[127.0.0.1:44225_solr]
[junit4] 2> 1078798 INFO (AutoscalingActionExecutor-3981-thread-1) [ ]
o.a.s.c.a.ComputePlanAction Computed Plan:
action=MOVEREPLICA&collection=testNodeAdded&targetNode=127.0.0.1:44225_solr&replica=core_node3
[junit4] 2> 1078798 DEBUG (AutoscalingActionExecutor-3981-thread-1) [ ]
o.a.s.c.a.ComputePlanAction Created suggester with targetNode:
[127.0.0.1:44225_solr]
[junit4] 2> 1078799 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C60822F581C43AC]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testNodeAdded
[junit4] 2> 1078805 INFO
(TEST-ComputePlanActionTest.testNodeWithMultipleReplicasLost-seed#[C60822F581C43AC])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testNodeWithMultipleReplicasLost
[junit4] 2> 1078805 DEBUG (zkCallback-2815-thread-2) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
8
[junit4] 2> 1078806 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 8
[junit4] 2> 1078806 INFO
(TEST-ComputePlanActionTest.testNodeWithMultipleReplicasLost-seed#[C60822F581C43AC])
[ ] o.e.j.s.AbstractConnector Stopped
ServerConnector@49178569{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1078806 INFO
(TEST-ComputePlanActionTest.testNodeWithMultipleReplicasLost-seed#[C60822F581C43AC])
[ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1390573627
[junit4] 2> 1078806 INFO
(TEST-ComputePlanActionTest.testNodeWithMultipleReplicasLost-seed#[C60822F581C43AC])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.node, tag=null
[junit4] 2> 1078806 INFO
(TEST-ComputePlanActionTest.testNodeWithMultipleReplicasLost-seed#[C60822F581C43AC])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@5a2ca408: rootName =
solr_37149, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@4cf812
[junit4] 2> 1078806 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 1078806 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 1078806 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 8,
lastZnodeVersion 8
[junit4] 2> 1078810 INFO
(TEST-ComputePlanActionTest.testNodeWithMultipleReplicasLost-seed#[C60822F581C43AC])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jvm, tag=null
[junit4] 2> 1078811 INFO
(TEST-ComputePlanActionTest.testNodeWithMultipleReplicasLost-seed#[C60822F581C43AC])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@3442e056: rootName =
solr_37149, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@581619ce
[junit4] 2> 1078814 INFO
(TEST-ComputePlanActionTest.testNodeWithMultipleReplicasLost-seed#[C60822F581C43AC])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jetty, tag=null
[junit4] 2> 1078814 INFO
(TEST-ComputePlanActionTest.testNodeWithMultipleReplicasLost-seed#[C60822F581C43AC])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@3c49ada: rootName =
solr_37149, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@59a98157
[junit4] 2> 1078815 INFO
(TEST-ComputePlanActionTest.testNodeWithMultipleReplicasLost-seed#[C60822F581C43AC])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.cluster, tag=null
[junit4] 2> 1078815 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:37149_solr"} current state version: 0
[junit4] 2> 1078815 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:37149_solr
[junit4] 2> 1078815 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] o.a.s.c.o.NodeMutator Update replica state for
core_node3:{"core":"testNodeAdded_shard1_replica_n1","base_url":"http://127.0.0.1:37149/solr","node_name":"127.0.0.1:37149_solr","state":"active","type":"NRT"}
to down
[junit4] 2> 1078815 INFO (coreCloseExecutor-4001-thread-1) [ ]
o.a.s.c.SolrCore [testNodeAdded_shard1_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@1876f60
[junit4] 2> 1078815 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] o.a.s.c.o.NodeMutator Update replica state for
core_node4:{"core":"testNodeAdded_shard1_replica_n2","base_url":"http://127.0.0.1:37149/solr","node_name":"127.0.0.1:37149_solr","state":"active","type":"NRT","leader":"true"}
to down
[junit4] 2> 1078815 INFO (coreCloseExecutor-4001-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.testNodeAdded.shard1.replica_n1, tag=25653088
[junit4] 2> 1078815 INFO (coreCloseExecutor-4001-thread-2) [ ]
o.a.s.c.SolrCore [testNodeAdded_shard1_replica_n2] CLOSING SolrCore
org.apache.solr.core.SolrCore@31be03c0
[junit4] 2> 1078815 INFO (coreCloseExecutor-4001-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@47c9ae53: rootName =
solr_37149, 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@48e4dded
[junit4] 2> 1078815 DEBUG
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeAdded/state.json version: 6
[junit4] 2> 1078816 INFO (zkCallback-2815-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 1078816 INFO (zkCallback-2815-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 1078820 INFO (coreCloseExecutor-4001-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.testNodeAdded.shard1.leader, tag=25653088
[junit4] 2> 1078820 INFO (coreCloseExecutor-4001-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.testNodeAdded.shard1.replica_n2, tag=834536384
[junit4] 2> 1078820 INFO (coreCloseExecutor-4001-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@6095c75f: rootName =
solr_37149, 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@705fc71a
[junit4] 2> 1078825 INFO (coreCloseExecutor-4001-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.testNodeAdded.shard1.leader, tag=834536384
[junit4] 2> 1078826 INFO
(TEST-ComputePlanActionTest.testNodeWithMultipleReplicasLost-seed#[C60822F581C43AC])
[ ] o.a.s.c.Overseer Overseer
(id=98788235907629059-127.0.0.1:37149_solr-n_0000000000) closing
[junit4] 2> 1078826 INFO
(OverseerStateUpdate-98788235907629059-127.0.0.1:37149_solr-n_0000000000) [
] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:37149_solr
[junit4] 2> 1078826 WARN
(OverseerAutoScalingTriggerThread-98788235907629059-127.0.0.1:37149_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 1078826 DEBUG
(TEST-ComputePlanActionTest.testNodeWithMultipleReplicasLost-seed#[C60822F581C43AC])
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed
explicitly
[junit4] 2> 1078827 WARN (zkCallback-2815-thread-1) [ ]
o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK:
[KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1078827 INFO (zkCallback-2841-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:44225_solr
[junit4] 2> 1078827 INFO (zkCallback-2820-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1078827 INFO (zkCallback-2841-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1078827 INFO
(TEST-ComputePlanActionTest.testNodeWithMultipleReplicasLost-seed#[C60822F581C43AC])
[ ] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@22893812{/solr,null,UNAVAILABLE}
[junit4] 2> 1078827 INFO (zkCallback-2841-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=98788235907629069-127.0.0.1:44225_solr-n_0000000001) starting
[junit4] 2> 1078827 INFO (qtp935826393-11161) [ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params
name=testNodeLost&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1078829 DEBUG
(OverseerStateUpdate-98788235907629069-127.0.0.1:44225_solr-n_0000000001) [
] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 1078829 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629069-127.0.0.1:44225_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger
[junit4] 2> 1078829 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629069-127.0.0.1:44225_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 9
[junit4] 2> 1078830 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629069-127.0.0.1:44225_solr-n_0000000001)
[ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:44225_solr]
[junit4] 2> 1078830 INFO (OverseerThreadFactory-4006-thread-1) [ ]
o.a.s.c.OverseerCollectionMessageHandler Executing Collection Cmd :
action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 1078830 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629069-127.0.0.1:44225_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 9,
lastZnodeVersion -1
[junit4] 2> 1078830 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629069-127.0.0.1:44225_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 9
[junit4] 2> 1078830 ERROR (OverseerThreadFactory-4006-thread-1) [ ]
o.a.s.c.OverseerCollectionMessageHandler Collection: testNodeLost operation:
delete failed:org.apache.solr.common.SolrException: Could not find collection :
testNodeLost
[junit4] 2> at
org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:111)
[junit4] 2> at
org.apache.solr.cloud.OverseerCollectionMessageHandler.collectionCmd(OverseerCollectionMessageHandler.java:798)
[junit4] 2> at
org.apache.solr.cloud.DeleteCollectionCmd.call(DeleteCollectionCmd.java:91)
[junit4] 2> at
org.apache.solr.cloud.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:229)
[junit4] 2> at
org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:464)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[junit4] 2> at
java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
[junit4] 2> at
java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2>
[junit4] 2> 1078831 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629069-127.0.0.1:44225_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 1078831 DEBUG
(OverseerAutoScalingTriggerThread-98788235907629069-127.0.0.1:44225_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 9,
lastZnodeVersion 9
[junit4] 2> 1078831 DEBUG (ScheduledTrigger-4004-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: [127.0.0.1:44225_solr]
[junit4] 2> 1078831 INFO (qtp935826393-11161) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/collections
params={name=testNodeLost&action=DELETE&wt=javabin&version=2} status=400 QTime=4
[junit4] 2> 1078832 INFO (qtp935826393-11162) [ ]
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> 1078833 INFO
(OverseerCollectionConfigSetProcessor-98788235907629069-127.0.0.1:44225_solr-n_0000000001)
[ ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000008 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 1078833 INFO (OverseerThreadFactory-4006-thread-2) [ ]
o.a.s.c.OverseerCollectionMessageHandler Executing Collection Cmd :
action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 1078834 DEBUG
(OverseerStateUpdate-98788235907629069-127.0.0.1:44225_solr-n_0000000001) [
] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"delete",
[junit4] 2> "name":"testNodeAdded"} current state version: 0
[junit4] 2> 1078935 DEBUG
(OverseerStateUpdate-98788235907629069-127.0.0.1:44225_solr-n_0000000001) [
] o.a.s.c.o.ZkStateWriter going to delete_collection
/collections/testNodeAdded/state.json
[junit4] 2> 1079537 INFO (qtp935826393-11162) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/collections
params={name=testNodeAdded&action=DELETE&wt=javabin&version=2} status=0
QTime=705
[junit4] 2> 1079537 INFO (qtp935826393-11156) [ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params
name=testNodeWithMultipleReplicasLost&action=DELETE&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 1079540 INFO
(OverseerCollectionConfigSetProcessor-98788235907629069-127.0.0.1:44225_solr-n_0000000001)
[ ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000010 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 1079540 INFO (OverseerThreadFactory-4006-thread-3) [ ]
o.a.s.c.OverseerCollectionMessageHandler Executing Collection Cmd :
action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 1079540 ERROR (OverseerThreadFactory-4006-thread-3) [ ]
o.a.s.c.OverseerCollectionMessageHandler Collection:
testNodeWithMultipleReplicasLost operation: delete
failed:org.apache.solr.common.SolrException: Could not find collection :
testNodeWithMultipleReplicasLost
[junit4] 2> at
org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:111)
[junit4] 2> at
org.apache.solr.cloud.OverseerCollectionMessageHandler.collectionCmd(OverseerCollectionMessageHandler.java:798)
[junit4] 2> at
org.apache.solr.cloud.DeleteCollectionCmd.call(DeleteCollectionCmd.java:91)
[junit4] 2> at
org.apache.solr.cloud.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:229)
[junit4] 2> at
org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:464)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[junit4] 2> at
java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
[junit4] 2> at
java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2>
[junit4] 2> 1079543 INFO (qtp935826393-11156) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/collections
params={name=testNodeWithMultipleReplicasLost&action=DELETE&wt=javabin&version=2}
status=400 QTime=5
[junit4] 2> 1079548 INFO (qtp935826393-11157) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1079549 INFO (qtp935826393-11157) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37417/solr ready
[junit4] 2> 1079550 INFO (qtp935826393-11162) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1079551 DEBUG (qtp935826393-11157) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1079552
[...truncated too long message...]
Executor-4061-thread-1) [ ] o.a.s.c.a.ComputePlanAction Unexpected exception
while processing event:
NodeLostEvent{id='14eb5231c5256400Tc1oze7k0agnw791ig8cjnb502',
source='node_lost_trigger', eventTime=1507388874000000000,
properties={eventTimes=[1507388874000000000],
_enqueue_time_=1507388875000000000, nodeNames=[127.0.0.1:39209_solr]}}
[junit4] 2> org.apache.solr.common.SolrException:
java.util.concurrent.TimeoutException: Could not connect to ZooKeeper
127.0.0.1:37417/solr within 10000 ms
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:183)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:117)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:107)
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader.<init>(ZkStateReader.java:277)
[junit4] 2> at
org.apache.solr.client.solrj.impl.ZkClientClusterStateProvider.connect(ZkClientClusterStateProvider.java:140)
[junit4] 2> at
org.apache.solr.client.solrj.impl.CloudSolrClient.getZkStateReader(CloudSolrClient.java:337)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ComputePlanAction.process(ComputePlanAction.java:58)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$add$2(ScheduledTriggers.java:228)
[junit4] 2> at
java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:514)
[junit4] 2> at
java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[junit4] 2> at
java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
[junit4] 2> at
java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> Caused by: java.util.concurrent.TimeoutException: Could not
connect to ZooKeeper 127.0.0.1:37417/solr within 10000 ms
[junit4] 2> at
org.apache.solr.common.cloud.ConnectionManager.waitForConnected(ConnectionManager.java:233)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:175)
[junit4] 2> ... 13 more
[junit4] 2> 1096082 WARN (zkCallback-2866-thread-3) [ ]
o.a.s.c.SyncStrategy Closed, skipping sync up.
[junit4] 2> 1096082 INFO (zkCallback-2866-thread-3) [ ]
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> 1096082 INFO (zkCallback-2866-thread-3) [ ]
o.a.s.c.SolrCore [testNodeLost_shard1_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@4722eaae
[junit4] 2> 1096082 INFO (zkCallback-2866-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.testNodeLost.shard1.replica_n1, tag=1193470638
[junit4] 2> 1096082 INFO (zkCallback-2866-thread-3) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@78b86304: rootName =
solr_42065, domain = solr.core.testNodeLost.shard1.replica_n1, service url =
null, agent id = null] for registry solr.core.testNodeLost.shard1.replica_n1 /
com.codahale.metrics.MetricRegistry@6e285830
[junit4] 2> 1096095 INFO (zkCallback-2866-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.testNodeLost.shard1.leader, tag=1193470638
[junit4] 2> 1096096 INFO (jetty-closer-2809-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@6ce9ab2{/solr,null,UNAVAILABLE}
[junit4] 2> 1096097 ERROR
(SUITE-ComputePlanActionTest-seed#[C60822F581C43AC]-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> 1096097 INFO
(SUITE-ComputePlanActionTest-seed#[C60822F581C43AC]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:37417 37417
[junit4] 2> 1101870 INFO (Thread-2699) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:37417 37417
[junit4] 2> 1101871 WARN (Thread-2699) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 25 /solr/clusterprops.json
[junit4] 2> 25 /solr/aliases.json
[junit4] 2> 7 /solr/security.json
[junit4] 2> 7 /solr/configs/conf
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 31
/solr/collections/testNodeWithMultipleReplicasLost/state.json
[junit4] 2> 25 /solr/clusterstate.json
[junit4] 2> 13 /solr/collections/testNodeLost/state.json
[junit4] 2> 8 /solr/collections/testNodeAdded/state.json
[junit4] 2> 3 /solr/autoscaling.json
[junit4] 2> 3
/solr/overseer_elect/election/98788235907629079-127.0.0.1:42065_solr-n_0000000003
[junit4] 2> 2
/solr/overseer_elect/election/98788235907629069-127.0.0.1:44225_solr-n_0000000001
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 25 /solr/collections
[junit4] 2> 21 /solr/live_nodes
[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> 2 /solr/overseer/queue-work
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ComputePlanActionTest_C60822F581C43AC-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {},
docValues:{}, maxPointsInLeafNode=2014, maxMBSortInHeap=6.785762441586259,
sim=RandomSimilarity(queryNorm=false): {}, locale=zh-CN, timezone=Indian/Mahe
[junit4] 2> NOTE: Linux 4.10.0-33-generic amd64/Oracle Corporation 9
(64-bit)/cpus=8,threads=1,free=241307216,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestDocTermOrds,
TestComplexPhraseQParserPlugin, TestUseDocValuesAsStored,
DistributedSpellCheckComponentTest, TestSolrDeletionPolicy1,
TestSizeLimitedDistributedMap, DistributedDebugComponentTest, SyncSliceTest,
ConcurrentDeleteAndCreateCollectionTest, RequestHandlersTest,
RollingRestartTest, TestManagedSynonymFilterFactory, TestFaceting,
TestExclusionRuleCollectionAccess, TestRealTimeGet,
BigEndianAscendingWordDeserializerTest, SolrIndexSplitterTest,
TestQueryWrapperFilter, UUIDUpdateProcessorFallbackTest,
IndexBasedSpellCheckerTest, HdfsBasicDistributedZk2Test,
CoreAdminRequestStatusTest, ExitableDirectoryReaderTest,
CreateCollectionCleanupTest, DistributedFacetPivotLargeTest,
PeerSyncReplicationTest, DateRangeFieldTest, ChaosMonkeyNothingIsSafeTest,
TestCollectionAPIs, TestDelegationWithHadoopAuth, SolrShardReporterTest,
TestNumericTerms64, MoveReplicaHDFSFailoverTest,
AddSchemaFieldsUpdateProcessorFactoryTest, TestSearcherReuse,
CollectionTooManyReplicasTest, DistributedVersionInfoTest,
SystemLogListenerTest, TestSQLHandler, TestConfigSetProperties,
TestAuthenticationFramework, TestComponentsName, TestFastOutputStream,
ImplicitSnitchTest, LeaderElectionIntegrationTest,
DeleteLastCustomShardedReplicaTest, ChaosMonkeySafeLeaderWithPullReplicasTest,
TestSchemaResource, SolrIndexConfigTest, TestReloadAndDeleteDocs,
AnalysisErrorHandlingTest, TestStressInPlaceUpdates, SolrRequestParserTest,
TestSerializedLuceneMatchVersion, SolrJmxReporterTest, TestRandomFaceting,
FieldMutatingUpdateProcessorTest, LeaderInitiatedRecoveryOnShardRestartTest,
TestManagedSchema, TestRandomDVFaceting, TestCSVResponseWriter,
DistribDocExpirationUpdateProcessorTest, TestAddFieldRealTimeGet,
TestBadConfig, TestXmlQParser, DefaultValueUpdateProcessorTest,
TestFieldTypeCollectionResource, TestFilteredDocIdSet, TestRandomFlRTGCloud,
ClassificationUpdateProcessorFactoryTest, TestExactStatsCache,
TestLMDirichletSimilarityFactory, SolrGangliaReporterTest,
TestRetrieveFieldsOptimizer, SpatialRPTFieldTypeTest,
TestHighFrequencyDictionaryFactory, TestExtendedDismaxParser, UpdateParamsTest,
TestBlendedInfixSuggestions, TestExceedMaxTermLength, TestDynamicFieldResource,
TestBinaryField, SolrCLIZkUtilsTest, BlockJoinFacetRandomTest,
DocumentAnalysisRequestHandlerTest, DebugComponentTest,
TestPHPSerializedResponseWriter, TestCrossCoreJoin, TestMacros,
TestGeoJSONResponseWriter, TestPushWriter, V2StandaloneTest, HLLUtilTest,
TestDistributedSearch, TestBackupRepositoryFactory,
TestReplicationHandlerBackup, TestSolrCloudWithKerberosAlt,
HighlighterMaxOffsetTest, TemplateUpdateProcessorTest, TestNumericRangeQuery64,
HdfsChaosMonkeySafeLeaderTest, TestPKIAuthenticationPlugin,
MultiThreadedOCPTest, ParsingFieldUpdateProcessorsTest, TestPrepRecovery,
ActionThrottleTest, TestLegacyField, DistributedQueryComponentOptimizationTest,
TestFunctionQuery, HdfsSyncSliceTest, TestLuceneIndexBackCompat,
ComputePlanActionTest]
[junit4] Completed [335/745 (1!)] on J0 in 28.42s, 3 tests, 1 failure <<<
FAILURES!
[...truncated 41124 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:826: The following
error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:706: The following
error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:693: Source checkout
is dirty (unversioned/missing files) after running tests!!! Offending files:
* lucene/licenses/morfologik-ukrainian-search-3.7.5.jar.sha1
Total time: 66 minutes 41 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org
