Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/1783/
Java: 32bit/jdk1.8.0_162 -server -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest.testRestoreState
Error Message:
Did not expect the processor to fire on first run! event={
"id":"726561cccb05eT5zvtwban4xm68dzgh8qfdjfi5",
"source":"node_added_trigger", "eventTime":2012476129194078,
"eventType":"NODEADDED", "properties":{ "eventTimes":[
2012476129194078, 2012476129195658], "nodeNames":[
"127.0.0.1:44783_solr", "127.0.0.1:44341_solr"]}}
Stack Trace:
java.lang.AssertionError: Did not expect the processor to fire on first run!
event={
"id":"726561cccb05eT5zvtwban4xm68dzgh8qfdjfi5",
"source":"node_added_trigger",
"eventTime":2012476129194078,
"eventType":"NODEADDED",
"properties":{
"eventTimes":[
2012476129194078,
2012476129195658],
"nodeNames":[
"127.0.0.1:44783_solr",
"127.0.0.1:44341_solr"]}}
at
__randomizedtesting.SeedInfo.seed([55E23CD92FEAE9A3:9B4C984AD7D391B5]: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 14439 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/J1/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_55E23CD92FEAE9A3-001/init-core-data-001
[junit4] 2> 1805823 INFO
(SUITE-NodeAddedTriggerTest-seed#[55E23CD92FEAE9A3]-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/J1/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_55E23CD92FEAE9A3-001/tempDir-001
[junit4] 2> 1805823 INFO
(SUITE-NodeAddedTriggerTest-seed#[55E23CD92FEAE9A3]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1805823 INFO (Thread-10481) [ ] o.a.s.c.ZkTestServer
client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1805823 INFO (Thread-10481) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1805825 ERROR (Thread-10481) [ ] 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> 1805923 INFO
(SUITE-NodeAddedTriggerTest-seed#[55E23CD92FEAE9A3]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:39071
[junit4] 2> 1805925 INFO (zkConnectionManagerCallback-10482-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1805928 INFO (jetty-launcher-10479-thread-1) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T18:27:37-03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1805928 INFO (jetty-launcher-10479-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1805928 INFO (jetty-launcher-10479-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1805928 INFO (jetty-launcher-10479-thread-1) [ ]
o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 1805928 INFO (jetty-launcher-10479-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1b8ffbd{/solr,null,AVAILABLE}
[junit4] 2> 1805928 INFO (jetty-launcher-10479-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@15672d8{HTTP/1.1,[http/1.1]}{127.0.0.1:44341}
[junit4] 2> 1805928 INFO (jetty-launcher-10479-thread-1) [ ]
o.e.j.s.Server Started @1805964ms
[junit4] 2> 1805928 INFO (jetty-launcher-10479-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=44341}
[junit4] 2> 1805929 ERROR (jetty-launcher-10479-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1805929 INFO (jetty-launcher-10479-thread-1) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1805929 INFO (jetty-launcher-10479-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.4.0
[junit4] 2> 1805929 INFO (jetty-launcher-10479-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1805929 INFO (jetty-launcher-10479-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1805929 INFO (jetty-launcher-10479-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-04-23T14:47:29.736Z
[junit4] 2> 1805929 INFO (zkConnectionManagerCallback-10484-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1805930 INFO (jetty-launcher-10479-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1805936 INFO (jetty-launcher-10479-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39071/solr
[junit4] 2> 1805936 INFO (zkConnectionManagerCallback-10488-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1805938 INFO (zkConnectionManagerCallback-10490-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1805966 INFO (jetty-launcher-10479-thread-1)
[n:127.0.0.1:44341_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1805967 INFO (jetty-launcher-10479-thread-1)
[n:127.0.0.1:44341_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:44341_solr
[junit4] 2> 1805967 INFO (jetty-launcher-10479-thread-1)
[n:127.0.0.1:44341_solr ] o.a.s.c.Overseer Overseer
(id=72189483606147075-127.0.0.1:44341_solr-n_0000000000) starting
[junit4] 2> 1805971 INFO (zkConnectionManagerCallback-10497-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1805972 INFO (jetty-launcher-10479-thread-1)
[n:127.0.0.1:44341_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:39071/solr ready
[junit4] 2> 1805972 INFO (jetty-launcher-10479-thread-1)
[n:127.0.0.1:44341_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:44341_solr
[junit4] 2> 1805973 INFO (zkCallback-10489-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1805973 INFO (zkCallback-10496-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1806420 INFO (jetty-launcher-10479-thread-1)
[n:127.0.0.1:44341_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_44341.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@f16f63
[junit4] 2> 1806428 INFO (jetty-launcher-10479-thread-1)
[n:127.0.0.1:44341_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_44341.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@f16f63
[junit4] 2> 1806428 INFO (jetty-launcher-10479-thread-1)
[n:127.0.0.1:44341_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_44341.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@f16f63
[junit4] 2> 1806429 INFO (jetty-launcher-10479-thread-1)
[n:127.0.0.1:44341_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_55E23CD92FEAE9A3-001/tempDir-001/node1/.
[junit4] 2> 1806447 INFO (zkConnectionManagerCallback-10499-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1806450 INFO (zkConnectionManagerCallback-10504-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1806450 INFO
(SUITE-NodeAddedTriggerTest-seed#[55E23CD92FEAE9A3]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1806451 INFO
(SUITE-NodeAddedTriggerTest-seed#[55E23CD92FEAE9A3]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39071/solr ready
[junit4] 2> 1806467 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3]) [
] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T18:27:37-03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1806467 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3]) [
] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1806467 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3]) [
] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1806467 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3]) [
] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 1806468 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3]) [
] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1f39ba7{/solr,null,AVAILABLE}
[junit4] 2> 1806468 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3]) [
] o.e.j.s.AbstractConnector Started
ServerConnector@72afb7{HTTP/1.1,[http/1.1]}{127.0.0.1:44783}
[junit4] 2> 1806468 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3]) [
] o.e.j.s.Server Started @1806503ms
[junit4] 2> 1806468 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3]) [
] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=44783}
[junit4] 2> 1806468 ERROR
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3]) [
] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1806468 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3]) [
] o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1806468 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3]) [
] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.4.0
[junit4] 2> 1806468 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3]) [
] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port
null
[junit4] 2> 1806468 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3]) [
] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1806468 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3]) [
] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-04-23T14:47:30.275Z
[junit4] 2> 1806475 INFO (zkConnectionManagerCallback-10506-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1806476 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3]) [
] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1806485 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3]) [
] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39071/solr
[junit4] 2> 1806486 INFO (zkConnectionManagerCallback-10510-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1806488 INFO (zkConnectionManagerCallback-10512-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1806491 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:44783_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1806492 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:44783_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1806493 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:44783_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 1806493 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:44783_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:44783_solr
[junit4] 2> 1806494 INFO (zkCallback-10496-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1806494 INFO (zkCallback-10511-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1806494 INFO (zkCallback-10503-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1806494 INFO (zkCallback-10489-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1806796 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:44783_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_44783.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@f16f63
[junit4] 2> 1806803 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:44783_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_44783.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@f16f63
[junit4] 2> 1806803 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:44783_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_44783.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@f16f63
[junit4] 2> 1806805 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:44783_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_55E23CD92FEAE9A3-001/tempDir-001/node2/.
[junit4] 2> 1806808 INFO (zkConnectionManagerCallback-10519-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1806809 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:44783_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 1806809 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:44783_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:39071/solr ready
[junit4] 2> 1806852 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[55E23CD92FEAE9A3]) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T18:27:37-03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1806854 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[55E23CD92FEAE9A3]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1806854 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[55E23CD92FEAE9A3]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1806854 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[55E23CD92FEAE9A3]) [ ]
o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 1806855 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[55E23CD92FEAE9A3]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1c506f0{/solr,null,AVAILABLE}
[junit4] 2> 1806855 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[55E23CD92FEAE9A3]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@9bcfda{HTTP/1.1,[http/1.1]}{127.0.0.1:39641}
[junit4] 2> 1806855 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[55E23CD92FEAE9A3]) [ ]
o.e.j.s.Server Started @1806890ms
[junit4] 2> 1806855 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=39641}
[junit4] 2> 1806855 ERROR
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1806855 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1806855 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.4.0
[junit4] 2> 1806855 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1806855 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1806855 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-04-23T14:47:30.662Z
[junit4] 2> 1806857 INFO (zkConnectionManagerCallback-10521-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1806857 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1806880 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39071/solr
[junit4] 2> 1806881 INFO (zkConnectionManagerCallback-10525-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1806883 INFO (zkConnectionManagerCallback-10527-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1806886 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:39641_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 1806886 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:39641_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1806887 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:39641_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 1806887 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:39641_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:39641_solr
[junit4] 2> 1806888 INFO (zkCallback-10511-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1806888 INFO (zkCallback-10496-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1806888 INFO (zkCallback-10489-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1806888 INFO (zkCallback-10526-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1806888 INFO (zkCallback-10518-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1806889 INFO (zkCallback-10503-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1808131 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:39641_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_39641.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@f16f63
[junit4] 2> 1808138 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:39641_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_39641.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@f16f63
[junit4] 2> 1808138 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:39641_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_39641.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@f16f63
[junit4] 2> 1808140 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:39641_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_55E23CD92FEAE9A3-001/tempDir-001/node3/.
[junit4] 2> 1808142 INFO (zkConnectionManagerCallback-10534-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1808143 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:39641_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 1808144 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:39641_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:39071/solr ready
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=NodeAddedTriggerTest -Dtests.method=testRestoreState
-Dtests.seed=55E23CD92FEAE9A3 -Dtests.multiplier=3 -Dtests.slow=true
-Dtests.locale=lt-LT -Dtests.timezone=Europe/Skopje -Dtests.asserts=true
-Dtests.file.encoding=US-ASCII
[junit4] FAILURE 1.36s J1 | NodeAddedTriggerTest.testRestoreState <<<
[junit4] > Throwable #1: java.lang.AssertionError: Did not expect the
processor to fire on first run! event={
[junit4] > "id":"726561cccb05eT5zvtwban4xm68dzgh8qfdjfi5",
[junit4] > "source":"node_added_trigger",
[junit4] > "eventTime":2012476129194078,
[junit4] > "eventType":"NODEADDED",
[junit4] > "properties":{
[junit4] > "eventTimes":[
[junit4] > 2012476129194078,
[junit4] > 2012476129195658],
[junit4] > "nodeNames":[
[junit4] > "127.0.0.1:44783_solr",
[junit4] > "127.0.0.1:44341_solr"]}}
[junit4] > at
__randomizedtesting.SeedInfo.seed([55E23CD92FEAE9A3:9B4C984AD7D391B5]: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> 1808208 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T18:27:37-03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1808208 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1808208 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1808208 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 1808209 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@51b3bf{/solr,null,AVAILABLE}
[junit4] 2> 1808209 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@11643af{HTTP/1.1,[http/1.1]}{127.0.0.1:45293}
[junit4] 2> 1808209 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.e.j.s.Server Started @1808244ms
[junit4] 2> 1808209 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=45293}
[junit4] 2> 1808209 ERROR
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1808209 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1808209 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.4.0
[junit4] 2> 1808209 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1808209 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1808209 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-04-23T14:47:32.016Z
[junit4] 2> 1808210 INFO (zkConnectionManagerCallback-10536-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1808211 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1808217 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39071/solr
[junit4] 2> 1808217 INFO (zkConnectionManagerCallback-10540-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1808218 INFO (zkConnectionManagerCallback-10542-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1808221 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:45293_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 1808222 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:45293_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1808223 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:45293_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 1808223 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:45293_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:45293_solr
[junit4] 2> 1808224 INFO (zkCallback-10503-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1808224 INFO (zkCallback-10496-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1808224 INFO (zkCallback-10518-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1808224 INFO (zkCallback-10511-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1808224 INFO (zkCallback-10526-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1808224 INFO (zkCallback-10489-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1808224 INFO (zkCallback-10541-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1808224 INFO (zkCallback-10533-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1808864 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:45293_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_45293.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@f16f63
[junit4] 2> 1808873 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:45293_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_45293.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@f16f63
[junit4] 2> 1808873 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:45293_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_45293.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@f16f63
[junit4] 2> 1808875 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:45293_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_55E23CD92FEAE9A3-001/tempDir-001/node4/.
[junit4] 2> 1808877 INFO (zkConnectionManagerCallback-10549-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1808878 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:45293_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 1808879 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:45293_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:39071/solr ready
[junit4] 2> 1808920 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T18:27:37-03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1808921 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1808921 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1808921 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 1808921 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1aa6bca{/solr,null,AVAILABLE}
[junit4] 2> 1808922 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@1d1982a{HTTP/1.1,[http/1.1]}{127.0.0.1:41121}
[junit4] 2> 1808922 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.e.j.s.Server Started @1808957ms
[junit4] 2> 1808922 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=41121}
[junit4] 2> 1808922 ERROR
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1808922 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1808922 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.4.0
[junit4] 2> 1808922 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1808922 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1808922 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-04-23T14:47:32.729Z
[junit4] 2> 1808923 INFO (zkConnectionManagerCallback-10551-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1808924 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1808931 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39071/solr
[junit4] 2> 1808932 INFO (zkConnectionManagerCallback-10555-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1808933 INFO (zkConnectionManagerCallback-10557-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1808936 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:41121_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 1808937 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:41121_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1808938 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:41121_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 1808938 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:41121_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:41121_solr
[junit4] 2> 1808939 INFO (zkCallback-10518-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1808939 INFO (zkCallback-10526-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1808939 INFO (zkCallback-10533-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1808939 INFO (zkCallback-10489-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1808940 INFO (zkCallback-10496-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1808940 INFO (zkCallback-10503-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1808940 INFO (zkCallback-10541-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1808940 INFO (zkCallback-10548-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1808941 INFO (zkCallback-10556-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1808942 INFO (zkCallback-10511-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1809908 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:41121_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41121.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@f16f63
[junit4] 2> 1809918 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:41121_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41121.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@f16f63
[junit4] 2> 1809918 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:41121_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41121.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@f16f63
[junit4] 2> 1809920 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:41121_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_55E23CD92FEAE9A3-001/tempDir-001/node5/.
[junit4] 2> 1809923 INFO (zkConnectionManagerCallback-10564-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1809924 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:41121_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (5)
[junit4] 2> 1809924 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:41121_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:39071/solr ready
[junit4] 2> 1812980 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T18:27:37-03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1812980 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1812980 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1812980 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 1812981 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@3e7064{/solr,null,AVAILABLE}
[junit4] 2> 1812981 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@2989a4{HTTP/1.1,[http/1.1]}{127.0.0.1:35899}
[junit4] 2> 1812981 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.e.j.s.Server Started @1813016ms
[junit4] 2> 1812981 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=35899}
[junit4] 2> 1812981 ERROR
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1812981 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1812981 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.4.0
[junit4] 2> 1812981 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1812981 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1812981 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-04-23T14:47:36.788Z
[junit4] 2> 1812982 INFO (zkConnectionManagerCallback-10566-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1812983 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1812990 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39071/solr
[junit4] 2> 1812990 INFO (zkConnectionManagerCallback-10570-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1812992 INFO (zkConnectionManagerCallback-10572-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1812994 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:35899_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (5)
[junit4] 2> 1812995 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:35899_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1812996 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:35899_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 1812996 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:35899_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:35899_solr
[junit4] 2> 1812996 INFO (zkCallback-10503-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
[junit4] 2> 1812996 INFO (zkCallback-10489-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
[junit4] 2> 1812996 INFO (zkCallback-10548-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
[junit4] 2> 1812996 INFO (zkCallback-10541-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
[junit4] 2> 1812997 INFO (zkCallback-10518-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
[junit4] 2> 1812997 INFO (zkCallback-10511-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
[junit4] 2> 1812997 INFO (zkCallback-10526-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
[junit4] 2> 1812997 INFO (zkCallback-10496-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
[junit4] 2> 1812997 INFO (zkCallback-10533-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
[junit4] 2> 1812997 INFO (zkCallback-10556-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
[junit4] 2> 1812997 INFO (zkCallback-10563-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
[junit4] 2> 1812998 INFO (zkCallback-10571-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
[junit4] 2> 1813424 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:35899_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_35899.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@f16f63
[junit4] 2> 1813432 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:35899_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_35899.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@f16f63
[junit4] 2> 1813432 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:35899_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_35899.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@f16f63
[junit4] 2> 1813433 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:35899_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_55E23CD92FEAE9A3-001/tempDir-001/node6/.
[junit4] 2> 1813439 INFO (zkConnectionManagerCallback-10579-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1813440 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:35899_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (6)
[junit4] 2> 1813441 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:35899_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:39071/solr ready
[junit4] 2> 1813485 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@2989a4{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1813485 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=373435
[junit4] 2> 1813486 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 1813486 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@180d139: rootName =
solr_35899, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@760857
[junit4] 2> 1813494 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 1813494 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1b661e0: rootName =
solr_35899, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@1ec7a36
[junit4] 2> 1813500 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 1813500 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@abd1ea: rootName =
solr_35899, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@47c543
[junit4] 2> 1813501 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:35899_solr
[junit4] 2> 1813502 INFO (zkCallback-10548-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
[junit4] 2> 1813502 INFO (zkCallback-10533-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
[junit4] 2> 1813502 INFO (zkCallback-10518-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
[junit4] 2> 1813502 INFO (zkCallback-10511-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
[junit4] 2> 1813502 INFO (zkCallback-10489-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
[junit4] 2> 1813502 INFO (zkCallback-10496-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
[junit4] 2> 1813502 INFO (zkCallback-10503-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
[junit4] 2> 1813502 INFO (zkCallback-10541-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
[junit4] 2> 1813502 INFO (zkCallback-10578-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
[junit4] 2> 1813502 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 1813502 INFO (zkCallback-10556-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
[junit4] 2> 1813502 INFO (zkCallback-10563-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
[junit4] 2> 1813502 INFO (zkCallback-10526-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
[junit4] 2> 1813502 INFO (zkCallback-10571-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
[junit4] 2> 1813503 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@3e7064{/solr,null,UNAVAILABLE}
[junit4] 2> 1813503 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[55E23CD92FEAE9A3]) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 1818546 INFO (jetty-closer-10480-thread-1) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@15672d8{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1818546 INFO (jetty-closer-10480-thread-4) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@11643af{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1818549 INFO (jetty-closer-10480-thread-2) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@72afb7{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1818549 INFO (jetty-closer-10480-thread-4) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=29265769
[junit4] 2> 1818549 INFO (jetty-closer-10480-thread-1) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=4425980
[junit4] 2> 1818550 INFO (jetty-closer-10480-thread-2) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=29797218
[junit4] 2> 1818550 INFO (jetty-closer-10480-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 1818550 INFO (jetty-closer-10480-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 1818550 INFO (jetty-closer-10480-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@4087ee: rootName =
solr_44341, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@ae7333
[junit4] 2> 1818550 INFO (jetty-closer-10480-thread-4) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@f20fa4: rootName =
solr_45293, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@1f228e0
[junit4] 2> 1818550 INFO (jetty-closer-10480-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 1818550 INFO (jetty-closer-10480-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@dbb14a: rootName =
solr_44783, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@d53073
[junit4] 2> 1818550 INFO (jetty-closer-10480-thread-3) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@9bcfda{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1818551 INFO (jetty-closer-10480-thread-3) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=22531328
[junit4] 2> 1818551 INFO (jetty-closer-10480-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 1818551 INFO (jetty-closer-10480-thread-3) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@ec3456: rootName =
solr_39641, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@647eb8
[junit4] 2> 1818551 INFO (jetty-closer-10480-thread-5) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@1d1982a{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1818551 INFO (jetty-closer-10480-thread-5) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=8433769
[junit4] 2> 1818551 INFO (jetty-closer-10480-thread-5) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 1818551 INFO (jetty-closer-10480-thread-5) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1e30dd0: rootName =
solr_41121, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@fe0f1b
[junit4] 2> 1818561 INFO (jetty-closer-10480-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 1818561 INFO (jetty-closer-10480-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1df92ab: rootName =
solr_44341, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@1ec7a36
[junit4] 2> 1818561 INFO (jetty-closer-10480-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 1818561 INFO (jetty-closer-10480-thread-4) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@3ae884: rootName =
solr_45293, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@1ec7a36
[junit4] 2> 1818561 INFO (jetty-closer-10480-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 1818561 INFO (jetty-closer-10480-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@b09794: rootName =
solr_44783, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@1ec7a36
[junit4] 2> 1818564 INFO (jetty-closer-10480-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 1818564 INFO (jetty-closer-10480-thread-3) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@448438: rootName =
solr_39641, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@1ec7a36
[junit4] 2> 1818568 INFO (jetty-closer-10480-thread-5) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 1818568 INFO (jetty-closer-10480-thread-5) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@165d555: rootName =
solr_41121, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@1ec7a36
[junit4] 2> 1818570 INFO (jetty-closer-10480-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 1818570 INFO (jetty-closer-10480-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@ba1122: rootName =
solr_44341, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@47c543
[junit4] 2> 1818570 INFO (jetty-closer-10480-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 1818570 INFO (jetty-closer-10480-thread-4) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@3e6c80: rootName =
solr_45293, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@47c543
[junit4] 2> 1818571 INFO (jetty-closer-10480-thread-1) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:44341_solr
[junit4] 2> 1818571 INFO (jetty-closer-10480-thread-4) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:45293_solr
[junit4] 2> 1818572 INFO (jetty-closer-10480-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 1818572 INFO (jetty-closer-10480-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@db32ff: rootName =
solr_44783, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@47c543
[junit4] 2> 1818572 INFO (zkCallback-10563-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 1818572 INFO (zkCallback-10526-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (3)
[junit4] 2> 1818572 INFO (zkCallback-10518-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (3)
[junit4] 2> 1818572 INFO (zkCallback-10556-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (3)
[junit4] 2> 1818572 INFO (zkCallback-10511-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (3)
[junit4] 2> 1818572 INFO (zkCallback-10533-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (3)
[junit4] 2> 1818572 INFO (zkCallback-10548-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (3)
[junit4] 2> 1818572 INFO (zkCallback-10496-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (3)
[junit4] 2> 1818573 INFO (jetty-closer-10480-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 1818573 INFO (jetty-closer-10480-thread-3) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@b99b5f: rootName =
solr_39641, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@47c543
[junit4] 2> 1818573 INFO (zkCallback-10541-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (3)
[junit4] 2> 1818573 INFO (jetty-closer-10480-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 1818573 INFO (jetty-closer-10480-thread-2) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:44783_solr
[junit4] 2> 1818573 INFO (jetty-closer-10480-thread-1) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 1818573 INFO (zkCallback-10489-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (3)
[junit4] 2> 1818574 INFO (jetty-closer-10480-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 1818579 INFO (jetty-closer-10480-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=72189483606147075-127.0.0.1:44341_solr-n_0000000000) closing
[junit4] 2> 1818579 INFO
(OverseerStateUpdate-72189483606147075-127.0.0.1:44341_solr-n_0000000000) [
] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:44341_solr
[junit4] 2> 1818579 WARN
(OverseerAutoScalingTriggerThread-72189483606147075-127.0.0.1:44341_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 1818579 INFO (zkCallback-10496-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1818580 INFO (zkCallback-10556-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1818580 INFO (zkCallback-10533-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1818580 INFO (zkCallback-10541-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1818580 INFO (zkCallback-10518-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1818580 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x10077f3eedd0012, likely client has closed socket
[junit4] 2> 1818581 INFO (zkCallback-10526-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1818581 INFO (zkCallback-10511-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1818581 INFO (zkCallback-10489-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1818581 INFO (zkCallback-10563-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (2)
[junit4] 2> 1818581 INFO (jetty-closer-10480-thread-5) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 1818581 INFO (jetty-closer-10480-thread-5) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@98942b: rootName =
solr_41121, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@47c543
[junit4] 2> 1818582 INFO (jetty-closer-10480-thread-3) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:39641_solr
[junit4] 2> 1818582 INFO (jetty-closer-10480-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 1818583 INFO (zkCallback-10533-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1818583 INFO (zkCallback-10556-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1818583 INFO (zkCallback-10541-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1818583 INFO (zkCallback-10563-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1818583 INFO (jetty-closer-10480-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 1818583 INFO (zkCallback-10526-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1818583 INFO (zkCallback-10511-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1818584 INFO (jetty-closer-10480-thread-5) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:41121_solr
[junit4] 2> 1818584 INFO (zkCallback-10511-thread-2) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:44783_solr
[junit4] 2> 1818584 INFO (zkCallback-10556-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 1818584 INFO (jetty-closer-10480-thread-5) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 1818584 INFO (zkCallback-10511-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 1818584 INFO (jetty-closer-10480-thread-3) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@1c506f0{/solr,null,UNAVAILABLE}
[junit4] 2> 1818585 INFO (jetty-closer-10480-thread-3) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 1818585 INFO (zkCallback-10541-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 1818585 INFO (zkCallback-10563-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 1818585 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x10077f3eedd0016, likely client has closed socket
[junit4] 2> 1818586 INFO (jetty-closer-10480-thread-5) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@1aa6bca{/solr,null,UNAVAILABLE}
[junit4] 2> 1818586 INFO (jetty-closer-10480-thread-5) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 1820080 WARN (zkCallback-10548-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> 1820080 INFO (jetty-closer-10480-thread-4) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@51b3bf{/solr,null,UNAVAILABLE}
[junit4] 2> 1820081 INFO (jetty-closer-10480-thread-4) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 1820083 WARN (zkCallback-10518-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> 1820083 WARN (zkCallback-10489-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> 1820083 INFO (jetty-closer-10480-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@1f39ba7{/solr,null,UNAVAILABLE}
[junit4] 2> 1820083 INFO (jetty-closer-10480-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@1b8ffbd{/solr,null,UNAVAILABLE}
[junit4] 2> 1820083 INFO (jetty-closer-10480-thread-2) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 1820083 INFO (jetty-closer-10480-thread-1) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 1820084 ERROR
(SUITE-NodeAddedTriggerTest-seed#[55E23CD92FEAE9A3]-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> 1820084 INFO
(SUITE-NodeAddedTriggerTest-seed#[55E23CD92FEAE9A3]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:39071 39071
[junit4] 2> 1820944 INFO (Thread-10481) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:39071 39071
[junit4] 2> 1820944 WARN (Thread-10481) [ ] 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/72189483606147081-127.0.0.1:44783_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/J1/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_55E23CD92FEAE9A3-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {},
docValues:{}, maxPointsInLeafNode=1948, maxMBSortInHeap=6.313143377350082,
sim=RandomSimilarity(queryNorm=true): {}, locale=lt-LT, timezone=Europe/Skopje
[junit4] 2> NOTE: Linux 4.13.0-37-generic i386/Oracle Corporation
1.8.0_162 (32-bit)/cpus=8,threads=1,free=54489024,total=454324224
[junit4] 2> NOTE: All tests run in this JVM: [QueryElevationComponentTest,
TestReload, BadComponentTest, AddSchemaFieldsUpdateProcessorFactoryTest,
CdcrVersionReplicationTest, XsltUpdateRequestHandlerTest, TransactionLogTest,
JvmMetricsTest, TestTrie, TestSolrXml, DOMUtilTest,
OutOfBoxZkACLAndCredentialsProvidersTest, TestLRUCache, TestSolr4Spatial,
CloudExitableDirectoryReaderTest, CoreAdminCreateDiscoverTest,
AnalyticsMergeStrategyTest, TestCloudSchemaless, TestCustomDocTransformer,
TestSubQueryTransformerCrossCore, TestSurroundQueryParser,
TestPartialUpdateDeduplication, RequiredFieldsTest, CloudMLTQParserTest,
TestCloudPseudoReturnFields, TestXmlQParserPlugin,
DistributedDebugComponentTest, HdfsBasicDistributedZkTest, CdcrUpdateLogTest,
TestPullReplicaErrorHandling, CursorMarkTest, JSONWriterTest, TestLargeCluster,
ScriptEngineTest, LegacyCloudClusterPropTest, TestHdfsUpdateLog,
V2ApiIntegrationTest, SolrInfoBeanTest, TestSolrCloudWithSecureImpersonation,
ZkShardTermsTest, TestPerFieldSimilarity, TestCloudInspectUtil,
TestManagedResourceStorage, TestAuthenticationFramework, DocValuesMissingTest,
TestBlobHandler, ReplicationFactorTest, PreAnalyzedFieldManagedSchemaCloudTest,
TestCollapseQParserPlugin, ActionThrottleTest, TestSolrQueryResponse,
DistributedQueryComponentOptimizationTest, HdfsLockFactoryTest,
DirectSolrConnectionTest, HdfsRestartWhileUpdatingTest, StressHdfsTest,
TestStressVersions, TestRestoreCore, RankQueryTest,
TestEmbeddedSolrServerSchemaAPI, TestConfigSetImmutable, TestRecovery,
TriLevelCompositeIdRoutingTest, TestPostingsSolrHighlighter, RulesTest,
TestRecoveryHdfs, ConnectionManagerTest, TestReplicaProperties,
TestFoldingMultitermQuery, SolrCLIZkUtilsTest, ExecutePlanActionTest,
TestExclusionRuleCollectionAccess, DeleteReplicaTest,
TemplateUpdateProcessorTest, DistributedFacetPivotSmallTest,
BlockJoinFacetDistribTest, PrimitiveFieldTypeTest, BasicFunctionalityTest,
ShardSplitTest, ChaosMonkeySafeLeaderTest, TestFuzzyAnalyzedSuggestions,
PeerSyncTest, TestInitParams, TestLegacyNumericRangeQueryBuilder,
TestSkipOverseerOperations, TestHdfsBackupRestoreCore,
TestSolrConfigHandlerCloud, BasicAuthIntegrationTest, TestPointFields,
TestJsonFacets, SolrShardReporterTest, TestScoreJoinQPNoScore,
QueryResultKeyTest, TestXIncludeConfig, SolrCoreTest, RemoteQueryErrorTest,
TestFieldTypeResource, SolrMetricManagerTest, TolerantUpdateProcessorTest,
HighlighterMaxOffsetTest, TestConfigSetsAPIZkFailure, PrimUtilsTest,
TestRawResponseWriter, FieldMutatingUpdateProcessorTest,
TestGraphMLResponseWriter, SolrJmxReporterTest, CachingDirectoryFactoryTest,
TestDistributedGrouping, DirectoryFactoryTest, TestSystemIdResolver,
ShardRoutingCustomTest, TestStressLiveNodes, OverseerTest,
TestExactSharedStatsCache, TestRebalanceLeaders, TestElisionMultitermQuery,
TestReplicationHandler, DistribCursorPagingTest, TestSolrCoreParser,
SearchRateTriggerIntegrationTest, TestQueryUtils, TestFieldCacheSortRandom,
ZkStateWriterTest, TestBM25SimilarityFactory, HLLUtilTest,
TestDistribDocBasedVersion, SchemaVersionSpecificBehaviorTest,
TestManagedSchema, SyncSliceTest, LoggingHandlerTest,
ChaosMonkeySafeLeaderWithPullReplicasTest, TestDFISimilarityFactory,
DirectUpdateHandlerOptimizeTest, TestPhraseSuggestions, EchoParamsTest,
TestDFRSimilarityFactory, TestFastOutputStream, URLClassifyProcessorTest,
TestFastLRUCache, ClusterStateTest, SliceStateTest, SystemInfoHandlerTest,
UUIDFieldTest, CircularListTest, TestRTGBase, DistributedIntervalFacetingTest,
TestTolerantSearch, TestLuceneIndexBackCompat, AssignBackwardCompatibilityTest,
CollectionsAPISolrJTest, DocValuesNotIndexedTest, ForceLeaderTest,
HttpPartitionOnCommitTest, MigrateRouteKeyTest, MoveReplicaTest,
MultiSolrCloudTestCaseTest, MultiThreadedOCPTest, NodeMutatorTest,
OverriddenZkACLAndCredentialsProvidersTest, OverseerTaskQueueTest,
SSLMigrationTest, SolrCloudExampleTest, TestLeaderElectionZkExpiry,
TestLeaderInitiatedRecoveryThread, TestMiniSolrCloudClusterSSL,
TestStressCloudBlindAtomicUpdates, TlogReplayBufferedWhileIndexingTest,
VMParamsZkACLAndCredentialsProvidersTest, CollectionReloadTest,
CollectionTooManyReplicasTest, ConcurrentDeleteAndCreateCollectionTest,
CustomCollectionTest, HdfsCollectionsAPIDistributedZkTest,
SimpleCollectionCreateDeleteTest, TestLocalFSCloudBackupRestore,
ComputePlanActionTest, MetricTriggerTest, NodeAddedTriggerTest]
[junit4] Completed [600/803 (1!)] on J1 in 15.15s, 4 tests, 1 failure <<<
FAILURES!
[...truncated 46948 lines...]
[repro] Jenkins log URL:
https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/1783/consoleText
[repro] Revision: c97d81d94f3091b9475e203a1747bbea8618b9c4
[repro] Ant options: "-Dargs=-server -XX:+UseSerialGC"
[repro] Repro line: ant test -Dtestcase=NodeAddedTriggerTest
-Dtests.method=testRestoreState -Dtests.seed=55E23CD92FEAE9A3
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=lt-LT
-Dtests.timezone=Europe/Skopje -Dtests.asserts=true
-Dtests.file.encoding=US-ASCII
[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=-server -XX:+UseSerialGC" -Dtests.seed=55E23CD92FEAE9A3
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=lt-LT
-Dtests.timezone=Europe/Skopje -Dtests.asserts=true
-Dtests.file.encoding=US-ASCII
[...truncated 32 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest
[junit4] 2> 2559 INFO
(SUITE-NodeAddedTriggerTest-seed#[55E23CD92FEAE9A3]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_55E23CD92FEAE9A3-001/init-core-data-001
[junit4] 2> 2708 INFO
(SUITE-NodeAddedTriggerTest-seed#[55E23CD92FEAE9A3]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 2759 INFO
(SUITE-NodeAddedTriggerTest-seed#[55E23CD92FEAE9A3]-worker) [ ] o.e.j.u.log
Logging initialized @2796ms to org.eclipse.jetty.util.log.Slf4jLog
[junit4] 2> 2767 INFO
(SUITE-NodeAddedTriggerTest-seed#[55E23CD92FEAE9A3]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 2788 INFO
(SUITE-NodeAddedTriggerTest-seed#[55E23CD92FEAE9A3]-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/J0/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_55E23CD92FEAE9A3-001/tempDir-001
[junit4] 2> 2796 INFO
(SUITE-NodeAddedTriggerTest-seed#[55E23CD92FEAE9A3]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2798 INFO (Thread-1) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2798 INFO (Thread-1) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 2882 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> 2898 INFO
(SUITE-NodeAddedTriggerTest-seed#[55E23CD92FEAE9A3]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:41097
[junit4] 2> 3015 INFO (zkConnectionManagerCallback-4-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3053 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x100780318a30000, likely client has closed socket
[junit4] 2> 3307 INFO (jetty-launcher-1-thread-1) [ ] o.e.j.s.Server
jetty-9.4.8.v20171121, build timestamp: 2017-11-21T22:27:37+01:00, git hash:
82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 3325 INFO (jetty-launcher-1-thread-1) [ ] o.e.j.s.session
DefaultSessionIdManager workerName=node0
[junit4] 2> 3326 INFO (jetty-launcher-1-thread-1) [ ] o.e.j.s.session
No SessionScavenger set, using defaults
[junit4] 2> 3330 INFO (jetty-launcher-1-thread-1) [ ] o.e.j.s.session
Scavenging every 600000ms
[junit4] 2> 3352 INFO (jetty-launcher-1-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@2665d6{/solr,null,AVAILABLE}
[junit4] 2> 3368 INFO (jetty-launcher-1-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@505fdf{HTTP/1.1,[http/1.1]}{127.0.0.1:42875}
[junit4] 2> 3368 INFO (jetty-launcher-1-thread-1) [ ] o.e.j.s.Server
Started @3408ms
[junit4] 2> 3368 INFO (jetty-launcher-1-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=42875}
[junit4] 2> 3417 INFO (jetty-launcher-1-thread-1) [ ]
o.a.s.u.c.SSLCredentialProviderFactory Processing SSL Credential Provider
chain: env;sysprop
[junit4] 2> 3459 ERROR (jetty-launcher-1-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 3460 INFO (jetty-launcher-1-thread-1) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 3460 INFO (jetty-launcher-1-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.4.0
[junit4] 2> 3464 INFO (jetty-launcher-1-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 3465 INFO (jetty-launcher-1-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 3479 INFO (jetty-launcher-1-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-04-23T15:04:03.976Z
[junit4] 2> 3505 INFO (jetty-launcher-1-thread-1) [ ]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 3507 INFO (zkConnectionManagerCallback-6-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3509 INFO (jetty-launcher-1-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 3963 INFO (jetty-launcher-1-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41097/solr
[junit4] 2> 3998 INFO (zkConnectionManagerCallback-10-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4016 INFO (zkConnectionManagerCallback-12-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4503 INFO (jetty-launcher-1-thread-1) [n:127.0.0.1:42875_solr
] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 4511 INFO (jetty-launcher-1-thread-1) [n:127.0.0.1:42875_solr
] o.a.s.c.OverseerElectionContext I am going to be the leader
127.0.0.1:42875_solr
[junit4] 2> 4527 INFO (jetty-launcher-1-thread-1) [n:127.0.0.1:42875_solr
] o.a.s.c.Overseer Overseer
(id=72189548731498499-127.0.0.1:42875_solr-n_0000000000) starting
[junit4] 2> 4651 INFO (zkConnectionManagerCallback-19-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4656 INFO (jetty-launcher-1-thread-1) [n:127.0.0.1:42875_solr
] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41097/solr
ready
[junit4] 2> 4846 INFO (jetty-launcher-1-thread-1) [n:127.0.0.1:42875_solr
] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:42875_solr
[junit4] 2> 4885 INFO (zkCallback-18-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 5019 INFO (zkCallback-11-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 7052 INFO (jetty-launcher-1-thread-1) [n:127.0.0.1:42875_solr
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42875.solr.node'
(registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1ffe437
[junit4] 2> 7054 INFO (jetty-launcher-1-thread-1) [n:127.0.0.1:42875_solr
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42875.solr.jvm'
(registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1ffe437
[junit4] 2> 7054 INFO (jetty-launcher-1-thread-1) [n:127.0.0.1:42875_solr
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42875.solr.jetty'
(registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1ffe437
[junit4] 2> 7082 INFO (jetty-launcher-1-thread-1) [n:127.0.0.1:42875_solr
] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_55E23CD92FEAE9A3-001/tempDir-001/node1/.
[junit4] 2> 7163 INFO (zkConnectionManagerCallback-21-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 7172 INFO (zkConnectionManagerCallback-26-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 7174 INFO
(SUITE-NodeAddedTriggerTest-seed#[55E23CD92FEAE9A3]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 7175 INFO
(SUITE-NodeAddedTriggerTest-seed#[55E23CD92FEAE9A3]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41097/solr ready
[junit4] 2> 7189 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3]) [
] o.a.s.SolrTestCaseJ4 ###Starting testListenerAcceptance
[junit4] 2> 7209 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3]) [
] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T22:27:37+01:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 7220 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3]) [
] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 7220 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3]) [
] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 7220 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3]) [
] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 7227 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3]) [
] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@e13783{/solr,null,AVAILABLE}
[junit4] 2> 7228 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3]) [
] o.e.j.s.AbstractConnector Started
ServerConnector@18b2973{HTTP/1.1,[http/1.1]}{127.0.0.1:41293}
[junit4] 2> 7229 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3]) [
] o.e.j.s.Server Started @7268ms
[junit4] 2> 7229 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3]) [
] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=41293}
[junit4] 2> 7229 ERROR
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3]) [
] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 7229 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3]) [
] o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 7230 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3]) [
] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.4.0
[junit4] 2> 7230 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3]) [
] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port
null
[junit4] 2> 7230 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3]) [
] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 7230 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3]) [
] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-04-23T15:04:07.740Z
[junit4] 2> 7233 INFO (zkConnectionManagerCallback-28-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 7234 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3]) [
] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 7249 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3]) [
] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41097/solr
[junit4] 2> 7252 INFO (zkConnectionManagerCallback-32-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 7275 INFO (zkConnectionManagerCallback-34-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 7282 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:41293_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 7284 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:41293_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 7289 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[55E23CD92FEAE9A3])
[n:127.0.0.1:41293_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient ca
[...truncated too long message...]
] 2> 19736 INFO (jetty-closer-2-thread-3) [ ] o.a.s.m.SolrMetricManager
Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 19736 INFO (zkCallback-55-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 19737 INFO (jetty-closer-2-thread-5) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@12155cb: rootName =
solr_43401, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@9fab6
[junit4] 2> 19736 INFO (zkCallback-18-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 19737 INFO (zkCallback-70-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 19736 INFO (zkCallback-33-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 19736 INFO (zkCallback-85-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 19737 INFO (zkCallback-63-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 19737 INFO
(OverseerStateUpdate-72189548731498499-127.0.0.1:42875_solr-n_0000000000) [
] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:42875_solr
[junit4] 2> 19738 WARN
(OverseerAutoScalingTriggerThread-72189548731498499-127.0.0.1:42875_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 19738 INFO (jetty-closer-2-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 19739 INFO (zkCallback-11-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (1)
[junit4] 2> 19740 INFO (zkCallback-18-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 19740 INFO (zkCallback-78-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (1)
[junit4] 2> 19740 INFO (zkCallback-85-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 19740 INFO (zkCallback-55-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 19740 INFO (zkCallback-70-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 19740 INFO (zkCallback-48-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (1)
[junit4] 2> 19740 INFO (zkCallback-33-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 19746 INFO (zkCallback-63-thread-3) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 19746 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x100780318a30004, likely client has closed socket
[junit4] 2> 19747 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x100780318a30003, likely client has closed socket
[junit4] 2> 19748 INFO (jetty-closer-2-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@2665d6{/solr,null,UNAVAILABLE}
[junit4] 2> 19748 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x100780318a30011, likely client has closed socket
[junit4] 2> 19748 INFO (jetty-closer-2-thread-1) [ ] o.e.j.s.session
Stopped scavenging
[junit4] 2> 19748 INFO (zkCallback-33-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:41293_solr
[junit4] 2> 19749 INFO (jetty-closer-2-thread-4) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@571060{/solr,null,UNAVAILABLE}
[junit4] 2> 19749 INFO (jetty-closer-2-thread-4) [ ] o.e.j.s.session
Stopped scavenging
[junit4] 2> 19749 INFO (jetty-closer-2-thread-5) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:43401_solr
[junit4] 2> 19750 INFO (jetty-closer-2-thread-3) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@97299b{/solr,null,UNAVAILABLE}
[junit4] 2> 19752 INFO (jetty-closer-2-thread-3) [ ] o.e.j.s.session
Stopped scavenging
[junit4] 2> 19753 INFO (zkCallback-85-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 19753 INFO (zkCallback-33-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 19753 INFO (zkCallback-78-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 19753 INFO (jetty-closer-2-thread-5) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 19757 INFO (jetty-closer-2-thread-5) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@5a65dc{/solr,null,UNAVAILABLE}
[junit4] 2> 19757 INFO (jetty-closer-2-thread-5) [ ] o.e.j.s.session
Stopped scavenging
[junit4] 2> 21237 WARN (zkCallback-40-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> 21239 INFO (jetty-closer-2-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@e13783{/solr,null,UNAVAILABLE}
[junit4] 2> 21239 INFO (jetty-closer-2-thread-2) [ ] o.e.j.s.session
Stopped scavenging
[junit4] 2> 21239 ERROR
(SUITE-NodeAddedTriggerTest-seed#[55E23CD92FEAE9A3]-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> 21240 INFO
(SUITE-NodeAddedTriggerTest-seed#[55E23CD92FEAE9A3]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:41097 41097
[junit4] 2> 21378 INFO (Thread-1) [ ] o.a.s.c.ZkTestServer connecting
to 127.0.0.1:41097 41097
[junit4] 2> 21379 WARN (Thread-1) [ ] 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/72189548731498505-127.0.0.1:41293_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/J0/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_55E23CD92FEAE9A3-001
[junit4] 2> Apr 23, 2018 3:04:21 PM
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=1948, maxMBSortInHeap=6.313143377350082,
sim=RandomSimilarity(queryNorm=true): {}, locale=lt-LT, timezone=Europe/Skopje
[junit4] 2> NOTE: Linux 4.13.0-37-generic i386/Oracle Corporation
1.8.0_162 (32-bit)/cpus=8,threads=1,free=39438760,total=65011712
[junit4] 2> NOTE: All tests run in this JVM: [NodeAddedTriggerTest]
[junit4] Completed [1/5 (1!)] on J0 in 21.36s, 4 tests, 1 failure <<<
FAILURES!
[...truncated 40 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, 1 failure [seed: 55E23CD92FEAE9A3]
Total time: 43 seconds
[repro] Setting last failure code to 256
[repro] Failures:
[repro] 1/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=c97d81d94f3091b9475e203a1747bbea8618b9c4,
workspace=/var/lib/jenkins/workspace/Lucene-Solr-7.x-Linux
[WARNINGS] Computing warning deltas based on reference build #1782
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]
