Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-master/1480/
6 tests failed.
FAILED:
junit.framework.TestSuite.org.apache.solr.analytics.OverallAnalyticsTest
Error Message:
Could not find collection:collection1
Stack Trace:
java.lang.AssertionError: Could not find collection:collection1
at __randomizedtesting.SeedInfo.seed([593785CF6C097BF4]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNotNull(Assert.java:526)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:155)
at
org.apache.solr.analytics.SolrAnalyticsTestCase.setupCollection(SolrAnalyticsTestCase.java:63)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:874)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: junit.framework.TestSuite.org.apache.solr.TestDistributedMissingSort
Error Message:
1 thread leaked from SUITE scope at org.apache.solr.TestDistributedMissingSort:
1) Thread[id=40588, name=qtp1389191814-40588, state=TIMED_WAITING,
group=TGRP-TestDistributedMissingSort] at sun.misc.Unsafe.park(Native
Method) at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE
scope at org.apache.solr.TestDistributedMissingSort:
1) Thread[id=40588, name=qtp1389191814-40588, state=TIMED_WAITING,
group=TGRP-TestDistributedMissingSort]
at sun.misc.Unsafe.park(Native Method)
at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([CD54F7C81B217EA9]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.TestDistributedMissingSort
Error Message:
There are still zombie threads that couldn't be terminated: 1)
Thread[id=40588, name=qtp1389191814-40588, state=TIMED_WAITING,
group=TGRP-TestDistributedMissingSort] at sun.misc.Unsafe.park(Native
Method) at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie
threads that couldn't be terminated:
1) Thread[id=40588, name=qtp1389191814-40588, state=TIMED_WAITING,
group=TGRP-TestDistributedMissingSort]
at sun.misc.Unsafe.park(Native Method)
at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([CD54F7C81B217EA9]:0)
FAILED:
org.apache.solr.cloud.autoscaling.AutoAddReplicasIntegrationTest.testSimple
Error Message:
Waiting for collection testSimple1 null Live Nodes: [127.0.0.1:33356_solr,
127.0.0.1:33400_solr] Last available state:
DocCollection(testSimple1//collections/testSimple1/state.json/22)={
"pullReplicas":"0", "replicationFactor":"2", "shards":{ "shard1":{
"range":"80000000-ffffffff", "state":"active", "replicas":{
"core_node3":{ "core":"testSimple1_shard1_replica_n1",
"base_url":"http://127.0.0.1:33400/solr";,
"node_name":"127.0.0.1:33400_solr", "state":"active",
"type":"NRT", "leader":"true"}, "core_node12":{
"core":"testSimple1_shard1_replica_n11",
"base_url":"http://127.0.0.1:33400/solr";,
"node_name":"127.0.0.1:33400_solr", "state":"active",
"type":"NRT"}}}, "shard2":{ "range":"0-7fffffff",
"state":"active", "replicas":{ "core_node7":{
"core":"testSimple1_shard2_replica_n4",
"base_url":"http://127.0.0.1:33400/solr";,
"node_name":"127.0.0.1:33400_solr", "state":"active",
"type":"NRT", "leader":"true"}, "core_node10":{
"core":"testSimple1_shard2_replica_n9",
"base_url":"http://127.0.0.1:42122/solr";,
"node_name":"127.0.0.1:42122_solr", "state":"down",
"type":"NRT"}}}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"2",
"autoAddReplicas":"true", "nrtReplicas":"2", "tlogReplicas":"0"}
Stack Trace:
java.lang.AssertionError: Waiting for collection testSimple1
null
Live Nodes: [127.0.0.1:33356_solr, 127.0.0.1:33400_solr]
Last available state:
DocCollection(testSimple1//collections/testSimple1/state.json/22)={
"pullReplicas":"0",
"replicationFactor":"2",
"shards":{
"shard1":{
"range":"80000000-ffffffff",
"state":"active",
"replicas":{
"core_node3":{
"core":"testSimple1_shard1_replica_n1",
"base_url":"http://127.0.0.1:33400/solr";,
"node_name":"127.0.0.1:33400_solr",
"state":"active",
"type":"NRT",
"leader":"true"},
"core_node12":{
"core":"testSimple1_shard1_replica_n11",
"base_url":"http://127.0.0.1:33400/solr";,
"node_name":"127.0.0.1:33400_solr",
"state":"active",
"type":"NRT"}}},
"shard2":{
"range":"0-7fffffff",
"state":"active",
"replicas":{
"core_node7":{
"core":"testSimple1_shard2_replica_n4",
"base_url":"http://127.0.0.1:33400/solr";,
"node_name":"127.0.0.1:33400_solr",
"state":"active",
"type":"NRT",
"leader":"true"},
"core_node10":{
"core":"testSimple1_shard2_replica_n9",
"base_url":"http://127.0.0.1:42122/solr";,
"node_name":"127.0.0.1:42122_solr",
"state":"down",
"type":"NRT"}}}},
"router":{"name":"compositeId"},
"maxShardsPerNode":"2",
"autoAddReplicas":"true",
"nrtReplicas":"2",
"tlogReplicas":"0"}
at
__randomizedtesting.SeedInfo.seed([CD54F7C81B217EA9:F5E7D3363CD2AA78]: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.AutoAddReplicasIntegrationTest.testSimple(AutoAddReplicasIntegrationTest.java:103)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED:
org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration.testSearchRate
Error Message:
The trigger did not fire at all
Stack Trace:
java.lang.AssertionError: The trigger did not fire at all
at
__randomizedtesting.SeedInfo.seed([CD54F7C81B217EA9:901CE941D4E7D8E6]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at
org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration.testSearchRate(TestTriggerIntegration.java:1179)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED:
org.apache.solr.handler.TestReplicationHandler.doTestReplicateAfterCoreReload
Error Message:
expected:<[{indexVersion=1518874195734,generation=2,filelist=[_4ox.cfe,
_4ox.cfs, _4ox.si, _4qo.cfe, _4qo.cfs, _4qo.si, _4r4.fdt, _4r4.fdx, _4r4.fnm,
_4r4.nvd, _4r4.nvm, _4r4.si, _4r4_Memory_0.ram, _4r5.cfe, _4r5.cfs, _4r5.si,
_4r7.fdt, _4r7.fdx, _4r7.fnm, _4r7.nvd, _4r7.nvm, _4r7.si, _4r7_Memory_0.ram,
_4r8.fdt, _4r8.fdx, _4r8.fnm, _4r8.nvd, _4r8.nvm, _4r8.si, _4r8_Memory_0.ram,
_4ro.fdt, _4ro.fdx, _4ro.fnm, _4ro.nvd, _4ro.nvm, _4ro.si, _4ro_Memory_0.ram,
_4rr.fdt, _4rr.fdx, _4rr.fnm, _4rr.nvd, _4rr.nvm, _4rr.si, _4rr_Memory_0.ram,
_4rs.cfe, _4rs.cfs, _4rs.si, _4rt.fdt, _4rt.fdx, _4rt.fnm, _4rt.nvd, _4rt.nvm,
_4rt.si, _4rt_Memory_0.ram, _4ru.fdt, _4ru.fdx, _4ru.fnm, _4ru.nvd, _4ru.nvm,
_4ru.si, _4ru_Memory_0.ram, _4rv.fdt, _4rv.fdx, _4rv.fnm, _4rv.nvd, _4rv.nvm,
_4rv.si, _4rv_Memory_0.ram, _4rw.fdt, _4rw.fdx, _4rw.fnm, _4rw.nvd, _4rw.nvm,
_4rw.si, _4rw_Memory_0.ram, _4rx.fdt, _4rx.fdx, _4rx.fnm, _4rx.nvd, _4rx.nvm,
_4rx.si, _4rx_Memory_0.ram, _4ry.fdt, _4ry.fdx, _4ry.fnm, _4ry.nvd, _4ry.nvm,
_4ry.si, _4ry_Memory_0.ram, _4rz.fdt, _4rz.fdx, _4rz.fnm, _4rz.nvd, _4rz.nvm,
_4rz.si, _4rz_Memory_0.ram, _4s0.fdt, _4s0.fdx, _4s0.fnm, _4s0.nvd, _4s0.nvm,
_4s0.si, _4s0_Memory_0.ram, _4s1.fdt, _4s1.fdx, _4s1.fnm, _4s1.nvd, _4s1.nvm,
_4s1.si, _4s1_Memory_0.ram, segments_2]}]> but
was:<[{indexVersion=1518874195734,generation=2,filelist=[_4ox.cfe, _4ox.cfs,
_4ox.si, _4qo.cfe, _4qo.cfs, _4qo.si, _4r4.fdt, _4r4.fdx, _4r4.fnm, _4r4.nvd,
_4r4.nvm, _4r4.si, _4r4_Memory_0.ram, _4r5.cfe, _4r5.cfs, _4r5.si, _4r7.fdt,
_4r7.fdx, _4r7.fnm, _4r7.nvd, _4r7.nvm, _4r7.si, _4r7_Memory_0.ram, _4r8.fdt,
_4r8.fdx, _4r8.fnm, _4r8.nvd, _4r8.nvm, _4r8.si, _4r8_Memory_0.ram, _4ro.fdt,
_4ro.fdx, _4ro.fnm, _4ro.nvd, _4ro.nvm, _4ro.si, _4ro_Memory_0.ram, _4rr.fdt,
_4rr.fdx, _4rr.fnm, _4rr.nvd, _4rr.nvm, _4rr.si, _4rr_Memory_0.ram, _4rs.cfe,
_4rs.cfs, _4rs.si, _4rt.fdt, _4rt.fdx, _4rt.fnm, _4rt.nvd, _4rt.nvm, _4rt.si,
_4rt_Memory_0.ram, _4ru.fdt, _4ru.fdx, _4ru.fnm, _4ru.nvd, _4ru.nvm, _4ru.si,
_4ru_Memory_0.ram, _4rv.fdt, _4rv.fdx, _4rv.fnm, _4rv.nvd, _4rv.nvm, _4rv.si,
_4rv_Memory_0.ram, _4rw.fdt, _4rw.fdx, _4rw.fnm, _4rw.nvd, _4rw.nvm, _4rw.si,
_4rw_Memory_0.ram, _4rx.fdt, _4rx.fdx, _4rx.fnm, _4rx.nvd, _4rx.nvm, _4rx.si,
_4rx_Memory_0.ram, _4ry.fdt, _4ry.fdx, _4ry.fnm, _4ry.nvd, _4ry.nvm, _4ry.si,
_4ry_Memory_0.ram, _4rz.fdt, _4rz.fdx, _4rz.fnm, _4rz.nvd, _4rz.nvm, _4rz.si,
_4rz_Memory_0.ram, _4s0.fdt, _4s0.fdx, _4s0.fnm, _4s0.nvd, _4s0.nvm, _4s0.si,
_4s0_Memory_0.ram, _4s1.fdt, _4s1.fdx, _4s1.fnm, _4s1.nvd, _4s1.nvm, _4s1.si,
_4s1_Memory_0.ram, segments_2]},
{indexVersion=1518874195734,generation=3,filelist=[_4r9.cfe, _4r9.cfs, _4r9.si,
_4ro.fdt, _4ro.fdx, _4ro.fnm, _4ro.nvd, _4ro.nvm, _4ro.si, _4ro_Memory_0.ram,
_4ru.fdt, _4ru.fdx, _4ru.fnm, _4ru.nvd, _4ru.nvm, _4ru.si, _4ru_Memory_0.ram,
_4s0.fdt, _4s0.fdx, _4s0.fnm, _4s0.nvd, _4s0.nvm, _4s0.si, _4s0_Memory_0.ram,
_4s1.fdt, _4s1.fdx, _4s1.fnm, _4s1.nvd, _4s1.nvm, _4s1.si, _4s1_Memory_0.ram,
_4s2.fdt, _4s2.fdx, _4s2.fnm, _4s2.nvd, _4s2.nvm, _4s2.si, _4s2_Memory_0.ram,
segments_3]}]>
Stack Trace:
java.lang.AssertionError:
expected:<[{indexVersion=1518874195734,generation=2,filelist=[_4ox.cfe,
_4ox.cfs, _4ox.si, _4qo.cfe, _4qo.cfs, _4qo.si, _4r4.fdt, _4r4.fdx, _4r4.fnm,
_4r4.nvd, _4r4.nvm, _4r4.si, _4r4_Memory_0.ram, _4r5.cfe, _4r5.cfs, _4r5.si,
_4r7.fdt, _4r7.fdx, _4r7.fnm, _4r7.nvd, _4r7.nvm, _4r7.si, _4r7_Memory_0.ram,
_4r8.fdt, _4r8.fdx, _4r8.fnm, _4r8.nvd, _4r8.nvm, _4r8.si, _4r8_Memory_0.ram,
_4ro.fdt, _4ro.fdx, _4ro.fnm, _4ro.nvd, _4ro.nvm, _4ro.si, _4ro_Memory_0.ram,
_4rr.fdt, _4rr.fdx, _4rr.fnm, _4rr.nvd, _4rr.nvm, _4rr.si, _4rr_Memory_0.ram,
_4rs.cfe, _4rs.cfs, _4rs.si, _4rt.fdt, _4rt.fdx, _4rt.fnm, _4rt.nvd, _4rt.nvm,
_4rt.si, _4rt_Memory_0.ram, _4ru.fdt, _4ru.fdx, _4ru.fnm, _4ru.nvd, _4ru.nvm,
_4ru.si, _4ru_Memory_0.ram, _4rv.fdt, _4rv.fdx, _4rv.fnm, _4rv.nvd, _4rv.nvm,
_4rv.si, _4rv_Memory_0.ram, _4rw.fdt, _4rw.fdx, _4rw.fnm, _4rw.nvd, _4rw.nvm,
_4rw.si, _4rw_Memory_0.ram, _4rx.fdt, _4rx.fdx, _4rx.fnm, _4rx.nvd, _4rx.nvm,
_4rx.si, _4rx_Memory_0.ram, _4ry.fdt, _4ry.fdx, _4ry.fnm, _4ry.nvd, _4ry.nvm,
_4ry.si, _4ry_Memory_0.ram, _4rz.fdt, _4rz.fdx, _4rz.fnm, _4rz.nvd, _4rz.nvm,
_4rz.si, _4rz_Memory_0.ram, _4s0.fdt, _4s0.fdx, _4s0.fnm, _4s0.nvd, _4s0.nvm,
_4s0.si, _4s0_Memory_0.ram, _4s1.fdt, _4s1.fdx, _4s1.fnm, _4s1.nvd, _4s1.nvm,
_4s1.si, _4s1_Memory_0.ram, segments_2]}]> but
was:<[{indexVersion=1518874195734,generation=2,filelist=[_4ox.cfe, _4ox.cfs,
_4ox.si, _4qo.cfe, _4qo.cfs, _4qo.si, _4r4.fdt, _4r4.fdx, _4r4.fnm, _4r4.nvd,
_4r4.nvm, _4r4.si, _4r4_Memory_0.ram, _4r5.cfe, _4r5.cfs, _4r5.si, _4r7.fdt,
_4r7.fdx, _4r7.fnm, _4r7.nvd, _4r7.nvm, _4r7.si, _4r7_Memory_0.ram, _4r8.fdt,
_4r8.fdx, _4r8.fnm, _4r8.nvd, _4r8.nvm, _4r8.si, _4r8_Memory_0.ram, _4ro.fdt,
_4ro.fdx, _4ro.fnm, _4ro.nvd, _4ro.nvm, _4ro.si, _4ro_Memory_0.ram, _4rr.fdt,
_4rr.fdx, _4rr.fnm, _4rr.nvd, _4rr.nvm, _4rr.si, _4rr_Memory_0.ram, _4rs.cfe,
_4rs.cfs, _4rs.si, _4rt.fdt, _4rt.fdx, _4rt.fnm, _4rt.nvd, _4rt.nvm, _4rt.si,
_4rt_Memory_0.ram, _4ru.fdt, _4ru.fdx, _4ru.fnm, _4ru.nvd, _4ru.nvm, _4ru.si,
_4ru_Memory_0.ram, _4rv.fdt, _4rv.fdx, _4rv.fnm, _4rv.nvd, _4rv.nvm, _4rv.si,
_4rv_Memory_0.ram, _4rw.fdt, _4rw.fdx, _4rw.fnm, _4rw.nvd, _4rw.nvm, _4rw.si,
_4rw_Memory_0.ram, _4rx.fdt, _4rx.fdx, _4rx.fnm, _4rx.nvd, _4rx.nvm, _4rx.si,
_4rx_Memory_0.ram, _4ry.fdt, _4ry.fdx, _4ry.fnm, _4ry.nvd, _4ry.nvm, _4ry.si,
_4ry_Memory_0.ram, _4rz.fdt, _4rz.fdx, _4rz.fnm, _4rz.nvd, _4rz.nvm, _4rz.si,
_4rz_Memory_0.ram, _4s0.fdt, _4s0.fdx, _4s0.fnm, _4s0.nvd, _4s0.nvm, _4s0.si,
_4s0_Memory_0.ram, _4s1.fdt, _4s1.fdx, _4s1.fnm, _4s1.nvd, _4s1.nvm, _4s1.si,
_4s1_Memory_0.ram, segments_2]},
{indexVersion=1518874195734,generation=3,filelist=[_4r9.cfe, _4r9.cfs, _4r9.si,
_4ro.fdt, _4ro.fdx, _4ro.fnm, _4ro.nvd, _4ro.nvm, _4ro.si, _4ro_Memory_0.ram,
_4ru.fdt, _4ru.fdx, _4ru.fnm, _4ru.nvd, _4ru.nvm, _4ru.si, _4ru_Memory_0.ram,
_4s0.fdt, _4s0.fdx, _4s0.fnm, _4s0.nvd, _4s0.nvm, _4s0.si, _4s0_Memory_0.ram,
_4s1.fdt, _4s1.fdx, _4s1.fnm, _4s1.nvd, _4s1.nvm, _4s1.si, _4s1_Memory_0.ram,
_4s2.fdt, _4s2.fdx, _4s2.fnm, _4s2.nvd, _4s2.nvm, _4s2.si, _4s2_Memory_0.ram,
segments_3]}]>
at
__randomizedtesting.SeedInfo.seed([CD54F7C81B217EA9:E883ECF86B6970AA]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:147)
at
org.apache.solr.handler.TestReplicationHandler.doTestReplicateAfterCoreReload(TestReplicationHandler.java:1277)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 13693 lines...]
[junit4] Suite:
org.apache.solr.cloud.autoscaling.AutoAddReplicasIntegrationTest
[junit4] 2> 2532345 INFO
(SUITE-AutoAddReplicasIntegrationTest-seed#[CD54F7C81B217EA9]-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/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoAddReplicasIntegrationTest_CD54F7C81B217EA9-001/init-core-data-001
[junit4] 2> 2532346 INFO
(SUITE-AutoAddReplicasIntegrationTest-seed#[CD54F7C81B217EA9]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 2532362 INFO
(SUITE-AutoAddReplicasIntegrationTest-seed#[CD54F7C81B217EA9]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 2532362 INFO
(SUITE-AutoAddReplicasIntegrationTest-seed#[CD54F7C81B217EA9]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 3 servers in
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoAddReplicasIntegrationTest_CD54F7C81B217EA9-001/tempDir-001
[junit4] 2> 2532363 INFO
(SUITE-AutoAddReplicasIntegrationTest-seed#[CD54F7C81B217EA9]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2532378 INFO (Thread-6439) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2532378 INFO (Thread-6439) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 2532435 ERROR (Thread-6439) [ ] 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> 2532491 INFO
(SUITE-AutoAddReplicasIntegrationTest-seed#[CD54F7C81B217EA9]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:42337
[junit4] 2> 2532654 INFO (zkConnectionManagerCallback-1340-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2532793 INFO (jetty-launcher-1337-thread-1) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T17:27:37-04:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 2532801 INFO (jetty-launcher-1337-thread-2) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T17:27:37-04:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 2532816 INFO (jetty-launcher-1337-thread-3) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T17:27:37-04:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 2532853 INFO (jetty-launcher-1337-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2532853 INFO (jetty-launcher-1337-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2532853 INFO (jetty-launcher-1337-thread-1) [ ]
o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 2532865 INFO (jetty-launcher-1337-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@4b6800b6{/solr,null,AVAILABLE}
[junit4] 2> 2532867 INFO (jetty-launcher-1337-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@19230520{HTTP/1.1,[http/1.1]}{127.0.0.1:?????}
[junit4] 2> 2532867 INFO (jetty-launcher-1337-thread-1) [ ]
o.e.j.s.Server Started @???????ms
[junit4] 2> 2532867 INFO (jetty-launcher-1337-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=33400}
[junit4] 2> 2532867 ERROR (jetty-launcher-1337-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2532867 INFO (jetty-launcher-1337-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 2532867 INFO (jetty-launcher-1337-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2532867 INFO (jetty-launcher-1337-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2532867 INFO (jetty-launcher-1337-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-17T12:28:56.039Z
[junit4] 2> 2532926 INFO (jetty-launcher-1337-thread-2) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2532926 INFO (jetty-launcher-1337-thread-2) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2532926 INFO (jetty-launcher-1337-thread-2) [ ]
o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 2532946 INFO (jetty-launcher-1337-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@639e67ad{/solr,null,AVAILABLE}
[junit4] 2> 2532947 INFO (jetty-launcher-1337-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@577f9f65{HTTP/1.1,[http/1.1]}{127.0.0.1:?????}
[junit4] 2> 2532947 INFO (jetty-launcher-1337-thread-2) [ ]
o.e.j.s.Server Started @???????ms
[junit4] 2> 2532947 INFO (jetty-launcher-1337-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=42122}
[junit4] 2> 2532947 ERROR (jetty-launcher-1337-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2532947 INFO (jetty-launcher-1337-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 2532947 INFO (jetty-launcher-1337-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2532947 INFO (jetty-launcher-1337-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2532947 INFO (jetty-launcher-1337-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-17T12:28:56.119Z
[junit4] 2> 2532991 INFO (zkConnectionManagerCallback-1344-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2532991 INFO (jetty-launcher-1337-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2533088 INFO (jetty-launcher-1337-thread-3) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2533088 INFO (jetty-launcher-1337-thread-3) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2533089 INFO (jetty-launcher-1337-thread-3) [ ]
o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 2533090 INFO (jetty-launcher-1337-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@755e54cc{/solr,null,AVAILABLE}
[junit4] 2> 2533090 INFO (jetty-launcher-1337-thread-3) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@72c41475{HTTP/1.1,[http/1.1]}{127.0.0.1:?????}
[junit4] 2> 2533090 INFO (jetty-launcher-1337-thread-3) [ ]
o.e.j.s.Server Started @???????ms
[junit4] 2> 2533090 INFO (jetty-launcher-1337-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=33356}
[junit4] 2> 2533090 ERROR (jetty-launcher-1337-thread-3) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2533090 INFO (jetty-launcher-1337-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 2533090 INFO (jetty-launcher-1337-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2533110 INFO (jetty-launcher-1337-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2533110 INFO (jetty-launcher-1337-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-17T12:28:56.282Z
[junit4] 2> 2533123 INFO (zkConnectionManagerCallback-1342-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2533125 INFO (jetty-launcher-1337-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2533194 INFO (jetty-launcher-1337-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42337/solr
[junit4] 2> 2533348 INFO (zkConnectionManagerCallback-1346-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2533355 INFO (jetty-launcher-1337-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2533401 INFO (zkConnectionManagerCallback-1350-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2533425 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x10047ffa5e80004, likely client has closed socket
[junit4] 2> 2533446 INFO (jetty-launcher-1337-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42337/solr
[junit4] 2> 2533519 INFO
(zkConnectionManagerCallback-1352-thread-1-processing-n:127.0.0.1:42122_solr)
[n:127.0.0.1:42122_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2533519 INFO (jetty-launcher-1337-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42337/solr
[junit4] 2> 2533592 INFO (zkConnectionManagerCallback-1356-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2533608 INFO (zkConnectionManagerCallback-1360-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2533649 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x10047ffa5e80006, likely client has closed socket
[junit4] 2> 2533649 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x10047ffa5e80007, likely client has closed socket
[junit4] 2> 2533730 INFO
(zkConnectionManagerCallback-1365-thread-1-processing-n:127.0.0.1:33356_solr)
[n:127.0.0.1:33356_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2533734 INFO
(zkConnectionManagerCallback-1363-thread-1-processing-n:127.0.0.1:33400_solr)
[n:127.0.0.1:33400_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2533790 INFO (jetty-launcher-1337-thread-3)
[n:127.0.0.1:33356_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2533791 INFO (jetty-launcher-1337-thread-3)
[n:127.0.0.1:33356_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:33356_solr
[junit4] 2> 2533804 INFO (jetty-launcher-1337-thread-3)
[n:127.0.0.1:33356_solr ] o.a.s.c.Overseer Overseer
(id=72136757363605513-127.0.0.1:33356_solr-n_0000000000) starting
[junit4] 2> 2533886 INFO (jetty-launcher-1337-thread-3)
[n:127.0.0.1:33356_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:33356_solr
[junit4] 2> 2533886 DEBUG
(OverseerAutoScalingTriggerThread-72136757363605513-127.0.0.1:33356_solr-n_0000000000)
[n:127.0.0.1:33356_solr ] o.a.s.c.a.OverseerTriggerThread Adding
.autoAddReplicas trigger
[junit4] 2> 2533921 DEBUG
(OverseerAutoScalingTriggerThread-72136757363605513-127.0.0.1:33356_solr-n_0000000000)
[n:127.0.0.1:33356_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 1
[junit4] 2> 2533922 DEBUG
(OverseerAutoScalingTriggerThread-72136757363605513-127.0.0.1:33356_solr-n_0000000000)
[n:127.0.0.1:33356_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: []
[junit4] 2> 2533922 DEBUG
(OverseerAutoScalingTriggerThread-72136757363605513-127.0.0.1:33356_solr-n_0000000000)
[n:127.0.0.1:33356_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion -1
[junit4] 2> 2533922 DEBUG
(OverseerAutoScalingTriggerThread-72136757363605513-127.0.0.1:33356_solr-n_0000000000)
[n:127.0.0.1:33356_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 1
[junit4] 2> 2533926 INFO
(zkCallback-1364-thread-1-processing-n:127.0.0.1:33356_solr)
[n:127.0.0.1:33356_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 2533960 INFO (jetty-launcher-1337-thread-1)
[n:127.0.0.1:33400_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 2533994 DEBUG
(OverseerAutoScalingTriggerThread-72136757363605513-127.0.0.1:33356_solr-n_0000000000)
[n:127.0.0.1:33356_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 2533994 DEBUG
(OverseerAutoScalingTriggerThread-72136757363605513-127.0.0.1:33356_solr-n_0000000000)
[n:127.0.0.1:33356_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 2533994 DEBUG (ScheduledTrigger-3324-thread-1)
[n:127.0.0.1:33356_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 1
[junit4] 2> 2533995 INFO (jetty-launcher-1337-thread-1)
[n:127.0.0.1:33400_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2533997 INFO (jetty-launcher-1337-thread-1)
[n:127.0.0.1:33400_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:33400_solr
[junit4] 2> 2534071 INFO
(zkCallback-1364-thread-2-processing-n:127.0.0.1:33356_solr)
[n:127.0.0.1:33356_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 2534198 INFO
(zkCallback-1362-thread-1-processing-n:127.0.0.1:33400_solr)
[n:127.0.0.1:33400_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 2534472 INFO (jetty-launcher-1337-thread-3)
[n:127.0.0.1:33356_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33356.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4053d1f7
[junit4] 2> 2534509 INFO (jetty-launcher-1337-thread-3)
[n:127.0.0.1:33356_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33356.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4053d1f7
[junit4] 2> 2534509 INFO (jetty-launcher-1337-thread-3)
[n:127.0.0.1:33356_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33356.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4053d1f7
[junit4] 2> 2534523 INFO (jetty-launcher-1337-thread-3)
[n:127.0.0.1:33356_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoAddReplicasIntegrationTest_CD54F7C81B217EA9-001/tempDir-001/node3/.
[junit4] 2> 2534965 INFO (jetty-launcher-1337-thread-1)
[n:127.0.0.1:33400_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33400.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4053d1f7
[junit4] 2> 2534989 INFO (jetty-launcher-1337-thread-1)
[n:127.0.0.1:33400_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33400.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4053d1f7
[junit4] 2> 2535001 DEBUG (ScheduledTrigger-3324-thread-1)
[n:127.0.0.1:33356_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 2
[junit4] 2> 2535003 INFO (jetty-launcher-1337-thread-1)
[n:127.0.0.1:33400_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33400.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4053d1f7
[junit4] 2> 2535004 INFO (jetty-launcher-1337-thread-1)
[n:127.0.0.1:33400_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoAddReplicasIntegrationTest_CD54F7C81B217EA9-001/tempDir-001/node1/.
[junit4] 2> 2535518 INFO (jetty-launcher-1337-thread-2)
[n:127.0.0.1:42122_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 2535519 INFO (jetty-launcher-1337-thread-2)
[n:127.0.0.1:42122_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2535521 INFO (jetty-launcher-1337-thread-2)
[n:127.0.0.1:42122_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:42122_solr
[junit4] 2> 2535558 INFO
(zkCallback-1364-thread-2-processing-n:127.0.0.1:33356_solr)
[n:127.0.0.1:33356_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 2535574 INFO
(zkCallback-1362-thread-1-processing-n:127.0.0.1:33400_solr)
[n:127.0.0.1:33400_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 2535667 INFO
(zkCallback-1351-thread-1-processing-n:127.0.0.1:42122_solr)
[n:127.0.0.1:42122_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 2535888 INFO (jetty-launcher-1337-thread-2)
[n:127.0.0.1:42122_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42122.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4053d1f7
[junit4] 2> 2535924 INFO (jetty-launcher-1337-thread-2)
[n:127.0.0.1:42122_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42122.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4053d1f7
[junit4] 2> 2535924 INFO (jetty-launcher-1337-thread-2)
[n:127.0.0.1:42122_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42122.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4053d1f7
[junit4] 2> 2535925 INFO (jetty-launcher-1337-thread-2)
[n:127.0.0.1:42122_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoAddReplicasIntegrationTest_CD54F7C81B217EA9-001/tempDir-001/node2/.
[junit4] 2> 2536009 DEBUG (ScheduledTrigger-3324-thread-2)
[n:127.0.0.1:33356_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 3
[junit4] 2> 2536211 INFO (zkConnectionManagerCallback-1372-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2536248 INFO (zkConnectionManagerCallback-1376-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2536249 INFO
(SUITE-AutoAddReplicasIntegrationTest-seed#[CD54F7C81B217EA9]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 2536250 INFO
(SUITE-AutoAddReplicasIntegrationTest-seed#[CD54F7C81B217EA9]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42337/solr ready
[junit4] 2> 2536469 INFO
(TEST-AutoAddReplicasIntegrationTest.testSimple-seed#[CD54F7C81B217EA9]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testSimple
[junit4] 2> 2536471 INFO (qtp1711881732-13250) [n:127.0.0.1:33356_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=2&collection.configName=conf&maxShardsPerNode=2&autoAddReplicas=true&name=testSimple1&nrtReplicas=2&action=CREATE&numShards=2&createNodeSet=127.0.0.1:33356_solr,127.0.0.1:33400_solr&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 2536540 INFO
(OverseerThreadFactory-3326-thread-1-processing-n:127.0.0.1:33356_solr)
[n:127.0.0.1:33356_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection
testSimple1
[junit4] 2> 2536746 INFO
(OverseerStateUpdate-72136757363605513-127.0.0.1:33356_solr-n_0000000000)
[n:127.0.0.1:33356_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testSimple1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testSimple1_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:33400/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2536783 INFO
(OverseerStateUpdate-72136757363605513-127.0.0.1:33356_solr-n_0000000000)
[n:127.0.0.1:33356_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testSimple1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testSimple1_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:33356/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2536875 INFO
(OverseerStateUpdate-72136757363605513-127.0.0.1:33356_solr-n_0000000000)
[n:127.0.0.1:33356_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testSimple1",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"testSimple1_shard2_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:33400/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2536930 INFO
(OverseerStateUpdate-72136757363605513-127.0.0.1:33356_solr-n_0000000000)
[n:127.0.0.1:33356_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testSimple1",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"testSimple1_shard2_replica_n6",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:33356/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2537016 DEBUG (ScheduledTrigger-3324-thread-1)
[n:127.0.0.1:33356_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 3
[junit4] 2> 2537221 INFO (qtp1883672795-13227) [n:127.0.0.1:33400_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=testSimple1_shard1_replica_n1&action=CREATE&numShards=2&collection=testSimple1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2537251 INFO (qtp1711881732-13249) [n:127.0.0.1:33356_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=testSimple1_shard2_replica_n6&action=CREATE&numShards=2&collection=testSimple1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2537251 INFO (qtp1711881732-13249) [n:127.0.0.1:33356_solr
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 2537252 INFO (qtp1883672795-13226) [n:127.0.0.1:33400_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=testSimple1_shard2_replica_n4&action=CREATE&numShards=2&collection=testSimple1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2537252 INFO (qtp1883672795-13226) [n:127.0.0.1:33400_solr
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 2537268 INFO (qtp1711881732-13254) [n:127.0.0.1:33356_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=testSimple1_shard1_replica_n2&action=CREATE&numShards=2&collection=testSimple1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2537766 INFO
(zkCallback-1364-thread-1-processing-n:127.0.0.1:33356_solr)
[n:127.0.0.1:33356_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testSimple1/state.json] for collection [testSimple1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 2537766 INFO
(zkCallback-1362-thread-2-processing-n:127.0.0.1:33400_solr)
[n:127.0.0.1:33400_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testSimple1/state.json] for collection [testSimple1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 2537769 INFO
(zkCallback-1362-thread-1-processing-n:127.0.0.1:33400_solr)
[n:127.0.0.1:33400_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testSimple1/state.json] for collection [testSimple1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 2537777 INFO
(zkCallback-1364-thread-2-processing-n:127.0.0.1:33356_solr)
[n:127.0.0.1:33356_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testSimple1/state.json] for collection [testSimple1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 2538025 DEBUG (ScheduledTrigger-3324-thread-1)
[n:127.0.0.1:33356_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 3
[junit4] 2> 2538663 INFO (qtp1711881732-13249) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 2538671 INFO (qtp1883672795-13226) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 2538692 INFO (qtp1883672795-13227) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 2538693 INFO (qtp1711881732-13249) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6]
o.a.s.s.IndexSchema [testSimple1_shard2_replica_n6] Schema name=minimal
[junit4] 2> 2538713 INFO (qtp1711881732-13249) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2538713 INFO (qtp1711881732-13249) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6]
o.a.s.c.CoreContainer Creating SolrCore 'testSimple1_shard2_replica_n6' using
configuration from collection testSimple1, trusted=true
[junit4] 2> 2538714 INFO (qtp1711881732-13249) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33356.solr.core.testSimple1.shard2.replica_n6' (registry
'solr.core.testSimple1.shard2.replica_n6') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4053d1f7
[junit4] 2> 2538714 INFO (qtp1711881732-13249) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2538714 INFO (qtp1711881732-13249) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6]
o.a.s.c.SolrCore [[testSimple1_shard2_replica_n6] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoAddReplicasIntegrationTest_CD54F7C81B217EA9-001/tempDir-001/node3/testSimple1_shard2_replica_n6],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoAddReplicasIntegrationTest_CD54F7C81B217EA9-001/tempDir-001/node3/./testSimple1_shard2_replica_n6/data/]
[junit4] 2> 2538730 INFO (qtp1883672795-13226) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4]
o.a.s.s.IndexSchema [testSimple1_shard2_replica_n4] Schema name=minimal
[junit4] 2> 2538765 INFO (qtp1883672795-13227) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1]
o.a.s.s.IndexSchema [testSimple1_shard1_replica_n1] Schema name=minimal
[junit4] 2> 2538767 INFO (qtp1883672795-13227) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2538767 INFO (qtp1883672795-13227) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1]
o.a.s.c.CoreContainer Creating SolrCore 'testSimple1_shard1_replica_n1' using
configuration from collection testSimple1, trusted=true
[junit4] 2> 2538784 INFO (qtp1711881732-13254) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 2538853 INFO (qtp1883672795-13227) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33400.solr.core.testSimple1.shard1.replica_n1' (registry
'solr.core.testSimple1.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4053d1f7
[junit4] 2> 2538854 INFO (qtp1883672795-13227) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2538854 INFO (qtp1883672795-13227) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1]
o.a.s.c.SolrCore [[testSimple1_shard1_replica_n1] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoAddReplicasIntegrationTest_CD54F7C81B217EA9-001/tempDir-001/node1/testSimple1_shard1_replica_n1],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoAddReplicasIntegrationTest_CD54F7C81B217EA9-001/tempDir-001/node1/./testSimple1_shard1_replica_n1/data/]
[junit4] 2> 2538876 INFO (qtp1883672795-13226) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2538876 INFO (qtp1883672795-13226) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4]
o.a.s.c.CoreContainer Creating SolrCore 'testSimple1_shard2_replica_n4' using
configuration from collection testSimple1, trusted=true
[junit4] 2> 2538876 INFO (qtp1883672795-13226) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33400.solr.core.testSimple1.shard2.replica_n4' (registry
'solr.core.testSimple1.shard2.replica_n4') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4053d1f7
[junit4] 2> 2538876 INFO (qtp1883672795-13226) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2538876 INFO (qtp1883672795-13226) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4]
o.a.s.c.SolrCore [[testSimple1_shard2_replica_n4] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoAddReplicasIntegrationTest_CD54F7C81B217EA9-001/tempDir-001/node1/testSimple1_shard2_replica_n4],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoAddReplicasIntegrationTest_CD54F7C81B217EA9-001/tempDir-001/node1/./testSimple1_shard2_replica_n4/data/]
[junit4] 2> 2538952 INFO (qtp1711881732-13254) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2]
o.a.s.s.IndexSchema [testSimple1_shard1_replica_n2] Schema name=minimal
[junit4] 2> 2538954 INFO (qtp1711881732-13254) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2538954 INFO (qtp1711881732-13254) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2]
o.a.s.c.CoreContainer Creating SolrCore 'testSimple1_shard1_replica_n2' using
configuration from collection testSimple1, trusted=true
[junit4] 2> 2538975 INFO (qtp1711881732-13254) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33356.solr.core.testSimple1.shard1.replica_n2' (registry
'solr.core.testSimple1.shard1.replica_n2') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4053d1f7
[junit4] 2> 2539064 INFO (qtp1711881732-13254) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2539065 INFO (qtp1711881732-13254) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2]
o.a.s.c.SolrCore [[testSimple1_shard1_replica_n2] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoAddReplicasIntegrationTest_CD54F7C81B217EA9-001/tempDir-001/node3/testSimple1_shard1_replica_n2],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoAddReplicasIntegrationTest_CD54F7C81B217EA9-001/tempDir-001/node3/./testSimple1_shard1_replica_n2/data/]
[junit4] 2> 2539111 DEBUG (ScheduledTrigger-3324-thread-1)
[n:127.0.0.1:33356_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 3
[junit4] 2> 2539720 INFO (qtp1883672795-13227) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 2539720 INFO (qtp1883672795-13227) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2539721 INFO (qtp1883672795-13227) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2539721 INFO (qtp1883672795-13227) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2539771 INFO (qtp1883672795-13227) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@67300391[testSimple1_shard1_replica_n1] main]
[junit4] 2> 2539775 INFO (qtp1711881732-13249) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 2539775 INFO (qtp1711881732-13249) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2539776 INFO (qtp1711881732-13249) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2539777 INFO (qtp1711881732-13249) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2539795 INFO (qtp1711881732-13249) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@91e9bba[testSimple1_shard2_replica_n6] main]
[junit4] 2> 2539804 INFO (qtp1883672795-13227) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 2539804 INFO (qtp1883672795-13227) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2539805 INFO (qtp1883672795-13227) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2539805 INFO (qtp1883672795-13227) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1592651198472650752
[junit4] 2> 2539808 INFO
(searcherExecutor-3334-thread-1-processing-n:127.0.0.1:33400_solr
x:testSimple1_shard1_replica_n1 s:shard1 c:testSimple1 r:core_node3)
[n:127.0.0.1:33400_solr c:testSimple1 s:shard1 r:core_node3
x:testSimple1_shard1_replica_n1] o.a.s.c.SolrCore
[testSimple1_shard1_replica_n1] Registered new searcher
Searcher@67300391[testSimple1_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2539868 INFO (qtp1711881732-13249) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 2539869 INFO (qtp1711881732-13249) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2539878 INFO (qtp1883672795-13227) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for
shard shard1: total=2 found=1 timeoutin=9999ms
[junit4] 2> 2539901 INFO (qtp1883672795-13226) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 2539901 INFO (qtp1883672795-13226) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2539903 INFO (qtp1883672795-13226) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2539903 INFO (qtp1883672795-13226) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2539904 INFO (qtp1883672795-13226) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@1e209687[testSimple1_shard2_replica_n4] main]
[junit4] 2> 2539906 INFO (qtp1711881732-13249) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2539906 INFO (qtp1711881732-13249) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1592651198578556928
[junit4] 2> 2539909 INFO
(searcherExecutor-3333-thread-1-processing-n:127.0.0.1:33356_solr
x:testSimple1_shard2_replica_n6 s:shard2 c:testSimple1 r:core_node8)
[n:127.0.0.1:33356_solr c:testSimple1 s:shard2 r:core_node8
x:testSimple1_shard2_replica_n6] o.a.s.c.SolrCore
[testSimple1_shard2_replica_n6] Registered new searcher
Searcher@91e9bba[testSimple1_shard2_replica_n6]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2539923 INFO (qtp1883672795-13226) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 2539923 INFO (qtp1883672795-13226) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2539924 INFO (qtp1883672795-13226) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2539924 INFO (qtp1883672795-13226) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1592651198597431296
[junit4] 2> 2539925 INFO
(searcherExecutor-3335-thread-1-processing-n:127.0.0.1:33400_solr
x:testSimple1_shard2_replica_n4 s:shard2 c:testSimple1 r:core_node7)
[n:127.0.0.1:33400_solr c:testSimple1 s:shard2 r:core_node7
x:testSimple1_shard2_replica_n4] o.a.s.c.SolrCore
[testSimple1_shard2_replica_n4] Registered new searcher
Searcher@1e209687[testSimple1_shard2_replica_n4]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2540051 INFO (qtp1711881732-13254) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 2540051 INFO (qtp1711881732-13254) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2540064 INFO (qtp1711881732-13254) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2540064 INFO (qtp1711881732-13254) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2540066 INFO (qtp1711881732-13254) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@78137b1b[testSimple1_shard1_replica_n2] main]
[junit4] 2> 2540083 INFO
(zkCallback-1362-thread-1-processing-n:127.0.0.1:33400_solr)
[n:127.0.0.1:33400_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testSimple1/state.json] for collection [testSimple1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 2540084 INFO
(zkCallback-1364-thread-2-processing-n:127.0.0.1:33356_solr)
[n:127.0.0.1:33356_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testSimple1/state.json] for collection [testSimple1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 2540084 INFO
(zkCallback-1364-thread-1-processing-n:127.0.0.1:33356_solr)
[n:127.0.0.1:33356_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testSimple1/state.json] for collection [testSimple1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 2540084 INFO
(zkCallback-1362-thread-3-processing-n:127.0.0.1:33400_solr)
[n:127.0.0.1:33400_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testSimple1/state.json] for collection [testSimple1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 2540089 INFO (qtp1711881732-13254) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 2540089 INFO (qtp1711881732-13254) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2540090 INFO (qtp1711881732-13254) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2540090 INFO (qtp1711881732-13254) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1592651198771494912
[junit4] 2> 2540100 INFO
(searcherExecutor-3336-thread-1-processing-n:127.0.0.1:33356_solr
x:testSimple1_shard1_replica_n2 s:shard1 c:testSimple1 r:core_node5)
[n:127.0.0.1:33356_solr c:testSimple1 s:shard1 r:core_node5
x:testSimple1_shard1_replica_n2] o.a.s.c.SolrCore
[testSimple1_shard1_replica_n2] Registered new searcher
Searcher@78137b1b[testSimple1_shard1_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2540107 INFO (qtp1711881732-13249) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2540107 INFO (qtp1711881732-13249) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2540107 INFO (qtp1711881732-13249) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:33356/solr/testSimple1_shard2_replica_n6/
[junit4] 2> 2540112 DEBUG (ScheduledTrigger-3324-thread-1)
[n:127.0.0.1:33356_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 3
[junit4] 2> 2540161 INFO (qtp1711881732-13249) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6]
o.a.s.u.PeerSync PeerSync: core=testSimple1_shard2_replica_n6
url=http://127.0.0.1:33356/solr START
replicas=[http://127.0.0.1:33400/solr/testSimple1_shard2_replica_n4/]
nUpdates=100
[junit4] 2> 2540199 INFO (qtp1883672795-13231) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4]
o.a.s.c.S.Request [testSimple1_shard2_replica_n4] webapp=/solr path=/get
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 2540235 INFO
(zkCallback-1362-thread-1-processing-n:127.0.0.1:33400_solr)
[n:127.0.0.1:33400_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testSimple1/state.json] for collection [testSimple1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 2540235 INFO
(zkCallback-1362-thread-3-processing-n:127.0.0.1:33400_solr)
[n:127.0.0.1:33400_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testSimple1/state.json] for collection [testSimple1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 2540235 INFO
(zkCallback-1364-thread-1-processing-n:127.0.0.1:33356_solr)
[n:127.0.0.1:33356_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testSimple1/state.json] for collection [testSimple1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 2540235 INFO
(zkCallback-1364-thread-2-processing-n:127.0.0.1:33356_solr)
[n:127.0.0.1:33356_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testSimple1/state.json] for collection [testSimple1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 2540403 INFO (qtp1883672795-13227) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2540404 INFO (qtp1883672795-13227) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2540404 INFO (qtp1883672795-13227) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:33400/solr/testSimple1_shard1_replica_n1/
[junit4] 2> 2540404 INFO (qtp1883672795-13227) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1]
o.a.s.u.PeerSync PeerSync: core=testSimple1_shard1_replica_n1
url=http://127.0.0.1:33400/solr START
replicas=[http://127.0.0.1:33356/solr/testSimple1_shard1_replica_n2/]
nUpdates=100
[junit4] 2> 2540432 INFO (qtp1711881732-13253) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2]
o.a.s.c.S.Request [testSimple1_shard1_replica_n2] webapp=/solr path=/get
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
status=0 QTime=15
[junit4] 2> 2540505 INFO (qtp1711881732-13249) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6]
o.a.s.u.PeerSync PeerSync: core=testSimple1_shard2_replica_n6
url=http://127.0.0.1:33356/solr DONE. We have no versions. sync failed.
[junit4] 2> 2540505 INFO (qtp1711881732-13249) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6]
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the
next candidate
[junit4] 2> 2540505 INFO (qtp1711881732-13249) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6]
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> 2540505 INFO (qtp1711881732-13249) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 2540507 INFO (qtp1711881732-13249) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:33356/solr/testSimple1_shard2_replica_n6/ shard2
[junit4] 2> 2540639 INFO
(zkCallback-1362-thread-3-processing-n:127.0.0.1:33400_solr)
[n:127.0.0.1:33400_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testSimple1/state.json] for collection [testSimple1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 2540639 INFO
(zkCallback-1364-thread-1-processing-n:127.0.0.1:33356_solr)
[n:127.0.0.1:33356_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testSimple1/state.json] for collection [testSimple1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 2540639 INFO
(zkCallback-1364-thread-2-processing-n:127.0.0.1:33356_solr)
[n:127.0.0.1:33356_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testSimple1/state.json] for collection [testSimple1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 2540651 INFO
(zkCallback-1362-thread-1-processing-n:127.0.0.1:33400_solr)
[n:127.0.0.1:33400_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testSimple1/state.json] for collection [testSimple1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 2540688 INFO (qtp1711881732-13249) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2540691 INFO (qtp1711881732-13249) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=testSimple1_shard2_replica_n6&action=CREATE&numShards=2&collection=testSimple1&shard=shard2&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=3439
[junit4] 2> 2540724 INFO (qtp1883672795-13227) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1]
o.a.s.u.PeerSync PeerSync: core=testSimple1_shard1_replica_n1
url=http://127.0.0.1:33400/solr DONE. We have no versions. sync failed.
[junit4] 2> 2540724 INFO (qtp1883672795-13227) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1]
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the
next candidate
[junit4] 2> 2540724 INFO (qtp1883672795-13227) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we
can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 2540724 INFO (qtp1883672795-13227) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 2540746 INFO (qtp1883672795-13227) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:33400/solr/testSimple1_shard1_replica_n1/ shard1
[junit4] 2> 2540931 INFO
(zkCallback-1362-thread-3-processing-n:127.0.0.1:33400_solr)
[n:127.0.0.1:33400_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testSimple1/state.json] for collection [testSimple1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 2540931 INFO
(zkCallback-1364-thread-2-processing-n:127.0.0.1:33356_solr)
[n:127.0.0.1:33356_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testSimple1/state.json] for collection [testSimple1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 2540931 INFO
(zkCallback-1364-thread-1-processing-n:127.0.0.1:33356_solr)
[n:127.0.0.1:33356_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testSimple1/state.json] for collection [testSimple1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 2540943 INFO
(zkCallback-1362-thread-1-processing-n:127.0.0.1:33400_solr)
[n:127.0.0.1:33400_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testSimple1/state.json] for collection [testSimple1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 2540965 INFO (qtp1883672795-13227) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2541002 INFO (qtp1883672795-13227) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard1 r:core_node3 x:testSimple1_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=testSimple1_shard1_replica_n1&action=CREATE&numShards=2&collection=testSimple1&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=3780
[junit4] 2> 2541112 INFO (qtp1883672795-13226) [n:127.0.0.1:33400_solr
c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=testSimple1_shard2_replica_n4&action=CREATE&numShards=2&collection=testSimple1&shard=shard2&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=3860
[junit4] 2> 2541114 DEBUG (ScheduledTrigger-3324-thread-1)
[n:127.0.0.1:33356_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 3
[junit4] 2> 2541155 INFO
(zkCallback-1362-thread-3-processing-n:127.0.0.1:33400_solr)
[n:127.0.0.1:33400_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testSimple1/state.json] for collection [testSimple1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 2541155 INFO
(zkCallback-1364-thread-1-processing-n:127.0.0.1:33356_solr)
[n:127.0.0.1:33356_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testSimple1/state.json] for collection [testSimple1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 2541155 INFO
(zkCallback-1364-thread-2-processing-n:127.0.0.1:33356_solr)
[n:127.0.0.1:33356_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testSimple1/state.json] for collection [testSimple1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 2541163 INFO
(zkCallback-1362-thread-1-processing-n:127.0.0.1:33400_solr)
[n:127.0.0.1:33400_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testSimple1/state.json] for collection [testSimple1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 2541165 INFO (qtp1711881732-13254) [n:127.0.0.1:33356_solr
c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=testSimple1_shard1_replica_n2&action=CREATE&numShards=2&collection=testSimple1&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=3897
[junit4] 2> 2541249 INFO (qtp1711881732-13250) [n:127.0.0.1:33356_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> 2541423 INFO
(zkCallback-1362-thread-3-processing-n:127.0.0.1:33400_solr)
[n:127.0.0.1:33400_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testSimple1/state.json] for collection [testSimple1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 2541423 INFO
(zkCallback-1362-thread-1-processing-n:127.0.0.1:33400_solr)
[n:127.0.0.1:33400_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDat
[...truncated too long message...]
st, SearchHandlerTest, PingRequestHandlerTest, TestPolicyCloud,
DistributedFacetPivotSmallTest, LeaderFailoverAfterPartitionTest,
TestHdfsUpdateLog, TestChildDocTransformer, TestSolrCloudWithKerberosAlt,
CopyFieldTest, HdfsWriteToMultipleCollectionsTest, TestFileDictionaryLookup,
TestFieldCacheSortRandom, TestLFUCache, TestRealTimeGet,
TestBulkSchemaConcurrent, ConvertedLegacyTest, DocValuesMultiTest,
TestMinMaxOnMultiValuedField, TestCoreBackup, ZkFailoverTest,
NotRequiredUniqueKeyTest, HdfsThreadLeakTest,
StatelessScriptUpdateProcessorFactoryTest, TestManagedStopFilterFactory,
SolrJmxReporterTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest,
ComputePlanActionTest, EnumFieldTest, DateFieldTest,
TestRandomRequestDistribution, TestLegacyFieldCache, PeerSyncReplicationTest,
InfixSuggestersTest, TestDFRSimilarityFactory, LeaderElectionTest,
TestImpersonationWithHadoopAuth, TestTolerantUpdateProcessorCloud,
TestNamedUpdateProcessors, SolrTestCaseJ4Test, SolrShardReporterTest,
PropertiesRequestHandlerTest, ClassificationUpdateProcessorIntegrationTest,
TestFiltering, TestReqParamsAPI, ConnectionReuseTest, CollectionReloadTest,
TestSizeLimitedDistributedMap, TestJmxIntegration, TestTriggerIntegration,
SolrCloudExampleTest, SolrIndexMetricsTest, TestCloudDeleteByQuery,
TestNestedDocsSort, DistributedFacetSimpleRefinementLongTailTest,
TestValueSourceCache, CdcrUpdateLogTest, HdfsBasicDistributedZk2Test,
HttpPartitionTest, TestCollationFieldDocValues, TestStressInPlaceUpdates,
MultiThreadedOCPTest, RegexBoostProcessorTest, MinimalSchemaTest,
TestAnalyzedSuggestions, TestHashQParserPlugin, ReturnFieldsTest,
TestManagedSchemaAPI, TestFieldCache, BasicZkTest, TestReplicationHandler,
TestCoreContainer, SolrCoreTest, StatsComponentTest,
QueryElevationComponentTest, PeerSyncTest, DirectUpdateHandlerTest,
TestIndexSearcher, SimplePostToolTest, TestCoreDiscovery, SuggesterFSTTest,
CoreAdminHandlerTest, SuggesterTSTTest, TestStressLucene, TestTrie,
TestCSVLoader, NoCacheHeaderTest, WordBreakSolrSpellCheckerTest,
SchemaVersionSpecificBehaviorTest, TestWordDelimiterFilterFactory,
DefaultValueUpdateProcessorTest, SortByFunctionTest, DistanceFunctionTest,
DebugComponentTest, DisMaxRequestHandlerTest, DirectSolrSpellCheckerTest,
TestReversedWildcardFilterFactory, FileBasedSpellCheckerTest,
PathHierarchyTokenizerFactoryTest, RequiredFieldsTest,
IndexSchemaRuntimeFieldTest, LoggingHandlerTest, IndexSchemaTest,
TestCSVResponseWriter, QueryParsingTest, UniqFieldsUpdateProcessorFactoryTest,
CSVRequestHandlerTest, TestComponentsName, SearchHandlerTest,
HighlighterConfigTest, ResponseLogComponentTest, MultiTermTest, SampleTest,
TestDocSet, TestBinaryField, TestSearchPerf, TestElisionMultitermQuery,
TestConfig, OutputWriterTest, TestSolrCoreProperties,
SpellPossibilityIteratorTest, TestCharFilters, TestCodecSupport,
SynonymTokenizerTest, TestSweetSpotSimilarityFactory, TestPerFieldSimilarity,
TestLMDirichletSimilarityFactory, TestLMJelinekMercerSimilarityFactory,
TimeZoneUtilsTest, ScriptEngineTest, PluginInfoTest, TestFastLRUCache,
PreAnalyzedFieldTest, RAMDirectoryFactoryTest, TestSolrJ, TestLRUCache,
TestDocumentBuilder, SliceStateTest, DistributedMLTComponentTest,
CursorPagingTest, TestCursorMarkWithoutUniqueKey, TestDistributedMissingSort]
[junit4] 2> NOTE: download the large Jenkins line-docs file by running
'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=TestDistributedMissingSort -Dtests.seed=CD54F7C81B217EA9
-Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt
-Dtests.locale=ms -Dtests.timezone=America/Vancouver -Dtests.asserts=true
-Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J2 | TestDistributedMissingSort (suite) <<<
[junit4] > Throwable #1:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE
scope at org.apache.solr.TestDistributedMissingSort:
[junit4] > 1) Thread[id=40588, name=qtp1389191814-40588,
state=TIMED_WAITING, group=TGRP-TestDistributedMissingSort]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] > at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
[junit4] > at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
[junit4] > at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
[junit4] > at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] > at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > at
__randomizedtesting.SeedInfo.seed([CD54F7C81B217EA9]:0)Throwable #2:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie
threads that couldn't be terminated:
[junit4] > 1) Thread[id=40588, name=qtp1389191814-40588,
state=TIMED_WAITING, group=TGRP-TestDistributedMissingSort]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] > at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
[junit4] > at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
[junit4] > at
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
[junit4] > at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] > at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > at
__randomizedtesting.SeedInfo.seed([CD54F7C81B217EA9]:0)
[junit4] Completed [477/778 (4!)] on J2 in 89.83s, 1 test, 2 errors <<<
FAILURES!
[...truncated 926 lines...]
[junit4] JVM J0: stdout was not empty, see:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/temp/junit4-J0-20180217_114631_9514204376321283356607.sysout
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] java.lang.OutOfMemoryError: GC overhead limit exceeded
[junit4] Dumping heap to
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/heapdumps/java_pid13093.hprof
...
[junit4] Heap dump file created [574605602 bytes in 30.494 secs]
[junit4] <<< JVM J0: EOF ----
[...truncated 3183 lines...]
[junit4] Suite: org.apache.solr.analytics.OverallAnalyticsTest
[junit4] 2> Creating dataDir:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/contrib/solr-analytics/test/J1/temp/solr.analytics.OverallAnalyticsTest_593785CF6C097BF4-001/init-core-data-001
[junit4] 2> Feb 17, 2018 2:15:20 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 3 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=CheapBastard,
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@60532504),
locale=el-GR, timezone=Europe/Skopje
[junit4] 2> NOTE: Linux 4.4.0-112-generic amd64/Oracle Corporation
1.8.0_152 (64-bit)/cpus=4,threads=1,free=266834064,total=316669952
[junit4] 2> NOTE: All tests run in this JVM: [CastingFloatValueTest,
IfFunctionTest, AbstractAnalyticsFieldTest, LegacyAbstractAnalyticsFacetTest,
DoubleFieldsTest, CastingLongValueStreamTest, AddFunctionTest,
CastingDoubleValueStreamTest, DateParseFunctionTest, LongFieldsTest,
CastingBooleanValueTest, LogFunctionTest, LegacyQueryFacetCloudTest,
OverallAnalyticsTest]
[junit4] 2> NOTE: download the large Jenkins line-docs file by running
'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=OverallAnalyticsTest -Dtests.seed=593785CF6C097BF4
-Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt
-Dtests.locale=el-GR -Dtests.timezone=Europe/Skopje -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J1 | OverallAnalyticsTest (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: Could not find
collection:collection1
[junit4] > at
__randomizedtesting.SeedInfo.seed([593785CF6C097BF4]:0)
[junit4] > at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:155)
[junit4] > at
org.apache.solr.analytics.SolrAnalyticsTestCase.setupCollection(SolrAnalyticsTestCase.java:63)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] Completed [75/78 (1!)] on J1 in 34.88s, 0 tests, 1 failure <<<
FAILURES!
[...truncated 5527 lines...]
BUILD FAILED
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/build.xml:626:
The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/build.xml:570:
Some of the tests produced a heap dump, but did not fail. Maybe a suppressed
OutOfMemoryError? Dumps created:
* java_pid13093.hprof
Total time: 594 minutes 37 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
