Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/1022/
Java: 64bit/jdk-9.0.1 -XX:+UseCompressedOops -XX:+UseSerialGC
6 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.core.TestLazyCores
Error Message:
1 thread leaked from SUITE scope at org.apache.solr.core.TestLazyCores: 1)
Thread[id=5107, name=searcherExecutor-2042-thread-1, state=WAITING,
group=TGRP-TestLazyCores] at
java.base@9.0.1/jdk.internal.misc.Unsafe.park(Native Method) at
java.base@9.0.1/java.util.concurrent.locks.LockSupport.park(LockSupport.java:194)
at
java.base@9.0.1/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2062)
at
java.base@9.0.1/java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:435)
at
java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1092)
at
java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1152)
at
java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
at java.base@9.0.1/java.lang.Thread.run(Thread.java:844)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE
scope at org.apache.solr.core.TestLazyCores:
1) Thread[id=5107, name=searcherExecutor-2042-thread-1, state=WAITING,
group=TGRP-TestLazyCores]
at java.base@9.0.1/jdk.internal.misc.Unsafe.park(Native Method)
at
java.base@9.0.1/java.util.concurrent.locks.LockSupport.park(LockSupport.java:194)
at
java.base@9.0.1/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2062)
at
java.base@9.0.1/java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:435)
at
java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1092)
at
java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1152)
at
java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
at java.base@9.0.1/java.lang.Thread.run(Thread.java:844)
at __randomizedtesting.SeedInfo.seed([421F99C15779D41E]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.core.TestLazyCores
Error Message:
There are still zombie threads that couldn't be terminated: 1)
Thread[id=5107, name=searcherExecutor-2042-thread-1, state=WAITING,
group=TGRP-TestLazyCores] at
java.base@9.0.1/jdk.internal.misc.Unsafe.park(Native Method) at
java.base@9.0.1/java.util.concurrent.locks.LockSupport.park(LockSupport.java:194)
at
java.base@9.0.1/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2062)
at
java.base@9.0.1/java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:435)
at
java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1092)
at
java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1152)
at
java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
at java.base@9.0.1/java.lang.Thread.run(Thread.java:844)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie
threads that couldn't be terminated:
1) Thread[id=5107, name=searcherExecutor-2042-thread-1, state=WAITING,
group=TGRP-TestLazyCores]
at java.base@9.0.1/jdk.internal.misc.Unsafe.park(Native Method)
at
java.base@9.0.1/java.util.concurrent.locks.LockSupport.park(LockSupport.java:194)
at
java.base@9.0.1/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2062)
at
java.base@9.0.1/java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:435)
at
java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1092)
at
java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1152)
at
java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
at java.base@9.0.1/java.lang.Thread.run(Thread.java:844)
at __randomizedtesting.SeedInfo.seed([421F99C15779D41E]:0)
FAILED: org.apache.solr.cloud.autoscaling.ComputePlanActionTest.testNodeAdded
Error Message:
Timed out waiting for replicas of new collection to be active null Live Nodes:
[127.0.0.1:46671_solr] Last available state:
DocCollection(testNodeAdded//collections/testNodeAdded/state.json/4)={
"pullReplicas":"0", "replicationFactor":"2", "shards":{"shard1":{
"range":"80000000-7fffffff", "state":"active", "replicas":{
"core_node3":{ "core":"testNodeAdded_shard1_replica_n1",
"base_url":"https://127.0.0.1:46671/solr";,
"node_name":"127.0.0.1:46671_solr", "state":"down",
"type":"NRT"}, "core_node4":{
"core":"testNodeAdded_shard1_replica_n2",
"base_url":"https://127.0.0.1:46671/solr";,
"node_name":"127.0.0.1:46671_solr", "state":"down",
"type":"NRT", "leader":"true"}}}}, "router":{"name":"compositeId"},
"maxShardsPerNode":"1", "autoAddReplicas":"false", "nrtReplicas":"2",
"tlogReplicas":"0"}
Stack Trace:
java.lang.AssertionError: Timed out waiting for replicas of new collection to
be active
null
Live Nodes: [127.0.0.1:46671_solr]
Last available state:
DocCollection(testNodeAdded//collections/testNodeAdded/state.json/4)={
"pullReplicas":"0",
"replicationFactor":"2",
"shards":{"shard1":{
"range":"80000000-7fffffff",
"state":"active",
"replicas":{
"core_node3":{
"core":"testNodeAdded_shard1_replica_n1",
"base_url":"https://127.0.0.1:46671/solr";,
"node_name":"127.0.0.1:46671_solr",
"state":"down",
"type":"NRT"},
"core_node4":{
"core":"testNodeAdded_shard1_replica_n2",
"base_url":"https://127.0.0.1:46671/solr";,
"node_name":"127.0.0.1:46671_solr",
"state":"down",
"type":"NRT",
"leader":"true"}}}},
"router":{"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"false",
"nrtReplicas":"2",
"tlogReplicas":"0"}
at
__randomizedtesting.SeedInfo.seed([421F99C15779D41E:27DCCFB6F5DA7C1D]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.cloud.SolrCloudTestCase.waitForState(SolrCloudTestCase.java:269)
at
org.apache.solr.cloud.autoscaling.ComputePlanActionTest.testNodeAdded(ComputePlanActionTest.java:347)
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)
FAILED:
org.apache.solr.cloud.autoscaling.ComputePlanActionTest.testNodeWithMultipleReplicasLost
Error Message:
Error from server at https://127.0.0.1:42057/solr: Could not fully create
collection: testNodeWithMultipleReplicasLost
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error
from server at https://127.0.0.1:42057/solr: Could not fully create collection:
testNodeWithMultipleReplicasLost
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1104)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:884)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:817)
at
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194)
at
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211)
at
org.apache.solr.cloud.autoscaling.ComputePlanActionTest.testNodeWithMultipleReplicasLost(ComputePlanActionTest.java:267)
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)
FAILED: org.apache.solr.cloud.autoscaling.ComputePlanActionTest.testNodeLost
Error Message:
Error from server at https://127.0.0.1:36867/solr: Could not fully create
collection: testNodeLost
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error
from server at https://127.0.0.1:36867/solr: Could not fully create collection:
testNodeLost
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1104)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:884)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:817)
at
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194)
at
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211)
at
org.apache.solr.cloud.autoscaling.ComputePlanActionTest.testNodeLost(ComputePlanActionTest.java:181)
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)
FAILED: org.apache.solr.core.TestLazyCores.testNoCommit
Error Message:
Exception during query
Stack Trace:
java.lang.RuntimeException: Exception during query
at
__randomizedtesting.SeedInfo.seed([421F99C15779D41E:9D7F38109C5EB7BB]:0)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:904)
at org.apache.solr.core.TestLazyCores.check10(TestLazyCores.java:847)
at
org.apache.solr.core.TestLazyCores.testNoCommit(TestLazyCores.java:829)
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)
Caused by: java.lang.RuntimeException: REQUEST FAILED:
xpath=//result[@numFound='10']
xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int
name="QTime">0</int><lst name="params"><str
name="q">*:*</str></lst></lst><result name="response" numFound="0"
start="0"></result>
</response>
request was:q=*:*
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:897)
... 40 more
Build Log:
[...truncated 11958 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.ComputePlanActionTest
[junit4] 2> 16328 INFO
(SUITE-ComputePlanActionTest-seed#[421F99C15779D41E]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ComputePlanActionTest_421F99C15779D41E-001/init-core-data-001
[junit4] 2> 16358 WARN
(SUITE-ComputePlanActionTest-seed#[421F99C15779D41E]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
[junit4] 2> 16359 INFO
(SUITE-ComputePlanActionTest-seed#[421F99C15779D41E]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 16360 INFO
(SUITE-ComputePlanActionTest-seed#[421F99C15779D41E]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) 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> 16567 INFO
(SUITE-ComputePlanActionTest-seed#[421F99C15779D41E]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ComputePlanActionTest_421F99C15779D41E-001/tempDir-001
[junit4] 2> 16572 INFO
(SUITE-ComputePlanActionTest-seed#[421F99C15779D41E]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 16609 INFO (Thread-1) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 16610 INFO (Thread-1) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 16715 INFO
(SUITE-ComputePlanActionTest-seed#[421F99C15779D41E]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:46793
[junit4] 2> 16824 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Exception causing close of session 0x0 due to
java.io.IOException: ZooKeeperServer not running
[junit4] 2> 16946 ERROR (Thread-1) [ ] 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> 18270 INFO (zkConnectionManagerCallback-6-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 18460 INFO (jetty-launcher-3-thread-1) [ ] o.e.j.s.Server
jetty-9.3.20.v20170531
[junit4] 2> 18582 INFO (jetty-launcher-3-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@77c18727{/solr,null,AVAILABLE}
[junit4] 2> 18653 INFO (jetty-launcher-3-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@3ca436e7{SSL,[ssl,
http/1.1]}{127.0.0.1:46671}
[junit4] 2> 18653 INFO (jetty-launcher-3-thread-1) [ ] o.e.j.s.Server
Started @20774ms
[junit4] 2> 18654 INFO (jetty-launcher-3-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=46671}
[junit4] 2> 18670 ERROR (jetty-launcher-3-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 18670 INFO (jetty-launcher-3-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.3.0
[junit4] 2> 18671 INFO (jetty-launcher-3-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 18677 INFO (jetty-launcher-3-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 18678 INFO (jetty-launcher-3-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-12-21T00:12:43.280365Z
[junit4] 2> 18679 INFO (jetty-launcher-3-thread-1) [ ]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 18688 INFO (zkConnectionManagerCallback-8-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 18706 INFO (jetty-launcher-3-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 18818 INFO (jetty-launcher-3-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46793/solr
[junit4] 2> 18866 INFO (zkConnectionManagerCallback-12-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 18934 INFO
(zkConnectionManagerCallback-14-thread-1-processing-n:127.0.0.1:46671_solr)
[n:127.0.0.1:46671_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 19270 INFO (jetty-launcher-3-thread-1)
[n:127.0.0.1:46671_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 19276 INFO (jetty-launcher-3-thread-1)
[n:127.0.0.1:46671_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:46671_solr
[junit4] 2> 19285 INFO (jetty-launcher-3-thread-1)
[n:127.0.0.1:46671_solr ] o.a.s.c.Overseer Overseer
(id=99209390411022339-127.0.0.1:46671_solr-n_0000000000) starting
[junit4] 2> 19385 DEBUG
(OverseerStateUpdate-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 19399 INFO (jetty-launcher-3-thread-1)
[n:127.0.0.1:46671_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:46671_solr
[junit4] 2> 19434 INFO
(zkCallback-13-thread-1-processing-n:127.0.0.1:46671_solr)
[n:127.0.0.1:46671_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 19471 DEBUG
(OverseerAutoScalingTriggerThread-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.OverseerTriggerThread Adding
.autoAddReplicas trigger
[junit4] 2> 19488 DEBUG
(OverseerAutoScalingTriggerThread-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 1
[junit4] 2> 19540 DEBUG
(OverseerAutoScalingTriggerThread-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:46671_solr]
[junit4] 2> 19540 DEBUG
(OverseerAutoScalingTriggerThread-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion -1
[junit4] 2> 19540 DEBUG
(OverseerAutoScalingTriggerThread-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 1
[junit4] 2> 19559 DEBUG
(OverseerAutoScalingTriggerThread-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 19560 DEBUG (ScheduledTrigger-18-thread-1)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 1
[junit4] 2> 19563 DEBUG
(OverseerAutoScalingTriggerThread-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 19805 INFO (jetty-launcher-3-thread-1)
[n:127.0.0.1:46671_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46671.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2841738f
[junit4] 2> 19806 INFO (jetty-launcher-3-thread-1)
[n:127.0.0.1:46671_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46671.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2841738f
[junit4] 2> 19807 INFO (jetty-launcher-3-thread-1)
[n:127.0.0.1:46671_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46671.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2841738f
[junit4] 2> 19839 INFO (jetty-launcher-3-thread-1)
[n:127.0.0.1:46671_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ComputePlanActionTest_421F99C15779D41E-001/tempDir-001/node1/.
[junit4] 2> 19936 INFO (zkConnectionManagerCallback-18-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 19957 INFO (zkConnectionManagerCallback-22-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 19965 INFO
(SUITE-ComputePlanActionTest-seed#[421F99C15779D41E]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 19971 INFO
(SUITE-ComputePlanActionTest-seed#[421F99C15779D41E]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:46793/solr ready
[junit4] 2> 19990 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[421F99C15779D41E]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testNodeAdded
[junit4] 2> 19994 DEBUG
(zkCallback-13-thread-1-processing-n:127.0.0.1:46671_solr)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 2
[junit4] 2> 20002 DEBUG
(OverseerAutoScalingTriggerThread-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 2
[junit4] 2> 20008 DEBUG
(OverseerAutoScalingTriggerThread-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 20009 DEBUG
(OverseerAutoScalingTriggerThread-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 20009 DEBUG
(OverseerAutoScalingTriggerThread-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 20412 INFO (qtp351978002-44) [n:127.0.0.1:46671_solr ]
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> 20433 INFO
(OverseerThreadFactory-20-thread-1-processing-n:127.0.0.1:46671_solr)
[n:127.0.0.1:46671_solr ] o.a.s.c.OverseerCollectionMessageHandler Executing
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 20435 ERROR
(OverseerThreadFactory-20-thread-1-processing-n:127.0.0.1:46671_solr)
[n:127.0.0.1:46671_solr ] 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:118)
[junit4] 2> at
org.apache.solr.cloud.OverseerCollectionMessageHandler.collectionCmd(OverseerCollectionMessageHandler.java:803)
[junit4] 2> at
org.apache.solr.cloud.DeleteCollectionCmd.call(DeleteCollectionCmd.java:94)
[junit4] 2> at
org.apache.solr.cloud.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:242)
[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> 20484 INFO
(OverseerCollectionConfigSetProcessor-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 20489 INFO (qtp351978002-44) [n:127.0.0.1:46671_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=testNodeLost&action=DELETE&wt=javabin&version=2} status=400
QTime=80
[junit4] 2> 20529 INFO (qtp351978002-38) [n:127.0.0.1:46671_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params
name=testNodeAdded&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 20538 INFO
(OverseerThreadFactory-20-thread-2-processing-n:127.0.0.1:46671_solr)
[n:127.0.0.1:46671_solr ] o.a.s.c.OverseerCollectionMessageHandler Executing
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 20538 ERROR
(OverseerThreadFactory-20-thread-2-processing-n:127.0.0.1:46671_solr)
[n:127.0.0.1:46671_solr ] 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:118)
[junit4] 2> at
org.apache.solr.cloud.OverseerCollectionMessageHandler.collectionCmd(OverseerCollectionMessageHandler.java:803)
[junit4] 2> at
org.apache.solr.cloud.DeleteCollectionCmd.call(DeleteCollectionCmd.java:94)
[junit4] 2> at
org.apache.solr.cloud.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:242)
[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> 20543 INFO (qtp351978002-38) [n:127.0.0.1:46671_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=testNodeAdded&action=DELETE&wt=javabin&version=2} status=400
QTime=14
[junit4] 2> 20548 INFO (qtp351978002-43) [n:127.0.0.1:46671_solr ]
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> 20562 INFO
(OverseerCollectionConfigSetProcessor-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] 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> 20566 INFO
(OverseerThreadFactory-20-thread-3-processing-n:127.0.0.1:46671_solr)
[n:127.0.0.1:46671_solr ] o.a.s.c.OverseerCollectionMessageHandler Executing
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 20567 ERROR
(OverseerThreadFactory-20-thread-3-processing-n:127.0.0.1:46671_solr)
[n:127.0.0.1:46671_solr ] 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:118)
[junit4] 2> at
org.apache.solr.cloud.OverseerCollectionMessageHandler.collectionCmd(OverseerCollectionMessageHandler.java:803)
[junit4] 2> at
org.apache.solr.cloud.DeleteCollectionCmd.call(DeleteCollectionCmd.java:94)
[junit4] 2> at
org.apache.solr.cloud.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:242)
[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> 20573 INFO (qtp351978002-43) [n:127.0.0.1:46671_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=testNodeWithMultipleReplicasLost&action=DELETE&wt=javabin&version=2}
status=400 QTime=24
[junit4] 2> 20619 INFO (qtp351978002-39) [n:127.0.0.1:46671_solr ]
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=2
[junit4] 2> 20622 DEBUG (qtp351978002-45) [n:127.0.0.1:46671_solr ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 20624 DEBUG
(zkCallback-13-thread-2-processing-n:127.0.0.1:46671_solr)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 3
[junit4] 2> 20624 INFO (qtp351978002-45) [n:127.0.0.1:46671_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=43
[junit4] 2> 20626 DEBUG
(OverseerAutoScalingTriggerThread-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 3
[junit4] 2> 20626 DEBUG
(OverseerAutoScalingTriggerThread-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 20626 DEBUG
(OverseerAutoScalingTriggerThread-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 20627 DEBUG
(OverseerAutoScalingTriggerThread-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 20636 INFO (qtp351978002-43) [n:127.0.0.1:46671_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=4
[junit4] 2> 20638 DEBUG (qtp351978002-45) [n:127.0.0.1:46671_solr ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 20639 DEBUG
(zkCallback-13-thread-2-processing-n:127.0.0.1:46671_solr)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 4
[junit4] 2> 20639 DEBUG
(OverseerAutoScalingTriggerThread-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 4
[junit4] 2> 20639 DEBUG
(OverseerAutoScalingTriggerThread-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 20640 DEBUG
(OverseerAutoScalingTriggerThread-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 20640 DEBUG
(OverseerAutoScalingTriggerThread-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 4, lastZnodeVersion 4
[junit4] 2> 20640 INFO (qtp351978002-45) [n:127.0.0.1:46671_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=12
[junit4] 2> 20655 INFO (qtp351978002-43) [n:127.0.0.1:46671_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 20656 DEBUG (qtp351978002-45) [n:127.0.0.1:46671_solr ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 20658 DEBUG
(zkCallback-13-thread-2-processing-n:127.0.0.1:46671_solr)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 5
[junit4] 2> 20660 INFO (qtp351978002-45) [n:127.0.0.1:46671_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=13
[junit4] 2> 20660 DEBUG
(zkCallback-13-thread-2-processing-n:127.0.0.1:46671_solr)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:46671_solr]
[junit4] 2> 20660 DEBUG
(zkCallback-13-thread-2-processing-n:127.0.0.1:46671_solr)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=1,
actions=[{name=compute_plan, class=solr.ComputePlanAction}, {name=test,
class=org.apache.solr.cloud.autoscaling.ComputePlanActionTest$AssertingTriggerAction}],
enabled=true}
[junit4] 2> 20660 DEBUG
(OverseerAutoScalingTriggerThread-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 5
[junit4] 2> 20669 INFO (qtp351978002-39) [n:127.0.0.1:46671_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 20671 DEBUG (qtp351978002-45) [n:127.0.0.1:46671_solr ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 20673 DEBUG
(zkCallback-13-thread-2-processing-n:127.0.0.1:46671_solr)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 6
[junit4] 2> 20673 INFO (qtp351978002-45) [n:127.0.0.1:46671_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=7
[junit4] 2> 20674 DEBUG
(zkCallback-13-thread-2-processing-n:127.0.0.1:46671_solr)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:46671_solr]
[junit4] 2> 20674 DEBUG
(zkCallback-13-thread-2-processing-n:127.0.0.1:46671_solr)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=1,
actions=[{name=compute_plan, class=solr.ComputePlanAction}, {name=test,
class=org.apache.solr.cloud.autoscaling.ComputePlanActionTest$AssertingTriggerAction}],
enabled=true}
[junit4] 2> 20681 INFO (qtp351978002-44) [n:127.0.0.1:46671_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=2&collection.configName=conf&name=testNodeAdded&nrtReplicas=2&action=CREATE&numShards=1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 20682 DEBUG
(OverseerAutoScalingTriggerThread-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 20665 DEBUG (ScheduledTrigger-18-thread-2)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 20688 DEBUG (ScheduledTrigger-18-thread-2)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 20687 DEBUG
(OverseerAutoScalingTriggerThread-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 6, lastZnodeVersion 5
[junit4] 2> 20689 DEBUG
(OverseerAutoScalingTriggerThread-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 6
[junit4] 2> 20690 DEBUG
(OverseerAutoScalingTriggerThread-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 20691 DEBUG
(OverseerAutoScalingTriggerThread-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 6, lastZnodeVersion 6
[junit4] 2> 20694 INFO
(OverseerCollectionConfigSetProcessor-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] 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> 20706 INFO
(OverseerThreadFactory-20-thread-4-processing-n:127.0.0.1:46671_solr)
[n:127.0.0.1:46671_solr ] o.a.s.c.CreateCollectionCmd Create collection
testNodeAdded
[junit4] 2> 20713 WARN
(OverseerThreadFactory-20-thread-4-processing-n:127.0.0.1:46671_solr)
[n:127.0.0.1:46671_solr ] 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> 20727 INFO (qtp351978002-38) [n:127.0.0.1:46671_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=1
[junit4] 2> 20748 INFO
(OverseerThreadFactory-20-thread-4-processing-n:127.0.0.1:46671_solr)
[n:127.0.0.1:46671_solr ] o.a.s.c.s.c.a.PolicyHelper returnSession,
curr-time 1516307322 sessionWrapper.createTime 1516307305251525,
this.sessionWrapper.createTime 1516307305251525
[junit4] 2> 20770 DEBUG
(OverseerStateUpdate-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "name":"testNodeAdded",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"conf",
[junit4] 2> "numShards":"1",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 20772 DEBUG
(OverseerStateUpdate-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.o.ClusterStateMutator building a new
cName: testNodeAdded
[junit4] 2> 20773 DEBUG
(OverseerStateUpdate-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.o.ZkStateWriter going to create_collection
/collections/testNodeAdded/state.json
[junit4] 2> 20879 DEBUG
(OverseerStateUpdate-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:46671/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 20880 INFO
(OverseerStateUpdate-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:46671/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 20884 DEBUG
(OverseerStateUpdate-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 20884 DEBUG
(OverseerStateUpdate-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node3":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "base_url":"https://127.0.0.1:46671/solr";,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 20885 DEBUG
(OverseerStateUpdate-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.Overseer processMessage: queueSize: 2,
message = {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:46671/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 20885 INFO
(OverseerStateUpdate-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:46671/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 20888 DEBUG
(OverseerStateUpdate-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node3":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "base_url":"https://127.0.0.1:46671/solr";,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 20888 DEBUG
(OverseerStateUpdate-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "base_url":"https://127.0.0.1:46671/solr";,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "base_url":"https://127.0.0.1:46671/solr";,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 20991 DEBUG
(OverseerStateUpdate-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeAdded/state.json version: 0
[junit4] 2> 21126 INFO (qtp351978002-40) [n:127.0.0.1:46671_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=testNodeAdded_shard1_replica_n1&action=CREATE&numShards=1&collection=testNodeAdded&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 21127 INFO (qtp351978002-40) [n:127.0.0.1:46671_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 21179 INFO (qtp351978002-85) [n:127.0.0.1:46671_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=testNodeAdded_shard1_replica_n2&action=CREATE&numShards=1&collection=testNodeAdded&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 21182 DEBUG
(OverseerStateUpdate-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node3",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:46671/solr";,
[junit4] 2> "node_name":"127.0.0.1:46671_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> 21183 DEBUG
(OverseerStateUpdate-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1
message={
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node3",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:46671/solr";,
[junit4] 2> "node_name":"127.0.0.1:46671_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> 21184 DEBUG
(OverseerStateUpdate-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.o.ReplicaMutator Will update state for
replica:
core_node3:{"core":"testNodeAdded_shard1_replica_n1","base_url":"https://127.0.0.1:46671/solr","node_name":"127.0.0.1:46671_solr","state":"down","type":"NRT"}
[junit4] 2> 21184 DEBUG
(OverseerStateUpdate-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.o.ReplicaMutator Collection is now:
DocCollection(testNodeAdded//collections/testNodeAdded/state.json/1)={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "base_url":"https://127.0.0.1:46671/solr";,
[junit4] 2> "node_name":"127.0.0.1:46671_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "base_url":"https://127.0.0.1:46671/solr";,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 21189 DEBUG
(OverseerStateUpdate-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.Overseer processMessage: queueSize: 2,
message = {
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "core_node_name":"core_node4",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:46671/solr";,
[junit4] 2> "node_name":"127.0.0.1:46671_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> 21189 DEBUG
(OverseerStateUpdate-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1
message={
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "core_node_name":"core_node4",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:46671/solr";,
[junit4] 2> "node_name":"127.0.0.1:46671_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> 21189 DEBUG
(OverseerStateUpdate-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.o.ReplicaMutator Will update state for
replica:
core_node4:{"core":"testNodeAdded_shard1_replica_n2","base_url":"https://127.0.0.1:46671/solr","node_name":"127.0.0.1:46671_solr","state":"down","type":"NRT"}
[junit4] 2> 21189 DEBUG
(OverseerStateUpdate-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.o.ReplicaMutator Collection is now:
DocCollection(testNodeAdded//collections/testNodeAdded/state.json/1)={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "base_url":"https://127.0.0.1:46671/solr";,
[junit4] 2> "node_name":"127.0.0.1:46671_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "base_url":"https://127.0.0.1:46671/solr";,
[junit4] 2> "node_name":"127.0.0.1:46671_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 21290 DEBUG
(OverseerStateUpdate-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeAdded/state.json version: 1
[junit4] 2> 21291 INFO
(zkCallback-13-thread-1-processing-n:127.0.0.1:46671_solr)
[n:127.0.0.1:46671_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 21291 INFO
(zkCallback-13-thread-2-processing-n:127.0.0.1:46671_solr)
[n:127.0.0.1:46671_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 21292 INFO
(zkCallback-13-thread-3-processing-n:127.0.0.1:46671_solr)
[n:127.0.0.1:46671_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 21692 DEBUG (ScheduledTrigger-18-thread-1)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 21692 DEBUG (ScheduledTrigger-18-thread-1)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 22206 INFO (qtp351978002-85) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 22218 INFO (qtp351978002-40) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 22237 INFO (qtp351978002-40) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.s.IndexSchema [testNodeAdded_shard1_replica_n1] Schema name=minimal
[junit4] 2> 22245 INFO (qtp351978002-85) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.s.IndexSchema [testNodeAdded_shard1_replica_n2] Schema name=minimal
[junit4] 2> 22244 WARN (qtp351978002-40) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class
[solr.TrieIntField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 22252 WARN (qtp351978002-40) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class
[solr.TrieLongField]. Please consult documentation how to replace it
accordingly.
[junit4] 2> 22252 WARN (qtp351978002-85) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class
[solr.TrieLongField]. Please consult documentation how to replace it
accordingly.
[junit4] 2> 22258 INFO (qtp351978002-85) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 22258 INFO (qtp351978002-85) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.CoreContainer Creating SolrCore 'testNodeAdded_shard1_replica_n2' using
configuration from collection testNodeAdded, trusted=true
[junit4] 2> 22271 INFO (qtp351978002-85) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46671.solr.core.testNodeAdded.shard1.replica_n2' (registry
'solr.core.testNodeAdded.shard1.replica_n2') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2841738f
[junit4] 2> 22272 INFO (qtp351978002-40) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 22272 INFO (qtp351978002-40) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.c.CoreContainer Creating SolrCore 'testNodeAdded_shard1_replica_n1' using
configuration from collection testNodeAdded, trusted=true
[junit4] 2> 22272 INFO (qtp351978002-85) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 22272 INFO (qtp351978002-40) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46671.solr.core.testNodeAdded.shard1.replica_n1' (registry
'solr.core.testNodeAdded.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2841738f
[junit4] 2> 22273 INFO (qtp351978002-40) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 22273 INFO (qtp351978002-40) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.c.SolrCore [[testNodeAdded_shard1_replica_n1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ComputePlanActionTest_421F99C15779D41E-001/tempDir-001/node1/testNodeAdded_shard1_replica_n1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ComputePlanActionTest_421F99C15779D41E-001/tempDir-001/node1/./testNodeAdded_shard1_replica_n1/data/]
[junit4] 2> 22272 INFO (qtp351978002-85) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.SolrCore [[testNodeAdded_shard1_replica_n2] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ComputePlanActionTest_421F99C15779D41E-001/tempDir-001/node1/testNodeAdded_shard1_replica_n2],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ComputePlanActionTest_421F99C15779D41E-001/tempDir-001/node1/./testNodeAdded_shard1_replica_n2/data/]
[junit4] 2> 22454 INFO (qtp351978002-40) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 22455 INFO (qtp351978002-40) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 22467 INFO (qtp351978002-40) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 22467 INFO (qtp351978002-40) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 22468 INFO (qtp351978002-85) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 22469 INFO (qtp351978002-85) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 22474 INFO (qtp351978002-85) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 22474 INFO (qtp351978002-85) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 22479 INFO (qtp351978002-85) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@3dca84c4[testNodeAdded_shard1_replica_n2] main]
[junit4] 2> 22483 INFO (qtp351978002-40) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@6d720e59[testNodeAdded_shard1_replica_n1] main]
[junit4] 2> 22484 INFO (qtp351978002-85) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 22484 INFO (qtp351978002-40) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 22487 INFO (qtp351978002-40) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 22487 INFO (qtp351978002-85) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 22488 INFO (qtp351978002-40) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 22489 INFO (qtp351978002-85) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 22499 INFO
(searcherExecutor-23-thread-1-processing-n:127.0.0.1:46671_solr
x:testNodeAdded_shard1_replica_n2 s:shard1 c:testNodeAdded r:core_node4)
[n:127.0.0.1:46671_solr c:testNodeAdded s:shard1 r:core_node4
x:testNodeAdded_shard1_replica_n2] o.a.s.c.SolrCore
[testNodeAdded_shard1_replica_n2] Registered new searcher
Searcher@3dca84c4[testNodeAdded_shard1_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 22500 INFO (qtp351978002-85) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1587350252659146752
[junit4] 2> 22500 INFO
(searcherExecutor-24-thread-1-processing-n:127.0.0.1:46671_solr
x:testNodeAdded_shard1_replica_n1 s:shard1 c:testNodeAdded r:core_node3)
[n:127.0.0.1:46671_solr c:testNodeAdded s:shard1 r:core_node3
x:testNodeAdded_shard1_replica_n1] o.a.s.c.SolrCore
[testNodeAdded_shard1_replica_n1] Registered new searcher
Searcher@6d720e59[testNodeAdded_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 22500 INFO (qtp351978002-40) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1587350252659146752
[junit4] 2> 22529 DEBUG
(OverseerStateUpdate-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded"} current state version: 0
[junit4] 2> 22535 INFO (qtp351978002-85) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 22535 INFO (qtp351978002-85) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 22535 INFO (qtp351978002-85) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:46671/solr/testNodeAdded_shard1_replica_n2/
[junit4] 2> 22546 INFO (qtp351978002-85) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.u.PeerSync PeerSync: core=testNodeAdded_shard1_replica_n2
url=https://127.0.0.1:46671/solr START
replicas=[https://127.0.0.1:46671/solr/testNodeAdded_shard1_replica_n1/]
nUpdates=100
[junit4] 2> 22606 INFO (qtp351978002-87) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
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=3
[junit4] 2> 22632 DEBUG
(OverseerStateUpdate-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeAdded/state.json version: 2
[junit4] 2> 22633 INFO
(zkCallback-13-thread-3-processing-n:127.0.0.1:46671_solr)
[n:127.0.0.1:46671_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 22633 INFO
(zkCallback-13-thread-2-processing-n:127.0.0.1:46671_solr)
[n:127.0.0.1:46671_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 22633 INFO
(zkCallback-13-thread-1-processing-n:127.0.0.1:46671_solr)
[n:127.0.0.1:46671_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 22692 DEBUG (ScheduledTrigger-18-thread-1)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 22692 DEBUG (ScheduledTrigger-18-thread-1)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 22882 INFO (qtp351978002-85) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.u.PeerSync PeerSync: core=testNodeAdded_shard1_replica_n2
url=https://127.0.0.1:46671/solr DONE. We have no versions. sync failed.
[junit4] 2> 22883 INFO (qtp351978002-85) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the
next candidate
[junit4] 2> 22883 INFO (qtp351978002-85) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
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> 22883 INFO (qtp351978002-85) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 22914 DEBUG
(OverseerStateUpdate-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "base_url":"https://127.0.0.1:46671/solr";,
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "state":"active"} current state version: 0
[junit4] 2> 22915 INFO (qtp351978002-85) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:46671/solr/testNodeAdded_shard1_replica_n2/ shard1
[junit4] 2> 23018 DEBUG
(OverseerStateUpdate-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeAdded/state.json version: 3
[junit4] 2> 23019 INFO
(zkCallback-13-thread-2-processing-n:127.0.0.1:46671_solr)
[n:127.0.0.1:46671_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 23019 INFO
(zkCallback-13-thread-3-processing-n:127.0.0.1:46671_solr)
[n:127.0.0.1:46671_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 23020 INFO
(zkCallback-13-thread-1-processing-n:127.0.0.1:46671_solr)
[n:127.0.0.1:46671_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 23071 INFO (qtp351978002-85) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 23108 INFO (qtp351978002-85) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=testNodeAdded_shard1_replica_n2&action=CREATE&numShards=1&collection=testNodeAdded&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1929
[junit4] 2> 23542 INFO (qtp351978002-40) [n:127.0.0.1:46671_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=testNodeAdded_shard1_replica_n1&action=CREATE&numShards=1&collection=testNodeAdded&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=2429
[junit4] 2> 23550 INFO (qtp351978002-44) [n:127.0.0.1:46671_solr ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 23693 DEBUG (ScheduledTrigger-18-thread-1)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 23693 DEBUG (ScheduledTrigger-18-thread-1)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 24693 DEBUG (ScheduledTrigger-18-thread-1)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 24693 DEBUG (ScheduledTrigger-18-thread-1)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 24714 INFO
(OverseerCollectionConfigSetProcessor-99209390411022339-127.0.0.1:46671_solr-n_0000000000)
[n:127.0.0.1:46671_solr ] 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> 25694 DEBUG (ScheduledTrigger-18-thread-1)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 25694 DEBUG (ScheduledTrigger-18-thread-1)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 26695 DEBUG (ScheduledTrigger-18-thread-1)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 26695 DEBUG (ScheduledTrigger-18-thread-1)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 27702 DEBUG (ScheduledTrigger-18-thread-1)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 27702 DEBUG (ScheduledTrigger-18-thread-1)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 28702 DEBUG (ScheduledTrigger-18-thread-3)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 28702 DEBUG (ScheduledTrigger-18-thread-3)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 29702 DEBUG (ScheduledTrigger-18-thread-3)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 29702 DEBUG (ScheduledTrigger-18-thread-3)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 30703 DEBUG (ScheduledTrigger-18-thread-3)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 30703 DEBUG (ScheduledTrigger-18-thread-3)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 31703 DEBUG (ScheduledTrigger-18-thread-3)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 31703 DEBUG (ScheduledTrigger-18-thread-3)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 32703 DEBUG (ScheduledTrigger-18-thread-3)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 32703 DEBUG (ScheduledTrigger-18-thread-3)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 33704 DEBUG (ScheduledTrigger-18-thread-3)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 33704 DEBUG (ScheduledTrigger-18-thread-3)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 34704 DEBUG (ScheduledTrigger-18-thread-3)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 34704 DEBUG (ScheduledTrigger-18-thread-3)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 35704 DEBUG (ScheduledTrigger-18-thread-3)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 35704 DEBUG (ScheduledTrigger-18-thread-3)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 36704 DEBUG (ScheduledTrigger-18-thread-3)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 36705 DEBUG (ScheduledTrigger-18-thread-3)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 37705 DEBUG (ScheduledTrigger-18-thread-3)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 37705 DEBUG (ScheduledTrigger-18-thread-3)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 38705 DEBUG (ScheduledTrigger-18-thread-2)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 38705 DEBUG (ScheduledTrigger-18-thread-2)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 39705 DEBUG (ScheduledTrigger-18-thread-2)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 39706 DEBUG (ScheduledTrigger-18-thread-2)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 40706 DEBUG (ScheduledTrigger-18-thread-2)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 40706 DEBUG (ScheduledTrigger-18-thread-2)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 41706 DEBUG (ScheduledTrigger-18-thread-2)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 41706 DEBUG (ScheduledTrigger-18-thread-2)
[n:127.0.0.1:46671_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 42706 DEBUG (Scheduled
[...truncated too long message...]
ore.collection2, tag=1033161629
[junit4] 2> 543414 INFO
(coreCloseExecutor-2309-thread-4-processing-x:collection3) [ x:collection2]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@2ab53f07: rootName = null,
domain = solr.core.collection2, service url = null, agent id = null] for
registry solr.core.collection2 / com.codahale.metrics.MetricRegistry@33a69696
[junit4] 2> 543418 INFO
(coreCloseExecutor-2309-thread-5-processing-x:collection3) [ x:collection3]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.collection3, tag=1732611973
[junit4] 2> 543418 INFO
(coreCloseExecutor-2309-thread-5-processing-x:collection3) [ x:collection3]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@710009e: rootName = null,
domain = solr.core.collection3, service url = null, agent id = null] for
registry solr.core.collection3 / com.codahale.metrics.MetricRegistry@637dfc02
[junit4] 2> 543422 INFO
(TEST-TestLazyCores.testLazyLoad-seed#[421F99C15779D41E]) [ x:collection3]
o.a.s.SolrTestCaseJ4 ###Ending testLazyLoad
[junit4] 2> 543423 INFO
(SUITE-TestLazyCores-seed#[421F99C15779D41E]-worker) [ x:collection3]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 543423 INFO
(SUITE-TestLazyCores-seed#[421F99C15779D41E]-worker) [ x:collection3]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=691455618
[junit4] 2> 543423 INFO
(SUITE-TestLazyCores-seed#[421F99C15779D41E]-worker) [ x:collection3]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 543423 INFO
(SUITE-TestLazyCores-seed#[421F99C15779D41E]-worker) [ x:collection3]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 543423 INFO
(SUITE-TestLazyCores-seed#[421F99C15779D41E]-worker) [ x:collection3]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestLazyCores_421F99C15779D41E-001
[junit4] 2> Dec 21, 2017 12:21:27 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> Dec 21, 2017 12:21:47 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> SEVERE: 1 thread leaked from SUITE scope at
org.apache.solr.core.TestLazyCores:
[junit4] 2> 1) Thread[id=5107, name=searcherExecutor-2042-thread-1,
state=WAITING, group=TGRP-TestLazyCores]
[junit4] 2> at
java.base@9.0.1/jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> at
java.base@9.0.1/java.util.concurrent.locks.LockSupport.park(LockSupport.java:194)
[junit4] 2> at
java.base@9.0.1/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2062)
[junit4] 2> at
java.base@9.0.1/java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:435)
[junit4] 2> at
java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1092)
[junit4] 2> at
java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1152)
[junit4] 2> at
java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] 2> at
java.base@9.0.1/java.lang.Thread.run(Thread.java:844)
[junit4] 2> Dec 21, 2017 12:21:47 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=5107, name=searcherExecutor-2042-thread-1,
state=WAITING, group=TGRP-TestLazyCores]
[junit4] 2> Dec 21, 2017 12:21:50 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> SEVERE: There are still zombie threads that couldn't be
terminated:
[junit4] 2> 1) Thread[id=5107, name=searcherExecutor-2042-thread-1,
state=WAITING, group=TGRP-TestLazyCores]
[junit4] 2> at
java.base@9.0.1/jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> at
java.base@9.0.1/java.util.concurrent.locks.LockSupport.park(LockSupport.java:194)
[junit4] 2> at
java.base@9.0.1/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2062)
[junit4] 2> at
java.base@9.0.1/java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:435)
[junit4] 2> at
java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1092)
[junit4] 2> at
java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1152)
[junit4] 2> at
java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] 2> at
java.base@9.0.1/java.lang.Thread.run(Thread.java:844)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70):
{v_t=PostingsFormat(name=Direct), id=PostingsFormat(name=Direct),
v_s=PostingsFormat(name=LuceneFixedGap), v_f=PostingsFormat(name=Asserting)},
docValues:{}, maxPointsInLeafNode=1718, maxMBSortInHeap=7.825478628375674,
sim=RandomSimilarity(queryNorm=false): {}, locale=gsw-LI,
timezone=Pacific/Wallis
[junit4] 2> NOTE: Linux 4.10.0-40-generic amd64/Oracle Corporation 9.0.1
(64-bit)/cpus=8,threads=2,free=218914632,total=518979584
[junit4] 2> NOTE: All tests run in this JVM:
[TestEmbeddedSolrServerAdminHandler, TestLegacyFieldCache, SmileWriterTest,
TestJsonRequest, RollingRestartTest, ShowFileRequestHandlerTest,
SuggesterFSTTest, TermVectorComponentDistributedTest, CircularListTest,
TestLegacyField, AssignTest, TestDocumentBuilder, HdfsDirectoryFactoryTest,
TestSimpleTrackingShardHandler, TestNodeLostTrigger, TestMaxTokenLenTokenizer,
DistributedVersionInfoTest, TestPolicyCloud, CurrencyFieldTypeTest,
TestConfigOverlay, MBeansHandlerTest, MetricUtilsTest, TestLuceneMatchVersion,
TestPivotHelperCode, TestManagedSchemaThreadSafety,
DistributedFacetPivotLargeTest, CdcrUpdateLogTest,
PreAnalyzedUpdateProcessorTest, TestConfigReload,
DeleteLastCustomShardedReplicaTest, PKIAuthenticationIntegrationTest,
PeerSyncReplicationTest, DistributedIntervalFacetingTest,
TestSolrCLIRunExample, TestSmileRequest, TestNoOpRegenerator,
CdcrRequestHandlerTest, TestLeaderElectionZkExpiry, BlockJoinFacetSimpleTest,
TestSubQueryTransformerDistrib, TestCloudRecovery, TestFieldCacheSortRandom,
TestRemoteStreaming, TestCollationField, TestFileDictionaryLookup,
MoreLikeThisHandlerTest, TestStressReorder, DocValuesMultiTest,
SolrShardReporterTest, DOMUtilTest, SolrIndexConfigTest, UpdateLogTest,
TestDistributedStatsComponentCardinality, DistributedFacetPivotSmallTest,
TestLazyCores]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestLazyCores
-Dtests.seed=421F99C15779D41E -Dtests.multiplier=3 -Dtests.slow=true
-Dtests.locale=gsw-LI -Dtests.timezone=Pacific/Wallis -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J1 | TestLazyCores (suite) <<<
[junit4] > Throwable #1:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE
scope at org.apache.solr.core.TestLazyCores:
[junit4] > 1) Thread[id=5107, name=searcherExecutor-2042-thread-1,
state=WAITING, group=TGRP-TestLazyCores]
[junit4] > at
java.base@9.0.1/jdk.internal.misc.Unsafe.park(Native Method)
[junit4] > at
java.base@9.0.1/java.util.concurrent.locks.LockSupport.park(LockSupport.java:194)
[junit4] > at
java.base@9.0.1/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2062)
[junit4] > at
java.base@9.0.1/java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:435)
[junit4] > at
java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1092)
[junit4] > at
java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1152)
[junit4] > at
java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] > at
java.base@9.0.1/java.lang.Thread.run(Thread.java:844)
[junit4] > at
__randomizedtesting.SeedInfo.seed([421F99C15779D41E]:0)Throwable #2:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie
threads that couldn't be terminated:
[junit4] > 1) Thread[id=5107, name=searcherExecutor-2042-thread-1,
state=WAITING, group=TGRP-TestLazyCores]
[junit4] > at
java.base@9.0.1/jdk.internal.misc.Unsafe.park(Native Method)
[junit4] > at
java.base@9.0.1/java.util.concurrent.locks.LockSupport.park(LockSupport.java:194)
[junit4] > at
java.base@9.0.1/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2062)
[junit4] > at
java.base@9.0.1/java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:435)
[junit4] > at
java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1092)
[junit4] > at
java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1152)
[junit4] > at
java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] > at
java.base@9.0.1/java.lang.Thread.run(Thread.java:844)
[junit4] > at
__randomizedtesting.SeedInfo.seed([421F99C15779D41E]:0)
[junit4] Completed [171/763 (2!)] on J1 in 33.68s, 9 tests, 3 errors <<<
FAILURES!
[...truncated 42729 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org
