Build: https://jenkins.thetaphi.de/job/Lucene-Solr-BadApples-master-Linux/14/
Java: 64bit/jdk1.8.0_162 -XX:+UseCompressedOops -XX:+UseSerialGC
5 tests failed.
FAILED:
org.apache.solr.cloud.autoscaling.ComputePlanActionTest.testSelectedCollections
Error Message:
The operations computed by ComputePlanAction should not be
nullSolrClientNodeStateProvider.DEBUG{AFTER_ACTION=[compute_plan, null],
BEFORE_ACTION=[compute_plan, null]}
Stack Trace:
java.lang.AssertionError: The operations computed by ComputePlanAction should
not be nullSolrClientNodeStateProvider.DEBUG{AFTER_ACTION=[compute_plan, null],
BEFORE_ACTION=[compute_plan, null]}
at
__randomizedtesting.SeedInfo.seed([C622B47E37F37E87:FC8C51A70997A7E9]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNotNull(Assert.java:526)
at
org.apache.solr.cloud.autoscaling.ComputePlanActionTest.testSelectedCollections(ComputePlanActionTest.java:470)
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.ComputePlanActionTest.testSelectedCollections
Error Message:
The operations computed by ComputePlanAction should not be
nullSolrClientNodeStateProvider.DEBUG{AFTER_ACTION=[compute_plan, null],
BEFORE_ACTION=[compute_plan, null]}
Stack Trace:
java.lang.AssertionError: The operations computed by ComputePlanAction should
not be nullSolrClientNodeStateProvider.DEBUG{AFTER_ACTION=[compute_plan, null],
BEFORE_ACTION=[compute_plan, null]}
at
__randomizedtesting.SeedInfo.seed([C622B47E37F37E87:FC8C51A70997A7E9]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNotNull(Assert.java:526)
at
org.apache.solr.cloud.autoscaling.ComputePlanActionTest.testSelectedCollections(ComputePlanActionTest.java:470)
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.ComputePlanActionTest.testSelectedCollections
Error Message:
The operations computed by ComputePlanAction should not be
nullSolrClientNodeStateProvider.DEBUG{AFTER_ACTION=[compute_plan, null],
BEFORE_ACTION=[compute_plan, null]}
Stack Trace:
java.lang.AssertionError: The operations computed by ComputePlanAction should
not be nullSolrClientNodeStateProvider.DEBUG{AFTER_ACTION=[compute_plan, null],
BEFORE_ACTION=[compute_plan, null]}
at
__randomizedtesting.SeedInfo.seed([C622B47E37F37E87:FC8C51A70997A7E9]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNotNull(Assert.java:526)
at
org.apache.solr.cloud.autoscaling.ComputePlanActionTest.testSelectedCollections(ComputePlanActionTest.java:470)
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.update.processor.AtomicUpdateProcessorFactoryTest.testMultipleThreads
Error Message:
Exception during query
Stack Trace:
java.lang.RuntimeException: Exception during query
at
__randomizedtesting.SeedInfo.seed([C622B47E37F37E87:EA10C3F3BB370803]:0)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:913)
at
org.apache.solr.update.processor.AtomicUpdateProcessorFactoryTest.testMultipleThreads(AtomicUpdateProcessorFactoryTest.java:264)
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)
Caused by: java.lang.RuntimeException: REQUEST FAILED:
xpath=//result[@numFound=1]
xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int
name="QTime">0</int></lst><result name="response" numFound="0"
start="0"></result>
</response>
request was:q=int_i:20&wt=xml
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:906)
... 40 more
FAILED:
org.apache.solr.update.processor.AtomicUpdateProcessorFactoryTest.testMultipleThreads
Error Message:
Exception during query
Stack Trace:
java.lang.RuntimeException: Exception during query
at
__randomizedtesting.SeedInfo.seed([C622B47E37F37E87:EA10C3F3BB370803]:0)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:913)
at
org.apache.solr.update.processor.AtomicUpdateProcessorFactoryTest.testMultipleThreads(AtomicUpdateProcessorFactoryTest.java:264)
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)
Caused by: java.lang.RuntimeException: REQUEST FAILED:
xpath=//result[@numFound=1]
xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int
name="QTime">0</int></lst><result name="response" numFound="0"
start="0"></result>
</response>
request was:q=int_i:20&wt=xml
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:906)
... 40 more
Build Log:
[...truncated 1843 lines...]
[junit4] JVM J0: stdout was not empty, see:
/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/lucene/build/core/test/temp/junit4-J0-20180326_021030_0734364242187792168245.sysout
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] codec: CheapBastard, pf: RAMOnly, dvf: Asserting
[junit4] <<< JVM J0: EOF ----
[...truncated 12292 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.ComputePlanActionTest
[junit4] 2> 2075617 INFO
(SUITE-ComputePlanActionTest-seed#[C622B47E37F37E87]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ComputePlanActionTest_C622B47E37F37E87-001/init-core-data-001
[junit4] 2> 2075617 WARN
(SUITE-ComputePlanActionTest-seed#[C622B47E37F37E87]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=7 numCloses=7
[junit4] 2> 2075617 INFO
(SUITE-ComputePlanActionTest-seed#[C622B47E37F37E87]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 2075618 INFO
(SUITE-ComputePlanActionTest-seed#[C622B47E37F37E87]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 2075619 INFO
(SUITE-ComputePlanActionTest-seed#[C622B47E37F37E87]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in
/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ComputePlanActionTest_C622B47E37F37E87-001/tempDir-001
[junit4] 2> 2075619 INFO
(SUITE-ComputePlanActionTest-seed#[C622B47E37F37E87]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2075619 INFO (Thread-5987) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2075619 INFO (Thread-5987) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 2075621 ERROR (Thread-5987) [ ] 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> 2075719 INFO
(SUITE-ComputePlanActionTest-seed#[C622B47E37F37E87]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:43213
[junit4] 2> 2075721 INFO (zkConnectionManagerCallback-10647-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2075726 INFO (jetty-launcher-10644-thread-1) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 2075727 INFO (jetty-launcher-10644-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2075727 INFO (jetty-launcher-10644-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2075727 INFO (jetty-launcher-10644-thread-1) [ ]
o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 2075727 INFO (jetty-launcher-10644-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@19f66171{/solr,null,AVAILABLE}
[junit4] 2> 2075728 INFO (jetty-launcher-10644-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@72c63593{SSL,[ssl,
http/1.1]}{127.0.0.1:42825}
[junit4] 2> 2075729 INFO (jetty-launcher-10644-thread-1) [ ]
o.e.j.s.Server Started @2077526ms
[junit4] 2> 2075729 INFO (jetty-launcher-10644-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=42825}
[junit4] 2> 2075729 ERROR (jetty-launcher-10644-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2075729 INFO (jetty-launcher-10644-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 2075729 INFO (jetty-launcher-10644-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2075729 INFO (jetty-launcher-10644-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2075729 INFO (jetty-launcher-10644-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-03-26T03:11:21.604Z
[junit4] 2> 2075731 INFO (zkConnectionManagerCallback-10649-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2075731 INFO (jetty-launcher-10644-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2075736 INFO (jetty-launcher-10644-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43213/solr
[junit4] 2> 2075737 INFO (zkConnectionManagerCallback-10653-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2075738 INFO
(zkConnectionManagerCallback-10655-thread-1-processing-n:127.0.0.1:42825_solr)
[n:127.0.0.1:42825_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2075765 INFO (jetty-launcher-10644-thread-1)
[n:127.0.0.1:42825_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2075766 INFO (jetty-launcher-10644-thread-1)
[n:127.0.0.1:42825_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:42825_solr
[junit4] 2> 2075766 INFO (jetty-launcher-10644-thread-1)
[n:127.0.0.1:42825_solr ] o.a.s.c.Overseer Overseer
(id=72199904714096643-127.0.0.1:42825_solr-n_0000000000) starting
[junit4] 2> 2075769 INFO
(zkConnectionManagerCallback-10660-thread-1-processing-n:127.0.0.1:42825_solr)
[n:127.0.0.1:42825_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2075770 INFO (jetty-launcher-10644-thread-1)
[n:127.0.0.1:42825_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:43213/solr ready
[junit4] 2> 2075770 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 2075770 INFO (jetty-launcher-10644-thread-1)
[n:127.0.0.1:42825_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:42825_solr
[junit4] 2> 2075770 DEBUG
(OverseerAutoScalingTriggerThread-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.OverseerTriggerThread Adding
.auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 2075770 INFO
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 2075770 DEBUG
(OverseerAutoScalingTriggerThread-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 1
[junit4] 2> 2075770 INFO
(zkCallback-10659-thread-1-processing-n:127.0.0.1:42825_solr)
[n:127.0.0.1:42825_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 2075771 DEBUG
(OverseerAutoScalingTriggerThread-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:42825_solr]
[junit4] 2> 2075771 DEBUG
(OverseerAutoScalingTriggerThread-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion -1
[junit4] 2> 2075771 DEBUG
(OverseerAutoScalingTriggerThread-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 1
[junit4] 2> 2075772 DEBUG
(OverseerAutoScalingTriggerThread-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 2075772 DEBUG
(OverseerAutoScalingTriggerThread-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 2075772 DEBUG (ScheduledTrigger-9847-thread-1)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 1
[junit4] 2> 2075855 INFO (jetty-launcher-10644-thread-1)
[n:127.0.0.1:42825_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42825.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@ca93c34
[junit4] 2> 2075860 INFO (jetty-launcher-10644-thread-1)
[n:127.0.0.1:42825_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42825.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@ca93c34
[junit4] 2> 2075860 INFO (jetty-launcher-10644-thread-1)
[n:127.0.0.1:42825_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42825.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@ca93c34
[junit4] 2> 2075861 INFO (jetty-launcher-10644-thread-1)
[n:127.0.0.1:42825_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ComputePlanActionTest_C622B47E37F37E87-001/tempDir-001/node1/.
[junit4] 2> 2075871 INFO (zkConnectionManagerCallback-10662-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2075874 INFO (zkConnectionManagerCallback-10666-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2075874 INFO
(SUITE-ComputePlanActionTest-seed#[C622B47E37F37E87]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2075875 INFO
(SUITE-ComputePlanActionTest-seed#[C622B47E37F37E87]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43213/solr ready
[junit4] 2> 2075882 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C622B47E37F37E87]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testNodeAdded
[junit4] 2> 2075882 DEBUG
(zkCallback-10659-thread-1-processing-n:127.0.0.1:42825_solr)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 2
[junit4] 2> 2075882 DEBUG
(OverseerAutoScalingTriggerThread-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 2
[junit4] 2> 2075882 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C622B47E37F37E87]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2075883 DEBUG
(OverseerAutoScalingTriggerThread-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 2075884 DEBUG
(OverseerAutoScalingTriggerThread-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 2075884 DEBUG
(OverseerAutoScalingTriggerThread-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 2075894 INFO (qtp1637589220-33758) [n:127.0.0.1:42825_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 2075894 DEBUG (qtp1637589220-33764) [n:127.0.0.1:42825_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2075895 INFO (qtp1637589220-33764) [n:127.0.0.1:42825_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 2075895 DEBUG
(zkCallback-10659-thread-1-processing-n:127.0.0.1:42825_solr)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 3
[junit4] 2> 2075895 DEBUG
(OverseerAutoScalingTriggerThread-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 3
[junit4] 2> 2075895 DEBUG
(OverseerAutoScalingTriggerThread-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 2075895 DEBUG
(OverseerAutoScalingTriggerThread-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 2075895 DEBUG
(OverseerAutoScalingTriggerThread-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 2075896 INFO (qtp1637589220-33763) [n:127.0.0.1:42825_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 2075896 DEBUG (qtp1637589220-33762) [n:127.0.0.1:42825_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2075896 INFO (qtp1637589220-33762) [n:127.0.0.1:42825_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 2075896 DEBUG
(zkCallback-10659-thread-1-processing-n:127.0.0.1:42825_solr)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 4
[junit4] 2> 2075896 DEBUG
(OverseerAutoScalingTriggerThread-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 4
[junit4] 2> 2075896 DEBUG
(OverseerAutoScalingTriggerThread-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 2075897 DEBUG
(OverseerAutoScalingTriggerThread-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 2075897 DEBUG
(OverseerAutoScalingTriggerThread-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 4, lastZnodeVersion 4
[junit4] 2> 2075898 INFO (qtp1637589220-33758) [n:127.0.0.1:42825_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 2075898 DEBUG (qtp1637589220-33764) [n:127.0.0.1:42825_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2075898 INFO (qtp1637589220-33764) [n:127.0.0.1:42825_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 2075898 DEBUG
(zkCallback-10659-thread-1-processing-n:127.0.0.1:42825_solr)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 5
[junit4] 2> 2075899 DEBUG
(zkCallback-10659-thread-1-processing-n:127.0.0.1:42825_solr)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:42825_solr]
[junit4] 2> 2075899 DEBUG
(zkCallback-10659-thread-1-processing-n:127.0.0.1:42825_solr)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=1,
actions=[{name=compute_plan, class=solr.ComputePlanAction}, {name=test,
class=org.apache.solr.cloud.autoscaling.ComputePlanActionTest$AssertingTriggerAction}],
enabled=true}
[junit4] 2> 2075899 DEBUG
(OverseerAutoScalingTriggerThread-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 5
[junit4] 2> 2075899 INFO (qtp1637589220-33763) [n:127.0.0.1:42825_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 2075900 DEBUG
(OverseerAutoScalingTriggerThread-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 2075900 DEBUG (qtp1637589220-33762) [n:127.0.0.1:42825_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2075900 DEBUG
(OverseerAutoScalingTriggerThread-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 5, lastZnodeVersion 5
[junit4] 2> 2075900 INFO (qtp1637589220-33762) [n:127.0.0.1:42825_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 2075900 DEBUG (ScheduledTrigger-9847-thread-2)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 2075900 DEBUG
(zkCallback-10659-thread-1-processing-n:127.0.0.1:42825_solr)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 6
[junit4] 2> 2075900 DEBUG (ScheduledTrigger-9847-thread-2)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 2075900 DEBUG
(zkCallback-10659-thread-1-processing-n:127.0.0.1:42825_solr)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:42825_solr]
[junit4] 2> 2075900 DEBUG
(zkCallback-10659-thread-1-processing-n:127.0.0.1:42825_solr)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=1,
actions=[{name=compute_plan, class=solr.ComputePlanAction}, {name=test,
class=org.apache.solr.cloud.autoscaling.ComputePlanActionTest$AssertingTriggerAction}],
enabled=true}
[junit4] 2> 2075900 DEBUG
(OverseerAutoScalingTriggerThread-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 6
[junit4] 2> 2075900 DEBUG
(OverseerAutoScalingTriggerThread-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 2075901 DEBUG
(OverseerAutoScalingTriggerThread-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 6, lastZnodeVersion 6
[junit4] 2> 2075901 INFO (qtp1637589220-33764) [n:127.0.0.1:42825_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=2&collection.configName=conf&name=testNodeAdded&nrtReplicas=2&action=CREATE&numShards=1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 2075902 INFO
(OverseerThreadFactory-9849-thread-1-processing-n:127.0.0.1:42825_solr)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection
testNodeAdded
[junit4] 2> 2075902 WARN
(OverseerThreadFactory-9849-thread-1-processing-n:127.0.0.1:42825_solr)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.c.CreateCollectionCmd Specified number
of replicas of 2 on collection testNodeAdded is higher than the number of Solr
instances currently live or live and part of your createNodeSet(1). It's
unusual to run two replica of the same slice on the same Solr-instance.
[junit4] 2> 2075903 INFO (qtp1637589220-33758) [n:127.0.0.1:42825_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 2075904 INFO
(OverseerThreadFactory-9849-thread-1-processing-n:127.0.0.1:42825_solr)
[n:127.0.0.1:42825_solr ] o.a.s.c.s.c.a.PolicyHelper returnSession,
curr-time 2171488873 sessionWrapper.createTime 2171488873231052,
this.sessionWrapper.createTime 2171488873231052
[junit4] 2> 2075905 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "name":"testNodeAdded",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"conf",
[junit4] 2> "numShards":"1",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 2075905 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.o.ClusterStateMutator building a new
cName: testNodeAdded
[junit4] 2> 2075906 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.o.ZkStateWriter going to create_collection
/collections/testNodeAdded/state.json
[junit4] 2> 2076007 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:42825/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 2076007 INFO
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:42825/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2076008 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 2076008 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node3":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "base_url":"https://127.0.0.1:42825/solr";,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 2076008 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.Overseer processMessage: queueSize: 2,
message = {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:42825/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 2076008 INFO
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:42825/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2076008 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node3":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "base_url":"https://127.0.0.1:42825/solr";,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 2076008 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "base_url":"https://127.0.0.1:42825/solr";,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "base_url":"https://127.0.0.1:42825/solr";,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 2076109 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeAdded/state.json version: 0
[junit4] 2> 2076211 INFO (qtp1637589220-33757) [n:127.0.0.1:42825_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=testNodeAdded_shard1_replica_n2&action=CREATE&numShards=1&collection=testNodeAdded&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2076211 INFO (qtp1637589220-33758) [n:127.0.0.1:42825_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=testNodeAdded_shard1_replica_n1&action=CREATE&numShards=1&collection=testNodeAdded&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2076212 INFO (qtp1637589220-33757) [n:127.0.0.1:42825_solr
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 2076213 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node3",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:42825/solr";,
[junit4] 2> "node_name":"127.0.0.1:42825_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 2076213 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1
message={
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node3",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:42825/solr";,
[junit4] 2> "node_name":"127.0.0.1:42825_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"}
[junit4] 2> 2076213 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.o.ReplicaMutator Will update state for
replica:
core_node3:{"core":"testNodeAdded_shard1_replica_n1","base_url":"https://127.0.0.1:42825/solr","node_name":"127.0.0.1:42825_solr","state":"down","type":"NRT"}
[junit4] 2> 2076213 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.o.ReplicaMutator Collection is now:
DocCollection(testNodeAdded//collections/testNodeAdded/state.json/1)={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "base_url":"https://127.0.0.1:42825/solr";,
[junit4] 2> "node_name":"127.0.0.1:42825_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "base_url":"https://127.0.0.1:42825/solr";,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 2076215 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.Overseer processMessage: queueSize: 2,
message = {
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "core_node_name":"core_node4",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:42825/solr";,
[junit4] 2> "node_name":"127.0.0.1:42825_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 2076215 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1
message={
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "core_node_name":"core_node4",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:42825/solr";,
[junit4] 2> "node_name":"127.0.0.1:42825_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"}
[junit4] 2> 2076215 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.o.ReplicaMutator Will update state for
replica:
core_node4:{"core":"testNodeAdded_shard1_replica_n2","base_url":"https://127.0.0.1:42825/solr","node_name":"127.0.0.1:42825_solr","state":"down","type":"NRT"}
[junit4] 2> 2076215 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.o.ReplicaMutator Collection is now:
DocCollection(testNodeAdded//collections/testNodeAdded/state.json/1)={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "base_url":"https://127.0.0.1:42825/solr";,
[junit4] 2> "node_name":"127.0.0.1:42825_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "base_url":"https://127.0.0.1:42825/solr";,
[junit4] 2> "node_name":"127.0.0.1:42825_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 2076316 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeAdded/state.json version: 1
[junit4] 2> 2076316 INFO
(zkCallback-10654-thread-1-processing-n:127.0.0.1:42825_solr)
[n:127.0.0.1:42825_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 2076901 DEBUG (ScheduledTrigger-9847-thread-3)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 2076901 DEBUG (ScheduledTrigger-9847-thread-3)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 2077221 INFO (qtp1637589220-33757) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 2077221 INFO (qtp1637589220-33758) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 2077226 INFO (qtp1637589220-33757) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.s.IndexSchema [testNodeAdded_shard1_replica_n2] Schema name=minimal
[junit4] 2> 2077226 INFO (qtp1637589220-33758) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.s.IndexSchema [testNodeAdded_shard1_replica_n1] Schema name=minimal
[junit4] 2> 2077227 INFO (qtp1637589220-33758) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2077227 INFO (qtp1637589220-33757) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2077227 INFO (qtp1637589220-33758) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.c.CoreContainer Creating SolrCore 'testNodeAdded_shard1_replica_n1' using
configuration from collection testNodeAdded, trusted=true
[junit4] 2> 2077227 INFO (qtp1637589220-33757) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.CoreContainer Creating SolrCore 'testNodeAdded_shard1_replica_n2' using
configuration from collection testNodeAdded, trusted=true
[junit4] 2> 2077229 INFO (qtp1637589220-33757) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42825.solr.core.testNodeAdded.shard1.replica_n2' (registry
'solr.core.testNodeAdded.shard1.replica_n2') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@ca93c34
[junit4] 2> 2077229 INFO (qtp1637589220-33758) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42825.solr.core.testNodeAdded.shard1.replica_n1' (registry
'solr.core.testNodeAdded.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@ca93c34
[junit4] 2> 2077229 INFO (qtp1637589220-33757) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2077229 INFO (qtp1637589220-33758) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2077229 INFO (qtp1637589220-33757) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.SolrCore [[testNodeAdded_shard1_replica_n2] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ComputePlanActionTest_C622B47E37F37E87-001/tempDir-001/node1/testNodeAdded_shard1_replica_n2],
dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ComputePlanActionTest_C622B47E37F37E87-001/tempDir-001/node1/./testNodeAdded_shard1_replica_n2/data/]
[junit4] 2> 2077229 INFO (qtp1637589220-33758) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.c.SolrCore [[testNodeAdded_shard1_replica_n1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ComputePlanActionTest_C622B47E37F37E87-001/tempDir-001/node1/testNodeAdded_shard1_replica_n1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ComputePlanActionTest_C622B47E37F37E87-001/tempDir-001/node1/./testNodeAdded_shard1_replica_n1/data/]
[junit4] 2> 2077261 INFO (qtp1637589220-33757) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 2077261 INFO (qtp1637589220-33758) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 2077261 INFO (qtp1637589220-33757) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2077261 INFO (qtp1637589220-33758) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2077274 INFO (qtp1637589220-33758) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2077274 INFO (qtp1637589220-33757) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2077274 INFO (qtp1637589220-33758) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2077274 INFO (qtp1637589220-33757) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2077275 INFO (qtp1637589220-33757) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@7f09ca16[testNodeAdded_shard1_replica_n2] main]
[junit4] 2> 2077275 INFO (qtp1637589220-33758) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@3f7d64a2[testNodeAdded_shard1_replica_n1] main]
[junit4] 2> 2077276 INFO (qtp1637589220-33758) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 2077276 INFO (qtp1637589220-33757) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 2077276 INFO (qtp1637589220-33758) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2077277 INFO (qtp1637589220-33757) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2077277 INFO (qtp1637589220-33758) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2077277 INFO (qtp1637589220-33757) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2077277 INFO (qtp1637589220-33758) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1595968201059991552
[junit4] 2> 2077277 INFO
(searcherExecutor-9853-thread-1-processing-n:127.0.0.1:42825_solr
x:testNodeAdded_shard1_replica_n2 s:shard1 c:testNodeAdded r:core_node4)
[n:127.0.0.1:42825_solr c:testNodeAdded s:shard1 r:core_node4
x:testNodeAdded_shard1_replica_n2] o.a.s.c.SolrCore
[testNodeAdded_shard1_replica_n2] Registered new searcher
Searcher@7f09ca16[testNodeAdded_shard1_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2077277 INFO
(searcherExecutor-9852-thread-1-processing-n:127.0.0.1:42825_solr
x:testNodeAdded_shard1_replica_n1 s:shard1 c:testNodeAdded r:core_node3)
[n:127.0.0.1:42825_solr c:testNodeAdded s:shard1 r:core_node3
x:testNodeAdded_shard1_replica_n1] o.a.s.c.SolrCore
[testNodeAdded_shard1_replica_n1] Registered new searcher
Searcher@3f7d64a2[testNodeAdded_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2077278 INFO (qtp1637589220-33757) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1595968201059991552
[junit4] 2> 2077280 INFO (qtp1637589220-33758) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.c.ZkShardTerms Successful update of terms at
/collections/testNodeAdded/terms/shard1 to Terms{values={core_node3=0},
version=0}
[junit4] 2> 2077280 INFO (qtp1637589220-33757) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.ZkShardTerms Failed to save terms, version is not a match, retrying
[junit4] 2> 2077280 INFO (qtp1637589220-33757) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.ZkShardTerms Successful update of terms at
/collections/testNodeAdded/terms/shard1 to Terms{values={core_node3=0,
core_node4=0}, version=1}
[junit4] 2> 2077281 INFO (qtp1637589220-33758) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2077281 INFO (qtp1637589220-33758) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2077282 INFO (qtp1637589220-33758) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:42825/solr/testNodeAdded_shard1_replica_n1/
[junit4] 2> 2077282 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded"} current state version: 0
[junit4] 2> 2077282 INFO (qtp1637589220-33758) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.u.PeerSync PeerSync: core=testNodeAdded_shard1_replica_n1
url=https://127.0.0.1:42825/solr START
replicas=[https://127.0.0.1:42825/solr/testNodeAdded_shard1_replica_n2/]
nUpdates=100
[junit4] 2> 2077284 INFO (qtp1637589220-33760) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.S.Request [testNodeAdded_shard1_replica_n2] webapp=/solr path=/get
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 2077382 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeAdded/state.json version: 2
[junit4] 2> 2077383 INFO
(zkCallback-10654-thread-2-processing-n:127.0.0.1:42825_solr)
[n:127.0.0.1:42825_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 2077582 INFO (qtp1637589220-33758) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.u.PeerSync PeerSync: core=testNodeAdded_shard1_replica_n1
url=https://127.0.0.1:42825/solr DONE. We have no versions. sync failed.
[junit4] 2> 2077582 INFO (qtp1637589220-33758) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the
next candidate
[junit4] 2> 2077582 INFO (qtp1637589220-33758) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we
can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 2077582 INFO (qtp1637589220-33758) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 2077586 INFO (qtp1637589220-33758) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:42825/solr/testNodeAdded_shard1_replica_n1/ shard1
[junit4] 2> 2077586 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "base_url":"https://127.0.0.1:42825/solr";,
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "state":"active"} current state version: 0
[junit4] 2> 2077687 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeAdded/state.json version: 3
[junit4] 2> 2077688 INFO
(zkCallback-10654-thread-2-processing-n:127.0.0.1:42825_solr)
[n:127.0.0.1:42825_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 2077737 INFO (qtp1637589220-33758) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2077739 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node3",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:42825/solr";,
[junit4] 2> "node_name":"127.0.0.1:42825_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 2077739 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1
message={
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node3",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:42825/solr";,
[junit4] 2> "node_name":"127.0.0.1:42825_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"}
[junit4] 2> 2077739 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.o.ReplicaMutator Will update state for
replica:
core_node3:{"core":"testNodeAdded_shard1_replica_n1","base_url":"https://127.0.0.1:42825/solr","node_name":"127.0.0.1:42825_solr","state":"active","type":"NRT","leader":"true"}
[junit4] 2> 2077739 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.o.ReplicaMutator Collection is now:
DocCollection(testNodeAdded//collections/testNodeAdded/state.json/4)={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "base_url":"https://127.0.0.1:42825/solr";,
[junit4] 2> "node_name":"127.0.0.1:42825_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "base_url":"https://127.0.0.1:42825/solr";,
[junit4] 2> "node_name":"127.0.0.1:42825_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 2077772 INFO (qtp1637589220-33758) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=testNodeAdded_shard1_replica_n1&action=CREATE&numShards=1&collection=testNodeAdded&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1561
[junit4] 2> 2077839 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeAdded/state.json version: 4
[junit4] 2> 2077840 INFO
(zkCallback-10654-thread-2-processing-n:127.0.0.1:42825_solr)
[n:127.0.0.1:42825_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 2077902 DEBUG (ScheduledTrigger-9847-thread-3)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 2077902 DEBUG (ScheduledTrigger-9847-thread-3)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 2078283 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "core_node_name":"core_node4",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:42825/solr";,
[junit4] 2> "node_name":"127.0.0.1:42825_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 2078283 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1
message={
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "core_node_name":"core_node4",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:42825/solr";,
[junit4] 2> "node_name":"127.0.0.1:42825_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"}
[junit4] 2> 2078283 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.o.ReplicaMutator Will update state for
replica:
core_node4:{"core":"testNodeAdded_shard1_replica_n2","base_url":"https://127.0.0.1:42825/solr","node_name":"127.0.0.1:42825_solr","state":"active","type":"NRT"}
[junit4] 2> 2078283 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.o.ReplicaMutator Collection is now:
DocCollection(testNodeAdded//collections/testNodeAdded/state.json/5)={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "base_url":"https://127.0.0.1:42825/solr";,
[junit4] 2> "node_name":"127.0.0.1:42825_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "base_url":"https://127.0.0.1:42825/solr";,
[junit4] 2> "node_name":"127.0.0.1:42825_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 2078305 INFO (qtp1637589220-33757) [n:127.0.0.1:42825_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=testNodeAdded_shard1_replica_n2&action=CREATE&numShards=1&collection=testNodeAdded&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=2093
[junit4] 2> 2078307 INFO (qtp1637589220-33764) [n:127.0.0.1:42825_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> 2078384 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeAdded/state.json version: 5
[junit4] 2> 2078385 INFO
(zkCallback-10654-thread-2-processing-n:127.0.0.1:42825_solr)
[n:127.0.0.1:42825_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 2078902 DEBUG (ScheduledTrigger-9847-thread-3)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 2078902 DEBUG (ScheduledTrigger-9847-thread-3)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 2079308 INFO (qtp1637589220-33764) [n:127.0.0.1:42825_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=2&collection.configName=conf&name=testNodeAdded&nrtReplicas=2&action=CREATE&numShards=1&wt=javabin&version=2}
status=0 QTime=3406
[junit4] 2> 2079314 INFO (qtp1637589220-33763) [n:127.0.0.1:42825_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={wt=javabin&version=2&key=solr.core.testNodeAdded.shard1.replica_n1:INDEX.sizeInBytes&key=solr.core.testNodeAdded.shard1.replica_n2:INDEX.sizeInBytes}
status=0 QTime=0
[junit4] 2> 2079316 INFO (qtp1637589220-33757) [n:127.0.0.1:42825_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 2079316 DEBUG (qtp1637589220-33762) [n:127.0.0.1:42825_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2079316 INFO (qtp1637589220-33762) [n:127.0.0.1:42825_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=6
[junit4] 2> 2079317 DEBUG
(zkCallback-10659-thread-1-processing-n:127.0.0.1:42825_solr)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 7
[junit4] 2> 2079317 DEBUG
(zkCallback-10659-thread-1-processing-n:127.0.0.1:42825_solr)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:42825_solr]
[junit4] 2> 2079317 DEBUG
(zkCallback-10659-thread-1-processing-n:127.0.0.1:42825_solr)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=1,
actions=[{name=compute_plan, class=solr.ComputePlanAction}, {name=test,
class=org.apache.solr.cloud.autoscaling.ComputePlanActionTest$AssertingTriggerAction}],
enabled=true}
[junit4] 2> 2079317 DEBUG
(OverseerAutoScalingTriggerThread-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 7
[junit4] 2> 2079318 DEBUG
(OverseerAutoScalingTriggerThread-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 2079318 DEBUG
(OverseerAutoScalingTriggerThread-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 7, lastZnodeVersion 7
[junit4] 2> 2079318 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C622B47E37F37E87]) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 2079319 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C622B47E37F37E87]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2079319 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C622B47E37F37E87]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2079319 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C622B47E37F37E87]) [ ]
o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 2079319 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C622B47E37F37E87]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@5c520cf9{/solr,null,AVAILABLE}
[junit4] 2> 2079320 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C622B47E37F37E87]) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@121f7419{SSL,[ssl,
http/1.1]}{127.0.0.1:38327}
[junit4] 2> 2079320 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C622B47E37F37E87]) [ ]
o.e.j.s.Server Started @2081117ms
[junit4] 2> 2079320 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C622B47E37F37E87]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=38327}
[junit4] 2> 2079320 ERROR
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C622B47E37F37E87]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2079320 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C622B47E37F37E87]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 2079320 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C622B47E37F37E87]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2079320 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C622B47E37F37E87]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2079320 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C622B47E37F37E87]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-03-26T03:11:25.195Z
[junit4] 2> 2079321 INFO (zkConnectionManagerCallback-10669-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2079322 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C622B47E37F37E87]) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2079327 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C622B47E37F37E87]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43213/solr
[junit4] 2> 2079328 INFO (zkConnectionManagerCallback-10673-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2079329 INFO
(zkConnectionManagerCallback-10675-thread-1-processing-n:127.0.0.1:38327_solr)
[n:127.0.0.1:38327_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2079332 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C622B47E37F37E87])
[n:127.0.0.1:38327_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 2079332 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C622B47E37F37E87])
[n:127.0.0.1:38327_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2079333 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[C622B47E37F37E87])
[n:127.0.0.1:38327_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:38327_solr
[junit4] 2> 2079333 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:38327_solr"} current state version: 0
[junit4] 2> 2079333 DEBUG
(OverseerStateUpdate-72199904714096643-127.0.0.1:42825_solr-n_0000000000)
[n:127.0.0.1:42825_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for
node: 127.0.0.1:38327_solr
[junit4] 2> 2079334 INFO (zkCallback-10665-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2079334 INFO
(zkCallback-10659-thread-1-processing-n:127.0.0.1:42825_solr)
[n:127.0.0.1:42825_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 2079334 INFO
(zkCallback-10654-thread-2-processing-n:127.0.0.1:42825_solr) [n:127.0.0.1
[...truncated too long message...]
3) [n:127.0.0.1:43609_solr ] o.a.s.c.o.NodeMutator DownNode state invoked
for node: 127.0.0.1:43609_solr
[junit4] 2> 89822 INFO
(zkCallback-217-thread-1-processing-n:127.0.0.1:43609_solr)
[n:127.0.0.1:43609_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (0)
[junit4] 2> 89822 DEBUG
(OverseerStateUpdate-72199995105476625-127.0.0.1:43609_solr-n_0000000003)
[n:127.0.0.1:43609_solr ] o.a.s.c.o.NodeMutator Update replica state for
core_node4:{"core":"testNodeLost_shard1_replica_n2","base_url":"https://127.0.0.1:43609/solr","node_name":"127.0.0.1:43609_solr","state":"active","type":"NRT","leader":"true"}
to down
[junit4] 2> 89823 INFO
(zkCallback-212-thread-1-processing-n:127.0.0.1:43609_solr)
[n:127.0.0.1:43609_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (0)
[junit4] 2> 89823 DEBUG
(OverseerStateUpdate-72199995105476625-127.0.0.1:43609_solr-n_0000000003)
[n:127.0.0.1:43609_solr ] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeLost/state.json version: 6
[junit4] 2> 89823 INFO (coreCloseExecutor-395-thread-1)
[n:127.0.0.1:43609_solr c:testNodeLost s:shard1 r:core_node4
x:testNodeLost_shard1_replica_n2] o.a.s.c.SolrCore
[testNodeLost_shard1_replica_n2] CLOSING SolrCore
org.apache.solr.core.SolrCore@511b540a
[junit4] 2> 89823 INFO (coreCloseExecutor-395-thread-1)
[n:127.0.0.1:43609_solr c:testNodeLost s:shard1 r:core_node4
x:testNodeLost_shard1_replica_n2] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.testNodeLost.shard1.replica_n2, tag=1360745482
[junit4] 2> 89823 INFO (coreCloseExecutor-395-thread-1)
[n:127.0.0.1:43609_solr c:testNodeLost s:shard1 r:core_node4
x:testNodeLost_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@17444dd6: rootName =
solr_43609, domain = solr.core.testNodeLost.shard1.replica_n2, service url =
null, agent id = null] for registry solr.core.testNodeLost.shard1.replica_n2 /
com.codahale.metrics.MetricRegistry@66448db
[junit4] 2> 89823 INFO
(zkCallback-212-thread-2-processing-n:127.0.0.1:43609_solr)
[n:127.0.0.1:43609_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeLost/state.json] for collection [testNodeLost] has
occurred - updating... (live nodes size: [0])
[junit4] 2> 89824 INFO
(zkCallback-212-thread-3-processing-n:127.0.0.1:43609_solr)
[n:127.0.0.1:43609_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeLost/state.json] for collection [testNodeLost] has
occurred - updating... (live nodes size: [0])
[junit4] 2> 89829 INFO (coreCloseExecutor-395-thread-1)
[n:127.0.0.1:43609_solr c:testNodeLost s:shard1 r:core_node4
x:testNodeLost_shard1_replica_n2] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.testNodeLost.shard1.leader,
tag=1360745482
[junit4] 2> 89835 INFO (jetty-closer-156-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=72199995105476625-127.0.0.1:43609_solr-n_0000000003) closing
[junit4] 2> 89835 INFO
(OverseerStateUpdate-72199995105476625-127.0.0.1:43609_solr-n_0000000003)
[n:127.0.0.1:43609_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:43609_solr
[junit4] 2> 89835 WARN
(OverseerAutoScalingTriggerThread-72199995105476625-127.0.0.1:43609_solr-n_0000000003)
[n:127.0.0.1:43609_solr ] o.a.s.c.a.OverseerTriggerThread
OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 89836 DEBUG (jetty-closer-156-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 89838 INFO (jetty-closer-156-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@3e80f9e9{/solr,null,UNAVAILABLE}
[junit4] 2> 89838 INFO (jetty-closer-156-thread-1) [ ] o.e.j.s.session
Stopped scavenging
[junit4] 2> 89838 ERROR
(SUITE-ComputePlanActionTest-seed#[C622B47E37F37E87]-worker) [ ]
o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper
server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 89839 INFO
(SUITE-ComputePlanActionTest-seed#[C622B47E37F37E87]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:35645 35645
[junit4] 2> 91010 INFO (Thread-125) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:35645 35645
[junit4] 2> 91011 WARN (Thread-125) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 25 /solr/aliases.json
[junit4] 2> 11 /solr/configs/conf
[junit4] 2> 10
/solr/collections/testNodeWithMultipleReplicasLost/terms/shard1
[junit4] 2> 10 /solr/security.json
[junit4] 2> 9
/solr/collections/testNodeWithMultipleReplicasLost/terms/shard2
[junit4] 2> 6 /solr/collections/testSelected2/terms/shard1
[junit4] 2> 6 /solr/collections/testSelected1/terms/shard1
[junit4] 2> 6 /solr/collections/testSelected3/terms/shard1
[junit4] 2> 5 /solr/collections/testNodeLost/terms/shard1
[junit4] 2> 5 /solr/collections/testNodeAdded/terms/shard1
[junit4] 2> 5 /solr/collections/testSelected1/terms/shard2
[junit4] 2> 5 /solr/collections/testSelected2/terms/shard2
[junit4] 2> 5 /solr/collections/testSelected3/terms/shard2
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 35 /solr/collections/testSelected1/state.json
[junit4] 2> 29 /solr/collections/testSelected2/state.json
[junit4] 2> 26 /solr/collections/testSelected3/state.json
[junit4] 2> 25 /solr/clusterprops.json
[junit4] 2> 25 /solr/clusterstate.json
[junit4] 2> 24
/solr/collections/testNodeWithMultipleReplicasLost/state.json
[junit4] 2> 14 /solr/collections/testNodeLost/state.json
[junit4] 2> 8 /solr/collections/testNodeAdded/state.json
[junit4] 2> 4
/solr/overseer_elect/election/72199995105476625-127.0.0.1:43609_solr-n_0000000003
[junit4] 2> 3
/solr/overseer_elect/election/72199995105476611-127.0.0.1:38165_solr-n_0000000000
[junit4] 2> 2 /solr/autoscaling.json
[junit4] 2> 2
/solr/collections/testNodeWithMultipleReplicasLost/leader_elect/shard1/election/72199995105476625-core_node3-n_0000000000
[junit4] 2> 2
/solr/overseer_elect/election/72199995105476635-127.0.0.1:41629_solr-n_0000000005
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 73 /solr/live_nodes
[junit4] 2> 58 /solr/collections
[junit4] 2> 2 /solr/overseer/queue
[junit4] 2> 2 /solr/autoscaling/events/.scheduled_maintenance
[junit4] 2> 2 /solr/autoscaling/events/.auto_add_replicas
[junit4] 2> 2 /solr/overseer/queue-work
[junit4] 2> 2 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ComputePlanActionTest_C622B47E37F37E87-002
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {},
docValues:{}, maxPointsInLeafNode=1304, maxMBSortInHeap=7.408491773755614,
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@3daff768),
locale=ru-RU, timezone=Asia/Tehran
[junit4] 2> NOTE: Linux 4.13.0-36-generic amd64/Oracle Corporation
1.8.0_162 (64-bit)/cpus=8,threads=1,free=185926552,total=470810624
[junit4] 2> NOTE: All tests run in this JVM: [ComputePlanActionTest,
ComputePlanActionTest]
[junit4] Completed [9/10 (4!)] on J0 in 40.64s, 4 tests, 1 failure <<<
FAILURES!
[...truncated 21 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/lucene/common-build.xml:1561:
The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/lucene/common-build.xml:1085:
There were test failures: 10 suites, 40 tests, 1 error, 3 failures [seed:
C622B47E37F37E87]
Total time: 1 minute 37 seconds
[repro] Setting last failure code to 256
[repro] Failures:
[repro] 1/5 failed:
org.apache.solr.update.processor.AtomicUpdateProcessorFactoryTest
[repro] 3/5 failed: org.apache.solr.cloud.autoscaling.ComputePlanActionTest
[repro] Exiting with code 256
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all
warnings.
<Git Blamer> GIT_COMMIT=4bb02d8689b9bc0419f3eb89fe8d41ca98703acd,
workspace=/var/lib/jenkins/workspace/Lucene-Solr-BadApples-master-Linux
[WARNINGS] Computing warning deltas based on reference build #12
Recording test results
Setting
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org
