Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/21538/
Java: 64bit/jdk-10-ea+43 -XX:+UseCompressedOops -XX:+UseG1GC
2 tests failed.
FAILED: org.apache.solr.cloud.ReplaceNodeNoTargetTest.test
Error Message:
Stack Trace:
java.lang.AssertionError
at
__randomizedtesting.SeedInfo.seed([34049F329D59DDB9:BC50A0E833A5B041]:0)
at org.junit.Assert.fail(Assert.java:92)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertTrue(Assert.java:54)
at
org.apache.solr.cloud.ReplaceNodeNoTargetTest.test(ReplaceNodeNoTargetTest.java:92)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
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
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.base/java.lang.Thread.run(Thread.java:844)
FAILED:
org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testNodeMarkersRegistration
Error Message:
Path /autoscaling/nodeAdded/127.0.0.1:36435_solr wasn't created
Stack Trace:
java.lang.AssertionError: Path /autoscaling/nodeAdded/127.0.0.1:36435_solr
wasn't created
at
__randomizedtesting.SeedInfo.seed([34049F329D59DDB9:2CBE173E936C1056]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at
org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testNodeMarkersRegistration(TriggerIntegrationTest.java:951)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
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
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.base/java.lang.Thread.run(Thread.java:844)
Build Log:
[...truncated 12471 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest
[junit4] 2> 92651 INFO
(SUITE-TriggerIntegrationTest-seed#[34049F329D59DDB9]-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-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_34049F329D59DDB9-001/init-core-data-001
[junit4] 2> 92652 WARN
(SUITE-TriggerIntegrationTest-seed#[34049F329D59DDB9]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=10 numCloses=10
[junit4] 2> 92662 INFO
(SUITE-TriggerIntegrationTest-seed#[34049F329D59DDB9]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 92662 INFO
(SUITE-TriggerIntegrationTest-seed#[34049F329D59DDB9]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0,
clientAuth=0.0/0.0)
[junit4] 2> 92663 INFO
(SUITE-TriggerIntegrationTest-seed#[34049F329D59DDB9]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_34049F329D59DDB9-001/tempDir-001
[junit4] 2> 92663 INFO
(SUITE-TriggerIntegrationTest-seed#[34049F329D59DDB9]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 92673 INFO (Thread-246) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 92673 INFO (Thread-246) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 92690 ERROR (Thread-246) [ ] 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> 92773 INFO
(SUITE-TriggerIntegrationTest-seed#[34049F329D59DDB9]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:38565
[junit4] 2> 92775 INFO (zkConnectionManagerCallback-154-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 92778 INFO (jetty-launcher-151-thread-2) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-22T08:27:37+11:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 92778 INFO (jetty-launcher-151-thread-1) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-22T08:27:37+11:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 92778 INFO (jetty-launcher-151-thread-2) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 92778 INFO (jetty-launcher-151-thread-2) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 92778 INFO (jetty-launcher-151-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 92778 INFO (jetty-launcher-151-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 92778 INFO (jetty-launcher-151-thread-2) [ ]
o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 92778 INFO (jetty-launcher-151-thread-1) [ ]
o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 92779 INFO (jetty-launcher-151-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@d43bedb{/solr,null,AVAILABLE}
[junit4] 2> 92779 INFO (jetty-launcher-151-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@3585fcd8{/solr,null,AVAILABLE}
[junit4] 2> 92781 INFO (jetty-launcher-151-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@423e5e96{HTTP/1.1,[http/1.1]}{127.0.0.1:33799}
[junit4] 2> 92781 INFO (jetty-launcher-151-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@490f2895{HTTP/1.1,[http/1.1]}{127.0.0.1:39529}
[junit4] 2> 92781 INFO (jetty-launcher-151-thread-1) [ ]
o.e.j.s.Server Started @108023ms
[junit4] 2> 92781 INFO (jetty-launcher-151-thread-2) [ ]
o.e.j.s.Server Started @108023ms
[junit4] 2> 92781 INFO (jetty-launcher-151-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=33799}
[junit4] 2> 92781 INFO (jetty-launcher-151-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=39529}
[junit4] 2> 92781 ERROR (jetty-launcher-151-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 92781 ERROR (jetty-launcher-151-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 92781 INFO (jetty-launcher-151-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 92781 INFO (jetty-launcher-151-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 92781 INFO (jetty-launcher-151-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 92781 INFO (jetty-launcher-151-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 92781 INFO (jetty-launcher-151-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-26T11:00:52.246898Z
[junit4] 2> 92781 INFO (jetty-launcher-151-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 92784 INFO (jetty-launcher-151-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 92784 INFO (jetty-launcher-151-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-26T11:00:52.249655Z
[junit4] 2> 92797 INFO (zkConnectionManagerCallback-158-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 92797 INFO (zkConnectionManagerCallback-156-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 92797 INFO (jetty-launcher-151-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 92797 INFO (jetty-launcher-151-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 92814 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
0x1008f68d6a10002, likely client has closed socket
[junit4] 2> 92821 INFO (jetty-launcher-151-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38565/solr
[junit4] 2> 92833 INFO (jetty-launcher-151-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38565/solr
[junit4] 2> 92837 INFO (zkConnectionManagerCallback-163-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 92840 INFO (zkConnectionManagerCallback-166-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 92842 INFO
(zkConnectionManagerCallback-168-thread-1-processing-n:127.0.0.1:33799_solr)
[n:127.0.0.1:33799_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 92842 INFO
(zkConnectionManagerCallback-170-thread-1-processing-n:127.0.0.1:39529_solr)
[n:127.0.0.1:39529_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 92878 INFO (jetty-launcher-151-thread-1)
[n:127.0.0.1:33799_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 92878 INFO (jetty-launcher-151-thread-2)
[n:127.0.0.1:39529_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 92879 INFO (jetty-launcher-151-thread-2)
[n:127.0.0.1:39529_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:39529_solr
[junit4] 2> 92879 INFO (jetty-launcher-151-thread-1)
[n:127.0.0.1:33799_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:33799_solr
[junit4] 2> 92879 INFO (jetty-launcher-151-thread-2)
[n:127.0.0.1:39529_solr ] o.a.s.c.Overseer Overseer
(id=72215274478174214-127.0.0.1:39529_solr-n_0000000000) starting
[junit4] 2> 92880 INFO
(zkCallback-169-thread-1-processing-n:127.0.0.1:39529_solr)
[n:127.0.0.1:39529_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 92880 INFO
(zkCallback-167-thread-1-processing-n:127.0.0.1:33799_solr)
[n:127.0.0.1:33799_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 92888 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174214-127.0.0.1:39529_solr-n_0000000000)
[n:127.0.0.1:39529_solr ] o.a.s.c.a.OverseerTriggerThread Adding
.autoAddReplicas trigger
[junit4] 2> 92888 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174214-127.0.0.1:39529_solr-n_0000000000)
[n:127.0.0.1:39529_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 1
[junit4] 2> 92888 INFO (jetty-launcher-151-thread-2)
[n:127.0.0.1:39529_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:39529_solr
[junit4] 2> 92889 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174214-127.0.0.1:39529_solr-n_0000000000)
[n:127.0.0.1:39529_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:33799_solr]
[junit4] 2> 92889 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174214-127.0.0.1:39529_solr-n_0000000000)
[n:127.0.0.1:39529_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion -1
[junit4] 2> 92889 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174214-127.0.0.1:39529_solr-n_0000000000)
[n:127.0.0.1:39529_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 1
[junit4] 2> 92889 INFO
(zkCallback-169-thread-1-processing-n:127.0.0.1:39529_solr)
[n:127.0.0.1:39529_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 92889 INFO
(zkCallback-167-thread-1-processing-n:127.0.0.1:33799_solr)
[n:127.0.0.1:33799_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 92890 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174214-127.0.0.1:39529_solr-n_0000000000)
[n:127.0.0.1:39529_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 92890 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174214-127.0.0.1:39529_solr-n_0000000000)
[n:127.0.0.1:39529_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 92890 DEBUG (ScheduledTrigger-449-thread-1)
[n:127.0.0.1:39529_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 2
[junit4] 2> 92916 INFO (jetty-launcher-151-thread-2)
[n:127.0.0.1:39529_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_39529.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a0c0a44
[junit4] 2> 92921 INFO (jetty-launcher-151-thread-2)
[n:127.0.0.1:39529_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_39529.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a0c0a44
[junit4] 2> 92921 INFO (jetty-launcher-151-thread-2)
[n:127.0.0.1:39529_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_39529.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a0c0a44
[junit4] 2> 92923 INFO (jetty-launcher-151-thread-2)
[n:127.0.0.1:39529_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_34049F329D59DDB9-001/tempDir-001/node2/.
[junit4] 2> 92948 INFO (jetty-launcher-151-thread-1)
[n:127.0.0.1:33799_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33799.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a0c0a44
[junit4] 2> 92954 INFO (jetty-launcher-151-thread-1)
[n:127.0.0.1:33799_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33799.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a0c0a44
[junit4] 2> 92954 INFO (jetty-launcher-151-thread-1)
[n:127.0.0.1:33799_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33799.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a0c0a44
[junit4] 2> 92955 INFO (jetty-launcher-151-thread-1)
[n:127.0.0.1:33799_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_34049F329D59DDB9-001/tempDir-001/node1/.
[junit4] 2> 92970 INFO (zkConnectionManagerCallback-176-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 92974 INFO (zkConnectionManagerCallback-180-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 92975 INFO
(SUITE-TriggerIntegrationTest-seed#[34049F329D59DDB9]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 92976 INFO
(SUITE-TriggerIntegrationTest-seed#[34049F329D59DDB9]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:38565/solr ready
[junit4] 2> 92982 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testListeners
[junit4] 2> 92984 INFO (qtp1309377313-862) [n:127.0.0.1:33799_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with
params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 92991 INFO (qtp1309377313-862) [n:127.0.0.1:33799_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=7
[junit4] 2> 92992 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.c.a.ScheduledTriggers -- removing trigger: .auto_add_replicas
[junit4] 2> 92994 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 2
[junit4] 2> 92994 DEBUG
(zkCallback-169-thread-1-processing-n:127.0.0.1:39529_solr)
[n:127.0.0.1:39529_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 2
[junit4] 2> 92994 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174214-127.0.0.1:39529_solr-n_0000000000)
[n:127.0.0.1:39529_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 2
[junit4] 2> 92994 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174214-127.0.0.1:39529_solr-n_0000000000)
[n:127.0.0.1:39529_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 92994 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174214-127.0.0.1:39529_solr-n_0000000000)
[n:127.0.0.1:39529_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 92994 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 92994 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174214-127.0.0.1:39529_solr-n_0000000000)
[n:127.0.0.1:39529_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 92995 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@490f2895{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 92995 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=2024330554
[junit4] 2> 92996 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 92996 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@61f76d03: rootName =
solr_39529, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@68a14e16
[junit4] 2> 93000 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 93000 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@74a0d458: rootName =
solr_39529, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@5ff822a6
[junit4] 2> 93003 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 93003 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1826dc50: rootName =
solr_39529, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@3c088e7a
[junit4] 2> 93005 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:39529_solr
[junit4] 2> 93005 INFO
(zkCallback-169-thread-1-processing-n:127.0.0.1:39529_solr)
[n:127.0.0.1:39529_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (1)
[junit4] 2> 93005 INFO
(zkCallback-167-thread-1-processing-n:127.0.0.1:33799_solr)
[n:127.0.0.1:33799_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (1)
[junit4] 2> 93005 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 93006 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 93006 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.c.Overseer Overseer
(id=72215274478174214-127.0.0.1:39529_solr-n_0000000000) closing
[junit4] 2> 93006 INFO
(OverseerStateUpdate-72215274478174214-127.0.0.1:39529_solr-n_0000000000)
[n:127.0.0.1:39529_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:39529_solr
[junit4] 2> 93006 WARN
(OverseerAutoScalingTriggerThread-72215274478174214-127.0.0.1:39529_solr-n_0000000000)
[n:127.0.0.1:39529_solr ] o.a.s.c.a.OverseerTriggerThread
OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 93009 INFO (zkCallback-179-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 93009 INFO (zkCallback-179-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 93010 DEBUG
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 93010 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@d43bedb{/solr,null,UNAVAILABLE}
[junit4] 2> 93010 INFO
(zkCallback-167-thread-1-processing-n:127.0.0.1:33799_solr)
[n:127.0.0.1:33799_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:33799_solr
[junit4] 2> 93011 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 93011 INFO
(zkCallback-167-thread-1-processing-n:127.0.0.1:33799_solr)
[n:127.0.0.1:33799_solr ] o.a.s.c.Overseer Overseer
(id=72215274478174213-127.0.0.1:33799_solr-n_0000000001) starting
[junit4] 2> 93018 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174213-127.0.0.1:33799_solr-n_0000000001)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.OverseerTriggerThread Adding
.autoAddReplicas trigger
[junit4] 2> 93018 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174213-127.0.0.1:33799_solr-n_0000000001)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 3
[junit4] 2> 93019 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174213-127.0.0.1:33799_solr-n_0000000001)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:33799_solr]
[junit4] 2> 93019 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174213-127.0.0.1:33799_solr-n_0000000001)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 3, lastZnodeVersion -1
[junit4] 2> 93019 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174213-127.0.0.1:33799_solr-n_0000000001)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 3
[junit4] 2> 93020 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174213-127.0.0.1:33799_solr-n_0000000001)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 93020 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174213-127.0.0.1:33799_solr-n_0000000001)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 93020 DEBUG (ScheduledTrigger-458-thread-1)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 1
[junit4] 2> 94020 DEBUG (ScheduledTrigger-458-thread-1)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 1
[junit4] 2> 95021 DEBUG (ScheduledTrigger-458-thread-2)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 1
[junit4] 2> 96021 DEBUG (ScheduledTrigger-458-thread-1)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 1
[junit4] 2> 97021 DEBUG (ScheduledTrigger-458-thread-1)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 1
[junit4] 2> 98012 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-22T08:27:37+11:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 98013 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 98013 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 98013 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 98014 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@e205810{/solr,null,AVAILABLE}
[junit4] 2> 98014 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@6ed415e2{HTTP/1.1,[http/1.1]}{127.0.0.1:38687}
[junit4] 2> 98014 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.e.j.s.Server Started @113256ms
[junit4] 2> 98014 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=38687}
[junit4] 2> 98014 ERROR
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 98015 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 98015 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 98015 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 98015 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-26T11:00:57.480249Z
[junit4] 2> 98016 INFO (zkConnectionManagerCallback-183-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 98017 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 98021 DEBUG (ScheduledTrigger-458-thread-1)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 1
[junit4] 2> 98022 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38565/solr
[junit4] 2> 98023 INFO (zkConnectionManagerCallback-187-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 98027 INFO
(zkConnectionManagerCallback-189-thread-1-processing-n:127.0.0.1:38687_solr)
[n:127.0.0.1:38687_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 98030 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9])
[n:127.0.0.1:38687_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 98031 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9])
[n:127.0.0.1:38687_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 98032 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9])
[n:127.0.0.1:38687_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:38687_solr
[junit4] 2> 98033 INFO (zkCallback-179-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 98033 INFO
(zkCallback-167-thread-1-processing-n:127.0.0.1:33799_solr)
[n:127.0.0.1:33799_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 98033 INFO (zkCallback-179-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 98038 INFO
(zkCallback-188-thread-1-processing-n:127.0.0.1:38687_solr)
[n:127.0.0.1:38687_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 98072 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9])
[n:127.0.0.1:38687_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_38687.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a0c0a44
[junit4] 2> 98080 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9])
[n:127.0.0.1:38687_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_38687.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a0c0a44
[junit4] 2> 98080 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9])
[n:127.0.0.1:38687_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_38687.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a0c0a44
[junit4] 2> 98081 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9])
[n:127.0.0.1:38687_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_34049F329D59DDB9-001/tempDir-001/node3/.
[junit4] 2> 98113 INFO (qtp1309377313-956) [n:127.0.0.1:33799_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 98115 INFO (qtp131675615-933) [n:127.0.0.1:38687_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 98116 DEBUG (qtp1309377313-870) [n:127.0.0.1:33799_solr ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 98117 INFO (qtp1309377313-870) [n:127.0.0.1:33799_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=8
[junit4] 2> 98117 DEBUG
(zkCallback-167-thread-1-processing-n:127.0.0.1:33799_solr)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 4
[junit4] 2> 98119 DEBUG
(zkCallback-167-thread-1-processing-n:127.0.0.1:33799_solr)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:33799_solr, 127.0.0.1:38687_solr]
[junit4] 2> 98119 DEBUG
(zkCallback-167-thread-1-processing-n:127.0.0.1:33799_solr)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_triggerL instantiated with properties: {event=nodeAdded, waitFor=1,
actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction},
{name=test1,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestDummyAction}],
enabled=true}
[junit4] 2> 98119 DEBUG
(zkCallback-167-thread-1-processing-n:127.0.0.1:33799_solr)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:33799_solr, 127.0.0.1:38687_solr]
[junit4] 2> 98119 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174213-127.0.0.1:33799_solr-n_0000000001)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 4
[junit4] 2> 98120 INFO
(OverseerAutoScalingTriggerThread-72215274478174213-127.0.0.1:33799_solr-n_0000000001)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.TriggerIntegrationTest
TestTriggerAction init
[junit4] 2> 98122 DEBUG (ScheduledTrigger-458-thread-3)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_triggerL
[junit4] 2> 98122 DEBUG (ScheduledTrigger-458-thread-3)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 98123 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174213-127.0.0.1:33799_solr-n_0000000001)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 4, lastZnodeVersion 4
[junit4] 2> 98125 INFO (qtp1309377313-872) [n:127.0.0.1:33799_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 98128 INFO (qtp131675615-937) [n:127.0.0.1:38687_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 98128 DEBUG (qtp1309377313-874) [n:127.0.0.1:33799_solr ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 98129 INFO (qtp1309377313-874) [n:127.0.0.1:33799_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=7
[junit4] 2> 98130 DEBUG
(zkCallback-167-thread-1-processing-n:127.0.0.1:33799_solr)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 5
[junit4] 2> 98130 DEBUG
(zkCallback-167-thread-1-processing-n:127.0.0.1:33799_solr)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:33799_solr, 127.0.0.1:38687_solr]
[junit4] 2> 98130 DEBUG
(zkCallback-167-thread-1-processing-n:127.0.0.1:33799_solr)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_triggerL instantiated with properties: {event=nodeAdded, waitFor=1,
actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction},
{name=test1,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestDummyAction}],
enabled=true}
[junit4] 2> 98131 DEBUG
(zkCallback-167-thread-1-processing-n:127.0.0.1:33799_solr)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:33799_solr, 127.0.0.1:38687_solr]
[junit4] 2> 98131 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174213-127.0.0.1:33799_solr-n_0000000001)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 5
[junit4] 2> 98132 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174213-127.0.0.1:33799_solr-n_0000000001)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 5, lastZnodeVersion 5
[junit4] 2> 98133 INFO (qtp1309377313-957) [n:127.0.0.1:33799_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 98135 INFO (qtp131675615-933) [n:127.0.0.1:38687_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 98136 DEBUG (qtp1309377313-870) [n:127.0.0.1:33799_solr ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 98136 INFO (qtp1309377313-870) [n:127.0.0.1:33799_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 98136 DEBUG
(zkCallback-167-thread-1-processing-n:127.0.0.1:33799_solr)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 6
[junit4] 2> 98137 DEBUG
(zkCallback-167-thread-1-processing-n:127.0.0.1:33799_solr)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:33799_solr, 127.0.0.1:38687_solr]
[junit4] 2> 98137 DEBUG
(zkCallback-167-thread-1-processing-n:127.0.0.1:33799_solr)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_triggerL instantiated with properties: {event=nodeAdded, waitFor=1,
actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction},
{name=test1,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestDummyAction}],
enabled=true}
[junit4] 2> 98137 DEBUG
(zkCallback-167-thread-1-processing-n:127.0.0.1:33799_solr)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:33799_solr, 127.0.0.1:38687_solr]
[junit4] 2> 98137 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174213-127.0.0.1:33799_solr-n_0000000001)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 6
[junit4] 2> 98138 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174213-127.0.0.1:33799_solr-n_0000000001)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 6, lastZnodeVersion 6
[junit4] 2> 98138 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-22T08:27:37+11:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 98165 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 98165 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 98165 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 98169 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@7f1c16bd{/solr,null,AVAILABLE}
[junit4] 2> 98170 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@73749bea{HTTP/1.1,[http/1.1]}{127.0.0.1:33523}
[junit4] 2> 98170 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.e.j.s.Server Started @113412ms
[junit4] 2> 98170 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=33523}
[junit4] 2> 98170 ERROR
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 98170 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 98171 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 98171 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 98171 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-26T11:00:57.636093Z
[junit4] 2> 98175 INFO (zkConnectionManagerCallback-193-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 98177 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 98186 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38565/solr
[junit4] 2> 98193 INFO (zkConnectionManagerCallback-197-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 98197 INFO
(zkConnectionManagerCallback-199-thread-1-processing-n:127.0.0.1:33523_solr)
[n:127.0.0.1:33523_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 98202 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9])
[n:127.0.0.1:33523_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 98203 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9])
[n:127.0.0.1:33523_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 98205 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9])
[n:127.0.0.1:33523_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:33523_solr
[junit4] 2> 98206 INFO
(zkCallback-188-thread-1-processing-n:127.0.0.1:38687_solr)
[n:127.0.0.1:38687_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 98207 INFO (zkCallback-179-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 98206 INFO (zkCallback-179-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 98207 INFO
(zkCallback-167-thread-1-processing-n:127.0.0.1:33799_solr)
[n:127.0.0.1:33799_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 98207 INFO
(zkCallback-198-thread-1-processing-n:127.0.0.1:33523_solr)
[n:127.0.0.1:33523_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 98254 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9])
[n:127.0.0.1:33523_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33523.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a0c0a44
[junit4] 2> 98260 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9])
[n:127.0.0.1:33523_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33523.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a0c0a44
[junit4] 2> 98260 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9])
[n:127.0.0.1:33523_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33523.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a0c0a44
[junit4] 2> 98261 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9])
[n:127.0.0.1:33523_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_34049F329D59DDB9-001/tempDir-001/node4/.
[junit4] 2> 99022 DEBUG (ScheduledTrigger-458-thread-2)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 3
[junit4] 2> 99124 DEBUG (ScheduledTrigger-458-thread-1)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_triggerL
[junit4] 2> 99125 DEBUG (ScheduledTrigger-458-thread-1)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 99125 DEBUG (ScheduledTrigger-458-thread-1)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node:
127.0.0.1:33523_solr at time 2406019027656828
[junit4] 2> 100023 DEBUG (ScheduledTrigger-458-thread-3)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 3
[junit4] 2> 100125 DEBUG (ScheduledTrigger-458-thread-4)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_triggerL
[junit4] 2> 100125 DEBUG (ScheduledTrigger-458-thread-4)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 100126 DEBUG (ScheduledTrigger-458-thread-4)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_triggerL firing registered processor for nodes:
[127.0.0.1:33523_solr] added at times [2406019027656828], now=2406020028536756
[junit4] 2> 100126 DEBUG (ScheduledTrigger-458-thread-4)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown
inactive - processing event: {
[junit4] 2> "id":"88c42f577607cT2gom9s21ih4d1mmwql74uzm5h",
[junit4] 2> "source":"node_added_triggerL",
[junit4] 2> "eventTime":2406019027656828,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[2406019027656828],
[junit4] 2> "nodeNames":["127.0.0.1:33523_solr"]}}
[junit4] 2> 100126 DEBUG (ScheduledTrigger-458-thread-4)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers:
[.auto_add_replicas, node_added_triggerL]
[junit4] 2> 100128 INFO (ScheduledTrigger-458-thread-4)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.SystemLogListener Collection .system
does not exist, disabling logging.
[junit4] 2> 100129 DEBUG
(AutoscalingActionExecutor-459-thread-1-processing-n:127.0.0.1:33799_solr)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions
for {
[junit4] 2> "id":"88c42f577607cT2gom9s21ih4d1mmwql74uzm5h",
[junit4] 2> "source":"node_added_triggerL",
[junit4] 2> "eventTime":2406019027656828,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[2406019027656828],
[junit4] 2> "_enqueue_time_":2406020029563288,
[junit4] 2> "nodeNames":["127.0.0.1:33523_solr"]}}
[junit4] 2> 100131 DEBUG
(AutoscalingActionExecutor-459-thread-1-processing-n:127.0.0.1:33799_solr)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger:
.auto_add_replicas after 5000ms
[junit4] 2> 100131 DEBUG
(AutoscalingActionExecutor-459-thread-1-processing-n:127.0.0.1:33799_solr)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger:
node_added_triggerL after 5000ms
[junit4] 2> 100132 DEBUG
(AutoscalingActionExecutor-459-thread-1-processing-n:127.0.0.1:33799_solr)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 2
ms for event id=88c42f577607cT2gom9s21ih4d1mmwql74uzm5h
[junit4] 2> 102131 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-22T08:27:37+11:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 102133 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 102133 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 102133 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 102133 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@40ecefce{/solr,null,AVAILABLE}
[junit4] 2> 102134 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@44295d9f{HTTP/1.1,[http/1.1]}{127.0.0.1:45927}
[junit4] 2> 102134 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.e.j.s.Server Started @117376ms
[junit4] 2> 102134 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=45927}
[junit4] 2> 102134 ERROR
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 102134 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 102134 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 102134 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 102134 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-26T11:01:01.599506Z
[junit4] 2> 102136 INFO (zkConnectionManagerCallback-203-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 102137 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 102142 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38565/solr
[junit4] 2> 102144 INFO (zkConnectionManagerCallback-207-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 102146 INFO
(zkConnectionManagerCallback-209-thread-1-processing-n:127.0.0.1:45927_solr)
[n:127.0.0.1:45927_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 102150 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9])
[n:127.0.0.1:45927_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 102151 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9])
[n:127.0.0.1:45927_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 102152 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9])
[n:127.0.0.1:45927_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:45927_solr
[junit4] 2> 102153 INFO (zkCallback-179-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 102153 INFO
(zkCallback-188-thread-1-processing-n:127.0.0.1:38687_solr)
[n:127.0.0.1:38687_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 102153 INFO
(zkCallback-167-thread-1-processing-n:127.0.0.1:33799_solr)
[n:127.0.0.1:33799_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 102153 INFO (zkCallback-179-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 102153 INFO
(zkCallback-198-thread-1-processing-n:127.0.0.1:33523_solr)
[n:127.0.0.1:33523_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 102153 INFO
(zkCallback-208-thread-1-processing-n:127.0.0.1:45927_solr)
[n:127.0.0.1:45927_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 102215 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9])
[n:127.0.0.1:45927_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_45927.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a0c0a44
[junit4] 2> 102224 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9])
[n:127.0.0.1:45927_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_45927.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a0c0a44
[junit4] 2> 102224 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9])
[n:127.0.0.1:45927_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_45927.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a0c0a44
[junit4] 2> 102226 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9])
[n:127.0.0.1:45927_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_34049F329D59DDB9-001/tempDir-001/node5/.
[junit4] 2> 105132 DEBUG (ScheduledTrigger-458-thread-1)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 4
[junit4] 2> 105132 DEBUG (ScheduledTrigger-458-thread-3)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_triggerL
[junit4] 2> 105132 DEBUG (ScheduledTrigger-458-thread-3)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 4
[junit4] 2> 105132 DEBUG (ScheduledTrigger-458-thread-3)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node:
127.0.0.1:45927_solr at time 2406025034743043
[junit4] 2> 106133 DEBUG (ScheduledTrigger-458-thread-1)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 4
[junit4] 2> 106133 DEBUG (ScheduledTrigger-458-thread-2)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_triggerL
[junit4] 2> 106133 DEBUG (ScheduledTrigger-458-thread-2)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 4
[junit4] 2> 106133 DEBUG (ScheduledTrigger-458-thread-2)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_triggerL firing registered processor for nodes:
[127.0.0.1:45927_solr] added at times [2406025034743043], now=2406026035612781
[junit4] 2> 106133 DEBUG (ScheduledTrigger-458-thread-2)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown
inactive - processing event: {
[junit4] 2> "id":"88c445b843d03T2gom9s21ih4d1mmwql74uzm5j",
[junit4] 2> "source":"node_added_triggerL",
[junit4] 2> "eventTime":2406025034743043,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[2406025034743043],
[junit4] 2> "nodeNames":["127.0.0.1:45927_solr"]}}
[junit4] 2> 106133 DEBUG (ScheduledTrigger-458-thread-2)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers:
[.auto_add_replicas, node_added_triggerL]
[junit4] 2> 106133 DEBUG
(AutoscalingActionExecutor-459-thread-1-processing-n:127.0.0.1:33799_solr)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions
for {
[junit4] 2> "id":"88c445b843d03T2gom9s21ih4d1mmwql74uzm5j",
[junit4] 2> "source":"node_added_triggerL",
[junit4] 2> "eventTime":2406025034743043,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[2406025034743043],
[junit4] 2> "_enqueue_time_":2406026035841234,
[junit4] 2> "nodeNames":["127.0.0.1:45927_solr"]}}
[junit4] 2> 106134 WARN
(AutoscalingActionExecutor-459-thread-1-processing-n:127.0.0.1:33799_solr)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.ScheduledTriggers Exception executing
actions
[junit4] 2> java.lang.Exception: Error executing action: test1 for trigger
event: {
[junit4] 2> "id":"88c445b843d03T2gom9s21ih4d1mmwql74uzm5j",
[junit4] 2> "source":"node_added_triggerL",
[junit4] 2> "eventTime":2406025034743043,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[2406025034743043],
[junit4] 2> "_enqueue_time_":2406026035841234,
[junit4] 2> "nodeNames":["127.0.0.1:45927_solr"]}}
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$add$3(ScheduledTriggers.java:300)
[junit4] 2> at
java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:514)
[junit4] 2> at
java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[junit4] 2> at
java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1135)
[junit4] 2> at
java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> Caused by: java.lang.RuntimeException: failure
[junit4] 2> at
org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestDummyAction.process(TriggerIntegrationTest.java:1002)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$add$3(ScheduledTriggers.java:297)
[junit4] 2> ... 6 more
[junit4] 2> 106134 DEBUG
(AutoscalingActionExecutor-459-thread-1-processing-n:127.0.0.1:33799_solr)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger:
.auto_add_replicas after 5000ms
[junit4] 2> 106134 DEBUG
(AutoscalingActionExecutor-459-thread-1-processing-n:127.0.0.1:33799_solr)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger:
node_added_triggerL after 5000ms
[junit4] 2> 106135 DEBUG
(AutoscalingActionExecutor-459-thread-1-processing-n:127.0.0.1:33799_solr)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 1
ms for event id=88c445b843d03T2gom9s21ih4d1mmwql74uzm5j
[junit4] 2> 108134 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[34049F329D59DDB9]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testListeners
[junit4] 2> 108147 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.a.s.SolrTestCaseJ4 ###Starting testNodeAddedTrigger
[junit4] 2> 108149 INFO (qtp1118961687-962) [n:127.0.0.1:33523_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with
params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 108154 INFO (qtp1118961687-962) [n:127.0.0.1:33523_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 108154 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.a.s.c.a.ScheduledTriggers -- removing trigger: .auto_add_replicas
[junit4] 2> 108155 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.a.s.c.a.ScheduledTriggers -- removing trigger: node_added_triggerL
[junit4] 2> 108156 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 7
[junit4] 2> 108156 DEBUG
(zkCallback-167-thread-1-processing-n:127.0.0.1:33799_solr)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 7
[junit4] 2> 108156 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174213-127.0.0.1:33799_solr-n_0000000001)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 7
[junit4] 2> 108156 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174213-127.0.0.1:33799_solr-n_0000000001)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 108156 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174213-127.0.0.1:33799_solr-n_0000000001)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 108157 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 108157 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174213-127.0.0.1:33799_solr-n_0000000001)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 7, lastZnodeVersion 7
[junit4] 2> 108158 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.e.j.s.AbstractConnector Stopped
ServerConnector@423e5e96{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 108158 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1393317952
[junit4] 2> 108158 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 108158 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@7c949477: rootName =
solr_33799, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@62828eae
[junit4] 2> 108165 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 108165 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@bf671ca: rootName =
solr_33799, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@5ff822a6
[junit4] 2> 108169 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 108169 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@4cb013b3: rootName =
solr_33799, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@3c088e7a
[junit4] 2> 108170 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:33799_solr
[junit4] 2> 108171 INFO
(zkCallback-188-thread-1-processing-n:127.0.0.1:38687_solr)
[n:127.0.0.1:38687_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (3)
[junit4] 2> 108171 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 108171 INFO
(zkCallback-208-thread-1-processing-n:127.0.0.1:45927_solr)
[n:127.0.0.1:45927_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (3)
[junit4] 2> 108171 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 108172 INFO
(zkCallback-167-thread-1-processing-n:127.0.0.1:33799_solr)
[n:127.0.0.1:33799_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (3)
[junit4] 2> 108172 INFO
(zkCallback-198-thread-1-processing-n:127.0.0.1:33523_solr)
[n:127.0.0.1:33523_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (3)
[junit4] 2> 108172 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.a.s.c.Overseer Overseer
(id=72215274478174213-127.0.0.1:33799_solr-n_0000000001) closing
[junit4] 2> 108172 INFO
(OverseerStateUpdate-72215274478174213-127.0.0.1:33799_solr-n_0000000001)
[n:127.0.0.1:33799_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:33799_solr
[junit4] 2> 108172 INFO (zkCallback-179-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 108172 WARN
(OverseerAutoScalingTriggerThread-72215274478174213-127.0.0.1:33799_solr-n_0000000001)
[n:127.0.0.1:33799_solr ] o.a.s.c.a.OverseerTriggerThread
OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 108173 INFO (zkCallback-179-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 108172 DEBUG
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed
explicitly
[junit4] 2> 108172 INFO (zkCallback-179-thread-3) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 108174 INFO
(zkCallback-188-thread-1-processing-n:127.0.0.1:38687_solr)
[n:127.0.0.1:38687_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:38687_solr
[junit4] 2> 108174 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@3585fcd8{/solr,null,UNAVAILABLE}
[junit4] 2> 108174 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.e.j.s.session Stopped scavenging
[junit4] 2> 108175 INFO
(zkCallback-188-thread-1-processing-n:127.0.0.1:38687_solr)
[n:127.0.0.1:38687_solr ] o.a.s.c.Overseer Overseer
(id=72215274478174219-127.0.0.1:38687_solr-n_0000000002) starting
[junit4] 2> 108177 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174219-127.0.0.1:38687_solr-n_0000000002)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.OverseerTriggerThread Adding
.autoAddReplicas trigger
[junit4] 2> 108185 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174219-127.0.0.1:38687_solr-n_0000000002)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 8
[junit4] 2> 108186 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174219-127.0.0.1:38687_solr-n_0000000002)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:33523_solr, 127.0.0.1:45927_solr, 127.0.0.1:38687_solr]
[junit4] 2> 108186 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174219-127.0.0.1:38687_solr-n_0000000002)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 8, lastZnodeVersion -1
[junit4] 2> 108186 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174219-127.0.0.1:38687_solr-n_0000000002)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 8
[junit4] 2> 108189 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174219-127.0.0.1:38687_solr-n_0000000002)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 108189 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174219-127.0.0.1:38687_solr-n_0000000002)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 8, lastZnodeVersion 8
[junit4] 2> 108190 DEBUG (ScheduledTrigger-481-thread-1)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 3
[junit4] 2> 109190 DEBUG (ScheduledTrigger-481-thread-1)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 3
[junit4] 2> 110191 DEBUG (ScheduledTrigger-481-thread-2)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 3
[junit4] 2> 111191 DEBUG (ScheduledTrigger-481-thread-1)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 3
[junit4] 2> 112191 DEBUG (ScheduledTrigger-481-thread-1)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 3
[junit4] 2> 113181 INFO (qtp1118961687-967) [n:127.0.0.1:33523_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 113183 INFO (qtp131675615-933) [n:127.0.0.1:38687_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 113185 INFO (qtp1016862878-987) [n:127.0.0.1:45927_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 113186 DEBUG (qtp1118961687-966) [n:127.0.0.1:33523_solr ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 113186 INFO (qtp1118961687-966) [n:127.0.0.1:33523_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=7
[junit4] 2> 113187 DEBUG
(zkCallback-188-thread-1-processing-n:127.0.0.1:38687_solr)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 9
[junit4] 2> 113187 DEBUG
(zkCallback-188-thread-1-processing-n:127.0.0.1:38687_solr)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:33523_solr, 127.0.0.1:45927_solr, 127.0.0.1:38687_solr]
[junit4] 2> 113187 DEBUG
(zkCallback-188-thread-1-processing-n:127.0.0.1:38687_solr)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:33523_solr, 127.0.0.1:45927_solr, 127.0.0.1:38687_solr]
[junit4] 2> 113187 DEBUG
(zkCallback-188-thread-1-processing-n:127.0.0.1:38687_solr)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=1,
actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}],
enabled=true}
[junit4] 2> 113187 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174219-127.0.0.1:38687_solr-n_0000000002)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 9
[junit4] 2> 113189 INFO
(OverseerAutoScalingTriggerThread-72215274478174219-127.0.0.1:38687_solr-n_0000000002)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.TriggerIntegrationTest
TestTriggerAction init
[junit4] 2> 113189 DEBUG
(OverseerAutoScalingTriggerThread-72215274478174219-127.0.0.1:38687_solr-n_0000000002)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 9, lastZnodeVersion 9
[junit4] 2> 113189 DEBUG (ScheduledTrigger-481-thread-4)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 113189 DEBUG (ScheduledTrigger-481-thread-4)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 113190 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-22T08:27:37+11:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 113191 DEBUG (ScheduledTrigger-481-thread-3)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 3
[junit4] 2> 113192 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 113192 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 113192 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 113196 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@3a72c293{/solr,null,AVAILABLE}
[junit4] 2> 113197 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.e.j.s.AbstractConnector Started
ServerConnector@7315f0b8{HTTP/1.1,[http/1.1]}{127.0.0.1:46819}
[junit4] 2> 113197 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.e.j.s.Server Started @128439ms
[junit4] 2> 113197 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=46819}
[junit4] 2> 113197 ERROR
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 113197 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 113197 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port
null
[junit4] 2> 113197 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 113198 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-26T11:01:12.662990Z
[junit4] 2> 113199 INFO (zkConnectionManagerCallback-214-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 113200 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 113205 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9]) [
] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38565/solr
[junit4] 2> 113207 INFO (zkConnectionManagerCallback-218-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 113209 INFO
(zkConnectionManagerCallback-220-thread-1-processing-n:127.0.0.1:46819_solr)
[n:127.0.0.1:46819_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 113213 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9])
[n:127.0.0.1:46819_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 113214 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9])
[n:127.0.0.1:46819_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 113215 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9])
[n:127.0.0.1:46819_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:46819_solr
[junit4] 2> 113217 INFO
(zkCallback-198-thread-1-processing-n:127.0.0.1:33523_solr)
[n:127.0.0.1:33523_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 113217 INFO (zkCallback-179-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 113217 INFO
(zkCallback-208-thread-1-processing-n:127.0.0.1:45927_solr)
[n:127.0.0.1:45927_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 113217 INFO (zkCallback-179-thread-3) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 113217 INFO (zkCallback-179-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 113217 INFO
(zkCallback-188-thread-1-processing-n:127.0.0.1:38687_solr)
[n:127.0.0.1:38687_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 113218 INFO
(zkCallback-219-thread-1-processing-n:127.0.0.1:46819_solr)
[n:127.0.0.1:46819_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 114190 DEBUG (ScheduledTrigger-481-thread-1)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 114190 DEBUG (ScheduledTrigger-481-thread-1)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 4
[junit4] 2> 114190 DEBUG (ScheduledTrigger-481-thread-1)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node:
127.0.0.1:46819_solr at time 2406034093364162
[junit4] 2> 114191 DEBUG (ScheduledTrigger-481-thread-4)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 4
[junit4] 2> 115191 DEBUG (ScheduledTrigger-481-thread-2)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 115191 DEBUG (ScheduledTrigger-481-thread-2)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 4
[junit4] 2> 115191 DEBUG (ScheduledTrigger-481-thread-2)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_trigger firing registered processor for nodes:
[127.0.0.1:46819_solr] added at times [2406034093364162], now=2406035094292979
[junit4] 2> 115191 DEBUG (ScheduledTrigger-481-thread-2)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown
inactive - processing event: {
[junit4] 2> "id":"88c467773d3c2T2gom9s21ih4d1mmwql74uzm5k",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":2406034093364162,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[2406034093364162],
[junit4] 2> "nodeNames":["127.0.0.1:46819_solr"]}}
[junit4] 2> 115192 DEBUG (ScheduledTrigger-481-thread-2)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers:
[.auto_add_replicas, node_added_trigger]
[junit4] 2> 115192 INFO (ScheduledTrigger-481-thread-2)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.SystemLogListener Collection .system
does not exist, disabling logging.
[junit4] 2> 115193 DEBUG
(AutoscalingActionExecutor-482-thread-1-processing-n:127.0.0.1:38687_solr)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions
for {
[junit4] 2> "id":"88c467773d3c2T2gom9s21ih4d1mmwql74uzm5k",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":2406034093364162,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[2406034093364162],
[junit4] 2> "_enqueue_time_":2406035094707200,
[junit4] 2> "nodeNames":["127.0.0.1:46819_solr"]}}
[junit4] 2> 115193 DEBUG
(AutoscalingActionExecutor-482-thread-1-processing-n:127.0.0.1:38687_solr)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger:
.auto_add_replicas after 5000ms
[junit4] 2> 115194 DEBUG
(AutoscalingActionExecutor-482-thread-1-processing-n:127.0.0.1:38687_solr)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger:
node_added_trigger after 5000ms
[junit4] 2> 115194 DEBUG
(AutoscalingActionExecutor-482-thread-1-processing-n:127.0.0.1:38687_solr)
[n:127.0.0.1:38687_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 0
ms for event id=88c467773d3c2T2gom9s21ih4d1mmwql74uzm5k
[junit4] 2> 118280 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9])
[n:127.0.0.1:46819_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46819.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a0c0a44
[junit4] 2> 118290 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9])
[n:127.0.0.1:46819_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46819.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a0c0a44
[junit4] 2> 118291 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9])
[n:127.0.0.1:46819_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46819.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a0c0a44
[junit4] 2> 118292 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[34049F329D59DDB9])
[n:127.0.0.1:46819_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_34049F329D59DDB9-001/tempDir-001/node6/.
[junit4] 2> 118378 INFO (qtp1118961687-966) [n:127.0.0.1:33523_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 118382 INFO (qtp131675615-934) [n:127.0.0.1:38687_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 118387 INFO (qtp1016862878-993) [n:127.0.0.1:45927_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime
[...truncated too long message...]
.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 553157 INFO (jetty-closer-313-thread-5) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 553157 INFO
(zkCallback-584-thread-2-processing-n:127.0.0.1:41217_solr)
[n:127.0.0.1:41217_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (1)
[junit4] 2> 553158 INFO (jetty-closer-313-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 553158 INFO
(zkCallback-543-thread-1-processing-n:127.0.0.1:37981_solr)
[n:127.0.0.1:37981_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (0)
[junit4] 2> 553158 INFO
(zkCallback-584-thread-1-processing-n:127.0.0.1:41217_solr)
[n:127.0.0.1:41217_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (0)
[junit4] 2> 553159 INFO (jetty-closer-313-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@18fda3d5{/solr,null,UNAVAILABLE}
[junit4] 2> 553159 INFO (jetty-closer-313-thread-2) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 553162 INFO (jetty-closer-313-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=72215582588928066-127.0.0.1:37981_solr-n_0000000020) closing
[junit4] 2> 553162 INFO
(OverseerStateUpdate-72215582588928066-127.0.0.1:37981_solr-n_0000000020)
[n:127.0.0.1:37981_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:37981_solr
[junit4] 2> 553162 WARN
(OverseerAutoScalingTriggerThread-72215582588928066-127.0.0.1:37981_solr-n_0000000020)
[n:127.0.0.1:37981_solr ] o.a.s.c.a.OverseerTriggerThread
OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 553162 DEBUG (jetty-closer-313-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 553163 INFO (jetty-closer-313-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@528d73a7{/solr,null,UNAVAILABLE}
[junit4] 2> 553163 INFO (jetty-closer-313-thread-1) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 554659 WARN
(zkCallback-584-thread-2-processing-n:127.0.0.1:41217_solr)
[n:127.0.0.1:41217_solr ] o.a.s.c.ZkController Unable to register nodeLost
path for 127.0.0.1:41217_solr
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException:
KeeperErrorCode = Session expired for /autoscaling/nodeLost/127.0.0.1:41217_solr
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:130)
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:54)
[junit4] 2> at
org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java:786)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.lambda$create$7(SolrZkClient.java:365)
[junit4] 2> at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.create(SolrZkClient.java:365)
[junit4] 2> at
org.apache.solr.cloud.ZkController.lambda$registerLiveNodesListener$2(ZkController.java:885)
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader.lambda$refreshLiveNodes$1(ZkStateReader.java:724)
[junit4] 2> at
java.base/java.util.concurrent.ConcurrentHashMap$KeySetView.forEach(ConcurrentHashMap.java:4717)
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader.refreshLiveNodes(ZkStateReader.java:723)
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader.access$1000(ZkStateReader.java:72)
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader$LiveNodeWatcher.refreshAndWatch(ZkStateReader.java:1110)
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader$LiveNodeWatcher.process(ZkStateReader.java:1105)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$1.lambda$process$1(SolrZkClient.java:269)
[junit4] 2> at
java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:514)
[junit4] 2> at
java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[junit4] 2> at
java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1135)
[junit4] 2> at
java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 554660 INFO (jetty-closer-313-thread-5) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@24addf14{/solr,null,UNAVAILABLE}
[junit4] 2> 554660 INFO (jetty-closer-313-thread-5) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 554661 ERROR
(SUITE-TriggerIntegrationTest-seed#[34049F329D59DDB9]-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> 554661 INFO
(SUITE-TriggerIntegrationTest-seed#[34049F329D59DDB9]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:42839 42839
[junit4] 2> 560486 INFO (Thread-328) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:42839 42839
[junit4] 2> 560487 WARN (Thread-328) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 43 /solr/aliases.json
[junit4] 2> 43 /solr/clusterprops.json
[junit4] 2> 35 /solr/collections/collection1/terms/shard1
[junit4] 2> 28 /solr/security.json
[junit4] 2> 11 /solr/collections/testMetricTrigger/terms/shard1
[junit4] 2> 9 /solr/configs/conf
[junit4] 2> 2 /solr/collections/testMetricTrigger/terms/shard2
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 62 /solr/collections/collection1/state.json
[junit4] 2> 43 /solr/clusterstate.json
[junit4] 2> 32 /solr/collections/testMetricTrigger/state.json
[junit4] 2> 20 /solr/autoscaling.json
[junit4] 2> 3
/solr/collections/collection1/leader_elect/shard1/election/72215582588928081-core_node3-n_0000000001
[junit4] 2> 2
/solr/overseer_elect/election/72215582588928066-127.0.0.1:37981_solr-n_0000000020
[junit4] 2> 2
/solr/overseer_elect/election/72215582588928081-127.0.0.1:35053_solr-n_0000000025
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 417 /solr/live_nodes
[junit4] 2> 81 /solr/collections
[junit4] 2> 20 /solr/overseer/queue-work
[junit4] 2> 20 /solr/autoscaling/events/.auto_add_replicas
[junit4] 2> 20 /solr/overseer/queue
[junit4] 2> 20 /solr/overseer/collection-queue-work
[junit4] 2> 2 /solr/autoscaling/events/node_lost_triggerMR
[junit4] 2> 2 /solr/autoscaling/events/node_added_triggerCTOOR
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_34049F329D59DDB9-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70):
{id=Lucene50(blocksize=128), x_s=FSTOrd50}, docValues:{},
maxPointsInLeafNode=1328, maxMBSortInHeap=5.9256879725597535,
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@29508cc8),
locale=nb-SJ, timezone=Etc/GMT+5
[junit4] 2> NOTE: Linux 4.13.0-32-generic amd64/Oracle Corporation 10
(64-bit)/cpus=8,threads=1,free=417247528,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TriggerIntegrationTest,
TriggerIntegrationTest]
[junit4] Completed [10/10 (4!)] on J0 in 279.48s, 14 tests, 1 failure <<<
FAILURES!
[...truncated 14 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1560:
The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1085:
There were test failures: 10 suites, 75 tests, 4 failures [seed:
34049F329D59DDB9]
Total time: 9 minutes 24 seconds
[repro] Setting last failure code to 256
[repro] Failures:
[repro] 2/5 failed: org.apache.solr.cloud.ReplaceNodeNoTargetTest
[repro] 2/5 failed: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest
[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=86be87391511b3636ea952d40343305762d4f63c,
workspace=/var/lib/jenkins/workspace/Lucene-Solr-master-Linux
[WARNINGS] Computing warning deltas based on reference build #21537
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]
