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 11:16:45 UTC
[JENKINS] Lucene-Solr-7.x-Linux (32bit/jdk1.8.0_162) - Build # 1767
- Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/1767/
Java: 32bit/jdk1.8.0_162 -client -XX:+UseParallelGC
3 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest.testRestoreState
Error Message:
Did not expect the processor to fire on first run! event={ "id":"67d8c6d4bb7bdTcos0c53yzaa0y5ywj30d1gl2j", "source":"node_added_trigger", "eventTime":1826891942836157, "eventType":"NODEADDED", "properties":{ "eventTimes":[ 1826891942836157, 1826891942838132, 1826891942838929, 1826891942839489], "nodeNames":[ "127.0.0.1:46087_solr", "127.0.0.1:38983_solr", "127.0.0.1:45645_solr", "127.0.0.1:35273_solr"]}}
Stack Trace:
java.lang.AssertionError: Did not expect the processor to fire on first run! event={
"id":"67d8c6d4bb7bdTcos0c53yzaa0y5ywj30d1gl2j",
"source":"node_added_trigger",
"eventTime":1826891942836157,
"eventType":"NODEADDED",
"properties":{
"eventTimes":[
1826891942836157,
1826891942838132,
1826891942838929,
1826891942839489],
"nodeNames":[
"127.0.0.1:46087_solr",
"127.0.0.1:38983_solr",
"127.0.0.1:45645_solr",
"127.0.0.1:35273_solr"]}}
at __randomizedtesting.SeedInfo.seed([AA5E9866AA51CFF3:64F03CF55268B7E5]: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":"67d8fd3111e87Tcos0c53yzaa0y5ywj30d1gl2n", "source":"node_added_trigger", "eventTime":1826906535173767, "eventType":"NODEADDED", "properties":{ "eventTimes":[ 1826906535173767, 1826906535175699, 1826906535176565, 1826906535177270], "nodeNames":[ "127.0.0.1:37879_solr", "127.0.0.1:34443_solr", "127.0.0.1:44795_solr", "127.0.0.1:39183_solr"]}}
Stack Trace:
java.lang.AssertionError: Did not expect the processor to fire on first run! event={
"id":"67d8fd3111e87Tcos0c53yzaa0y5ywj30d1gl2n",
"source":"node_added_trigger",
"eventTime":1826906535173767,
"eventType":"NODEADDED",
"properties":{
"eventTimes":[
1826906535173767,
1826906535175699,
1826906535176565,
1826906535177270],
"nodeNames":[
"127.0.0.1:37879_solr",
"127.0.0.1:34443_solr",
"127.0.0.1:44795_solr",
"127.0.0.1:39183_solr"]}}
at __randomizedtesting.SeedInfo.seed([AA5E9866AA51CFF3:64F03CF55268B7E5]: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":"67b44652f20a5Tcos0c53yzaa0y5ywj30d1gooe", "source":"node_added_trigger", "eventTime":1824383545843877, "eventType":"NODEADDED", "properties":{ "eventTimes":[ 1824383545843877, 1824383545845235, 1824383545845646, 1824383545845983], "nodeNames":[ "127.0.0.1:36883_solr", "127.0.0.1:33695_solr", "127.0.0.1:34523_solr", "127.0.0.1:45865_solr"]}}
Stack Trace:
java.lang.AssertionError: Did not expect the processor to fire on first run! event={
"id":"67b44652f20a5Tcos0c53yzaa0y5ywj30d1gooe",
"source":"node_added_trigger",
"eventTime":1824383545843877,
"eventType":"NODEADDED",
"properties":{
"eventTimes":[
1824383545843877,
1824383545845235,
1824383545845646,
1824383545845983],
"nodeNames":[
"127.0.0.1:36883_solr",
"127.0.0.1:33695_solr",
"127.0.0.1:34523_solr",
"127.0.0.1:45865_solr"]}}
at __randomizedtesting.SeedInfo.seed([AA5E9866AA51CFF3:64F03CF55268B7E5]: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 13510 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_AA5E9866AA51CFF3-001/init-core-data-001
[junit4] 2> 1149028 WARN (SUITE-NodeAddedTriggerTest-seed#[AA5E9866AA51CFF3]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=124 numCloses=124
[junit4] 2> 1149028 INFO (SUITE-NodeAddedTriggerTest-seed#[AA5E9866AA51CFF3]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1149029 INFO (SUITE-NodeAddedTriggerTest-seed#[AA5E9866AA51CFF3]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 1149029 INFO (SUITE-NodeAddedTriggerTest-seed#[AA5E9866AA51CFF3]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1149030 INFO (SUITE-NodeAddedTriggerTest-seed#[AA5E9866AA51CFF3]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_AA5E9866AA51CFF3-001/tempDir-001
[junit4] 2> 1149030 INFO (SUITE-NodeAddedTriggerTest-seed#[AA5E9866AA51CFF3]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1149030 INFO (Thread-2519) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1149030 INFO (Thread-2519) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1149032 ERROR (Thread-2519) [ ] 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> 1149130 INFO (SUITE-NodeAddedTriggerTest-seed#[AA5E9866AA51CFF3]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:44315
[junit4] 2> 1149132 INFO (zkConnectionManagerCallback-5137-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1149136 INFO (jetty-launcher-5134-thread-1) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T00:27:37+03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1149138 INFO (jetty-launcher-5134-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1149138 INFO (jetty-launcher-5134-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1149138 INFO (jetty-launcher-5134-thread-1) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 1149139 INFO (jetty-launcher-5134-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@e21c3a{/solr,null,AVAILABLE}
[junit4] 2> 1149141 INFO (jetty-launcher-5134-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@147f77{HTTP/1.1,[http/1.1]}{127.0.0.1:45865}
[junit4] 2> 1149141 INFO (jetty-launcher-5134-thread-1) [ ] o.e.j.s.Server Started @1149168ms
[junit4] 2> 1149141 INFO (jetty-launcher-5134-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45865}
[junit4] 2> 1149142 ERROR (jetty-launcher-5134-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1149142 INFO (jetty-launcher-5134-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1149142 INFO (jetty-launcher-5134-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.4.0
[junit4] 2> 1149142 INFO (jetty-launcher-5134-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1149142 INFO (jetty-launcher-5134-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1149142 INFO (jetty-launcher-5134-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-21T10:32:24.865Z
[junit4] 2> 1149143 INFO (zkConnectionManagerCallback-5139-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1149144 INFO (jetty-launcher-5134-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1149154 INFO (jetty-launcher-5134-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44315/solr
[junit4] 2> 1149155 INFO (zkConnectionManagerCallback-5143-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1149158 INFO (zkConnectionManagerCallback-5145-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1149212 INFO (jetty-launcher-5134-thread-1) [n:127.0.0.1:45865_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1149225 INFO (jetty-launcher-5134-thread-1) [n:127.0.0.1:45865_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:45865_solr
[junit4] 2> 1149225 INFO (jetty-launcher-5134-thread-1) [n:127.0.0.1:45865_solr ] o.a.s.c.Overseer Overseer (id=72177155964928003-127.0.0.1:45865_solr-n_0000000000) starting
[junit4] 2> 1149231 INFO (zkConnectionManagerCallback-5152-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1149235 INFO (jetty-launcher-5134-thread-1) [n:127.0.0.1:45865_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44315/solr ready
[junit4] 2> 1149239 INFO (jetty-launcher-5134-thread-1) [n:127.0.0.1:45865_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45865_solr
[junit4] 2> 1149248 INFO (zkCallback-5144-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1149248 INFO (zkCallback-5151-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1151033 INFO (jetty-launcher-5134-thread-1) [n:127.0.0.1:45865_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45865.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@a3f68c
[junit4] 2> 1151042 INFO (jetty-launcher-5134-thread-1) [n:127.0.0.1:45865_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45865.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@a3f68c
[junit4] 2> 1151042 INFO (jetty-launcher-5134-thread-1) [n:127.0.0.1:45865_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45865.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@a3f68c
[junit4] 2> 1151044 INFO (jetty-launcher-5134-thread-1) [n:127.0.0.1:45865_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_AA5E9866AA51CFF3-001/tempDir-001/node1/.
[junit4] 2> 1151065 INFO (zkConnectionManagerCallback-5154-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1151068 INFO (zkConnectionManagerCallback-5159-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1151070 INFO (SUITE-NodeAddedTriggerTest-seed#[AA5E9866AA51CFF3]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1151070 INFO (SUITE-NodeAddedTriggerTest-seed#[AA5E9866AA51CFF3]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44315/solr ready
[junit4] 2> 1151087 INFO (TEST-NodeAddedTriggerTest.testActionLifecycle-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testActionLifecycle
[junit4] 2> 1151089 INFO (TEST-NodeAddedTriggerTest.testActionLifecycle-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testActionLifecycle
[junit4] 2> 1151103 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testListenerAcceptance
[junit4] 2> 1151105 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T00:27:37+03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1151106 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1151106 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1151106 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 1151106 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1d2b153{/solr,null,AVAILABLE}
[junit4] 2> 1151107 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5034d0{HTTP/1.1,[http/1.1]}{127.0.0.1:33695}
[junit4] 2> 1151107 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.Server Started @1151134ms
[junit4] 2> 1151107 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33695}
[junit4] 2> 1151107 ERROR (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1151107 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1151107 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.4.0
[junit4] 2> 1151107 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1151107 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1151107 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-21T10:32:26.830Z
[junit4] 2> 1151109 INFO (zkConnectionManagerCallback-5161-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1151109 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1151120 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44315/solr
[junit4] 2> 1151121 INFO (zkConnectionManagerCallback-5165-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1151123 INFO (zkConnectionManagerCallback-5167-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1151128 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:33695_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1151129 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:33695_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1151131 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:33695_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1151131 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:33695_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33695_solr
[junit4] 2> 1151132 INFO (zkCallback-5151-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1151132 INFO (zkCallback-5144-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1151139 INFO (zkCallback-5158-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1151139 INFO (zkCallback-5166-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1152039 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:33695_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33695.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@a3f68c
[junit4] 2> 1152049 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:33695_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33695.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@a3f68c
[junit4] 2> 1152049 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:33695_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33695.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@a3f68c
[junit4] 2> 1152051 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:33695_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_AA5E9866AA51CFF3-001/tempDir-001/node2/.
[junit4] 2> 1152054 INFO (zkConnectionManagerCallback-5174-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1152056 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:33695_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1152056 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:33695_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44315/solr ready
[junit4] 2> 1152088 INFO (TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testListenerAcceptance
[junit4] 2> 1152113 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testTrigger
[junit4] 2> 1152114 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T00:27:37+03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1152115 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1152115 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1152115 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 1152115 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@114383f{/solr,null,AVAILABLE}
[junit4] 2> 1152116 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1442e1b{HTTP/1.1,[http/1.1]}{127.0.0.1:36883}
[junit4] 2> 1152116 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.Server Started @1152143ms
[junit4] 2> 1152116 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=36883}
[junit4] 2> 1152116 ERROR (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1152116 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1152116 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.4.0
[junit4] 2> 1152116 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1152117 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1152117 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-21T10:32:27.840Z
[junit4] 2> 1152118 INFO (zkConnectionManagerCallback-5176-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1152126 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1152136 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44315/solr
[junit4] 2> 1152137 INFO (zkConnectionManagerCallback-5180-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1152140 INFO (zkConnectionManagerCallback-5182-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1152145 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:36883_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1152146 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:36883_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1152148 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:36883_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1152148 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:36883_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36883_solr
[junit4] 2> 1152149 INFO (zkCallback-5158-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1152149 INFO (zkCallback-5144-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1152149 INFO (zkCallback-5151-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1152150 INFO (zkCallback-5166-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1152151 INFO (zkCallback-5173-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1152151 INFO (zkCallback-5181-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1153103 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:36883_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36883.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@a3f68c
[junit4] 2> 1153113 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:36883_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36883.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@a3f68c
[junit4] 2> 1153113 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:36883_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36883.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@a3f68c
[junit4] 2> 1153115 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:36883_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_AA5E9866AA51CFF3-001/tempDir-001/node3/.
[junit4] 2> 1153119 INFO (zkConnectionManagerCallback-5189-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1153120 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:36883_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1153121 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:36883_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44315/solr ready
[junit4] 2> 1153154 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T00:27:37+03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1153155 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1153155 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1153155 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 1153156 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4391a7{/solr,null,AVAILABLE}
[junit4] 2> 1153156 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@172cbbd{HTTP/1.1,[http/1.1]}{127.0.0.1:34523}
[junit4] 2> 1153156 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.Server Started @1153183ms
[junit4] 2> 1153156 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34523}
[junit4] 2> 1153157 ERROR (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1153157 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1153157 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.4.0
[junit4] 2> 1153157 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1153157 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1153157 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-21T10:32:28.880Z
[junit4] 2> 1153158 INFO (zkConnectionManagerCallback-5191-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1153159 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1153170 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44315/solr
[junit4] 2> 1153171 INFO (zkConnectionManagerCallback-5195-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1153173 INFO (zkConnectionManagerCallback-5197-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1153177 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:34523_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1153179 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:34523_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1153181 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:34523_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1153181 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:34523_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34523_solr
[junit4] 2> 1153181 INFO (zkCallback-5158-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1153182 INFO (zkCallback-5151-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1153182 INFO (zkCallback-5173-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1153182 INFO (zkCallback-5181-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1153182 INFO (zkCallback-5166-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1153182 INFO (zkCallback-5144-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1153184 INFO (zkCallback-5188-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1153185 INFO (zkCallback-5196-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1154142 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:34523_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34523.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@a3f68c
[junit4] 2> 1154151 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:34523_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34523.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@a3f68c
[junit4] 2> 1154151 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:34523_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34523.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@a3f68c
[junit4] 2> 1154153 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:34523_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_AA5E9866AA51CFF3-001/tempDir-001/node4/.
[junit4] 2> 1154156 INFO (zkConnectionManagerCallback-5204-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1154157 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:34523_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1154158 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:34523_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44315/solr ready
[junit4] 2> 1156208 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T00:27:37+03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1156209 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1156209 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1156209 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 1156209 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@107a460{/solr,null,AVAILABLE}
[junit4] 2> 1156210 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@10d9d5b{HTTP/1.1,[http/1.1]}{127.0.0.1:46683}
[junit4] 2> 1156210 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.Server Started @1156237ms
[junit4] 2> 1156210 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46683}
[junit4] 2> 1156210 ERROR (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1156210 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1156210 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.4.0
[junit4] 2> 1156210 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1156210 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1156210 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-21T10:32:31.933Z
[junit4] 2> 1156212 INFO (zkConnectionManagerCallback-5206-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1156213 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1156222 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44315/solr
[junit4] 2> 1156223 INFO (zkConnectionManagerCallback-5210-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1156226 INFO (zkConnectionManagerCallback-5212-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1156231 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:46683_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1156232 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:46683_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1156233 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:46683_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1156233 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:46683_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46683_solr
[junit4] 2> 1156234 INFO (zkCallback-5196-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1156234 INFO (zkCallback-5158-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1156234 INFO (zkCallback-5166-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1156234 INFO (zkCallback-5173-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1156234 INFO (zkCallback-5181-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1156234 INFO (zkCallback-5144-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1156234 INFO (zkCallback-5188-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1156235 INFO (zkCallback-5203-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1156235 INFO (zkCallback-5151-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1156239 INFO (zkCallback-5211-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1157185 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:46683_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46683.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@a3f68c
[junit4] 2> 1157193 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:46683_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46683.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@a3f68c
[junit4] 2> 1157194 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:46683_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46683.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@a3f68c
[junit4] 2> 1157195 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:46683_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_AA5E9866AA51CFF3-001/tempDir-001/node5/.
[junit4] 2> 1157198 INFO (zkConnectionManagerCallback-5219-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1157200 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:46683_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
[junit4] 2> 1157202 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:46683_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44315/solr ready
[junit4] 2> 1157249 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@10d9d5b{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1157249 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=30725352
[junit4] 2> 1157250 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1157295 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@ef49a2: rootName = solr_46683, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@12c643d
[junit4] 2> 1157301 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1157301 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@186fbb7: rootName = solr_46683, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@fd09c3
[junit4] 2> 1157305 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1157305 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1de1790: rootName = solr_46683, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@29c119
[junit4] 2> 1157306 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:46683_solr
[junit4] 2> 1157307 INFO (zkCallback-5158-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 1157307 INFO (zkCallback-5196-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 1157307 INFO (zkCallback-5173-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 1157307 INFO (zkCallback-5144-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 1157307 INFO (zkCallback-5151-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 1157307 INFO (zkCallback-5181-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 1157307 INFO (zkCallback-5203-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 1157307 INFO (zkCallback-5166-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 1157307 INFO (zkCallback-5188-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 1157308 INFO (zkCallback-5218-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 1157308 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 1157308 INFO (zkCallback-5211-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 1157310 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@107a460{/solr,null,UNAVAILABLE}
[junit4] 2> 1157310 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 1162311 INFO (TEST-NodeAddedTriggerTest.testTrigger-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testTrigger
[junit4] 2> 1162352 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRestoreState
[junit4] 2> 1162353 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T00:27:37+03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1162353 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1162353 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1162353 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 1162354 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@a42043{/solr,null,AVAILABLE}
[junit4] 2> 1162354 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@9ac0d7{HTTP/1.1,[http/1.1]}{127.0.0.1:46361}
[junit4] 2> 1162354 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[AA5E9866AA51CFF3]) [ ] o.e.j.s.Server Started @1162381ms
[junit4] 2> 1162354 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46361}
[junit4] 2> 1162354 ERROR (TEST-NodeAddedTriggerTest.testRestoreState-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1162354 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1162354 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.4.0
[junit4] 2> 1162355 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1162355 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1162355 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-21T10:32:38.078Z
[junit4] 2> 1162356 INFO (zkConnectionManagerCallback-5221-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1162357 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1162365 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44315/solr
[junit4] 2> 1162367 INFO (zkConnectionManagerCallback-5225-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1162369 INFO (zkConnectionManagerCallback-5227-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1162373 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:46361_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1162374 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:46361_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1162375 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:46361_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1162375 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:46361_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46361_solr
[junit4] 2> 1162376 INFO (zkCallback-5158-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1162376 INFO (zkCallback-5196-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1162376 INFO (zkCallback-5144-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1162376 INFO (zkCallback-5166-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1162376 INFO (zkCallback-5151-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1162376 INFO (zkCallback-5173-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1162376 INFO (zkCallback-5203-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1162376 INFO (zkCallback-5181-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1162377 INFO (zkCallback-5226-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1162377 INFO (zkCallback-5188-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1163617 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:46361_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46361.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@a3f68c
[junit4] 2> 1163626 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:46361_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46361.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@a3f68c
[junit4] 2> 1163626 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:46361_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46361.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@a3f68c
[junit4] 2> 1163627 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:46361_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_AA5E9866AA51CFF3-001/tempDir-001/node6/.
[junit4] 2> 1163631 INFO (zkConnectionManagerCallback-5234-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1163633 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:46361_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
[junit4] 2> 1163634 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[AA5E9866AA51CFF3]) [n:127.0.0.1:46361_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44315/solr ready
[junit4] 2> 1163680 INFO (TEST-NodeAddedTriggerTest.testRestoreState-seed#[AA5E9866AA51CFF3]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testRestoreState
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=NodeAddedTriggerTest -Dtests.method=testRestoreState -Dtests.seed=AA5E9866AA51CFF3 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ru-RU -Dtests.timezone=Pacific/Niue -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 1.37s J2 | NodeAddedTriggerTest.testRestoreState <<<
[junit4] > Throwable #1: java.lang.AssertionError: Did not expect the processor to fire on first run! event={
[junit4] > "id":"67b44652f20a5Tcos0c53yzaa0y5ywj30d1gooe",
[junit4] > "source":"node_added_trigger",
[junit4] > "eventTime":1824383545843877,
[junit4] > "eventType":"NODEADDED",
[junit4] > "properties":{
[junit4] > "eventTimes":[
[junit4] > 1824383545843877,
[junit4] > 1824383545845235,
[junit4] > 1824383545845646,
[junit4] > 1824383545845983],
[junit4] > "nodeNames":[
[junit4] > "127.0.0.1:36883_solr",
[junit4] > "127.0.0.1:33695_solr",
[junit4] > "127.0.0.1:34523_solr",
[junit4] > "127.0.0.1:45865_solr"]}}
[junit4] > at __randomizedtesting.SeedInfo.seed([AA5E9866AA51CFF3:64F03CF55268B7E5]: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> 1163684 INFO (jetty-closer-5135-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@147f77{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1163684 INFO (jetty-closer-5135-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1442e1b{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1163684 INFO (jetty-closer-5135-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@5034d0{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1163686 INFO (jetty-closer-5135-thread-2) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=26675850
[junit4] 2> 1163686 INFO (jetty-closer-5135-thread-3) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=13804077
[junit4] 2> 1163686 INFO (jetty-closer-5135-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=15282244
[junit4] 2> 1163686 INFO (jetty-closer-5135-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1163686 INFO (jetty-closer-5135-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1163686 INFO (jetty-closer-5135-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@55ba87: rootName = solr_36883, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@50e76c
[junit4] 2> 1163686 INFO (jetty-closer-5135-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1163686 INFO (jetty-closer-5135-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@73b886: rootName = solr_45865, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@c5d62a
[junit4] 2> 1163686 INFO (jetty-closer-5135-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@392d3c: rootName = solr_33695, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@1a8b379
[junit4] 2> 1163686 INFO (jetty-closer-5135-thread-6) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@9ac0d7{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1163687 INFO (jetty-closer-5135-thread-6) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=21045652
[junit4] 2> 1163687 INFO (jetty-closer-5135-thread-6) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1163687 INFO (jetty-closer-5135-thread-6) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7d2d9a: rootName = solr_46361, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@dd9e28
[junit4] 2> 1163691 INFO (jetty-closer-5135-thread-4) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@172cbbd{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1163691 INFO (jetty-closer-5135-thread-4) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=9149668
[junit4] 2> 1163692 INFO (jetty-closer-5135-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1163692 INFO (jetty-closer-5135-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@11bb698: rootName = solr_34523, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@1ad5853
[junit4] 2> 1163693 INFO (jetty-closer-5135-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1163693 INFO (jetty-closer-5135-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1c37016: rootName = solr_36883, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@fd09c3
[junit4] 2> 1163694 INFO (jetty-closer-5135-thread-6) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1163694 INFO (jetty-closer-5135-thread-6) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@72aaf5: rootName = solr_46361, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@fd09c3
[junit4] 2> 1163695 INFO (jetty-closer-5135-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1163695 INFO (jetty-closer-5135-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1163695 INFO (jetty-closer-5135-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@13e6dd8: rootName = solr_33695, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@fd09c3
[junit4] 2> 1163695 INFO (jetty-closer-5135-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@16dd16b: rootName = solr_45865, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@fd09c3
[junit4] 2> 1163699 INFO (jetty-closer-5135-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1163699 INFO (jetty-closer-5135-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1205a0e: rootName = solr_36883, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@29c119
[junit4] 2> 1163699 INFO (jetty-closer-5135-thread-6) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1163699 INFO (jetty-closer-5135-thread-6) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@9fda53: rootName = solr_46361, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@29c119
[junit4] 2> 1163700 INFO (jetty-closer-5135-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1163700 INFO (jetty-closer-5135-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@e6e4d0: rootName = solr_33695, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@29c119
[junit4] 2> 1163700 INFO (jetty-closer-5135-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1163700 INFO (jetty-closer-5135-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@41e7f2: rootName = solr_34523, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@fd09c3
[junit4] 2> 1163700 INFO (jetty-closer-5135-thread-3) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:36883_solr
[junit4] 2> 1163700 INFO (jetty-closer-5135-thread-6) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:46361_solr
[junit4] 2> 1163701 INFO (jetty-closer-5135-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1163701 INFO (jetty-closer-5135-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@52e6b: rootName = solr_45865, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@29c119
[junit4] 2> 1163701 INFO (zkCallback-5196-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (3)
[junit4] 2> 1163701 INFO (zkCallback-5144-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (3)
[junit4] 2> 1163701 INFO (zkCallback-5173-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (3)
[junit4] 2> 1163701 INFO (jetty-closer-5135-thread-6) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 1163702 INFO (jetty-closer-5135-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 1163702 INFO (zkCallback-5188-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (3)
[junit4] 2> 1163702 INFO (zkCallback-5203-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (3)
[junit4] 2> 1163702 INFO (zkCallback-5181-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (3)
[junit4] 2> 1163702 INFO (zkCallback-5151-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (3)
[junit4] 2> 1163702 INFO (zkCallback-5226-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (3)
[junit4] 2> 1163702 INFO (zkCallback-5166-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (3)
[junit4] 2> 1163703 INFO (jetty-closer-5135-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:45865_solr
[junit4] 2> 1163704 INFO (zkCallback-5196-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1163704 INFO (zkCallback-5173-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1163704 INFO (zkCallback-5166-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1163704 INFO (zkCallback-5226-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1163704 INFO (zkCallback-5188-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1163704 INFO (zkCallback-5203-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1163704 INFO (zkCallback-5151-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1163704 INFO (jetty-closer-5135-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 1163704 INFO (jetty-closer-5135-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1163704 INFO (jetty-closer-5135-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72177155964928003-127.0.0.1:45865_solr-n_0000000000) closing
[junit4] 2> 1163704 INFO (OverseerStateUpdate-72177155964928003-127.0.0.1:45865_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:45865_solr
[junit4] 2> 1163704 WARN (OverseerAutoScalingTriggerThread-72177155964928003-127.0.0.1:45865_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 1163705 INFO (zkCallback-5144-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1163705 INFO (jetty-closer-5135-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1163705 INFO (jetty-closer-5135-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@93741c: rootName = solr_34523, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@29c119
[junit4] 2> 1163706 INFO (zkCallback-5233-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (2)
[junit4] 2> 1163708 INFO (jetty-closer-5135-thread-2) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:33695_solr
[junit4] 2> 1163708 INFO (zkCallback-5181-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1163708 INFO (jetty-closer-5135-thread-4) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:34523_solr
[junit4] 2> 1163709 INFO (jetty-closer-5135-thread-6) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@a42043{/solr,null,UNAVAILABLE}
[junit4] 2> 1163709 INFO (jetty-closer-5135-thread-6) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 1163710 INFO (zkCallback-5173-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1163710 INFO (zkCallback-5166-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1163710 INFO (jetty-closer-5135-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 1163710 INFO (zkCallback-5203-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1163710 INFO (zkCallback-5196-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1163711 INFO (zkCallback-5166-thread-2) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:33695_solr
[junit4] 2> 1163711 INFO (jetty-closer-5135-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 1163711 INFO (zkCallback-5203-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 1163711 WARN (zkCallback-5173-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> 1163712 INFO (zkCallback-5196-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 1163712 INFO (zkCallback-5166-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 1165210 WARN (zkCallback-5144-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> 1165210 WARN (zkCallback-5181-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> 1165211 INFO (jetty-closer-5135-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@114383f{/solr,null,UNAVAILABLE}
[junit4] 2> 1165211 INFO (jetty-closer-5135-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@e21c3a{/solr,null,UNAVAILABLE}
[junit4] 2> 1165212 INFO (jetty-closer-5135-thread-1) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 1165212 INFO (jetty-closer-5135-thread-3) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 1165213 INFO (jetty-closer-5135-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1d2b153{/solr,null,UNAVAILABLE}
[junit4] 2> 1165213 INFO (jetty-closer-5135-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4391a7{/solr,null,UNAVAILABLE}
[junit4] 2> 1165213 INFO (jetty-closer-5135-thread-2) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 1165213 INFO (jetty-closer-5135-thread-4) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 1165213 ERROR (SUITE-NodeAddedTriggerTest-seed#[AA5E9866AA51CFF3]-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> 1165214 INFO (SUITE-NodeAddedTriggerTest-seed#[AA5E9866AA51CFF3]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:44315 44315
[junit4] 2> 1166098 INFO (Thread-2519) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:44315 44315
[junit4] 2> 1166099 WARN (Thread-2519) [ ] 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> 13 /solr/clusterprops.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> 2 /solr/overseer_elect/election/72177155964928017-127.0.0.1:34523_solr-n_0000000003
[junit4] 2> 2 /solr/overseer_elect/election/72177155964928009-127.0.0.1:33695_solr-n_0000000001
[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-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_AA5E9866AA51CFF3-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=1870, maxMBSortInHeap=5.026892246346122, sim=RandomSimilarity(queryNorm=false): {}, locale=ru-RU, timezone=Pacific/Niue
[junit4] 2> NOTE: Linux 4.13.0-37-generic i386/Oracle Corporation 1.8.0_162 (32-bit)/cpus=8,threads=1,free=147883104,total=461111296
[junit4] 2> NOTE: All tests run in this JVM: [TestUtilizeNode, TestUnifiedSolrHighlighter, TestSolrConfigHandlerConcurrent, PreAnalyzedFieldTest, TestJavabinTupleStreamParser, TestConfigSetsAPI, MigrateRouteKeyTest, CustomCollectionTest, RecoveryZkTest, TestRandomDVFaceting, TriggerCooldownIntegrationTest, MoreLikeThisHandlerTest, URLClassifyProcessorTest, DistanceUnitsTest, TestNonDefinedSimilarityFactory, DisMaxRequestHandlerTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, TestSolrCloudWithDelegationTokens, TestJettySolrRunner, AsyncCallRequestStatusResponseTest, TestExpandComponent, LIRRollingUpdatesTest, BufferStoreTest, HdfsUnloadDistributedZkTest, TestExactStatsCache, DistributedSpellCheckComponentTest, CollectionPropsTest, TestSchemaManager, TestChildDocTransformer, SolrMetricReporterTest, ResponseBuilderTest, AddReplicaTest, TestStressCloudBlindAtomicUpdates, TestFieldCacheReopen, TestHdfsCloudBackupRestore, OverriddenZkACLAndCredentialsProvidersTest, OverseerRolesTest, CoreAdminHandlerTest, MetricUtilsTest, DeleteShardTest, CacheHeaderTest, TestRestManager, CdcrReplicationHandlerTest, DocValuesTest, TestLFUCache, TestMultiValuedNumericRangeQuery, TestTrieFacet, DirectSolrSpellCheckerTest, TestCoreContainer, CreateCollectionCleanupTest, BadCopyFieldTest, SolrGraphiteReporterTest, TestBackupRepositoryFactory, TestUseDocValuesAsStored, DateFieldTest, TestPKIAuthenticationPlugin, SolrCmdDistributorTest, SimpleMLTQParserTest, TestStressReorder, TestConfigSetProperties, TestSortByMinMaxFunction, AnalyticsQueryTest, SearchRateTriggerTest, LukeRequestHandlerTest, TestNoOpRegenerator, LeaderElectionTest, TestComplexPhraseLeadingWildcard, MetricsConfigTest, TestShortCircuitedRequests, TestDFRSimilarityFactory, TestSimpleTextCodec, ShowFileRequestHandlerTest, TestReplicationHandlerBackup, TestManagedStopFilterFactory, SearchHandlerTest, V2StandaloneTest, TestNumericTerms32, DistribDocExpirationUpdateProcessorTest, DataDrivenBlockJoinTest, TestRequestForwarding, DistributedVersionInfoTest, DateMathParserTest, SimpleCollectionCreateDeleteTest, HdfsSyncSliceTest, ClusterStateTest, TestBlendedInfixSuggestions, TestPayloadScoreQParserPlugin, TestLazyCores, TestSolrDeletionPolicy2, TestFieldSortValues, HdfsRecoverLeaseTest, TestNumericTokenStream, TestScoreJoinQPScore, TestRequestStatusCollectionAPI, TestSQLHandler, TestMacroExpander, InfoHandlerTest, PeerSyncReplicationTest, TestSimDistributedQueue, LeaderFailureAfterFreshStartTest, TestLMJelinekMercerSimilarityFactory, TestTlogReplica, NodeAddedTriggerTest]
[junit4] Completed [341/802 (1!)] on J2 in 17.09s, 4 tests, 1 failure <<< FAILURES!
[...truncated 47896 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/1767/consoleText
[repro] Revision: b16b380b2cec1614597df6a045599307628988c2
[repro] Ant options: "-Dargs=-client -XX:+UseParallelGC"
[repro] Repro line: ant test -Dtestcase=NodeAddedTriggerTest -Dtests.method=testRestoreState -Dtests.seed=AA5E9866AA51CFF3 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ru-RU -Dtests.timezone=Pacific/Niue -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] NodeAddedTriggerTest
[repro] ant compile-test
[...truncated 3316 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.NodeAddedTriggerTest" -Dtests.showOutput=onerror "-Dargs=-client -XX:+UseParallelGC" -Dtests.seed=AA5E9866AA51CFF3 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ru-RU -Dtests.timezone=Pacific/Niue -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 32 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_AA5E9866AA51CFF3-001/init-core-data-001
[junit4] 2> 3219 INFO (SUITE-NodeAddedTriggerTest-seed#[AA5E9866AA51CFF3]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 3293 INFO (SUITE-NodeAddedTriggerTest-seed#[AA5E9866AA51CFF3]-worker) [ ] o.e.j.u.log Logging initialized @3371ms to org.eclipse.jetty.util.log.Slf4jLog
[junit4] 2> 3303 INFO (SUITE-NodeAddedTriggerTest-seed#[AA5E9866AA51CFF3]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 3311 INFO (SUITE-NodeAddedTriggerTest-seed#[AA5E9866AA51CFF3]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 3320 INFO (SUITE-NodeAddedTriggerTest-seed#[AA5E9866AA51CFF3]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_AA5E9866AA51CFF3-001/tempDir-001
[junit4] 2> 3327 INFO (SUITE-NodeAddedTriggerTest-seed#[AA5E9866AA51CFF3]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 3329 INFO (Thread-1) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 3329 INFO (Thread-1) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 3392 ERROR (Thread-1) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 3429 INFO (SUITE-NodeAddedTriggerTest-seed#[AA5E9866AA51CFF3]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:44501
[junit4] 2> 3518 INFO (zkConnectionManagerCallback-4-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3716 INFO (jetty-launcher-1-thread-1) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T10:27:37-11:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 3729 INFO (jetty-launcher-1-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 37
[...truncated too long message...]
er nodeLost path for 127.0.0.1:44795_solr
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /autoscaling/nodeLost/127.0.0.1:44795_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> 40772 WARN (zkCallback-149-thread-1) [ ] o.a.s.c.ZkController Unable to register nodeLost path for 127.0.0.1:44795_solr
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /autoscaling/nodeLost/127.0.0.1:44795_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> 40775 INFO (jetty-closer-103-thread-6) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@d28057{/solr,null,UNAVAILABLE}
[junit4] 2> 40775 INFO (jetty-closer-103-thread-6) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 40775 INFO (jetty-closer-103-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@14cdd09{/solr,null,UNAVAILABLE}
[junit4] 2> 40775 INFO (jetty-closer-103-thread-3) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 40776 ERROR (SUITE-NodeAddedTriggerTest-seed#[AA5E9866AA51CFF3]-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> 40776 INFO (SUITE-NodeAddedTriggerTest-seed#[AA5E9866AA51CFF3]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:33533 33533
[junit4] 2> 40971 INFO (Thread-61) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:33533 33533
[junit4] 2> 40971 WARN (Thread-61) [ ] 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> 13 /solr/clusterprops.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> 2 /solr/overseer_elect/election/72177321188786193-127.0.0.1:37879_solr-n_0000000003
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 13 /solr/collections
[junit4] 2> 12 /solr/live_nodes
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_AA5E9866AA51CFF3-002
[junit4] 2> Apr 21, 2018 11:14:45 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=1870, maxMBSortInHeap=5.026892246346122, sim=RandomSimilarity(queryNorm=false): {}, locale=ru-RU, timezone=Pacific/Niue
[junit4] 2> NOTE: Linux 4.13.0-37-generic i386/Oracle Corporation 1.8.0_162 (32-bit)/cpus=8,threads=1,free=146422208,total=204472320
[junit4] 2> NOTE: All tests run in this JVM: [NodeAddedTriggerTest, NodeAddedTriggerTest]
[junit4] Completed [4/5 (4!)] on J2 in 19.91s, 4 tests, 1 failure <<< FAILURES!
[...truncated 21 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/lucene/common-build.xml:1568: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/lucene/common-build.xml:1092: There were test failures: 5 suites, 20 tests, 4 failures [seed: AA5E9866AA51CFF3]
Total time: 51 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=b16b380b2cec1614597df6a045599307628988c2, workspace=/var/lib/jenkins/workspace/Lucene-Solr-7.x-Linux
[WARNINGS] Computing warning deltas based on reference build #1766
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