Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/21703/ Java: 64bit/jdk-11-ea+5 -XX:-UseCompressedOops -XX:+UseG1GC
1 tests failed. FAILED: org.apache.solr.cloud.autoscaling.NodeLostTriggerTest.testListenerAcceptance Error Message: expected:<1> but was:<0> Stack Trace: java.lang.AssertionError: expected:<1> but was:<0> at __randomizedtesting.SeedInfo.seed([6D1BDB623344752E:7CAF81858ACD61D8]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.failNotEquals(Assert.java:647) at org.junit.Assert.assertEquals(Assert.java:128) at org.junit.Assert.assertEquals(Assert.java:472) at org.junit.Assert.assertEquals(Assert.java:456) at org.apache.solr.cloud.autoscaling.NodeLostTriggerTest.testListenerAcceptance(NodeLostTriggerTest.java:253) 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:841) Build Log: [...truncated 1843 lines...] [junit4] JVM J0: stdout was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/test/temp/junit4-J0-20180326_143126_1061936921092816481956.sysout [junit4] >>> JVM J0 emitted unexpected output (verbatim) ---- [junit4] codec: FastDecompressionCompressingStoredFields, pf: FST50, dvf: Asserting [junit4] <<< JVM J0: EOF ---- [...truncated 12358 lines...] [junit4] Suite: org.apache.solr.cloud.autoscaling.NodeLostTriggerTest [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.NodeLostTriggerTest_6D1BDB623344752E-001/init-core-data-001 [junit4] 2> 1734949 INFO (SUITE-NodeLostTriggerTest-seed#[6D1BDB623344752E]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 5 servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.NodeLostTriggerTest_6D1BDB623344752E-001/tempDir-001 [junit4] 2> 1734949 INFO (SUITE-NodeLostTriggerTest-seed#[6D1BDB623344752E]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1734950 INFO (Thread-4737) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1734950 INFO (Thread-4737) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1734951 ERROR (Thread-4737) [ ] 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> 1735050 INFO (SUITE-NodeLostTriggerTest-seed#[6D1BDB623344752E]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:35267 [junit4] 2> 1735051 INFO (zkConnectionManagerCallback-4403-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1735053 INFO (jetty-launcher-4400-thread-3) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: ????-??-??T??:??:??+01:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 1735053 INFO (jetty-launcher-4400-thread-4) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: ????-??-??T??:??:??+01:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 1735053 INFO (jetty-launcher-4400-thread-5) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: ????-??-??T??:??:??+01:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 1735053 INFO (jetty-launcher-4400-thread-1) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: ????-??-??T??:??:??+01:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 1735054 INFO (jetty-launcher-4400-thread-2) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: ????-??-??T??:??:??+01:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 1735058 INFO (jetty-launcher-4400-thread-5) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1735058 INFO (jetty-launcher-4400-thread-5) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1735058 INFO (jetty-launcher-4400-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1735058 INFO (jetty-launcher-4400-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1735058 INFO (jetty-launcher-4400-thread-5) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 1735058 INFO (jetty-launcher-4400-thread-2) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 1735061 INFO (jetty-launcher-4400-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1735061 INFO (jetty-launcher-4400-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1735061 INFO (jetty-launcher-4400-thread-1) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 1735061 INFO (jetty-launcher-4400-thread-5) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4f16918d{/solr,null,AVAILABLE} [junit4] 2> 1735061 INFO (jetty-launcher-4400-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5d142375{/solr,null,AVAILABLE} [junit4] 2> 1735062 INFO (jetty-launcher-4400-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@64dfd069{HTTP/1.1,[http/1.1]}{127.0.0.1:?????} [junit4] 2> 1735062 INFO (jetty-launcher-4400-thread-2) [ ] o.e.j.s.Server Started @???????ms [junit4] 2> 1735062 INFO (jetty-launcher-4400-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=37377} [junit4] 2> 1735062 ERROR (jetty-launcher-4400-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1735062 INFO (jetty-launcher-4400-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1735062 INFO (jetty-launcher-4400-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 1735062 INFO (jetty-launcher-4400-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1735062 INFO (jetty-launcher-4400-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1735062 INFO (jetty-launcher-4400-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-26T15:26:16.134783Z [junit4] 2> 1735063 INFO (jetty-launcher-4400-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1735063 INFO (jetty-launcher-4400-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1100cae7{/solr,null,AVAILABLE} [junit4] 2> 1735063 INFO (jetty-launcher-4400-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1735063 INFO (jetty-launcher-4400-thread-3) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 1735063 INFO (jetty-launcher-4400-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@37c797a7{HTTP/1.1,[http/1.1]}{127.0.0.1:?????} [junit4] 2> 1735063 INFO (jetty-launcher-4400-thread-1) [ ] o.e.j.s.Server Started @???????ms [junit4] 2> 1735063 INFO (jetty-launcher-4400-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=39557} [junit4] 2> 1735063 INFO (jetty-launcher-4400-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1735063 INFO (jetty-launcher-4400-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1735063 INFO (jetty-launcher-4400-thread-4) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 1735064 ERROR (jetty-launcher-4400-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1735064 INFO (jetty-launcher-4400-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1735064 INFO (jetty-launcher-4400-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 1735064 INFO (jetty-launcher-4400-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@22444740{/solr,null,AVAILABLE} [junit4] 2> 1735064 INFO (jetty-launcher-4400-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1735064 INFO (jetty-launcher-4400-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1735064 INFO (zkConnectionManagerCallback-4405-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1735064 INFO (jetty-launcher-4400-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-26T15:26:16.136207Z [junit4] 2> 1735064 INFO (jetty-launcher-4400-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@615bb958{HTTP/1.1,[http/1.1]}{127.0.0.1:?????} [junit4] 2> 1735064 INFO (jetty-launcher-4400-thread-4) [ ] o.e.j.s.Server Started @???????ms [junit4] 2> 1735064 INFO (jetty-launcher-4400-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46145} [junit4] 2> 1735063 INFO (jetty-launcher-4400-thread-5) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2daa6a51{HTTP/1.1,[http/1.1]}{127.0.0.1:?????} [junit4] 2> 1735064 INFO (jetty-launcher-4400-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@594441a2{/solr,null,AVAILABLE} [junit4] 2> 1735064 INFO (jetty-launcher-4400-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1735064 ERROR (jetty-launcher-4400-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1735064 INFO (jetty-launcher-4400-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1735064 INFO (jetty-launcher-4400-thread-5) [ ] o.e.j.s.Server Started @???????ms [junit4] 2> 1735064 INFO (jetty-launcher-4400-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 1735064 INFO (jetty-launcher-4400-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1735064 INFO (jetty-launcher-4400-thread-5) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44075} [junit4] 2> 1735064 INFO (jetty-launcher-4400-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@50e3ea38{HTTP/1.1,[http/1.1]}{127.0.0.1:?????} [junit4] 2> 1735064 INFO (jetty-launcher-4400-thread-3) [ ] o.e.j.s.Server Started @???????ms [junit4] 2> 1735064 INFO (jetty-launcher-4400-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42043} [junit4] 2> 1735064 ERROR (jetty-launcher-4400-thread-5) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1735064 INFO (jetty-launcher-4400-thread-5) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1735065 INFO (jetty-launcher-4400-thread-5) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 1735065 ERROR (jetty-launcher-4400-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1735065 INFO (jetty-launcher-4400-thread-5) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1735065 INFO (jetty-launcher-4400-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1735065 INFO (jetty-launcher-4400-thread-5) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1735065 INFO (jetty-launcher-4400-thread-5) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-26T15:26:16.137104Z [junit4] 2> 1735065 INFO (jetty-launcher-4400-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 1735065 INFO (jetty-launcher-4400-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1735065 INFO (jetty-launcher-4400-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1735065 INFO (jetty-launcher-4400-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-26T15:26:16.137181Z [junit4] 2> 1735064 INFO (jetty-launcher-4400-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1735065 INFO (jetty-launcher-4400-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-26T15:26:16.137430Z [junit4] 2> 1735073 INFO (zkConnectionManagerCallback-4407-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1735073 INFO (zkConnectionManagerCallback-4411-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1735073 INFO (zkConnectionManagerCallback-4413-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1735073 INFO (zkConnectionManagerCallback-4409-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1735073 INFO (jetty-launcher-4400-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1735073 INFO (jetty-launcher-4400-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1735073 INFO (jetty-launcher-4400-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1735073 INFO (jetty-launcher-4400-thread-5) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1735074 INFO (jetty-launcher-4400-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35267/solr [junit4] 2> 1735077 INFO (zkConnectionManagerCallback-4417-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1735077 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 0x100840f1d8d0005, likely client has closed socket [junit4] 2> 1735077 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 0x100840f1d8d0003, likely client has closed socket [junit4] 2> 1735077 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 0x100840f1d8d0004, likely client has closed socket [junit4] 2> 1735078 INFO (jetty-launcher-4400-thread-5) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35267/solr [junit4] 2> 1735078 INFO (jetty-launcher-4400-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35267/solr [junit4] 2> 1735078 INFO (jetty-launcher-4400-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35267/solr [junit4] 2> 1735079 INFO (zkConnectionManagerCallback-4420-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1735079 INFO (jetty-launcher-4400-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35267/solr [junit4] 2> 1735079 INFO (zkConnectionManagerCallback-4431-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1735079 INFO (zkConnectionManagerCallback-4433-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1735079 INFO (zkConnectionManagerCallback-4429-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1735079 INFO (zkConnectionManagerCallback-4435-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1735080 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 0x100840f1d8d0009, likely client has closed socket [junit4] 2> 1735080 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 0x100840f1d8d000b, likely client has closed socket [junit4] 2> 1735081 INFO (zkConnectionManagerCallback-4439-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1735081 INFO (zkConnectionManagerCallback-4437-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1735081 INFO (zkConnectionManagerCallback-4441-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1735081 INFO (zkConnectionManagerCallback-4443-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1735133 INFO (jetty-launcher-4400-thread-3) [n:127.0.0.1:42043_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1735133 INFO (jetty-launcher-4400-thread-4) [n:127.0.0.1:46145_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1735133 INFO (jetty-launcher-4400-thread-2) [n:127.0.0.1:37377_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1735133 INFO (jetty-launcher-4400-thread-3) [n:127.0.0.1:42043_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:42043_solr [junit4] 2> 1735133 INFO (jetty-launcher-4400-thread-2) [n:127.0.0.1:37377_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37377_solr [junit4] 2> 1735133 INFO (jetty-launcher-4400-thread-4) [n:127.0.0.1:46145_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46145_solr [junit4] 2> 1735134 INFO (jetty-launcher-4400-thread-3) [n:127.0.0.1:42043_solr ] o.a.s.c.Overseer Overseer (id=72202794493083661-127.0.0.1:42043_solr-n_0000000000) starting [junit4] 2> 1735134 INFO (jetty-launcher-4400-thread-1) [n:127.0.0.1:39557_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1735134 INFO (zkCallback-4438-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1735134 INFO (zkCallback-4436-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1735135 INFO (jetty-launcher-4400-thread-5) [n:127.0.0.1:44075_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1735135 INFO (jetty-launcher-4400-thread-5) [n:127.0.0.1:44075_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1735136 INFO (jetty-launcher-4400-thread-5) [n:127.0.0.1:44075_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44075_solr [junit4] 2> 1735137 INFO (zkCallback-4436-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1735137 INFO (zkCallback-4438-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1735143 INFO (zkCallback-4419-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 1735145 INFO (zkCallback-4442-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 1735157 INFO (zkCallback-4440-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1735158 INFO (jetty-launcher-4400-thread-1) [n:127.0.0.1:39557_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39557_solr [junit4] 2> 1735158 INFO (zkCallback-4438-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1735159 INFO (zkCallback-4419-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1735159 INFO (zkCallback-4440-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1735159 INFO (zkCallback-4442-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1735161 INFO (zkCallback-4436-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1735165 INFO (zkConnectionManagerCallback-4452-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1735166 INFO (jetty-launcher-4400-thread-3) [n:127.0.0.1:42043_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 1735175 INFO (jetty-launcher-4400-thread-3) [n:127.0.0.1:42043_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35267/solr ready [junit4] 2> 1735195 INFO (jetty-launcher-4400-thread-4) [n:127.0.0.1:46145_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46145.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5093e08a [junit4] 2> 1735200 INFO (jetty-launcher-4400-thread-4) [n:127.0.0.1:46145_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46145.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5093e08a [junit4] 2> 1735200 INFO (jetty-launcher-4400-thread-4) [n:127.0.0.1:46145_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46145.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5093e08a [junit4] 2> 1735201 INFO (jetty-launcher-4400-thread-4) [n:127.0.0.1:46145_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.NodeLostTriggerTest_6D1BDB623344752E-001/tempDir-001/node4/. [junit4] 2> 1735201 DEBUG (OverseerAutoScalingTriggerThread-72202794493083661-127.0.0.1:42043_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers [junit4] 2> 1735202 INFO (jetty-launcher-4400-thread-3) [n:127.0.0.1:42043_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42043_solr [junit4] 2> 1735202 DEBUG (OverseerAutoScalingTriggerThread-72202794493083661-127.0.0.1:42043_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1 [junit4] 2> 1735203 INFO (zkCallback-4438-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 1735203 INFO (zkCallback-4419-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 1735203 INFO (zkCallback-4436-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 1735203 DEBUG (OverseerAutoScalingTriggerThread-72202794493083661-127.0.0.1:42043_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:39557_solr, 127.0.0.1:37377_solr, 127.0.0.1:44075_solr, 127.0.0.1:46145_solr] [junit4] 2> 1735203 INFO (zkCallback-4440-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 1735203 DEBUG (OverseerAutoScalingTriggerThread-72202794493083661-127.0.0.1:42043_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion -1 [junit4] 2> 1735203 DEBUG (OverseerAutoScalingTriggerThread-72202794493083661-127.0.0.1:42043_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1 [junit4] 2> 1735203 INFO (zkCallback-4442-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 1735226 INFO (zkCallback-4451-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 1735231 INFO (jetty-launcher-4400-thread-2) [n:127.0.0.1:37377_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37377.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5093e08a [junit4] 2> 1735237 INFO (jetty-launcher-4400-thread-2) [n:127.0.0.1:37377_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37377.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5093e08a [junit4] 2> 1735237 INFO (jetty-launcher-4400-thread-2) [n:127.0.0.1:37377_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37377.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5093e08a [junit4] 2> 1735237 DEBUG (OverseerAutoScalingTriggerThread-72202794493083661-127.0.0.1:42043_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 1735237 INFO (jetty-launcher-4400-thread-2) [n:127.0.0.1:37377_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.NodeLostTriggerTest_6D1BDB623344752E-001/tempDir-001/node2/. [junit4] 2> 1735237 DEBUG (OverseerAutoScalingTriggerThread-72202794493083661-127.0.0.1:42043_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1 [junit4] 2> 1735238 DEBUG (ScheduledTrigger-6747-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 [junit4] 2> 1735256 INFO (jetty-launcher-4400-thread-1) [n:127.0.0.1:39557_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39557.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5093e08a [junit4] 2> 1735264 INFO (jetty-launcher-4400-thread-1) [n:127.0.0.1:39557_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39557.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5093e08a [junit4] 2> 1735264 INFO (jetty-launcher-4400-thread-1) [n:127.0.0.1:39557_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39557.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5093e08a [junit4] 2> 1735265 INFO (jetty-launcher-4400-thread-1) [n:127.0.0.1:39557_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.NodeLostTriggerTest_6D1BDB623344752E-001/tempDir-001/node1/. [junit4] 2> 1735265 INFO (zkConnectionManagerCallback-4456-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1735266 INFO (jetty-launcher-4400-thread-4) [n:127.0.0.1:46145_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5) [junit4] 2> 1735266 INFO (jetty-launcher-4400-thread-4) [n:127.0.0.1:46145_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35267/solr ready [junit4] 2> 1735281 INFO (zkConnectionManagerCallback-4460-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1735282 INFO (jetty-launcher-4400-thread-2) [n:127.0.0.1:37377_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5) [junit4] 2> 1735282 INFO (jetty-launcher-4400-thread-2) [n:127.0.0.1:37377_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35267/solr ready [junit4] 2> 1735289 INFO (zkConnectionManagerCallback-4464-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1735290 INFO (jetty-launcher-4400-thread-1) [n:127.0.0.1:39557_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5) [junit4] 2> 1735291 INFO (jetty-launcher-4400-thread-1) [n:127.0.0.1:39557_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35267/solr ready [junit4] 2> 1735293 INFO (jetty-launcher-4400-thread-5) [n:127.0.0.1:44075_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44075.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5093e08a [junit4] 2> 1735300 INFO (jetty-launcher-4400-thread-5) [n:127.0.0.1:44075_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44075.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5093e08a [junit4] 2> 1735300 INFO (jetty-launcher-4400-thread-5) [n:127.0.0.1:44075_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44075.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5093e08a [junit4] 2> 1735300 INFO (jetty-launcher-4400-thread-3) [n:127.0.0.1:42043_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42043.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5093e08a [junit4] 2> 1735301 INFO (jetty-launcher-4400-thread-5) [n:127.0.0.1:44075_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.NodeLostTriggerTest_6D1BDB623344752E-001/tempDir-001/node5/. [junit4] 2> 1735305 INFO (zkConnectionManagerCallback-4468-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1735306 INFO (jetty-launcher-4400-thread-5) [n:127.0.0.1:44075_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5) [junit4] 2> 1735306 INFO (jetty-launcher-4400-thread-5) [n:127.0.0.1:44075_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35267/solr ready [junit4] 2> 1735307 INFO (jetty-launcher-4400-thread-3) [n:127.0.0.1:42043_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42043.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5093e08a [junit4] 2> 1735307 INFO (jetty-launcher-4400-thread-3) [n:127.0.0.1:42043_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42043.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5093e08a [junit4] 2> 1735308 INFO (jetty-launcher-4400-thread-3) [n:127.0.0.1:42043_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.NodeLostTriggerTest_6D1BDB623344752E-001/tempDir-001/node3/. [junit4] 2> 1735482 INFO (zkConnectionManagerCallback-4470-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1735484 INFO (zkConnectionManagerCallback-4474-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1735485 INFO (SUITE-NodeLostTriggerTest-seed#[6D1BDB623344752E]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5) [junit4] 2> 1735486 INFO (SUITE-NodeLostTriggerTest-seed#[6D1BDB623344752E]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35267/solr ready [junit4] 2> 1735508 DEBUG (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:39557_solr, 127.0.0.1:37377_solr, 127.0.0.1:42043_solr, 127.0.0.1:44075_solr, 127.0.0.1:46145_solr] [junit4] 2> 1735509 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: ????-??-??T??:??:??+01:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 1735510 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1735510 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1735510 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 1735510 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@56d3ce97{/solr,null,AVAILABLE} [junit4] 2> 1735510 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@dede9c6{HTTP/1.1,[http/1.1]}{127.0.0.1:?????} [junit4] 2> 1735510 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [ ] o.e.j.s.Server Started @???????ms [junit4] 2> 1735510 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=35175} [junit4] 2> 1735510 ERROR (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1735511 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1735511 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 1735511 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1735511 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1735511 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-26T15:26:16.583204Z [junit4] 2> 1735512 INFO (zkConnectionManagerCallback-4476-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1735512 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1735517 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35267/solr [junit4] 2> 1735517 INFO (zkConnectionManagerCallback-4480-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1735519 INFO (zkConnectionManagerCallback-4482-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1735521 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [n:127.0.0.1:35175_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5) [junit4] 2> 1735522 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [n:127.0.0.1:35175_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1735523 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [n:127.0.0.1:35175_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35175_solr [junit4] 2> 1735523 INFO (zkCallback-4442-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 1735523 INFO (zkCallback-4440-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 1735524 INFO (zkCallback-4436-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 1735524 INFO (zkCallback-4438-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 1735523 INFO (zkCallback-4419-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 1735524 INFO (zkCallback-4451-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 1735524 INFO (zkCallback-4459-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 1735524 INFO (zkCallback-4467-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 1735524 INFO (zkCallback-4481-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 1735524 INFO (zkCallback-4463-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 1735524 INFO (zkCallback-4455-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 1735524 INFO (zkCallback-4473-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 1735607 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [n:127.0.0.1:35175_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35175.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5093e08a [junit4] 2> 1735614 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [n:127.0.0.1:35175_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35175.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5093e08a [junit4] 2> 1735614 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [n:127.0.0.1:35175_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35175.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5093e08a [junit4] 2> 1735615 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [n:127.0.0.1:35175_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.NodeLostTriggerTest_6D1BDB623344752E-001/tempDir-001/node6/. [junit4] 2> 1735617 INFO (zkConnectionManagerCallback-4487-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1735617 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [n:127.0.0.1:35175_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (6) [junit4] 2> 1735618 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [n:127.0.0.1:35175_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35267/solr ready [junit4] 2> 1735676 INFO (zkConnectionManagerCallback-4489-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1735676 DEBUG (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_added_trigger with currently live nodes: 6 [junit4] 2> 1735677 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@dede9c6{HTTP/1.1,[http/1.1]}{127.0.0.1:?} [junit4] 2> 1735677 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1262524831 [junit4] 2> 1735677 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 1735677 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@21dc60ac: rootName = solr_35175, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@40838bac [junit4] 2> 1735682 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 1735682 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4cf39f1d: rootName = solr_35175, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@66a4a7f1 [junit4] 2> 1735685 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 1735685 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1862c154: rootName = solr_35175, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@5b1f5c3b [junit4] 2> 1735686 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:35175_solr [junit4] 2> 1735686 INFO (zkCallback-4473-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 1735686 INFO (zkCallback-4419-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 1735686 INFO (zkCallback-4451-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 1735686 INFO (zkCallback-4467-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 1735686 INFO (zkCallback-4442-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 1735686 INFO (zkCallback-4436-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 1735686 INFO (zkCallback-4440-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 1735686 INFO (zkCallback-4459-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 1735686 INFO (zkCallback-4438-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 1735686 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 1735686 INFO (zkCallback-4481-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 1735686 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1735686 INFO (zkCallback-4486-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 1735687 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@56d3ce97{/solr,null,UNAVAILABLE} [junit4] 2> 1735687 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 1735688 INFO (zkConnectionManagerCallback-4491-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1735689 DEBUG (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[6D1BDB623344752E]) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_added_trigger with currently live nodes: 6 [junit4] 2> 1735689 INFO (zkCallback-4463-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 1735689 INFO (zkCallback-4455-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=NodeLostTriggerTest -Dtests.method=testListenerAcceptance -Dtests.seed=6D1BDB623344752E -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ar-AE -Dtests.timezone=America/Blanc-Sablon -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [junit4] FAILURE 0.20s J0 | NodeLostTriggerTest.testListenerAcceptance <<< [junit4] > Throwable #1: java.lang.AssertionError: expected:<1> but was:<0> [junit4] > at __randomizedtesting.SeedInfo.seed([6D1BDB623344752E:7CAF81858ACD61D8]:0) [junit4] > at org.apache.solr.cloud.autoscaling.NodeLostTriggerTest.testListenerAcceptance(NodeLostTriggerTest.java:253) [junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564) [junit4] > at java.base/java.lang.Thread.run(Thread.java:841) [junit4] 2> 1735715 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: ????-??-??T??:??:??+01:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 1735716 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1735716 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1735716 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 1735716 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4d3fab20{/solr,null,AVAILABLE} [junit4] 2> 1735716 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5c5c630{HTTP/1.1,[http/1.1]}{127.0.0.1:?????} [junit4] 2> 1735716 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.e.j.s.Server Started @???????ms [junit4] 2> 1735716 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=35641} [junit4] 2> 1735717 ERROR (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1735717 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1735717 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 1735717 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1735717 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1735717 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-26T15:26:16.789206Z [junit4] 2> 1735725 INFO (zkConnectionManagerCallback-4493-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1735726 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1735735 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35267/solr [junit4] 2> 1735736 INFO (zkConnectionManagerCallback-4497-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1735737 INFO (zkConnectionManagerCallback-4499-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1735740 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [n:127.0.0.1:35641_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5) [junit4] 2> 1735741 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [n:127.0.0.1:35641_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1735741 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [n:127.0.0.1:35641_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35641_solr [junit4] 2> 1735742 INFO (zkCallback-4442-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 1735742 INFO (zkCallback-4419-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 1735742 INFO (zkCallback-4459-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 1735742 INFO (zkCallback-4438-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 1735742 INFO (zkCallback-4451-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 1735742 INFO (zkCallback-4463-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 1735742 INFO (zkCallback-4436-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 1735742 INFO (zkCallback-4455-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 1735742 INFO (zkCallback-4440-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 1735742 INFO (zkCallback-4473-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 1735745 INFO (zkCallback-4467-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 1735749 INFO (zkCallback-4498-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 1735882 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [n:127.0.0.1:35641_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35641.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5093e08a [junit4] 2> 1735889 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [n:127.0.0.1:35641_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35641.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5093e08a [junit4] 2> 1735889 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [n:127.0.0.1:35641_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35641.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5093e08a [junit4] 2> 1735890 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [n:127.0.0.1:35641_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.NodeLostTriggerTest_6D1BDB623344752E-001/tempDir-001/node7/. [junit4] 2> 1735891 INFO (zkConnectionManagerCallback-4504-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1735892 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [n:127.0.0.1:35641_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (6) [junit4] 2> 1735892 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [n:127.0.0.1:35641_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35267/solr ready [junit4] 2> 1735986 DEBUG (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:39557_solr, 127.0.0.1:37377_solr, 127.0.0.1:35641_solr, 127.0.0.1:42043_solr, 127.0.0.1:44075_solr, 127.0.0.1:46145_solr] [junit4] 2> 1735987 DEBUG (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 6 [junit4] 2> 1735987 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@5c5c630{HTTP/1.1,[http/1.1]}{127.0.0.1:?} [junit4] 2> 1735987 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=284517227 [junit4] 2> 1735987 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 1735987 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@ab8e03e: rootName = solr_35641, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@7b1edc22 [junit4] 2> 1735996 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 1735996 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@21bac333: rootName = solr_35641, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@66a4a7f1 [junit4] 2> 1736001 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 1736001 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4ceea203: rootName = solr_35641, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@5b1f5c3b [junit4] 2> 1736002 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:35641_solr [junit4] 2> 1736003 INFO (zkCallback-4459-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 1736003 INFO (zkCallback-4455-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 1736003 INFO (zkCallback-4419-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 1736003 INFO (zkCallback-4473-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 1736003 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 1736003 INFO (zkCallback-4463-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 1736003 INFO (zkCallback-4467-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 1736003 INFO (zkCallback-4498-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 1736003 INFO (zkCallback-4440-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 1736003 INFO (zkCallback-4438-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 1736003 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1736003 INFO (zkCallback-4436-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 1736003 INFO (zkCallback-4442-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 1736003 INFO (zkCallback-4451-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 1736004 INFO (zkCallback-4503-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 1736004 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4d3fab20{/solr,null,UNAVAILABLE} [junit4] 2> 1736004 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 1736004 DEBUG (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 5 [junit4] 2> 1736004 DEBUG (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.a.s.c.a.NodeLostTrigger Tracking lost node: 127.0.0.1:35641_solr [junit4] 2> 1736004 DEBUG (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:39557_solr, 127.0.0.1:37377_solr, 127.0.0.1:42043_solr, 127.0.0.1:44075_solr, 127.0.0.1:46145_solr] [junit4] 2> 1736005 DEBUG (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:39557_solr, 127.0.0.1:37377_solr, 127.0.0.1:42043_solr, 127.0.0.1:44075_solr, 127.0.0.1:46145_solr] [junit4] 2> 1736006 DEBUG (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 5 [junit4] 2> 1736239 DEBUG (ScheduledTrigger-6747-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 [junit4] 2> 1737006 DEBUG (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 5 [junit4] 2> 1737239 DEBUG (ScheduledTrigger-6747-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 [junit4] 2> 1738006 DEBUG (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 5 [junit4] 2> 1738239 DEBUG (ScheduledTrigger-6747-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 [junit4] 2> 1739006 DEBUG (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 5 [junit4] 2> 1739006 DEBUG (TEST-NodeLostTriggerTest.testRestoreState-seed#[6D1BDB623344752E]) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger firing registered processor for lost nodes: [127.0.0.1:35641_solr] [junit4] 2> 1739239 DEBUG (ScheduledTrigger-6747-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 [junit4] 2> 1740031 DEBUG (TEST-NodeLostTriggerTest.testActionLifecycle-seed#[6D1BDB623344752E]) [ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:39557_solr, 127.0.0.1:37377_solr, 127.0.0.1:42043_solr, 127.0.0.1:44075_solr, 127.0.0.1:46145_solr] [junit4] 2> 1740031 DEBUG (TEST-NodeLostTriggerTest.testActionLifecycle-seed#[6D1BDB623344752E]) [ ] o.a.s.c.a.NodeLostTrigger Adding lost node from marker path: 127.0.0.1:35175_solr [junit4] 2> 1740053 DEBUG (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:39557_solr, 127.0.0.1:37377_solr, 127.0.0.1:42043_solr, 127.0.0.1:44075_solr, 127.0.0.1:46145_solr] [junit4] 2> 1740053 DEBUG (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 5 [junit4] 2> 1740053 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@50e3ea38{HTTP/1.1,[http/1.1]}{127.0.0.1:?} [junit4] 2> 1740053 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1960395404 [junit4] 2> 1740053 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 1740053 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@797678c1: rootName = solr_42043, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@7736bade [junit4] 2> 1740062 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 1740062 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4a0a6ee3: rootName = solr_42043, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@66a4a7f1 [junit4] 2> 1740068 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 1740068 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1953c05f: rootName = solr_42043, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@5b1f5c3b [junit4] 2> 1740069 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:42043_solr [junit4] 2> 1740069 INFO (zkCallback-4459-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4) [junit4] 2> 1740069 INFO (zkCallback-4419-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4) [junit4] 2> 1740069 INFO (zkCallback-4473-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4) [junit4] 2> 1740069 INFO (zkCallback-4451-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4) [junit4] 2> 1740069 INFO (zkCallback-4440-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4) [junit4] 2> 1740069 INFO (zkCallback-4442-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4) [junit4] 2> 1740069 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 1740069 INFO (zkCallback-4436-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4) [junit4] 2> 1740069 INFO (zkCallback-4467-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4) [junit4] 2> 1740069 INFO (zkCallback-4455-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4) [junit4] 2> 1740069 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1740069 INFO (zkCallback-4463-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4) [junit4] 2> 1740069 INFO (zkCallback-4438-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4) [junit4] 2> 1740070 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.c.Overseer Overseer (id=72202794493083661-127.0.0.1:42043_solr-n_0000000000) closing [junit4] 2> 1740070 INFO (OverseerStateUpdate-72202794493083661-127.0.0.1:42043_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:42043_solr [junit4] 2> 1740070 WARN (OverseerAutoScalingTriggerThread-72202794493083661-127.0.0.1:42043_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 1740071 DEBUG (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly [junit4] 2> 1740071 INFO (zkCallback-4442-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:46145_solr [junit4] 2> 1740072 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@594441a2{/solr,null,UNAVAILABLE} [junit4] 2> 1740072 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 1740072 INFO (zkCallback-4442-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72202794493083663-127.0.0.1:46145_solr-n_0000000001) starting [junit4] 2> 1740073 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@2daa6a51{HTTP/1.1,[http/1.1]}{127.0.0.1:?} [junit4] 2> 1740073 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1499819400 [junit4] 2> 1740073 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 1740073 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@46904082: rootName = solr_44075, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@47fcffe2 [junit4] 2> 1740075 DEBUG (OverseerAutoScalingTriggerThread-72202794493083663-127.0.0.1:46145_solr-n_0000000001) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1 [junit4] 2> 1740075 DEBUG (OverseerAutoScalingTriggerThread-72202794493083663-127.0.0.1:46145_solr-n_0000000001) [ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:39557_solr, 127.0.0.1:37377_solr, 127.0.0.1:44075_solr, 127.0.0.1:46145_solr] [junit4] 2> 1740075 DEBUG (OverseerAutoScalingTriggerThread-72202794493083663-127.0.0.1:46145_solr-n_0000000001) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion -1 [junit4] 2> 1740075 DEBUG (OverseerAutoScalingTriggerThread-72202794493083663-127.0.0.1:46145_solr-n_0000000001) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1 [junit4] 2> 1740076 DEBUG (OverseerAutoScalingTriggerThread-72202794493083663-127.0.0.1:46145_solr-n_0000000001) [ ] o.a.s.c.a.NodeLostTrigger Adding lost node from marker path: 127.0.0.1:42043_solr [junit4] 2> 1740076 DEBUG (OverseerAutoScalingTriggerThread-72202794493083663-127.0.0.1:46145_solr-n_0000000001) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 1740076 DEBUG (OverseerAutoScalingTriggerThread-72202794493083663-127.0.0.1:46145_solr-n_0000000001) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1 [junit4] 2> 1740076 DEBUG (ScheduledTrigger-6776-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 1740076 DEBUG (ScheduledTrigger-6776-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Tracking lost node: 127.0.0.1:42043_solr [junit4] 2> 1740082 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 1740082 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@450ebcf9: rootName = solr_44075, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@66a4a7f1 [junit4] 2> 1740087 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 1740087 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@642e0892: rootName = solr_44075, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@5b1f5c3b [junit4] 2> 1740088 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:44075_solr [junit4] 2> 1740089 INFO (zkCallback-4459-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1740089 INFO (zkCallback-4473-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1740089 INFO (zkCallback-4419-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1740089 INFO (zkCallback-4463-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1740089 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 1740089 INFO (zkCallback-4436-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1740089 INFO (zkCallback-4467-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1740089 INFO (zkCallback-4442-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1740089 INFO (zkCallback-4455-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1740089 INFO (zkCallback-4440-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1740089 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1740090 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4f16918d{/solr,null,UNAVAILABLE} [junit4] 2> 1740090 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 1741077 DEBUG (ScheduledTrigger-6776-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 3 [junit4] 2> 1741077 DEBUG (ScheduledTrigger-6776-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Tracking lost node: 127.0.0.1:44075_solr [junit4] 2> 1741091 DEBUG (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 3 [junit4] 2> 1741091 DEBUG (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.c.a.NodeLostTrigger Tracking lost node: 127.0.0.1:42043_solr [junit4] 2> 1741091 DEBUG (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.c.a.NodeLostTrigger Tracking lost node: 127.0.0.1:44075_solr [junit4] 2> 1742077 DEBUG (ScheduledTrigger-6776-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 3 [junit4] 2> 1742091 DEBUG (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 3 [junit4] 2> 1742091 DEBUG (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger firing registered processor for lost nodes: [127.0.0.1:42043_solr, 127.0.0.1:44075_solr] [junit4] 2> 1743078 DEBUG (ScheduledTrigger-6776-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 3 [junit4] 2> 1743093 DEBUG (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:39557_solr, 127.0.0.1:37377_solr, 127.0.0.1:46145_solr] [junit4] 2> 1743093 DEBUG (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 3 [junit4] 2> 1743093 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@37c797a7{HTTP/1.1,[http/1.1]}{127.0.0.1:?} [junit4] 2> 1743093 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2004701913 [junit4] 2> 1743094 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 1743094 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@38477f6d: rootName = solr_39557, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@d19499b [junit4] 2> 1743098 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 1743098 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@160c74e7: rootName = solr_39557, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@66a4a7f1 [junit4] 2> 1743101 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 1743101 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3518f0ee: rootName = solr_39557, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@5b1f5c3b [junit4] 2> 1743102 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:39557_solr [junit4] 2> 1743103 INFO (zkCallback-4442-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 1743103 INFO (zkCallback-4419-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 1743103 INFO (zkCallback-4459-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 1743103 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 1743103 INFO (zkCallback-4473-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 1743103 INFO (zkCallback-4463-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 1743103 INFO (zkCallback-4436-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 1743103 INFO (zkCallback-4455-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 1743103 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1743104 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1100cae7{/solr,null,UNAVAILABLE} [junit4] 2> 1743104 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 1743104 DEBUG (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 2 [junit4] 2> 1743104 DEBUG (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.c.a.NodeLostTrigger Tracking lost node: 127.0.0.1:39557_solr [junit4] 2> 1743105 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: ????-??-??T??:??:??+01:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 1743109 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1743110 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1743110 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 1743113 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@577b68f2{/solr,null,AVAILABLE} [junit4] 2> 1743113 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3cb3bce{HTTP/1.1,[http/1.1]}{127.0.0.1:?????} [junit4] 2> 1743113 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.e.j.s.Server Started @???????ms [junit4] 2> 1743113 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=39557} [junit4] 2> 1743114 ERROR (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1743114 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1743114 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 1743114 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1743114 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1743114 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-26T15:26:24.186533Z [junit4] 2> 1743115 INFO (zkConnectionManagerCallback-4506-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1743116 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1743120 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35267/solr [junit4] 2> 1743121 INFO (zkConnectionManagerCallback-4510-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1743122 INFO (zkConnectionManagerCallback-4512-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1743125 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [n:127.0.0.1:39557_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1743125 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [n:127.0.0.1:39557_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1743126 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [n:127.0.0.1:39557_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39557_solr [junit4] 2> 1743127 INFO (zkCallback-4459-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1743127 INFO (zkCallback-4473-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1743127 INFO (zkCallback-4455-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1743127 INFO (zkCallback-4419-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1743127 INFO (zkCallback-4442-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1743127 INFO (zkCallback-4511-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1743246 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [n:127.0.0.1:39557_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39557.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5093e08a [junit4] 2> 1743253 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [n:127.0.0.1:39557_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39557.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5093e08a [junit4] 2> 1743253 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [n:127.0.0.1:39557_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39557.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5093e08a [junit4] 2> 1743254 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [n:127.0.0.1:39557_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.NodeLostTriggerTest_6D1BDB623344752E-001/tempDir-001/node1/. [junit4] 2> 1743256 INFO (zkConnectionManagerCallback-4517-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1743257 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [n:127.0.0.1:39557_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 1743257 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[6D1BDB623344752E]) [n:127.0.0.1:39557_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35267/solr ready [junit4] 2> 1743277 DEBUG (TEST-Node [...truncated too long message...] stHashQParserPlugin, RequestHandlersTest, TestSubQueryTransformerDistrib, SmileWriterTest, TestMaxScoreQueryParser, TestInPlaceUpdatesStandalone, TestNoOpRegenerator, BasicDistributedZkTest, AddSchemaFieldsUpdateProcessorFactoryTest, ChaosMonkeyNothingIsSafeTest, TestOmitPositions, StressHdfsTest, SynonymTokenizerTest, DocValuesNotIndexedTest, TestRandomRequestDistribution, TestLegacyField, TestPseudoReturnFields, SolrMetricManagerTest, RequestLoggingTest, TestTolerantUpdateProcessorCloud, TestJsonFacetsWithNestedObjects, TestExactStatsCache, MoveReplicaHDFSTest, PeerSyncWithIndexFingerprintCachingTest, EchoParamsTest, TemplateUpdateProcessorTest, SolrShardReporterTest, SuggesterWFSTTest, SimpleMLTQParserTest, TestMergePolicyConfig, LargeFieldTest, OpenExchangeRatesOrgProviderTest, TestJoin, TestWordDelimiterFilterFactory, HttpTriggerListenerTest, TestInitParams, TestNumericRangeQuery64, CurrencyFieldTypeTest, BasicAuthStandaloneTest, SolrCoreCheckLockOnStartupTest, StatsComponentTest, DirectUpdateHandlerTest, TestRawTransformer, SolrPluginUtilsTest, DistributedFacetPivotLargeTest, OutputWriterTest, MigrateRouteKeyTest, CdcrReplicationDistributedZkTest, QueryResultKeyTest, TestZkAclsWithHadoopAuth, TestCSVResponseWriter, ShowFileRequestHandlerTest, DistributedQueryElevationComponentTest, SolrIndexSplitterTest, AnalysisAfterCoreReloadTest, TestFoldingMultitermQuery, DocValuesTest, SuggesterTSTTest, TestStressLucene, TestTrie, SpatialFilterTest, NoCacheHeaderTest, WordBreakSolrSpellCheckerTest, TestUpdate, FieldMutatingUpdateProcessorTest, TestAtomicUpdateErrorCases, DirectUpdateHandlerOptimizeTest, DefaultValueUpdateProcessorTest, TestRemoteStreaming, DocValuesMultiTest, DebugComponentTest, IndexBasedSpellCheckerTest, TestSurroundQueryParser, LukeRequestHandlerTest, TestWriterPerf, DirectSolrSpellCheckerTest, TestQueryTypes, PrimitiveFieldTypeTest, FileBasedSpellCheckerTest, XmlUpdateRequestHandlerTest, DocumentBuilderTest, TestIndexingPerformance, MoreLikeThisHandlerTest, RequiredFieldsTest, FieldAnalysisRequestHandlerTest, TestSolrQueryParser, FastVectorHighlighterTest, IndexSchemaRuntimeFieldTest, LoggingHandlerTest, RegexBoostProcessorTest, TestJmxIntegration, TestCollationField, UpdateRequestProcessorFactoryTest, MBeansHandlerTest, TestAnalyzedSuggestions, UniqFieldsUpdateProcessorFactoryTest, JsonLoaderTest, TestPartialUpdateDeduplication, PingRequestHandlerTest, CSVRequestHandlerTest, TestComponentsName, SearchHandlerTest, HighlighterConfigTest, SOLR749Test, TestQuerySenderListener, TestSolrIndexConfig, TestQuerySenderNoQuery, CopyFieldTest, SolrIndexConfigTest, BadComponentTest, TestStressRecovery, TestBinaryField, MinimalSchemaTest, TestConfig, TestFuzzyAnalyzedSuggestions, ExternalFileFieldSortTest, TestPostingsSolrHighlighter, TestLuceneMatchVersion, TestPhraseSuggestions, TestCharFilters, TestXIncludeConfig, TestSweetSpotSimilarityFactory, TestDFRSimilarityFactory, TestLMDirichletSimilarityFactory, TestIBSimilarityFactory, TestFastWriter, ResourceLoaderTest, ScriptEngineTest, PluginInfoTest, PrimUtilsTest, ClusterStateTest, TestDocumentBuilder, SliceStateTest, UUIDFieldTest, CursorPagingTest, TestEmbeddedSolrServerConstructors, ConnectionReuseTest, AliasIntegrationTest, ConcurrentCreateRoutedAliasTest, DeleteInactiveReplicaTest, DeleteShardTest, DeleteStatusTest, DistribCursorPagingTest, LeaderElectionContextKeyTest, LeaderFailoverAfterPartitionTest, LeaderVoteWaitTimeoutTest, MoveReplicaHDFSFailoverTest, RestartWhileUpdatingTest, SolrCLIZkUtilsTest, TestAuthenticationFramework, TestCloudJSONFacetJoinDomain, TestCloudSearcherWarming, TestRandomFlRTGCloud, TestSSLRandomization, TestShortCircuitedRequests, TestSolrCloudWithKerberosAlt, TestTolerantUpdateProcessorRandomCloud, CollectionsAPIDistributedZkTest, TestReplicaProperties, AutoAddReplicasIntegrationTest, ComputePlanActionTest, NodeLostTriggerTest] [junit4] Completed [594/786 (1!)] on J0 in 14.16s, 4 tests, 1 failure <<< FAILURES! [...truncated 33803 lines...] BUILD FAILED /home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:633: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:122: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build.xml:108: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/tools/custom-tasks.xml:108: java.lang.IllegalArgumentException: named capturing group is missing trailing '}' at java.base/java.util.regex.Matcher.appendExpandedReplacement(Matcher.java:1052) at java.base/java.util.regex.Matcher.appendReplacement(Matcher.java:908) at org.apache.lucene.dependencies.InterpolatedProperties.interpolate(InterpolatedProperties.java:64) at org.apache.lucene.dependencies.InterpolatedProperties.load(InterpolatedProperties.java:50) at org.apache.lucene.validation.LibVersionsCheckTask.collectDirectDependencies(LibVersionsCheckTask.java:385) at org.apache.lucene.validation.LibVersionsCheckTask.execute(LibVersionsCheckTask.java:220) at org.apache.tools.ant.UnknownElement.execute(UnknownElement.java:291) at jdk.internal.reflect.GeneratedMethodAccessor4.invoke(Unknown Source) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at org.apache.tools.ant.dispatch.DispatchUtils.execute(DispatchUtils.java:106) at org.apache.tools.ant.Task.perform(Task.java:348) at org.apache.tools.ant.taskdefs.Sequential.execute(Sequential.java:68) at org.apache.tools.ant.UnknownElement.execute(UnknownElement.java:291) at jdk.internal.reflect.GeneratedMethodAccessor4.invoke(Unknown Source) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at org.apache.tools.ant.dispatch.DispatchUtils.execute(DispatchUtils.java:106) at org.apache.tools.ant.Task.perform(Task.java:348) at org.apache.tools.ant.taskdefs.MacroInstance.execute(MacroInstance.java:398) at org.apache.tools.ant.UnknownElement.execute(UnknownElement.java:291) at jdk.internal.reflect.GeneratedMethodAccessor4.invoke(Unknown Source) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at org.apache.tools.ant.dispatch.DispatchUtils.execute(DispatchUtils.java:106) at org.apache.tools.ant.Task.perform(Task.java:348) at org.apache.tools.ant.Target.execute(Target.java:390) at org.apache.tools.ant.Target.performTasks(Target.java:411) at org.apache.tools.ant.Project.executeSortedTargets(Project.java:1399) at org.apache.tools.ant.helper.SingleCheckExecutor.executeTargets(SingleCheckExecutor.java:38) at org.apache.tools.ant.Project.executeTargets(Project.java:1251) at org.apache.tools.ant.taskdefs.Ant.execute(Ant.java:442) at org.apache.tools.ant.taskdefs.SubAnt.execute(SubAnt.java:302) at org.apache.tools.ant.taskdefs.SubAnt.execute(SubAnt.java:221) at org.apache.tools.ant.UnknownElement.execute(UnknownElement.java:291) at jdk.internal.reflect.GeneratedMethodAccessor4.invoke(Unknown Source) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at org.apache.tools.ant.dispatch.DispatchUtils.execute(DispatchUtils.java:106) at org.apache.tools.ant.Task.perform(Task.java:348) at org.apache.tools.ant.Target.execute(Target.java:390) at org.apache.tools.ant.Target.performTasks(Target.java:411) at org.apache.tools.ant.Project.executeSortedTargets(Project.java:1399) at org.apache.tools.ant.helper.SingleCheckExecutor.executeTargets(SingleCheckExecutor.java:38) at org.apache.tools.ant.Project.executeTargets(Project.java:1251) at org.apache.tools.ant.taskdefs.Ant.execute(Ant.java:442) at org.apache.tools.ant.taskdefs.CallTarget.execute(CallTarget.java:105) at org.apache.tools.ant.UnknownElement.execute(UnknownElement.java:291) at jdk.internal.reflect.GeneratedMethodAccessor4.invoke(Unknown Source) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at org.apache.tools.ant.dispatch.DispatchUtils.execute(DispatchUtils.java:106) at org.apache.tools.ant.Task.perform(Task.java:348) at org.apache.tools.ant.Target.execute(Target.java:390) at org.apache.tools.ant.Target.performTasks(Target.java:411) at org.apache.tools.ant.Project.executeSortedTargets(Project.java:1399) at org.apache.tools.ant.Project.executeTarget(Project.java:1368) at org.apache.tools.ant.helper.DefaultExecutor.executeTargets(DefaultExecutor.java:41) at org.apache.tools.ant.Project.executeTargets(Project.java:1251) at org.apache.tools.ant.Main.runBuild(Main.java:809) at org.apache.tools.ant.Main.startAnt(Main.java:217) at org.apache.tools.ant.launch.Launcher.run(Launcher.java:280) at org.apache.tools.ant.launch.Launcher.main(Launcher.java:109) Total time: 68 minutes 50 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 [WARNINGS] Skipping publisher since build result is FAILURE Recording test results Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Email was triggered for: Failure - Any Sending email for trigger: Failure - Any 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