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