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
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
