You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2018/04/21 15:55:03 UTC
[JENKINS] Lucene-Solr-master-Linux (32bit/jdk1.8.0_162) - Build #
21877 - Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/21877/
Java: 32bit/jdk1.8.0_162 -client -XX:+UseG1GC
4 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest.testRestoreState
Error Message:
Did not expect the processor to fire on first run! event={ "id":"68cbc1dad9432Tf1iz00uyuo15x1mcnywcvvbag", "source":"node_added_trigger", "eventTime":1843589439919154, "eventType":"NODEADDED", "properties":{ "eventTimes":[ 1843589439919154, 1843589439920845, 1843589439921546, 1843589439922026], "nodeNames":[ "127.0.0.1:41761_solr", "127.0.0.1:42365_solr", "127.0.0.1:33213_solr", "127.0.0.1:42563_solr"]}}
Stack Trace:
java.lang.AssertionError: Did not expect the processor to fire on first run! event={
"id":"68cbc1dad9432Tf1iz00uyuo15x1mcnywcvvbag",
"source":"node_added_trigger",
"eventTime":1843589439919154,
"eventType":"NODEADDED",
"properties":{
"eventTimes":[
1843589439919154,
1843589439920845,
1843589439921546,
1843589439922026],
"nodeNames":[
"127.0.0.1:41761_solr",
"127.0.0.1:42365_solr",
"127.0.0.1:33213_solr",
"127.0.0.1:42563_solr"]}}
at __randomizedtesting.SeedInfo.seed([CA063B9166DD5B70:4A89F029EE42366]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest.lambda$new$0(NodeAddedTriggerTest.java:49)
at org.apache.solr.cloud.autoscaling.NodeAddedTrigger.run(NodeAddedTrigger.java:161)
at org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest.testRestoreState(NodeAddedTriggerTest.java:257)
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.NodeAddedTriggerTest.testRestoreState
Error Message:
Did not expect the processor to fire on first run! event={ "id":"68cc188d5819aTf1iz00uyuo15x1mcnywcvvbak", "source":"node_added_trigger", "eventTime":1843612712534426, "eventType":"NODEADDED", "properties":{ "eventTimes":[ 1843612712534426, 1843612712536780, 1843612712537746, 1843612712538421], "nodeNames":[ "127.0.0.1:40571_solr", "127.0.0.1:40377_solr", "127.0.0.1:33281_solr", "127.0.0.1:43411_solr"]}}
Stack Trace:
java.lang.AssertionError: Did not expect the processor to fire on first run! event={
"id":"68cc188d5819aTf1iz00uyuo15x1mcnywcvvbak",
"source":"node_added_trigger",
"eventTime":1843612712534426,
"eventType":"NODEADDED",
"properties":{
"eventTimes":[
1843612712534426,
1843612712536780,
1843612712537746,
1843612712538421],
"nodeNames":[
"127.0.0.1:40571_solr",
"127.0.0.1:40377_solr",
"127.0.0.1:33281_solr",
"127.0.0.1:43411_solr"]}}
at __randomizedtesting.SeedInfo.seed([CA063B9166DD5B70:4A89F029EE42366]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest.lambda$new$0(NodeAddedTriggerTest.java:49)
at org.apache.solr.cloud.autoscaling.NodeAddedTrigger.run(NodeAddedTrigger.java:161)
at org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest.testRestoreState(NodeAddedTriggerTest.java:257)
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.NodeAddedTriggerTest.testRestoreState
Error Message:
Did not expect the processor to fire on first run! event={ "id":"68cc1adbc8dcbTf1iz00uyuo15x1mcnywcvvbak", "source":"node_added_trigger", "eventTime":1843613331656139, "eventType":"NODEADDED", "properties":{ "eventTimes":[ 1843613331656139, 1843613331658369, 1843613331659165, 1843613331659780], "nodeNames":[ "127.0.0.1:41027_solr", "127.0.0.1:40903_solr", "127.0.0.1:44029_solr", "127.0.0.1:41971_solr"]}}
Stack Trace:
java.lang.AssertionError: Did not expect the processor to fire on first run! event={
"id":"68cc1adbc8dcbTf1iz00uyuo15x1mcnywcvvbak",
"source":"node_added_trigger",
"eventTime":1843613331656139,
"eventType":"NODEADDED",
"properties":{
"eventTimes":[
1843613331656139,
1843613331658369,
1843613331659165,
1843613331659780],
"nodeNames":[
"127.0.0.1:41027_solr",
"127.0.0.1:40903_solr",
"127.0.0.1:44029_solr",
"127.0.0.1:41971_solr"]}}
at __randomizedtesting.SeedInfo.seed([CA063B9166DD5B70:4A89F029EE42366]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest.lambda$new$0(NodeAddedTriggerTest.java:49)
at org.apache.solr.cloud.autoscaling.NodeAddedTrigger.run(NodeAddedTrigger.java:161)
at org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest.testRestoreState(NodeAddedTriggerTest.java:257)
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.NodeAddedTriggerTest.testRestoreState
Error Message:
Did not expect the processor to fire on first run! event={ "id":"68aaf47352604Tf1iz00uyuo15x1mcnywcvvhky", "source":"node_added_trigger", "eventTime":1841335278839300, "eventType":"NODEADDED", "properties":{ "eventTimes":[ 1841335278839300, 1841335278842620, 1841335278843301, 1841335278843780], "nodeNames":[ "127.0.0.1:44361_solr", "127.0.0.1:44017_solr", "127.0.0.1:43263_solr", "127.0.0.1:42463_solr"]}}
Stack Trace:
java.lang.AssertionError: Did not expect the processor to fire on first run! event={
"id":"68aaf47352604Tf1iz00uyuo15x1mcnywcvvhky",
"source":"node_added_trigger",
"eventTime":1841335278839300,
"eventType":"NODEADDED",
"properties":{
"eventTimes":[
1841335278839300,
1841335278842620,
1841335278843301,
1841335278843780],
"nodeNames":[
"127.0.0.1:44361_solr",
"127.0.0.1:44017_solr",
"127.0.0.1:43263_solr",
"127.0.0.1:42463_solr"]}}
at __randomizedtesting.SeedInfo.seed([CA063B9166DD5B70:4A89F029EE42366]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest.lambda$new$0(NodeAddedTriggerTest.java:49)
at org.apache.solr.cloud.autoscaling.NodeAddedTrigger.run(NodeAddedTrigger.java:161)
at org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest.testRestoreState(NodeAddedTriggerTest.java:257)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 13277 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_CA063B9166DD5B70-001/init-core-data-001
[junit4] 2> 886957 INFO (SUITE-NodeAddedTriggerTest-seed#[CA063B9166DD5B70]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 886958 INFO (SUITE-NodeAddedTriggerTest-seed#[CA063B9166DD5B70]-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> 886958 INFO (SUITE-NodeAddedTriggerTest-seed#[CA063B9166DD5B70]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 886959 INFO (SUITE-NodeAddedTriggerTest-seed#[CA063B9166DD5B70]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_CA063B9166DD5B70-001/tempDir-001
[junit4] 2> 886959 INFO (SUITE-NodeAddedTriggerTest-seed#[CA063B9166DD5B70]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 886959 INFO (Thread-2016) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 886959 INFO (Thread-2016) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 886967 ERROR (Thread-2016) [ ] 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> 887059 INFO (SUITE-NodeAddedTriggerTest-seed#[CA063B9166DD5B70]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:44837
[junit4] 2> 887062 INFO (zkConnectionManagerCallback-1941-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 887068 INFO (jetty-launcher-1938-thread-1) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T21:27:37Z, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 887078 INFO (jetty-launcher-1938-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 887078 INFO (jetty-launcher-1938-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 887078 INFO (jetty-launcher-1938-thread-1) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 887080 INFO (jetty-launcher-1938-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@984eb{/solr,null,AVAILABLE}
[junit4] 2> 887083 INFO (jetty-launcher-1938-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@9cb295{SSL,[ssl, http/1.1]}{127.0.0.1:42463}
[junit4] 2> 887083 INFO (jetty-launcher-1938-thread-1) [ ] o.e.j.s.Server Started @887110ms
[junit4] 2> 887083 INFO (jetty-launcher-1938-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42463}
[junit4] 2> 887083 ERROR (jetty-launcher-1938-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 887083 INFO (jetty-launcher-1938-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 887083 INFO (jetty-launcher-1938-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 887083 INFO (jetty-launcher-1938-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 887083 INFO (jetty-launcher-1938-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 887083 INFO (jetty-launcher-1938-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-21T15:14:55.130Z
[junit4] 2> 887098 INFO (zkConnectionManagerCallback-1943-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 887099 INFO (jetty-launcher-1938-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 887112 INFO (jetty-launcher-1938-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44837/solr
[junit4] 2> 887114 INFO (zkConnectionManagerCallback-1947-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 887122 INFO (zkConnectionManagerCallback-1949-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 887215 INFO (jetty-launcher-1938-thread-1) [n:127.0.0.1:42463_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 887216 INFO (jetty-launcher-1938-thread-1) [n:127.0.0.1:42463_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:42463_solr
[junit4] 2> 887218 INFO (jetty-launcher-1938-thread-1) [n:127.0.0.1:42463_solr ] o.a.s.c.Overseer Overseer (id=72178266816708611-127.0.0.1:42463_solr-n_0000000000) starting
[junit4] 2> 887224 INFO (zkConnectionManagerCallback-1956-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 887226 INFO (jetty-launcher-1938-thread-1) [n:127.0.0.1:42463_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44837/solr ready
[junit4] 2> 887227 INFO (jetty-launcher-1938-thread-1) [n:127.0.0.1:42463_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42463_solr
[junit4] 2> 887229 INFO (zkCallback-1955-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 887230 INFO (zkCallback-1948-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 887947 INFO (jetty-launcher-1938-thread-1) [n:127.0.0.1:42463_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42463.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed2279
[junit4] 2> 887957 INFO (jetty-launcher-1938-thread-1) [n:127.0.0.1:42463_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42463.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed2279
[junit4] 2> 887958 INFO (jetty-launcher-1938-thread-1) [n:127.0.0.1:42463_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42463.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed2279
[junit4] 2> 887959 INFO (jetty-launcher-1938-thread-1) [n:127.0.0.1:42463_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_CA063B9166DD5B70-001/tempDir-001/node1/.
[junit4] 2> 887988 INFO (zkConnectionManagerCallback-1958-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 887991 INFO (zkConnectionManagerCallback-1963-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 887992 INFO (SUITE-NodeAddedTriggerTest-seed#[CA063B9166DD5B70]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 887993 INFO (SUITE-NodeAddedTriggerTest-seed#[CA063B9166DD5B70]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44837/solr ready
[junit4] 2> 888009 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CA063B9166DD5B70]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testListenerAcceptance
[junit4] 2> 888037 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T21:27:37Z, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 888038 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 888039 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 888039 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 888039 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@ea298b{/solr,null,AVAILABLE}
[junit4] 2> 888040 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@140efda{SSL,[ssl, http/1.1]}{127.0.0.1:43263}
[junit4] 2> 888040 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.Server Started @888067ms
[junit4] 2> 888040 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CA063B9166DD5B70]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=43263}
[junit4] 2> 888040 ERROR (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CA063B9166DD5B70]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 888040 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CA063B9166DD5B70]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 888040 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CA063B9166DD5B70]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 888040 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CA063B9166DD5B70]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 888040 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CA063B9166DD5B70]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 888040 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CA063B9166DD5B70]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-21T15:14:56.087Z
[junit4] 2> 888042 INFO (zkConnectionManagerCallback-1965-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 888043 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CA063B9166DD5B70]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 888059 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CA063B9166DD5B70]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44837/solr
[junit4] 2> 888061 INFO (zkConnectionManagerCallback-1969-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 888063 INFO (zkConnectionManagerCallback-1971-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 888069 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CA063B9166DD5B70]) [n:127.0.0.1:43263_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 888070 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CA063B9166DD5B70]) [n:127.0.0.1:43263_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 888072 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CA063B9166DD5B70]) [n:127.0.0.1:43263_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 888072 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CA063B9166DD5B70]) [n:127.0.0.1:43263_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43263_solr
[junit4] 2> 888073 INFO (zkCallback-1955-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 888073 INFO (zkCallback-1948-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 888074 INFO (zkCallback-1962-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 888074 INFO (zkCallback-1970-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 890283 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CA063B9166DD5B70]) [n:127.0.0.1:43263_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43263.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed2279
[junit4] 2> 890295 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CA063B9166DD5B70]) [n:127.0.0.1:43263_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43263.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed2279
[junit4] 2> 890295 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CA063B9166DD5B70]) [n:127.0.0.1:43263_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43263.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed2279
[junit4] 2> 890297 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CA063B9166DD5B70]) [n:127.0.0.1:43263_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_CA063B9166DD5B70-001/tempDir-001/node2/.
[junit4] 2> 890304 INFO (zkConnectionManagerCallback-1978-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 890305 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CA063B9166DD5B70]) [n:127.0.0.1:43263_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 890306 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CA063B9166DD5B70]) [n:127.0.0.1:43263_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44837/solr ready
[junit4] 2> 890338 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CA063B9166DD5B70]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testListenerAcceptance
[junit4] 2> 890356 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testTrigger
[junit4] 2> 890359 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T21:27:37Z, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 890360 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 890360 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 890360 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 890360 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@67b42b{/solr,null,AVAILABLE}
[junit4] 2> 890360 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@167ffc7{SSL,[ssl, http/1.1]}{127.0.0.1:44361}
[junit4] 2> 890361 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.Server Started @890388ms
[junit4] 2> 890361 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44361}
[junit4] 2> 890361 ERROR (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 890361 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 890361 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 890361 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 890361 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 890361 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-21T15:14:58.408Z
[junit4] 2> 890362 INFO (zkConnectionManagerCallback-1980-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 890363 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 890371 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44837/solr
[junit4] 2> 890373 INFO (zkConnectionManagerCallback-1984-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 890375 INFO (zkConnectionManagerCallback-1986-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 890380 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [n:127.0.0.1:44361_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 890382 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [n:127.0.0.1:44361_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 890384 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [n:127.0.0.1:44361_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 890384 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [n:127.0.0.1:44361_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44361_solr
[junit4] 2> 890384 INFO (zkCallback-1970-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 890385 INFO (zkCallback-1955-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 890385 INFO (zkCallback-1962-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 890385 INFO (zkCallback-1948-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 890385 INFO (zkCallback-1985-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 890385 INFO (zkCallback-1977-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 891487 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [n:127.0.0.1:44361_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44361.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed2279
[junit4] 2> 891497 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [n:127.0.0.1:44361_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44361.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed2279
[junit4] 2> 891497 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [n:127.0.0.1:44361_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44361.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed2279
[junit4] 2> 891498 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [n:127.0.0.1:44361_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_CA063B9166DD5B70-001/tempDir-001/node3/.
[junit4] 2> 891501 INFO (zkConnectionManagerCallback-1993-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 891502 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [n:127.0.0.1:44361_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 891503 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [n:127.0.0.1:44361_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44837/solr ready
[junit4] 2> 891536 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T21:27:37Z, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 891537 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 891537 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 891537 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 891537 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1a610c9{/solr,null,AVAILABLE}
[junit4] 2> 891538 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@16ba393{SSL,[ssl, http/1.1]}{127.0.0.1:44017}
[junit4] 2> 891538 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.Server Started @891565ms
[junit4] 2> 891538 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44017}
[junit4] 2> 891538 ERROR (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 891538 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 891538 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 891538 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 891538 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 891538 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-21T15:14:59.585Z
[junit4] 2> 891540 INFO (zkConnectionManagerCallback-1995-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 891540 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 891549 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44837/solr
[junit4] 2> 891549 INFO (zkConnectionManagerCallback-1999-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 891551 INFO (zkConnectionManagerCallback-2001-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 891557 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [n:127.0.0.1:44017_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 891558 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [n:127.0.0.1:44017_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 891560 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [n:127.0.0.1:44017_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 891560 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [n:127.0.0.1:44017_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44017_solr
[junit4] 2> 891561 INFO (zkCallback-1948-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 891561 INFO (zkCallback-1955-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 891561 INFO (zkCallback-1962-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 891561 INFO (zkCallback-1985-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 891561 INFO (zkCallback-1977-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 891561 INFO (zkCallback-1970-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 891561 INFO (zkCallback-1992-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 891562 INFO (zkCallback-2000-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 891943 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [n:127.0.0.1:44017_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44017.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed2279
[junit4] 2> 891952 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [n:127.0.0.1:44017_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44017.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed2279
[junit4] 2> 891952 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [n:127.0.0.1:44017_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44017.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed2279
[junit4] 2> 891953 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [n:127.0.0.1:44017_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_CA063B9166DD5B70-001/tempDir-001/node4/.
[junit4] 2> 891956 INFO (zkConnectionManagerCallback-2008-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 891957 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [n:127.0.0.1:44017_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 891958 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [n:127.0.0.1:44017_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44837/solr ready
[junit4] 2> 896019 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T21:27:37Z, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 896022 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 896022 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 896022 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 896023 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@11d3a4a{/solr,null,AVAILABLE}
[junit4] 2> 896024 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@197c409{SSL,[ssl, http/1.1]}{127.0.0.1:37349}
[junit4] 2> 896024 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.Server Started @896052ms
[junit4] 2> 896025 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=37349}
[junit4] 2> 896025 ERROR (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 896025 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 896025 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 896025 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 896025 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 896025 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-21T15:15:04.072Z
[junit4] 2> 896030 INFO (zkConnectionManagerCallback-2010-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 896031 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 896041 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44837/solr
[junit4] 2> 896043 INFO (zkConnectionManagerCallback-2014-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 896046 INFO (zkConnectionManagerCallback-2016-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 896052 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [n:127.0.0.1:37349_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 896053 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [n:127.0.0.1:37349_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 896055 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [n:127.0.0.1:37349_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 896055 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [n:127.0.0.1:37349_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37349_solr
[junit4] 2> 896056 INFO (zkCallback-1955-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 896057 INFO (zkCallback-1948-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 896057 INFO (zkCallback-2000-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 896057 INFO (zkCallback-1977-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 896057 INFO (zkCallback-1962-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 896057 INFO (zkCallback-1992-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 896057 INFO (zkCallback-1970-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 896057 INFO (zkCallback-1985-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 896063 INFO (zkCallback-2007-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 896064 INFO (zkCallback-2015-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 896582 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [n:127.0.0.1:37349_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37349.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed2279
[junit4] 2> 896593 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [n:127.0.0.1:37349_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37349.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed2279
[junit4] 2> 896593 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [n:127.0.0.1:37349_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37349.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed2279
[junit4] 2> 896595 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [n:127.0.0.1:37349_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_CA063B9166DD5B70-001/tempDir-001/node5/.
[junit4] 2> 896599 INFO (zkConnectionManagerCallback-2023-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 896600 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [n:127.0.0.1:37349_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
[junit4] 2> 896601 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [n:127.0.0.1:37349_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44837/solr ready
[junit4] 2> 896658 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@197c409{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 896659 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=3248191
[junit4] 2> 896659 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 896659 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1b6f86d: rootName = solr_37349, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@1297277
[junit4] 2> 896667 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 896667 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1c7816b: rootName = solr_37349, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@10ef9af
[junit4] 2> 896671 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 896671 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7b314a: rootName = solr_37349, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@16720cb
[junit4] 2> 896672 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:37349_solr
[junit4] 2> 896673 INFO (zkCallback-1955-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 896673 INFO (zkCallback-1948-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 896673 INFO (zkCallback-1992-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 896673 INFO (zkCallback-1977-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 896673 INFO (zkCallback-2000-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 896673 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 896674 INFO (zkCallback-1985-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 896674 INFO (zkCallback-2007-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 896674 INFO (zkCallback-2015-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 896674 INFO (zkCallback-1970-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 896679 INFO (zkCallback-2022-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 896679 INFO (zkCallback-1962-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 896680 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@11d3a4a{/solr,null,UNAVAILABLE}
[junit4] 2> 896680 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 901682 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[CA063B9166DD5B70]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testTrigger
[junit4] 2> 901722 INFO (TEST-NodeAddedTriggerTest.testActionLifecycle-seed#[CA063B9166DD5B70]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testActionLifecycle
[junit4] 2> 901724 INFO (TEST-NodeAddedTriggerTest.testActionLifecycle-seed#[CA063B9166DD5B70]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testActionLifecycle
[junit4] 2> 901761 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[CA063B9166DD5B70]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRestoreState
[junit4] 2> 901763 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T21:27:37Z, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 901764 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 901764 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 901764 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 901764 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1e70706{/solr,null,AVAILABLE}
[junit4] 2> 901765 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@170e928{SSL,[ssl, http/1.1]}{127.0.0.1:38171}
[junit4] 2> 901765 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[CA063B9166DD5B70]) [ ] o.e.j.s.Server Started @901792ms
[junit4] 2> 901765 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[CA063B9166DD5B70]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38171}
[junit4] 2> 901766 ERROR (TEST-NodeAddedTriggerTest.testRestoreState-seed#[CA063B9166DD5B70]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 901766 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[CA063B9166DD5B70]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 901766 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[CA063B9166DD5B70]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 901766 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[CA063B9166DD5B70]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 901766 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[CA063B9166DD5B70]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 901766 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[CA063B9166DD5B70]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-21T15:15:09.813Z
[junit4] 2> 901770 INFO (zkConnectionManagerCallback-2025-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 901771 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[CA063B9166DD5B70]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 901780 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[CA063B9166DD5B70]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44837/solr
[junit4] 2> 901781 INFO (zkConnectionManagerCallback-2029-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 901783 INFO (zkConnectionManagerCallback-2031-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 901788 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[CA063B9166DD5B70]) [n:127.0.0.1:38171_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 901789 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[CA063B9166DD5B70]) [n:127.0.0.1:38171_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 901791 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[CA063B9166DD5B70]) [n:127.0.0.1:38171_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 901791 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[CA063B9166DD5B70]) [n:127.0.0.1:38171_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38171_solr
[junit4] 2> 901792 INFO (zkCallback-1955-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 901792 INFO (zkCallback-1962-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 901792 INFO (zkCallback-1992-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 901792 INFO (zkCallback-2000-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 901793 INFO (zkCallback-1977-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 901793 INFO (zkCallback-2007-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 901793 INFO (zkCallback-1970-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 901793 INFO (zkCallback-1985-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 901793 INFO (zkCallback-1948-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 901793 INFO (zkCallback-2030-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 903774 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[CA063B9166DD5B70]) [n:127.0.0.1:38171_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38171.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed2279
[junit4] 2> 903784 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[CA063B9166DD5B70]) [n:127.0.0.1:38171_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38171.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed2279
[junit4] 2> 903784 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[CA063B9166DD5B70]) [n:127.0.0.1:38171_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38171.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ed2279
[junit4] 2> 903787 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[CA063B9166DD5B70]) [n:127.0.0.1:38171_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_CA063B9166DD5B70-001/tempDir-001/node6/.
[junit4] 2> 903790 INFO (zkConnectionManagerCallback-2038-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 903792 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[CA063B9166DD5B70]) [n:127.0.0.1:38171_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
[junit4] 2> 903793 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[CA063B9166DD5B70]) [n:127.0.0.1:38171_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44837/solr ready
[junit4] 2> 903856 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[CA063B9166DD5B70]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testRestoreState
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=NodeAddedTriggerTest -Dtests.method=testRestoreState -Dtests.seed=CA063B9166DD5B70 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sq-AL -Dtests.timezone=America/Maceio -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 2.13s J0 | NodeAddedTriggerTest.testRestoreState <<<
[junit4] > Throwable #1: java.lang.AssertionError: Did not expect the processor to fire on first run! event={
[junit4] > "id":"68aaf47352604Tf1iz00uyuo15x1mcnywcvvhky",
[junit4] > "source":"node_added_trigger",
[junit4] > "eventTime":1841335278839300,
[junit4] > "eventType":"NODEADDED",
[junit4] > "properties":{
[junit4] > "eventTimes":[
[junit4] > 1841335278839300,
[junit4] > 1841335278842620,
[junit4] > 1841335278843301,
[junit4] > 1841335278843780],
[junit4] > "nodeNames":[
[junit4] > "127.0.0.1:44361_solr",
[junit4] > "127.0.0.1:44017_solr",
[junit4] > "127.0.0.1:43263_solr",
[junit4] > "127.0.0.1:42463_solr"]}}
[junit4] > at __randomizedtesting.SeedInfo.seed([CA063B9166DD5B70:4A89F029EE42366]:0)
[junit4] > at org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest.lambda$new$0(NodeAddedTriggerTest.java:49)
[junit4] > at org.apache.solr.cloud.autoscaling.NodeAddedTrigger.run(NodeAddedTrigger.java:161)
[junit4] > at org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest.testRestoreState(NodeAddedTriggerTest.java:257)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 903861 INFO (jetty-closer-1939-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@140efda{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 903861 INFO (jetty-closer-1939-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@167ffc7{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 903861 INFO (jetty-closer-1939-thread-4) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@16ba393{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 903861 INFO (jetty-closer-1939-thread-2) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=3348222
[junit4] 2> 903862 INFO (jetty-closer-1939-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 903862 INFO (jetty-closer-1939-thread-4) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=28825748
[junit4] 2> 903862 INFO (jetty-closer-1939-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@19f99f: rootName = solr_43263, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@963402
[junit4] 2> 903862 INFO (jetty-closer-1939-thread-3) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=8606184
[junit4] 2> 903862 INFO (jetty-closer-1939-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 903862 INFO (jetty-closer-1939-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 903862 INFO (jetty-closer-1939-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1010d61: rootName = solr_44361, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@780fc1
[junit4] 2> 903862 INFO (jetty-closer-1939-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1eb7537: rootName = solr_44017, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@1fdd5d6
[junit4] 2> 903866 INFO (jetty-closer-1939-thread-5) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@170e928{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 903868 INFO (jetty-closer-1939-thread-5) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=10172389
[junit4] 2> 903868 INFO (jetty-closer-1939-thread-5) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 903868 INFO (jetty-closer-1939-thread-5) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1a58c24: rootName = solr_38171, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@13dab37
[junit4] 2> 903869 INFO (jetty-closer-1939-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@9cb295{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 903869 INFO (jetty-closer-1939-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=4389706
[junit4] 2> 903870 INFO (jetty-closer-1939-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 903870 INFO (jetty-closer-1939-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@141770b: rootName = solr_42463, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@c8aada
[junit4] 2> 903871 INFO (jetty-closer-1939-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 903871 INFO (jetty-closer-1939-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@c67bf1: rootName = solr_44361, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@10ef9af
[junit4] 2> 903874 INFO (jetty-closer-1939-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 903874 INFO (jetty-closer-1939-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1c71312: rootName = solr_44017, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@10ef9af
[junit4] 2> 903877 INFO (jetty-closer-1939-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 903877 INFO (jetty-closer-1939-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1316: rootName = solr_44361, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@16720cb
[junit4] 2> 903879 INFO (jetty-closer-1939-thread-3) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:44361_solr
[junit4] 2> 903879 INFO (jetty-closer-1939-thread-5) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 903879 INFO (jetty-closer-1939-thread-5) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1f47d07: rootName = solr_38171, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@10ef9af
[junit4] 2> 903879 INFO (jetty-closer-1939-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 903879 INFO (jetty-closer-1939-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@ff3e6d: rootName = solr_44017, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@16720cb
[junit4] 2> 903879 INFO (jetty-closer-1939-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 903879 INFO (jetty-closer-1939-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@12d77d8: rootName = solr_42463, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@10ef9af
[junit4] 2> 903880 INFO (zkCallback-1955-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 903880 INFO (zkCallback-1992-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 903880 INFO (jetty-closer-1939-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 903880 INFO (zkCallback-2000-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 903881 INFO (jetty-closer-1939-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 903881 INFO (jetty-closer-1939-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@e22573: rootName = solr_43263, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@10ef9af
[junit4] 2> 903885 INFO (jetty-closer-1939-thread-5) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 903885 INFO (jetty-closer-1939-thread-5) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@af7f1a: rootName = solr_38171, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@16720cb
[junit4] 2> 903885 INFO (jetty-closer-1939-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 903885 INFO (jetty-closer-1939-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7bcae0: rootName = solr_42463, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@16720cb
[junit4] 2> 903887 INFO (jetty-closer-1939-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 903887 INFO (jetty-closer-1939-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@c8d1b0: rootName = solr_43263, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@16720cb
[junit4] 2> 903894 INFO (zkCallback-1985-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 903894 INFO (zkCallback-1970-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 903895 INFO (zkCallback-1977-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 903895 INFO (zkCallback-2007-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 903895 INFO (zkCallback-2030-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 903895 INFO (zkCallback-2037-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 903895 INFO (zkCallback-1948-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 903895 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x1006dc0521d000e, likely client has closed socket
[junit4] 2> 903897 INFO (jetty-closer-1939-thread-4) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:44017_solr
[junit4] 2> 903897 INFO (jetty-closer-1939-thread-2) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:43263_solr
[junit4] 2> 903897 INFO (jetty-closer-1939-thread-5) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:38171_solr
[junit4] 2> 903898 INFO (jetty-closer-1939-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:42463_solr
[junit4] 2> 903899 INFO (jetty-closer-1939-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 903899 INFO (jetty-closer-1939-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 903899 INFO (zkCallback-2007-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (1)
[junit4] 2> 903899 INFO (jetty-closer-1939-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 903899 INFO (zkCallback-1977-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (1)
[junit4] 2> 903899 INFO (zkCallback-1970-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (1)
[junit4] 2> 903899 INFO (zkCallback-2030-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (1)
[junit4] 2> 903899 INFO (zkCallback-1948-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (1)
[junit4] 2> 903899 INFO (zkCallback-1955-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (1)
[junit4] 2> 903899 INFO (jetty-closer-1939-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 903900 INFO (jetty-closer-1939-thread-5) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 903900 INFO (zkCallback-1955-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 903900 INFO (zkCallback-2007-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 903901 INFO (zkCallback-1977-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 903903 INFO (jetty-closer-1939-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72178266816708611-127.0.0.1:42463_solr-n_0000000000) closing
[junit4] 2> 903908 INFO (zkCallback-1948-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 903908 INFO (zkCallback-2030-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 903908 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x1006dc0521d000a, likely client has closed socket
[junit4] 2> 903908 INFO (zkCallback-2000-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (0)
[junit4] 2> 903908 INFO (zkCallback-1970-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 903908 INFO (jetty-closer-1939-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@67b42b{/solr,null,UNAVAILABLE}
[junit4] 2> 903908 INFO (zkCallback-2037-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (0)
[junit4] 2> 903909 INFO (jetty-closer-1939-thread-3) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 903909 INFO (OverseerStateUpdate-72178266816708611-127.0.0.1:42463_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:42463_solr
[junit4] 2> 903909 WARN (OverseerAutoScalingTriggerThread-72178266816708611-127.0.0.1:42463_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 903911 INFO (jetty-closer-1939-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@ea298b{/solr,null,UNAVAILABLE}
[junit4] 2> 903914 INFO (jetty-closer-1939-thread-2) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 903916 INFO (jetty-closer-1939-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@984eb{/solr,null,UNAVAILABLE}
[junit4] 2> 903916 INFO (jetty-closer-1939-thread-1) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 905411 INFO (jetty-closer-1939-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1a610c9{/solr,null,UNAVAILABLE}
[junit4] 2> 905412 INFO (jetty-closer-1939-thread-4) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 905415 INFO (jetty-closer-1939-thread-5) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1e70706{/solr,null,UNAVAILABLE}
[junit4] 2> 905415 INFO (jetty-closer-1939-thread-5) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 905416 ERROR (SUITE-NodeAddedTriggerTest-seed#[CA063B9166DD5B70]-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> 905416 INFO (SUITE-NodeAddedTriggerTest-seed#[CA063B9166DD5B70]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:44837 44837
[junit4] 2> 905609 INFO (Thread-2016) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:44837 44837
[junit4] 2> 905610 WARN (Thread-2016) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 13 /solr/aliases.json
[junit4] 2> 6 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 13 /solr/clusterstate.json
[junit4] 2> 13 /solr/clusterprops.json
[junit4] 2> 2 /solr/overseer_elect/election/72178266816708625-127.0.0.1:44017_solr-n_0000000003
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 13 /solr/live_nodes
[junit4] 2> 13 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_CA063B9166DD5B70-001
[junit4] 2> NOTE: test params are: codec=HighCompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=3906, maxDocsPerChunk=732, blockSize=806), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=3906, blockSize=806)), sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@16eeeb3), locale=sq-AL, timezone=America/Maceio
[junit4] 2> NOTE: Linux 4.13.0-37-generic i386/Oracle Corporation 1.8.0_162 (32-bit)/cpus=8,threads=1,free=244208344,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestOmitPositions, DebugComponentTest, TestSolrCloudSnapshots, RAMDirectoryFactoryTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, TestFieldSortValues, BlobRepositoryCloudTest, CurrencyFieldTypeTest, TestSolrDeletionPolicy1, CreateRoutedAliasTest, TimeRoutedAliasUpdateProcessorTest, SolrCloudExampleTest, TestIBSimilarityFactory, TestSimpleTextCodec, TestFastOutputStream, TestBackupRepositoryFactory, ShardRoutingTest, SimpleFacetsTest, DeleteStatusTest, SpellingQueryConverterTest, TestPrepRecovery, TestCloudConsistency, TestRemoteStreaming, HdfsSyncSliceTest, ExternalFileFieldSortTest, TestMergePolicyConfig, TestNumericTokenStream, FullSolrCloudDistribCmdsTest, HighlighterConfigTest, SolrSlf4jReporterTest, TestConfigSetProperties, TestReplicationHandlerBackup, AnalyticsQueryTest, DistributedFacetPivotLongTailTest, TestNoOpRegenerator, BasicDistributedZkTest, CdcrBidirectionalTest, TestRestManager, TestUtilizeNode, BitVectorTest, RestartWhileUpdatingTest, SchemaApiFailureTest, TestScoreJoinQPScore, MoveReplicaHDFSTest, TestComponentsName, TestUnifiedSolrHighlighter, CoreAdminOperationTest, TestGroupingSearch, SolrRequestParserTest, BufferStoreTest, TestCrossCoreJoin, TestPseudoReturnFields, TestUseDocValuesAsStored, TestInPlaceUpdatesDistrib, TestDelegationWithHadoopAuth, V2StandaloneTest, SortSpecParsingTest, SpellCheckCollatorTest, TestJsonFacetsWithNestedObjects, TestSolrQueryParser, TestStressCloudBlindAtomicUpdates, CleanupOldIndexTest, BinaryUpdateRequestHandlerTest, ConnectionReuseTest, TestSortByMinMaxFunction, TestWordDelimiterFilterFactory, TestZkAclsWithHadoopAuth, DeleteNodeTest, TestLMDirichletSimilarityFactory, TestExportWriter, TestFieldCacheVsDocValues, TestHashPartitioner, VersionInfoTest, TestBlendedInfixSuggestions, ZkNodePropsTest, HdfsAutoAddReplicasIntegrationTest, DistributedFacetPivotWhiteBoxTest, TestComplexPhraseLeadingWildcard, BlockJoinFacetRandomTest, SolrPlugin
[...truncated too long message...]
1)
[junit4] 2> 51419 INFO (zkCallback-201-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 51419 INFO (zkCallback-164-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (1)
[junit4] 2> 51420 INFO (zkCallback-149-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 51421 INFO (jetty-closer-103-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@a1ce1e{/solr,null,UNAVAILABLE}
[junit4] 2> 51421 INFO (zkCallback-112-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 51421 INFO (jetty-closer-103-thread-3) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 51421 INFO (zkCallback-134-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 51421 INFO (zkCallback-194-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 51422 INFO (jetty-closer-103-thread-6) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:45345_solr
[junit4] 2> 51424 INFO (jetty-closer-103-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1470ba{/solr,null,UNAVAILABLE}
[junit4] 2> 51424 INFO (zkCallback-112-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 51425 INFO (zkCallback-194-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 51425 INFO (jetty-closer-103-thread-6) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 51424 INFO (zkCallback-201-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 51424 INFO (jetty-closer-103-thread-2) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 51428 INFO (jetty-closer-103-thread-6) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@26ff1b{/solr,null,UNAVAILABLE}
[junit4] 2> 51428 INFO (jetty-closer-103-thread-6) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 52919 WARN (zkCallback-119-thread-1) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 52921 INFO (jetty-closer-103-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@ddc5e3{/solr,null,UNAVAILABLE}
[junit4] 2> 52921 INFO (jetty-closer-103-thread-1) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 52923 WARN (zkCallback-164-thread-1) [ ] o.a.s.c.ZkController Unable to register nodeLost path for 127.0.0.1:44029_solr
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /autoscaling/nodeLost/127.0.0.1:44029_solr
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:130) ~[zookeeper-3.4.11.jar:3.4.11-37e277162d567b55a07d1755f0b31c32e93c01a0]
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) ~[zookeeper-3.4.11.jar:3.4.11-37e277162d567b55a07d1755f0b31c32e93c01a0]
[junit4] 2> at org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java:786) ~[zookeeper-3.4.11.jar:3.4.11-37e277162d567b55a07d1755f0b31c32e93c01a0]
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$create$7(SolrZkClient.java:365) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.create(SolrZkClient.java:365) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.ZkController.lambda$registerLiveNodesListener$2(ZkController.java:885) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.lambda$refreshLiveNodes$3(ZkStateReader.java:791) ~[java/:?]
[junit4] 2> at java.util.concurrent.ConcurrentHashMap$KeySetView.forEach(ConcurrentHashMap.java:4649) ~[?:1.8.0_162]
[junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.refreshLiveNodes(ZkStateReader.java:790) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.access$1400(ZkStateReader.java:73) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.ZkStateReader$LiveNodeWatcher.refreshAndWatch(ZkStateReader.java:1283) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.ZkStateReader$LiveNodeWatcher.process(ZkStateReader.java:1278) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$1.lambda$process$1(SolrZkClient.java:269) ~[java/:?]
[junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) ~[?:1.8.0_162]
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_162]
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:192) ~[java/:?]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_162]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_162]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_162]
[junit4] 2> 52931 INFO (jetty-closer-103-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1138b15{/solr,null,UNAVAILABLE}
[junit4] 2> 52931 INFO (jetty-closer-103-thread-4) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 52931 ERROR (SUITE-NodeAddedTriggerTest-seed#[CA063B9166DD5B70]-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> 52931 INFO (SUITE-NodeAddedTriggerTest-seed#[CA063B9166DD5B70]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:38039 38039
[junit4] 2> 55245 INFO (Thread-59) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:38039 38039
[junit4] 2> 55246 WARN (Thread-59) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 13 /solr/aliases.json
[junit4] 2> 6 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 13 /solr/clusterstate.json
[junit4] 2> 13 /solr/clusterprops.json
[junit4] 2> 2 /solr/overseer_elect/election/72178415789342723-127.0.0.1:41027_solr-n_0000000000
[junit4] 2> 2 /solr/overseer_elect/election/72178415789342737-127.0.0.1:44029_solr-n_0000000003
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 13 /solr/live_nodes
[junit4] 2> 13 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_CA063B9166DD5B70-002
[junit4] 2> NOTE: test params are: codec=HighCompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=3906, maxDocsPerChunk=732, blockSize=806), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=3906, blockSize=806)), sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@35b649), locale=sq-AL, timezone=America/Maceio
[junit4] 2> NOTE: Linux 4.13.0-37-generic i386/Oracle Corporation 1.8.0_162 (32-bit)/cpus=8,threads=1,free=38861704,total=94371840
[junit4] 2> NOTE: All tests run in this JVM: [NodeAddedTriggerTest, NodeAddedTriggerTest]
[junit4] Completed [5/5 (4!)] on J1 in 25.87s, 4 tests, 1 failure <<< FAILURES!
[...truncated 14 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1568: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1092: There were test failures: 5 suites, 20 tests, 4 failures [seed: CA063B9166DD5B70]
Total time: 59 seconds
[repro] Setting last failure code to 256
[repro] Failures:
[repro] 4/5 failed: org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest
[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=8f296d0ccf82174f9c612920ce25b928196a1fa8, workspace=/var/lib/jenkins/workspace/Lucene-Solr-master-Linux
[WARNINGS] Computing warning deltas based on reference build #21876
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