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: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org