Build: https://builds.apache.org/job/Lucene-Solr-Tests-7.x/323/
2 tests failed. FAILED: org.apache.solr.cloud.autoscaling.NodeLostTriggerTest.testListenerAcceptance Error Message: expected:<1> but was:<0> Stack Trace: java.lang.AssertionError: expected:<1> but was:<0> at __randomizedtesting.SeedInfo.seed([9DD5E15780543BAE:8C61BBB039DD2F58]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.failNotEquals(Assert.java:647) at org.junit.Assert.assertEquals(Assert.java:128) at org.junit.Assert.assertEquals(Assert.java:472) at org.junit.Assert.assertEquals(Assert.java:456) at org.apache.solr.cloud.autoscaling.NodeLostTriggerTest.testListenerAcceptance(NodeLostTriggerTest.java:253) at 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) FAILED: org.apache.solr.client.solrj.io.stream.StreamExpressionTest.testDistributions Error Message: Stack Trace: java.lang.AssertionError at __randomizedtesting.SeedInfo.seed([858D41DF7385970:B7A795B729C2B9EC]:0) at org.junit.Assert.fail(Assert.java:92) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertTrue(Assert.java:54) at org.apache.solr.client.solrj.io.stream.StreamExpressionTest.testDistributions(StreamExpressionTest.java:6637) 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 13638 lines...] [junit4] Suite: org.apache.solr.cloud.autoscaling.NodeLostTriggerTest [junit4] 2> Creating dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.NodeLostTriggerTest_9DD5E15780543BAE-001/init-core-data-001 [junit4] 2> 3659186 WARN (SUITE-NodeLostTriggerTest-seed#[9DD5E15780543BAE]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=24 numCloses=24 [junit4] 2> 3659186 INFO (SUITE-NodeLostTriggerTest-seed#[9DD5E15780543BAE]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 3659187 INFO (SUITE-NodeLostTriggerTest-seed#[9DD5E15780543BAE]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN) [junit4] 2> 3659188 INFO (SUITE-NodeLostTriggerTest-seed#[9DD5E15780543BAE]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 3659188 INFO (SUITE-NodeLostTriggerTest-seed#[9DD5E15780543BAE]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 5 servers in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.NodeLostTriggerTest_9DD5E15780543BAE-001/tempDir-001 [junit4] 2> 3659188 INFO (SUITE-NodeLostTriggerTest-seed#[9DD5E15780543BAE]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 3659189 INFO (Thread-4254) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 3659189 INFO (Thread-4254) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 3659190 ERROR (Thread-4254) [ ] 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> 3659289 INFO (SUITE-NodeLostTriggerTest-seed#[9DD5E15780543BAE]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:35496 [junit4] 2> 3659290 INFO (zkConnectionManagerCallback-7134-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3659293 INFO (jetty-launcher-7131-thread-1) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T00:27:37+03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 3659293 INFO (jetty-launcher-7131-thread-2) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T00:27:37+03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 3659294 INFO (jetty-launcher-7131-thread-3) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T00:27:37+03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 3659294 INFO (jetty-launcher-7131-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 3659294 INFO (jetty-launcher-7131-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 3659294 INFO (jetty-launcher-7131-thread-1) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 3659294 INFO (jetty-launcher-7131-thread-4) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T00:27:37+03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 3659294 INFO (jetty-launcher-7131-thread-5) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T00:27:37+03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 3659294 INFO (jetty-launcher-7131-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 3659294 INFO (jetty-launcher-7131-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 3659294 INFO (jetty-launcher-7131-thread-2) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 3659294 INFO (jetty-launcher-7131-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@24b1f9ae{/solr,null,AVAILABLE} [junit4] 2> 3659294 INFO (jetty-launcher-7131-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 3659294 INFO (jetty-launcher-7131-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 3659295 INFO (jetty-launcher-7131-thread-3) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 3659295 INFO (jetty-launcher-7131-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@25385ae1{/solr,null,AVAILABLE} [junit4] 2> 3659295 INFO (jetty-launcher-7131-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6d8c087f{SSL,[ssl, http/1.1]}{127.0.0.1:38366} [junit4] 2> 3659295 INFO (jetty-launcher-7131-thread-1) [ ] o.e.j.s.Server Started @3668948ms [junit4] 2> 3659295 INFO (jetty-launcher-7131-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38366} [junit4] 2> 3659295 INFO (jetty-launcher-7131-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4576f23c{SSL,[ssl, http/1.1]}{127.0.0.1:36402} [junit4] 2> 3659295 ERROR (jetty-launcher-7131-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 3659295 INFO (jetty-launcher-7131-thread-3) [ ] o.e.j.s.Server Started @3668948ms [junit4] 2> 3659295 INFO (jetty-launcher-7131-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0 [junit4] 2> 3659295 INFO (jetty-launcher-7131-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=36402} [junit4] 2> 3659295 INFO (jetty-launcher-7131-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 3659295 INFO (jetty-launcher-7131-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 3659295 ERROR (jetty-launcher-7131-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 3659295 INFO (jetty-launcher-7131-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-01-20T20:07:40.201Z [junit4] 2> 3659296 INFO (jetty-launcher-7131-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0 [junit4] 2> 3659296 INFO (jetty-launcher-7131-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 3659296 INFO (jetty-launcher-7131-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 3659296 INFO (jetty-launcher-7131-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-01-20T20:07:40.202Z [junit4] 2> 3659296 INFO (jetty-launcher-7131-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@77af8ba3{/solr,null,AVAILABLE} [junit4] 2> 3659297 INFO (jetty-launcher-7131-thread-5) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 3659297 INFO (jetty-launcher-7131-thread-5) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 3659297 INFO (jetty-launcher-7131-thread-5) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 3659297 INFO (jetty-launcher-7131-thread-5) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7134da09{/solr,null,AVAILABLE} [junit4] 2> 3659297 INFO (jetty-launcher-7131-thread-5) [ ] o.e.j.s.AbstractConnector Started ServerConnector@248bba31{SSL,[ssl, http/1.1]}{127.0.0.1:42464} [junit4] 2> 3659297 INFO (jetty-launcher-7131-thread-5) [ ] o.e.j.s.Server Started @3668950ms [junit4] 2> 3659297 INFO (jetty-launcher-7131-thread-5) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42464} [junit4] 2> 3659297 ERROR (jetty-launcher-7131-thread-5) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 3659298 INFO (jetty-launcher-7131-thread-5) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0 [junit4] 2> 3659298 INFO (jetty-launcher-7131-thread-5) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 3659298 INFO (jetty-launcher-7131-thread-5) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 3659298 INFO (jetty-launcher-7131-thread-5) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-01-20T20:07:40.204Z [junit4] 2> 3659306 INFO (zkConnectionManagerCallback-7140-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3659306 INFO (jetty-launcher-7131-thread-5) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 3659367 INFO (jetty-launcher-7131-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@25c8b0aa{SSL,[ssl, http/1.1]}{127.0.0.1:44384} [junit4] 2> 3659367 INFO (jetty-launcher-7131-thread-2) [ ] o.e.j.s.Server Started @3669020ms [junit4] 2> 3659367 INFO (jetty-launcher-7131-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44384} [junit4] 2> 3659367 ERROR (jetty-launcher-7131-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 3659368 INFO (jetty-launcher-7131-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0 [junit4] 2> 3659368 INFO (jetty-launcher-7131-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 3659368 INFO (zkConnectionManagerCallback-7138-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3659368 INFO (jetty-launcher-7131-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 3659368 INFO (jetty-launcher-7131-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-01-20T20:07:40.274Z [junit4] 2> 3659368 INFO (jetty-launcher-7131-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 3659368 INFO (jetty-launcher-7131-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 3659369 INFO (jetty-launcher-7131-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 3659369 INFO (jetty-launcher-7131-thread-4) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 3659369 INFO (jetty-launcher-7131-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@78825b0c{/solr,null,AVAILABLE} [junit4] 2> 3659369 INFO (jetty-launcher-7131-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@118af13b{SSL,[ssl, http/1.1]}{127.0.0.1:36112} [junit4] 2> 3659370 INFO (jetty-launcher-7131-thread-4) [ ] o.e.j.s.Server Started @3669023ms [junit4] 2> 3659370 INFO (jetty-launcher-7131-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=36112} [junit4] 2> 3659370 ERROR (jetty-launcher-7131-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 3659370 INFO (jetty-launcher-7131-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0 [junit4] 2> 3659370 INFO (jetty-launcher-7131-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 3659370 INFO (jetty-launcher-7131-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 3659370 INFO (jetty-launcher-7131-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-01-20T20:07:40.276Z [junit4] 2> 3659371 INFO (zkConnectionManagerCallback-7142-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3659377 INFO (zkConnectionManagerCallback-7136-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3659377 INFO (jetty-launcher-7131-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 3659383 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x161152f0b3f0002, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> 3659383 INFO (jetty-launcher-7131-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 3659400 INFO (jetty-launcher-7131-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35496/solr [junit4] 2> 3659402 INFO (zkConnectionManagerCallback-7148-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3659403 INFO (zkConnectionManagerCallback-7144-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3659403 INFO (jetty-launcher-7131-thread-5) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35496/solr [junit4] 2> 3659404 INFO (jetty-launcher-7131-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35496/solr [junit4] 2> 3659405 INFO (jetty-launcher-7131-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 3659406 INFO (zkConnectionManagerCallback-7153-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3659419 INFO (jetty-launcher-7131-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35496/solr [junit4] 2> 3659420 INFO (zkConnectionManagerCallback-7156-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3659421 INFO (jetty-launcher-7131-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35496/solr [junit4] 2> 3659431 INFO (zkConnectionManagerCallback-7162-thread-1-processing-n:127.0.0.1:36402_solr) [n:127.0.0.1:36402_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3659448 INFO (zkConnectionManagerCallback-7160-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3659452 INFO (zkConnectionManagerCallback-7166-thread-1-processing-n:127.0.0.1:42464_solr) [n:127.0.0.1:42464_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3659452 INFO (zkConnectionManagerCallback-7168-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3659452 INFO (zkConnectionManagerCallback-7171-thread-1-processing-n:127.0.0.1:44384_solr) [n:127.0.0.1:44384_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3659453 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x161152f0b3f000c, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> 3659453 INFO (zkConnectionManagerCallback-7173-thread-1-processing-n:127.0.0.1:38366_solr) [n:127.0.0.1:38366_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3659454 INFO (zkConnectionManagerCallback-7175-thread-1-processing-n:127.0.0.1:36112_solr) [n:127.0.0.1:36112_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3659485 INFO (jetty-launcher-7131-thread-5) [n:127.0.0.1:42464_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 3659486 INFO (jetty-launcher-7131-thread-5) [n:127.0.0.1:42464_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:42464_solr [junit4] 2> 3659486 INFO (jetty-launcher-7131-thread-5) [n:127.0.0.1:42464_solr ] o.a.s.c.Overseer Overseer (id=99383958575185931-127.0.0.1:42464_solr-n_0000000000) starting [junit4] 2> 3659497 INFO (jetty-launcher-7131-thread-2) [n:127.0.0.1:44384_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 3659498 INFO (jetty-launcher-7131-thread-2) [n:127.0.0.1:44384_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44384_solr [junit4] 2> 3659499 INFO (zkCallback-7172-thread-1-processing-n:127.0.0.1:38366_solr) [n:127.0.0.1:38366_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 3659499 INFO (zkCallback-7165-thread-1-processing-n:127.0.0.1:42464_solr) [n:127.0.0.1:42464_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 3659500 INFO (jetty-launcher-7131-thread-1) [n:127.0.0.1:38366_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 3659529 INFO (zkCallback-7170-thread-1-processing-n:127.0.0.1:44384_solr) [n:127.0.0.1:44384_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 3659545 INFO (jetty-launcher-7131-thread-1) [n:127.0.0.1:38366_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38366_solr [junit4] 2> 3659554 INFO (jetty-launcher-7131-thread-5) [n:127.0.0.1:42464_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42464_solr [junit4] 2> 3659557 INFO (jetty-launcher-7131-thread-4) [n:127.0.0.1:36112_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 3659557 INFO (zkCallback-7170-thread-1-processing-n:127.0.0.1:44384_solr) [n:127.0.0.1:44384_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3) [junit4] 2> 3659557 INFO (zkCallback-7165-thread-1-processing-n:127.0.0.1:42464_solr) [n:127.0.0.1:42464_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3) [junit4] 2> 3659558 INFO (zkCallback-7172-thread-1-processing-n:127.0.0.1:38366_solr) [n:127.0.0.1:38366_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3) [junit4] 2> 3659558 INFO (jetty-launcher-7131-thread-4) [n:127.0.0.1:36112_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36112_solr [junit4] 2> 3659577 INFO (zkCallback-7165-thread-1-processing-n:127.0.0.1:42464_solr) [n:127.0.0.1:42464_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 3659589 INFO (zkCallback-7172-thread-1-processing-n:127.0.0.1:38366_solr) [n:127.0.0.1:38366_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 3659589 INFO (zkCallback-7170-thread-1-processing-n:127.0.0.1:44384_solr) [n:127.0.0.1:44384_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 3659630 INFO (zkCallback-7174-thread-1-processing-n:127.0.0.1:36112_solr) [n:127.0.0.1:36112_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 3659661 INFO (jetty-launcher-7131-thread-2) [n:127.0.0.1:44384_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44384.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a46acea [junit4] 2> 3659696 INFO (jetty-launcher-7131-thread-2) [n:127.0.0.1:44384_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44384.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a46acea [junit4] 2> 3659696 INFO (jetty-launcher-7131-thread-2) [n:127.0.0.1:44384_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44384.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a46acea [junit4] 2> 3659697 INFO (jetty-launcher-7131-thread-2) [n:127.0.0.1:44384_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.NodeLostTriggerTest_9DD5E15780543BAE-001/tempDir-001/node2/. [junit4] 2> 3659808 INFO (jetty-launcher-7131-thread-1) [n:127.0.0.1:38366_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38366.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a46acea [junit4] 2> 3659809 INFO (jetty-launcher-7131-thread-5) [n:127.0.0.1:42464_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42464.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a46acea [junit4] 2> 3659828 INFO (jetty-launcher-7131-thread-4) [n:127.0.0.1:36112_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36112.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a46acea [junit4] 2> 3659865 INFO (jetty-launcher-7131-thread-1) [n:127.0.0.1:38366_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38366.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a46acea [junit4] 2> 3659865 INFO (jetty-launcher-7131-thread-1) [n:127.0.0.1:38366_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38366.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a46acea [junit4] 2> 3659866 INFO (jetty-launcher-7131-thread-5) [n:127.0.0.1:42464_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42464.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a46acea [junit4] 2> 3659866 INFO (jetty-launcher-7131-thread-5) [n:127.0.0.1:42464_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42464.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a46acea [junit4] 2> 3659903 INFO (jetty-launcher-7131-thread-1) [n:127.0.0.1:38366_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.NodeLostTriggerTest_9DD5E15780543BAE-001/tempDir-001/node1/. [junit4] 2> 3659904 INFO (jetty-launcher-7131-thread-5) [n:127.0.0.1:42464_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.NodeLostTriggerTest_9DD5E15780543BAE-001/tempDir-001/node5/. [junit4] 2> 3659907 INFO (jetty-launcher-7131-thread-4) [n:127.0.0.1:36112_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36112.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a46acea [junit4] 2> 3659907 INFO (jetty-launcher-7131-thread-4) [n:127.0.0.1:36112_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36112.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a46acea [junit4] 2> 3659907 INFO (jetty-launcher-7131-thread-4) [n:127.0.0.1:36112_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.NodeLostTriggerTest_9DD5E15780543BAE-001/tempDir-001/node4/. [junit4] 2> 3659942 INFO (jetty-launcher-7131-thread-3) [n:127.0.0.1:36402_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 3659943 INFO (jetty-launcher-7131-thread-3) [n:127.0.0.1:36402_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 3659944 INFO (jetty-launcher-7131-thread-3) [n:127.0.0.1:36402_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36402_solr [junit4] 2> 3659945 INFO (zkCallback-7172-thread-1-processing-n:127.0.0.1:38366_solr) [n:127.0.0.1:38366_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 3659945 INFO (zkCallback-7165-thread-1-processing-n:127.0.0.1:42464_solr) [n:127.0.0.1:42464_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 3659955 INFO (zkCallback-7170-thread-1-processing-n:127.0.0.1:44384_solr) [n:127.0.0.1:44384_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 3659955 INFO (zkCallback-7161-thread-1-processing-n:127.0.0.1:36402_solr) [n:127.0.0.1:36402_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 3659958 INFO (zkCallback-7174-thread-1-processing-n:127.0.0.1:36112_solr) [n:127.0.0.1:36112_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 3660039 INFO (jetty-launcher-7131-thread-3) [n:127.0.0.1:36402_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36402.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a46acea [junit4] 2> 3660062 INFO (jetty-launcher-7131-thread-3) [n:127.0.0.1:36402_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36402.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a46acea [junit4] 2> 3660062 INFO (jetty-launcher-7131-thread-3) [n:127.0.0.1:36402_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36402.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a46acea [junit4] 2> 3660075 INFO (jetty-launcher-7131-thread-3) [n:127.0.0.1:36402_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.NodeLostTriggerTest_9DD5E15780543BAE-001/tempDir-001/node3/. [junit4] 2> 3660210 INFO (zkConnectionManagerCallback-7186-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3660211 INFO (zkConnectionManagerCallback-7190-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3660212 INFO (SUITE-NodeLostTriggerTest-seed#[9DD5E15780543BAE]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5) [junit4] 2> 3660212 INFO (SUITE-NodeLostTriggerTest-seed#[9DD5E15780543BAE]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35496/solr ready [junit4] 2> 3660304 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRestoreState [junit4] 2> 3660305 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T00:27:37+03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 3660305 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 3660306 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 3660306 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 3660306 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@56fe1649{/solr,null,AVAILABLE} [junit4] 2> 3660314 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3b9d4025{SSL,[ssl, http/1.1]}{127.0.0.1:41797} [junit4] 2> 3660314 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.Server Started @3669967ms [junit4] 2> 3660314 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=41797} [junit4] 2> 3660315 ERROR (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 3660315 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0 [junit4] 2> 3660315 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 3660315 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 3660315 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-01-20T20:07:41.221Z [junit4] 2> 3660316 INFO (zkConnectionManagerCallback-7192-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3660316 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 3660331 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35496/solr [junit4] 2> 3660331 INFO (zkConnectionManagerCallback-7196-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3660333 INFO (zkConnectionManagerCallback-7198-thread-1-processing-n:127.0.0.1:41797_solr) [n:127.0.0.1:41797_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3660336 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [n:127.0.0.1:41797_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5) [junit4] 2> 3660337 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [n:127.0.0.1:41797_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 3660339 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [n:127.0.0.1:41797_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41797_solr [junit4] 2> 3660339 INFO (zkCallback-7174-thread-1-processing-n:127.0.0.1:36112_solr) [n:127.0.0.1:36112_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 3660339 INFO (zkCallback-7170-thread-1-processing-n:127.0.0.1:44384_solr) [n:127.0.0.1:44384_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 3660339 INFO (zkCallback-7172-thread-1-processing-n:127.0.0.1:38366_solr) [n:127.0.0.1:38366_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 3660340 INFO (zkCallback-7165-thread-1-processing-n:127.0.0.1:42464_solr) [n:127.0.0.1:42464_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 3660340 INFO (zkCallback-7161-thread-1-processing-n:127.0.0.1:36402_solr) [n:127.0.0.1:36402_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 3660340 INFO (zkCallback-7189-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 3660353 INFO (zkCallback-7197-thread-1-processing-n:127.0.0.1:41797_solr) [n:127.0.0.1:41797_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 3660437 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [n:127.0.0.1:41797_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41797.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a46acea [junit4] 2> 3660454 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [n:127.0.0.1:41797_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41797.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a46acea [junit4] 2> 3660454 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [n:127.0.0.1:41797_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41797.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a46acea [junit4] 2> 3660455 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [n:127.0.0.1:41797_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.NodeLostTriggerTest_9DD5E15780543BAE-001/tempDir-001/node6/. [junit4] 2> 3660527 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@3b9d4025{SSL,[ssl, http/1.1]}{127.0.0.1:0} [junit4] 2> 3660527 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1520097024 [junit4] 2> 3660528 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 3660528 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2bb1f501: rootName = solr_41797, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@14d9e5c8 [junit4] 2> 3660533 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 3660533 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@53cc7de9: rootName = solr_41797, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1b7b93b7 [junit4] 2> 3660537 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 3660537 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6e70aec5: rootName = solr_41797, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@1de62806 [junit4] 2> 3660538 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:41797_solr [junit4] 2> 3660539 INFO (zkCallback-7174-thread-1-processing-n:127.0.0.1:36112_solr) [n:127.0.0.1:36112_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 3660539 INFO (zkCallback-7170-thread-1-processing-n:127.0.0.1:44384_solr) [n:127.0.0.1:44384_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 3660539 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 3660539 INFO (zkCallback-7165-thread-1-processing-n:127.0.0.1:42464_solr) [n:127.0.0.1:42464_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 3660539 INFO (zkCallback-7197-thread-1-processing-n:127.0.0.1:41797_solr) [n:127.0.0.1:41797_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 3660539 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 3660539 INFO (zkCallback-7161-thread-1-processing-n:127.0.0.1:36402_solr) [n:127.0.0.1:36402_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 3660539 INFO (zkCallback-7189-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 3660539 INFO (zkCallback-7172-thread-1-processing-n:127.0.0.1:38366_solr) [n:127.0.0.1:38366_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 3660540 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@56fe1649{/solr,null,UNAVAILABLE} [junit4] 2> 3660540 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 3665551 INFO (TEST-NodeLostTriggerTest.testRestoreState-seed#[9DD5E15780543BAE]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testRestoreState [junit4] 2> 3665697 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testListenerAcceptance [junit4] 2> 3665699 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T00:27:37+03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 3665699 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 3665699 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 3665699 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 3665700 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7f0a9142{/solr,null,AVAILABLE} [junit4] 2> 3665700 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3040a4a9{SSL,[ssl, http/1.1]}{127.0.0.1:39250} [junit4] 2> 3665700 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.Server Started @3675353ms [junit4] 2> 3665700 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=39250} [junit4] 2> 3665700 ERROR (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 3665700 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0 [junit4] 2> 3665700 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 3665701 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 3665701 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-01-20T20:07:46.607Z [junit4] 2> 3665702 INFO (zkConnectionManagerCallback-7202-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3665702 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 3665771 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35496/solr [junit4] 2> 3665772 INFO (zkConnectionManagerCallback-7206-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3665795 INFO (zkConnectionManagerCallback-7208-thread-1-processing-n:127.0.0.1:39250_solr) [n:127.0.0.1:39250_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3665816 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [n:127.0.0.1:39250_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5) [junit4] 2> 3665818 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [n:127.0.0.1:39250_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 3665827 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [n:127.0.0.1:39250_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39250_solr [junit4] 2> 3665828 INFO (zkCallback-7161-thread-1-processing-n:127.0.0.1:36402_solr) [n:127.0.0.1:36402_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 3665828 INFO (zkCallback-7189-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 3665829 INFO (zkCallback-7165-thread-1-processing-n:127.0.0.1:42464_solr) [n:127.0.0.1:42464_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 3665829 INFO (zkCallback-7172-thread-1-processing-n:127.0.0.1:38366_solr) [n:127.0.0.1:38366_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 3665829 INFO (zkCallback-7174-thread-1-processing-n:127.0.0.1:36112_solr) [n:127.0.0.1:36112_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 3665829 INFO (zkCallback-7170-thread-1-processing-n:127.0.0.1:44384_solr) [n:127.0.0.1:44384_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 3665847 INFO (zkCallback-7207-thread-1-processing-n:127.0.0.1:39250_solr) [n:127.0.0.1:39250_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 3666006 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [n:127.0.0.1:39250_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39250.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a46acea [junit4] 2> 3666043 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [n:127.0.0.1:39250_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39250.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a46acea [junit4] 2> 3666043 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [n:127.0.0.1:39250_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39250.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a46acea [junit4] 2> 3666045 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [n:127.0.0.1:39250_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.NodeLostTriggerTest_9DD5E15780543BAE-001/tempDir-001/node7/. [junit4] 2> 3666301 INFO (zkConnectionManagerCallback-7212-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3666302 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@3040a4a9{SSL,[ssl, http/1.1]}{127.0.0.1:0} [junit4] 2> 3666302 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1169408518 [junit4] 2> 3666302 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 3666302 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5300a059: rootName = solr_39250, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@7f61cba5 [junit4] 2> 3666320 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 3666320 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@35e0fd69: rootName = solr_39250, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1b7b93b7 [junit4] 2> 3666336 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 3666336 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@444b20ee: rootName = solr_39250, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@1de62806 [junit4] 2> 3666337 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:39250_solr [junit4] 2> 3666338 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 3666338 INFO (zkCallback-7207-thread-1-processing-n:127.0.0.1:39250_solr) [n:127.0.0.1:39250_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 3666338 INFO (zkCallback-7174-thread-1-processing-n:127.0.0.1:36112_solr) [n:127.0.0.1:36112_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 3666338 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 3666338 INFO (zkCallback-7165-thread-1-processing-n:127.0.0.1:42464_solr) [n:127.0.0.1:42464_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 3666339 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7f0a9142{/solr,null,UNAVAILABLE} [junit4] 2> 3666339 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 3666340 INFO (zkConnectionManagerCallback-7214-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3666341 INFO (TEST-NodeLostTriggerTest.testListenerAcceptance-seed#[9DD5E15780543BAE]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testListenerAcceptance [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=NodeLostTriggerTest -Dtests.method=testListenerAcceptance -Dtests.seed=9DD5E15780543BAE -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=ms -Dtests.timezone=Libya -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [junit4] FAILURE 0.79s J1 | NodeLostTriggerTest.testListenerAcceptance <<< [junit4] > Throwable #1: java.lang.AssertionError: expected:<1> but was:<0> [junit4] > at __randomizedtesting.SeedInfo.seed([9DD5E15780543BAE:8C61BBB039DD2F58]:0) [junit4] > at org.apache.solr.cloud.autoscaling.NodeLostTriggerTest.testListenerAcceptance(NodeLostTriggerTest.java:253) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] 2> 3666359 INFO (zkCallback-7170-thread-1-processing-n:127.0.0.1:44384_solr) [n:127.0.0.1:44384_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 3666359 INFO (zkCallback-7161-thread-1-processing-n:127.0.0.1:36402_solr) [n:127.0.0.1:36402_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 3666359 INFO (zkCallback-7189-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 3666359 INFO (zkCallback-7172-thread-1-processing-n:127.0.0.1:38366_solr) [n:127.0.0.1:38366_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5) [junit4] 2> 3666444 INFO (TEST-NodeLostTriggerTest.testActionLifecycle-seed#[9DD5E15780543BAE]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testActionLifecycle [junit4] 2> 3666445 INFO (TEST-NodeLostTriggerTest.testActionLifecycle-seed#[9DD5E15780543BAE]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testActionLifecycle [junit4] 2> 3666526 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testTrigger [junit4] 2> 3666527 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@118af13b{SSL,[ssl, http/1.1]}{127.0.0.1:0} [junit4] 2> 3666527 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=78428045 [junit4] 2> 3666527 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 3666527 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@b05745: rootName = solr_36112, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@52697f5b [junit4] 2> 3666541 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 3666541 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@60ca0389: rootName = solr_36112, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1b7b93b7 [junit4] 2> 3666545 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 3666545 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@557c3af7: rootName = solr_36112, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@1de62806 [junit4] 2> 3666546 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:36112_solr [junit4] 2> 3666547 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 3666547 INFO (zkCallback-7174-thread-1-processing-n:127.0.0.1:36112_solr) [n:127.0.0.1:36112_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4) [junit4] 2> 3666547 INFO (zkCallback-7170-thread-1-processing-n:127.0.0.1:44384_solr) [n:127.0.0.1:44384_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4) [junit4] 2> 3666547 INFO (zkCallback-7161-thread-1-processing-n:127.0.0.1:36402_solr) [n:127.0.0.1:36402_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4) [junit4] 2> 3666547 INFO (zkCallback-7172-thread-1-processing-n:127.0.0.1:38366_solr) [n:127.0.0.1:38366_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4) [junit4] 2> 3666547 INFO (zkCallback-7189-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4) [junit4] 2> 3666547 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 3666547 INFO (zkCallback-7165-thread-1-processing-n:127.0.0.1:42464_solr) [n:127.0.0.1:42464_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4) [junit4] 2> 3666548 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@78825b0c{/solr,null,UNAVAILABLE} [junit4] 2> 3666548 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 3666553 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@248bba31{SSL,[ssl, http/1.1]}{127.0.0.1:0} [junit4] 2> 3666554 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=390489604 [junit4] 2> 3666554 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 3666554 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5b3bbca2: rootName = solr_42464, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@22fa1df9 [junit4] 2> 3666563 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 3666563 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5355a88e: rootName = solr_42464, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1b7b93b7 [junit4] 2> 3666567 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 3666567 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@58c7efd: rootName = solr_42464, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@1de62806 [junit4] 2> 3666580 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:42464_solr [junit4] 2> 3666581 INFO (zkCallback-7172-thread-1-processing-n:127.0.0.1:38366_solr) [n:127.0.0.1:38366_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 3666581 INFO (zkCallback-7161-thread-1-processing-n:127.0.0.1:36402_solr) [n:127.0.0.1:36402_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 3666581 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 3666581 INFO (zkCallback-7165-thread-1-processing-n:127.0.0.1:42464_solr) [n:127.0.0.1:42464_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 3666582 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 3666582 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.c.Overseer Overseer (id=99383958575185931-127.0.0.1:42464_solr-n_0000000000) closing [junit4] 2> 3666582 INFO (OverseerStateUpdate-99383958575185931-127.0.0.1:42464_solr-n_0000000000) [n:127.0.0.1:42464_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:42464_solr [junit4] 2> 3666582 WARN (OverseerAutoScalingTriggerThread-99383958575185931-127.0.0.1:42464_solr-n_0000000000) [n:127.0.0.1:42464_solr ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 3666585 INFO (zkCallback-7170-thread-1-processing-n:127.0.0.1:44384_solr) [n:127.0.0.1:44384_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 3666586 INFO (zkCallback-7189-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 3666586 INFO (zkCallback-7170-thread-2-processing-n:127.0.0.1:44384_solr) [n:127.0.0.1:44384_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:44384_solr [junit4] 2> 3666587 INFO (zkCallback-7170-thread-2-processing-n:127.0.0.1:44384_solr) [n:127.0.0.1:44384_solr ] o.a.s.c.Overseer Overseer (id=99383958575185933-127.0.0.1:44384_solr-n_0000000001) starting [junit4] 2> 3668086 WARN (zkCallback-7165-thread-1-processing-n:127.0.0.1:42464_solr) [n:127.0.0.1:42464_solr ] o.a.s.c.ZkController Unable to register nodeLost path for 127.0.0.1:42464_solr [junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /autoscaling/nodeLost/127.0.0.1:42464_solr [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127) [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51) [junit4] 2> at org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java:783) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$create$7(SolrZkClient.java:364) [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.create(SolrZkClient.java:364) [junit4] 2> at org.apache.solr.cloud.ZkController.lambda$registerLiveNodesListener$2(ZkController.java:879) [junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.lambda$refreshLiveNodes$1(ZkStateReader.java:704) [junit4] 2> at java.util.concurrent.ConcurrentHashMap$KeySetView.forEach(ConcurrentHashMap.java:4649) [junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.refreshLiveNodes(ZkStateReader.java:703) [junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.access$900(ZkStateReader.java:72) [junit4] 2> at org.apache.solr.common.cloud.ZkStateReader$LiveNodeWatcher.refreshAndWatch(ZkStateReader.java:1090) [junit4] 2> at org.apache.solr.common.cloud.ZkStateReader$LiveNodeWatcher.process(ZkStateReader.java:1085) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$1.lambda$process$1(SolrZkClient.java:268) [junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> 3668090 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7134da09{/solr,null,UNAVAILABLE} [junit4] 2> 3668090 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 3673117 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@6d8c087f{SSL,[ssl, http/1.1]}{127.0.0.1:0} [junit4] 2> 3673118 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1338798192 [junit4] 2> 3673118 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 3673118 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3c54d05f: rootName = solr_38366, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@1b592dc3 [junit4] 2> 3673123 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 3673123 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@44e52b5e: rootName = solr_38366, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1b7b93b7 [junit4] 2> 3673176 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 3673176 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@618028b9: rootName = solr_38366, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@1de62806 [junit4] 2> 3673177 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:38366_solr [junit4] 2> 3673177 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 3673177 INFO (zkCallback-7161-thread-1-processing-n:127.0.0.1:36402_solr) [n:127.0.0.1:36402_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 3673177 INFO (zkCallback-7189-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 3673177 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 3673178 INFO (zkCallback-7170-thread-2-processing-n:127.0.0.1:44384_solr) [n:127.0.0.1:44384_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 3673178 INFO (zkCallback-7172-thread-1-processing-n:127.0.0.1:38366_solr) [n:127.0.0.1:38366_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 3673178 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@24b1f9ae{/solr,null,UNAVAILABLE} [junit4] 2> 3673179 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 3673180 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T00:27:37+03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 3673181 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 3673181 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 3673181 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 3673181 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@532c59df{/solr,null,AVAILABLE} [junit4] 2> 3673182 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@104ea67{SSL,[ssl, http/1.1]}{127.0.0.1:38366} [junit4] 2> 3673182 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.e.j.s.Server Started @3682835ms [junit4] 2> 3673182 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38366} [junit4] 2> 3673182 ERROR (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 3673182 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0 [junit4] 2> 3673182 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 3673182 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 3673182 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-01-20T20:07:54.088Z [junit4] 2> 3673183 INFO (zkConnectionManagerCallback-7216-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3673184 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 3673194 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35496/solr [junit4] 2> 3673217 INFO (zkConnectionManagerCallback-7220-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3673219 INFO (zkConnectionManagerCallback-7222-thread-1-processing-n:127.0.0.1:38366_solr) [n:127.0.0.1:38366_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3673222 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [n:127.0.0.1:38366_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 3673223 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [n:127.0.0.1:38366_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 3673224 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [n:127.0.0.1:38366_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38366_solr [junit4] 2> 3673225 INFO (zkCallback-7161-thread-1-processing-n:127.0.0.1:36402_solr) [n:127.0.0.1:36402_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 3673225 INFO (zkCallback-7170-thread-2-processing-n:127.0.0.1:44384_solr) [n:127.0.0.1:44384_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 3673225 INFO (zkCallback-7189-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 3673225 INFO (zkCallback-7221-thread-1-processing-n:127.0.0.1:38366_solr) [n:127.0.0.1:38366_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 3673291 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [n:127.0.0.1:38366_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38366.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a46acea [junit4] 2> 3673300 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [n:127.0.0.1:38366_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38366.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a46acea [junit4] 2> 3673300 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [n:127.0.0.1:38366_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38366.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a46acea [junit4] 2> 3673301 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [n:127.0.0.1:38366_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.NodeLostTriggerTest_9DD5E15780543BAE-001/tempDir-001/node1/. [junit4] 2> 3678340 INFO (TEST-NodeLostTriggerTest.testTrigger-seed#[9DD5E15780543BAE]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testTrigger [junit4] 2> 3678343 INFO (jetty-closer-7132-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@104ea67{SSL,[ssl, http/1.1]}{127.0.0.1:38366} [junit4] 2> 3678343 INFO (jetty-closer-7132-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@25c8b0aa{SSL,[ssl, http/1.1]}{127.0.0.1:0} [junit4] 2> 3678343 INFO (jetty-closer-7132-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@4576f23c{SSL,[ssl, http/1.1]}{127.0.0.1:0} [junit4] 2> 3678344 INFO (jetty-closer-7132-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=746668030 [junit4] 2> 3678350 INFO (jetty-closer-7132-thread-3) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2102868912 [junit4] 2> 3678343 INFO (jetty-closer-7132-thread-2) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=984763327 [junit4] 2> 3678350 INFO (jetty-closer-7132-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 3678350 INFO (jetty-closer-7132-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 3678350 INFO (jetty-closer-7132-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@716fb70e: rootName = solr_44384, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@5a9d44ef [junit4] 2> 3678350 INFO (jetty-closer-7132-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3308d4c: rootName = solr_38366, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@382275a0 [junit4] 2> 3678354 INFO (jetty-closer-7132-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 3678354 INFO (jetty-closer-7132-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@58e3361f: rootName = solr_44384, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1b7b93b7 [junit4] 2> 3678370 INFO (jetty-closer-7132-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 3678370 INFO (jetty-closer-7132-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@784a04fd: rootName = solr_38366, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1b7b93b7 [junit4] 2> 3678373 INFO (jetty-closer-7132-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 3678373 INFO (jetty-closer-7132-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5046a165: rootName = solr_44384, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@1de62806 [junit4] 2> 3678375 INFO (jetty-closer-7132-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:44384_solr [junit4] 2> 3678375 INFO (jetty-closer-7132-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 3678376 INFO (jetty-closer-7132-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 3678376 INFO (jetty-closer-7132-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@335723af: rootName = solr_36402, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@62bce920 [junit4] 2> 3678392 INFO (jetty-closer-7132-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 3678393 INFO (jetty-closer-7132-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@23c5daee: rootName = solr_36402, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1b7b93b7 [junit4] 2> 3678393 INFO (jetty-closer-7132-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 3678393 INFO (jetty-closer-7132-thread-1) [ ] o.a.s.c.Overseer Overseer (id=99383958575185933-127.0.0.1:44384_solr-n_0000000001) closing [junit4] 2> 3678393 INFO (OverseerStateUpdate-99383958575185933-127.0.0.1:44384_solr-n_0000000001) [n:127.0.0.1:44384_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:44384_solr [junit4] 2> 3678393 WARN (OverseerAutoScalingTriggerThread-99383958575185933-127.0.0.1:44384_solr-n_0000000001) [n:127.0.0.1:44384_solr ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 3678405 INFO (zkCallback-7170-thread-1-processing-n:127.0.0.1:44384_solr) [n:127.0.0.1:44384_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 3678405 INFO (zkCallback-7221-thread-1-processing-n:127.0.0.1:38366_solr) [n:127.0.0.1:38366_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 3678406 INFO (zkCallback-7161-thread-1-processing-n:127.0.0.1:36402_solr) [n:127.0.0.1:36402_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 3678407 INFO (jetty-closer-7132-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 3678407 INFO (jetty-closer-7132-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@42180e6e: rootName = solr_38366, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@1de62806 [junit4] 2> 3678408 INFO (jetty-closer-7132-thread-2) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:38366_solr [junit4] 2> 3678408 INFO (jetty-closer-7132-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 3678408 INFO (jetty-closer-7132-thread-2) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 3678410 INFO (jetty-closer-7132-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 3678410 INFO (jetty-closer-7132-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5e41d2e8: rootName = solr_36402, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@1de62806 [junit4] 2> 3678430 INFO (jetty-closer-7132-thread-3) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:36402_solr [junit4] 2> 3678430 INFO (jetty-closer-7132-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 3678430 INFO (jetty-closer-7132-thread-3) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 3678453 INFO (zkCallback-7161-thread-1-processing-n:127.0.0.1:36402_solr) [n:127.0.0.1:36402_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 3678453 INFO (jetty-closer-7132-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@77af8ba3{/solr,null,UNAVAILABLE} [junit4] 2> 3678465 INFO (zkCallback-7161-thread-2-processing-n:127.0.0.1:36402_solr) [n:127.0.0.1:36402_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:36402_solr [junit4] 2> 3678466 INFO (jetty-closer-7132-thread-1) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 3678466 INFO (jetty-closer-7132-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@25385ae1{/solr,null,UNAVAILABLE} [junit4] 2> 3678467 INFO (jetty-closer-7132-thread-3) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 3679929 WARN (zkCallback-7221-thread-1-processing-n:127.0.0.1:38366_solr) [n:127.0.0.1:38366_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 3679930 INFO (jetty-closer-7132-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@532c59df{/solr,null,UNAVAILABLE} [junit4] 2> 3679930 INFO (jetty-closer-7132-thread-2) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 3679930 ERROR (SUITE-NodeLostTriggerTest-seed#[9DD5E15780543BAE]-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> 3679931 INFO (SUITE-NodeLostTriggerTest-seed#[9DD5E15780543BAE]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:35496 35496 [junit4] 2> 3679984 INFO (Thread-4254) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:35496 35496 [junit4] 2> 3679984 WARN (Thread-4254) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 9 /solr/aliases.json [...truncated too long message...] ry solr.core.collection1.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@50f887c [junit4] 2> 492952 INFO (coreCloseExecutor-849-thread-1) [n:127.0.0.1:35379_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1.shard1.leader, tag=1576205090 [junit4] 2> 492968 INFO (coreCloseExecutor-850-thread-1) [n:127.0.0.1:34587_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.SolrCore [collection1_shard2_replica_n2] CLOSING SolrCore org.apache.solr.core.SolrCore@572058ac [junit4] 2> 492969 INFO (coreCloseExecutor-850-thread-1) [n:127.0.0.1:34587_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1.shard2.replica_n2, tag=1461737644 [junit4] 2> 492969 INFO (coreCloseExecutor-850-thread-1) [n:127.0.0.1:34587_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@63491833: rootName = solr_34587, domain = solr.core.collection1.shard2.replica_n2, service url = null, agent id = null] for registry solr.core.collection1.shard2.replica_n2 / com.codahale.metrics.MetricRegistry@c49e68c [junit4] 2> 492969 INFO (jetty-closer-402-thread-4) [ ] o.a.s.c.Overseer Overseer (id=99384004033970188-127.0.0.1:35379_solr-n_0000000000) closing [junit4] 2> 492970 INFO (OverseerStateUpdate-99384004033970188-127.0.0.1:35379_solr-n_0000000000) [n:127.0.0.1:35379_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:35379_solr [junit4] 2> 492970 WARN (OverseerAutoScalingTriggerThread-99384004033970188-127.0.0.1:35379_solr-n_0000000000) [n:127.0.0.1:35379_solr ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 492980 INFO (zkCallback-431-thread-3-processing-n:127.0.0.1:34587_solr) [n:127.0.0.1:34587_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:34587_solr [junit4] 2> 492988 INFO (coreCloseExecutor-850-thread-1) [n:127.0.0.1:34587_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1.shard2.leader, tag=1461737644 [junit4] 2> 492989 INFO (jetty-closer-402-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1433a77d{/solr,null,UNAVAILABLE} [junit4] 2> 492992 INFO (jetty-closer-402-thread-4) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 493005 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x1611539a0cd000a, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> 493005 INFO (jetty-closer-402-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@19d8d92b{/solr,null,UNAVAILABLE} [junit4] 2> 493005 INFO (jetty-closer-402-thread-3) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 493006 ERROR (SUITE-StreamExpressionTest-seed#[858D41DF7385970]-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> 493006 INFO (SUITE-StreamExpressionTest-seed#[858D41DF7385970]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:35631 35631 [junit4] 2> 493248 INFO (Thread-488) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:35631 35631 [junit4] 2> 493249 WARN (Thread-488) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 99 /solr/aliases.json [junit4] 2> 11 /solr/configs/conf [junit4] 2> 8 /solr/configs/ml [junit4] 2> 4 /solr/security.json [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 99 /solr/clusterprops.json [junit4] 2> 99 /solr/clusterstate.json [junit4] 2> 42 /solr/collections/destinationCollection/state.json [junit4] 2> 28 /solr/collections/parallelDestinationCollection1/state.json [junit4] 2> 22 /solr/collections/parallelDestinationCollection/state.json [junit4] 2> 14 /solr/collections/collection1/state.json [junit4] 2> 12 /solr/collections/workQueue1/state.json [junit4] 2> 10 /solr/collections/mainCorpus1/state.json [junit4] 2> 10 /solr/collections/destination/state.json [junit4] 2> 10 /solr/collections/destination1/state.json [junit4] 2> 10 /solr/collections/workQueue/state.json [junit4] 2> 10 /solr/collections/uknownCollection/state.json [junit4] 2> 10 /solr/collections/modelCollection/state.json [junit4] 2> 10 /solr/collections/checkpointCollection/state.json [junit4] 2> 10 /solr/collections/mainCorpus/state.json [junit4] 2> 2 /solr/overseer_elect/election/99384004033970187-127.0.0.1:40422_solr-n_0000000001 [junit4] 2> 2 /solr/overseer_elect/election/99384004033970188-127.0.0.1:35379_solr-n_0000000000 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 99 /solr/live_nodes [junit4] 2> 99 /solr/collections [junit4] 2> [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_858D41DF7385970-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {expr_s=PostingsFormat(name=Asserting), name_s=PostingsFormat(name=LuceneFixedGap), terms_ss=PostingsFormat(name=MockRandom), join2_s=PostingsFormat(name=MockRandom), field_s=PostingsFormat(name=Memory), multiDefault=PostingsFormat(name=MockRandom), subject=PostingsFormat(name=MockRandom), a1_s=PostingsFormat(name=MockRandom), a2_s=PostingsFormat(name=LuceneFixedGap), level1_s=PostingsFormat(name=Asserting), body_t=PostingsFormat(name=LuceneFixedGap), s_multi=PostingsFormat(name=LuceneFixedGap), a_ss=PostingsFormat(name=Memory), level2_s=PostingsFormat(name=Memory), col_s=PostingsFormat(name=MockRandom), a_s=PostingsFormat(name=LuceneFixedGap), tv_text=PostingsFormat(name=Memory), term_s=PostingsFormat(name=MockRandom), a_t=PostingsFormat(name=Memory), text_s=PostingsFormat(name=Asserting), id=PostingsFormat(name=Asserting), text=PostingsFormat(name=LuceneFixedGap), featureSet_s=PostingsFormat(name=MockRandom), checkpoint_ss=PostingsFormat(name=LuceneFixedGap), test_t=PostingsFormat(name=LuceneFixedGap), ident_s=PostingsFormat(name=MockRandom), whitetok=PostingsFormat(name=LuceneFixedGap), side_s=PostingsFormat(name=LuceneFixedGap)}, docValues:{expr_s=DocValuesFormat(name=Lucene70), name_s=DocValuesFormat(name=Lucene70), iteration_i=DocValuesFormat(name=Lucene70), terms_ss=DocValuesFormat(name=Direct), join2_s=DocValuesFormat(name=Direct), field_s=DocValuesFormat(name=Memory), multiDefault=DocValuesFormat(name=Direct), price_f=DocValuesFormat(name=Direct), a1_s=DocValuesFormat(name=Direct), intDefault=DocValuesFormat(name=Memory), a_f=DocValuesFormat(name=Direct), join1_i=DocValuesFormat(name=Lucene70), a2_s=DocValuesFormat(name=Lucene70), falseNegative_i=DocValuesFormat(name=Memory), a_i=DocValuesFormat(name=Lucene70), join3_i=DocValuesFormat(name=Lucene70), test_i=DocValuesFormat(name=Direct), falsePositive_i=DocValuesFormat(name=Memory), field_i=DocValuesFormat(name=Direct), level1_s=DocValuesFormat(name=Lucene70), s_multi=DocValuesFormat(name=Lucene70), a_ss=DocValuesFormat(name=Memory), level2_s=DocValuesFormat(name=Memory), col_s=DocValuesFormat(name=Direct), a_s=DocValuesFormat(name=Lucene70), score_f=DocValuesFormat(name=Lucene70), term_s=DocValuesFormat(name=Direct), text_s=DocValuesFormat(name=Lucene70), id=DocValuesFormat(name=Lucene70), timestamp=DocValuesFormat(name=Memory), i_multi=DocValuesFormat(name=Lucene70), featureSet_s=DocValuesFormat(name=Direct), trueNegative_i=DocValuesFormat(name=Lucene70), index_i=DocValuesFormat(name=Direct), b_ls=DocValuesFormat(name=Direct), test_dt=DocValuesFormat(name=Lucene70), out_i=DocValuesFormat(name=Direct), checkpoint_ss=DocValuesFormat(name=Lucene70), ident_s=DocValuesFormat(name=Direct), order_i=DocValuesFormat(name=Direct), _version_=DocValuesFormat(name=Memory), side_s=DocValuesFormat(name=Lucene70), truePositive_i=DocValuesFormat(name=Lucene70), miles_i=DocValuesFormat(name=Direct)}, maxPointsInLeafNode=509, maxMBSortInHeap=7.714132312339974, sim=RandomSimilarity(queryNorm=false): {}, locale=en-US, timezone=Australia/Adelaide [junit4] 2> NOTE: Linux 4.4.0-104-generic amd64/Oracle Corporation 1.8.0_152 (64-bit)/cpus=4,threads=1,free=212349512,total=457179136 [junit4] 2> NOTE: All tests run in this JVM: [NaturalLogEvaluatorTest, FieldValueEvaluatorTest, UsingSolrJRefGuideExamplesTest, TestPathTrie, RawValueEvaluatorTest, TestXMLEscaping, ReverseEvaluatorTest, TestClusteringResponse, CubedRootEvaluatorTest, FieldAnalysisResponseTest, LessThanEvaluatorTest, TestEmbeddedSolrServer, SolrExampleStreamingTest, LargeVolumeEmbeddedTest, GreaterThanEvaluatorTest, ContentStreamTest, GraphExpressionTest, SchemaTest, TestToleratedUpdateError, ConcurrentUpdateSolrClientBuilderTest, AscEvaluatorTest, SolrSchemalessExampleTest, GraphTest, JDBCStreamTest, StreamExpressionTest] [junit4] Completed [146/146 (1!)] on J0 in 227.52s, 151 tests, 1 failure <<< FAILURES! [...truncated 47225 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org