Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/414/ Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
2 tests failed. FAILED: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testCooldown Error Message: Stack Trace: java.lang.AssertionError at __randomizedtesting.SeedInfo.seed([A7318FFE63EC6385:968FE21A1D461677]:0) at org.junit.Assert.fail(Assert.java:92) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertTrue(Assert.java:54) at org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testCooldown(TriggerIntegrationTest.java:1177) 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([806090A8020763D1:3F9FD102DCFD834D]: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:6639) 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 13625 lines...] [junit4] Suite: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest [junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_A7318FFE63EC6385-001/init-core-data-001 [junit4] 2> 3839001 WARN (SUITE-TriggerIntegrationTest-seed#[A7318FFE63EC6385]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=21 numCloses=21 [junit4] 2> 3839002 INFO (SUITE-TriggerIntegrationTest-seed#[A7318FFE63EC6385]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 3839003 INFO (SUITE-TriggerIntegrationTest-seed#[A7318FFE63EC6385]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) [junit4] 2> 3839003 INFO (SUITE-TriggerIntegrationTest-seed#[A7318FFE63EC6385]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 3839003 INFO (SUITE-TriggerIntegrationTest-seed#[A7318FFE63EC6385]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_A7318FFE63EC6385-001/tempDir-001 [junit4] 2> 3839003 INFO (SUITE-TriggerIntegrationTest-seed#[A7318FFE63EC6385]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 3839004 INFO (Thread-7093) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 3839004 INFO (Thread-7093) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 3839007 ERROR (Thread-7093) [ ] 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> 3839104 INFO (SUITE-TriggerIntegrationTest-seed#[A7318FFE63EC6385]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:51178 [junit4] 2> 3839116 INFO (zkConnectionManagerCallback-6053-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3839130 INFO (jetty-launcher-6050-thread-1) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T05:27:37+08:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 3839135 INFO (jetty-launcher-6050-thread-2) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T05:27:37+08:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 3839138 INFO (jetty-launcher-6050-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 3839138 INFO (jetty-launcher-6050-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 3839138 INFO (jetty-launcher-6050-thread-1) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 3839139 INFO (jetty-launcher-6050-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@43c3e359{/solr,null,AVAILABLE} [junit4] 2> 3839142 INFO (jetty-launcher-6050-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 3839142 INFO (jetty-launcher-6050-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 3839142 INFO (jetty-launcher-6050-thread-2) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 3839142 INFO (jetty-launcher-6050-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3f7f67eb{HTTP/1.1,[http/1.1]}{127.0.0.1:62061} [junit4] 2> 3839142 INFO (jetty-launcher-6050-thread-1) [ ] o.e.j.s.Server Started @3846075ms [junit4] 2> 3839142 INFO (jetty-launcher-6050-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=62061} [junit4] 2> 3839142 ERROR (jetty-launcher-6050-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 3839143 INFO (jetty-launcher-6050-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@26e9c345{/solr,null,AVAILABLE} [junit4] 2> 3839143 INFO (jetty-launcher-6050-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0 [junit4] 2> 3839143 INFO (jetty-launcher-6050-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 3839143 INFO (jetty-launcher-6050-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 3839143 INFO (jetty-launcher-6050-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-01-26T19:03:39.074Z [junit4] 2> 3839143 INFO (jetty-launcher-6050-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@780ce69e{HTTP/1.1,[http/1.1]}{127.0.0.1:45555} [junit4] 2> 3839143 INFO (jetty-launcher-6050-thread-2) [ ] o.e.j.s.Server Started @3846077ms [junit4] 2> 3839144 INFO (jetty-launcher-6050-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45555} [junit4] 2> 3839144 ERROR (jetty-launcher-6050-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 3839144 INFO (jetty-launcher-6050-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0 [junit4] 2> 3839144 INFO (jetty-launcher-6050-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 3839144 INFO (jetty-launcher-6050-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 3839145 INFO (jetty-launcher-6050-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-01-26T19:03:39.076Z [junit4] 2> 3839147 INFO (zkConnectionManagerCallback-6055-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3839148 INFO (zkConnectionManagerCallback-6057-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3839149 INFO (jetty-launcher-6050-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 3839149 INFO (jetty-launcher-6050-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 3839178 INFO (jetty-launcher-6050-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51178/solr [junit4] 2> 3839178 INFO (jetty-launcher-6050-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51178/solr [junit4] 2> 3839179 INFO (zkConnectionManagerCallback-6063-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3839180 INFO (zkConnectionManagerCallback-6065-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3839182 INFO (zkConnectionManagerCallback-6067-thread-1-processing-n:127.0.0.1:62061_solr) [n:127.0.0.1:62061_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3839183 INFO (zkConnectionManagerCallback-6069-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3839308 INFO (jetty-launcher-6050-thread-2) [n:127.0.0.1:45555_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 3839308 INFO (jetty-launcher-6050-thread-2) [n:127.0.0.1:45555_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:45555_solr [junit4] 2> 3839310 INFO (jetty-launcher-6050-thread-2) [n:127.0.0.1:45555_solr ] o.a.s.c.Overseer Overseer (id=99417680703389702-127.0.0.1:45555_solr-n_0000000000) starting [junit4] 2> 3839314 INFO (jetty-launcher-6050-thread-1) [n:127.0.0.1:62061_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 3839316 INFO (jetty-launcher-6050-thread-1) [n:127.0.0.1:62061_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:62061_solr [junit4] 2> 3839319 INFO (zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 3839322 INFO (zkCallback-6066-thread-1-processing-n:127.0.0.1:62061_solr) [n:127.0.0.1:62061_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 3839325 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger [junit4] 2> 3839326 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1 [junit4] 2> 3839326 INFO (jetty-launcher-6050-thread-2) [n:127.0.0.1:45555_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45555_solr [junit4] 2> 3839326 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:62061_solr] [junit4] 2> 3839326 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion -1 [junit4] 2> 3839326 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1 [junit4] 2> 3839327 INFO (zkCallback-6066-thread-1-processing-n:127.0.0.1:62061_solr) [n:127.0.0.1:62061_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 3839328 INFO (zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 3839329 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 3839330 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1 [junit4] 2> 3839330 DEBUG (ScheduledTrigger-10134-thread-1) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 3839419 INFO (jetty-launcher-6050-thread-2) [n:127.0.0.1:45555_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45555.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861 [junit4] 2> 3839425 INFO (jetty-launcher-6050-thread-2) [n:127.0.0.1:45555_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45555.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861 [junit4] 2> 3839425 INFO (jetty-launcher-6050-thread-2) [n:127.0.0.1:45555_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45555.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861 [junit4] 2> 3839426 INFO (jetty-launcher-6050-thread-2) [n:127.0.0.1:45555_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_A7318FFE63EC6385-001/tempDir-001/node2/. [junit4] 2> 3839490 INFO (jetty-launcher-6050-thread-1) [n:127.0.0.1:62061_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62061.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861 [junit4] 2> 3839495 INFO (jetty-launcher-6050-thread-1) [n:127.0.0.1:62061_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62061.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861 [junit4] 2> 3839496 INFO (jetty-launcher-6050-thread-1) [n:127.0.0.1:62061_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62061.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861 [junit4] 2> 3839496 INFO (jetty-launcher-6050-thread-1) [n:127.0.0.1:62061_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_A7318FFE63EC6385-001/tempDir-001/node1/. [junit4] 2> 3839515 INFO (zkConnectionManagerCallback-6075-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3839519 INFO (zkConnectionManagerCallback-6079-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3839521 INFO (SUITE-TriggerIntegrationTest-seed#[A7318FFE63EC6385]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 3839522 INFO (SUITE-TriggerIntegrationTest-seed#[A7318FFE63EC6385]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:51178/solr ready [junit4] 2> 3839685 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[A7318FFE63EC6385]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testNodeLostTrigger [junit4] 2> 3839686 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[A7318FFE63EC6385]) [ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 2 [junit4] 2> 3839686 DEBUG (zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2 [junit4] 2> 3839686 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2 [junit4] 2> 3839692 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 3839693 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 3839693 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2 [junit4] 2> 3839696 INFO (qtp70527448-31078) [n:127.0.0.1:45555_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 3839707 INFO (qtp70527448-31078) [n:127.0.0.1:45555_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=11 [junit4] 2> 3839710 INFO (qtp70527448-31084) [n:127.0.0.1:45555_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3839712 INFO (qtp2037307113-31070) [n:127.0.0.1:62061_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3839712 DEBUG (qtp70527448-31081) [n:127.0.0.1:45555_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 3839713 INFO (qtp70527448-31081) [n:127.0.0.1:45555_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=4 [junit4] 2> 3839714 DEBUG (zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3 [junit4] 2> 3839717 DEBUG (zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:62061_solr, 127.0.0.1:45555_solr] [junit4] 2> 3839717 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3 [junit4] 2> 3839720 INFO (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init [junit4] 2> 3839724 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 3839724 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@3f7f67eb{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 3839727 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[A7318FFE63EC6385]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1982072115 [junit4] 2> 3839728 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[A7318FFE63EC6385]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 3839728 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[A7318FFE63EC6385]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@9646f38: rootName = solr_62061, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@1e84c4f7 [junit4] 2> 3839728 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3 [junit4] 2> 3839728 DEBUG (ScheduledTrigger-10134-thread-1) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 2 [junit4] 2> 3839734 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[A7318FFE63EC6385]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 3839734 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[A7318FFE63EC6385]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@8c5a00e: rootName = solr_62061, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@511c9d73 [junit4] 2> 3839738 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[A7318FFE63EC6385]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 3839738 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[A7318FFE63EC6385]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@47cc30f0: rootName = solr_62061, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@4b02a68 [junit4] 2> 3839741 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[A7318FFE63EC6385]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:62061_solr [junit4] 2> 3839742 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[A7318FFE63EC6385]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 3839743 INFO (zkCallback-6066-thread-1-processing-n:127.0.0.1:62061_solr) [n:127.0.0.1:62061_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 3839745 INFO (zkCallback-6078-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 3839745 INFO (zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 3839746 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[A7318FFE63EC6385]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 3839747 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@43c3e359{/solr,null,UNAVAILABLE} [junit4] 2> 3839750 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 3840740 DEBUG (ScheduledTrigger-10134-thread-2) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 1 [junit4] 2> 3840741 DEBUG (ScheduledTrigger-10134-thread-2) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeLostTrigger Tracking lost node: 127.0.0.1:62061_solr [junit4] 2> 3841705 INFO (OverseerCollectionConfigSetProcessor-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 3841745 DEBUG (ScheduledTrigger-10134-thread-2) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 1 [junit4] 2> 3842749 DEBUG (ScheduledTrigger-10134-thread-2) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 1 [junit4] 2> 3842749 DEBUG (ScheduledTrigger-10134-thread-2) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger firing registered processor for lost nodes: [127.0.0.1:62061_solr] [junit4] 2> 3842749 DEBUG (ScheduledTrigger-10134-thread-2) [n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { [junit4] 2> "id":"27815b487ef5bT1d5ts6h4h7mqkj92livzrfjss", [junit4] 2> "source":"node_lost_trigger", [junit4] 2> "eventTime":694984571875163, [junit4] 2> "eventType":"NODELOST", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[694984571875163], [junit4] 2> "nodeNames":["127.0.0.1:62061_solr"]}} [junit4] 2> 3842754 DEBUG (ScheduledTrigger-10134-thread-2) [n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: [node_lost_trigger] [junit4] 2> 3842755 INFO (ScheduledTrigger-10134-thread-2) [n:127.0.0.1:45555_solr ] o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling logging. [junit4] 2> 3842757 DEBUG (AutoscalingActionExecutor-10135-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions for { [junit4] 2> "id":"27815b487ef5bT1d5ts6h4h7mqkj92livzrfjss", [junit4] 2> "source":"node_lost_trigger", [junit4] 2> "eventTime":694984571875163, [junit4] 2> "eventType":"NODELOST", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[694984571875163], [junit4] 2> "_enqueue_time_":694986585374410, [junit4] 2> "nodeNames":["127.0.0.1:62061_solr"]}} [junit4] 2> 3842760 DEBUG (AutoscalingActionExecutor-10135-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_lost_trigger after 5000ms [junit4] 2> 3842760 DEBUG (AutoscalingActionExecutor-10135-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 3 ms for event id=27815b487ef5bT1d5ts6h4h7mqkj92livzrfjss [junit4] 2> 3842764 INFO (qtp70527448-31081) [n:127.0.0.1:45555_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3842764 DEBUG (qtp70527448-31083) [n:127.0.0.1:45555_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 3842765 INFO (qtp70527448-31083) [n:127.0.0.1:45555_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=5 [junit4] 2> 3842766 DEBUG (zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 4 [junit4] 2> 3842767 DEBUG (zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:45555_solr] [junit4] 2> 3842767 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 4 [junit4] 2> 3842768 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 3842769 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVersion 4 [junit4] 2> 3844776 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[A7318FFE63EC6385]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testNodeLostTrigger [junit4] 2> 3844928 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testListeners [junit4] 2> 3844928 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 5 [junit4] 2> 3844929 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T05:27:37+08:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 3844929 DEBUG (zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 5 [junit4] 2> 3844929 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 5 [junit4] 2> 3844930 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 3844930 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 3844930 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 3844930 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6ba697f9{/solr,null,AVAILABLE} [junit4] 2> 3844930 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4bc41d2e{HTTP/1.1,[http/1.1]}{127.0.0.1:62904} [junit4] 2> 3844930 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.Server Started @3851866ms [junit4] 2> 3844930 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=62904} [junit4] 2> 3844931 ERROR (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 3844931 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0 [junit4] 2> 3844931 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 3844931 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 3844931 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-01-26T19:03:44.862Z [junit4] 2> 3844932 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 3844933 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 3844933 INFO (zkConnectionManagerCallback-6081-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3844933 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5, lastZnodeVersion 5 [junit4] 2> 3844934 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 3844941 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51178/solr [junit4] 2> 3844943 INFO (zkConnectionManagerCallback-6085-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3844946 INFO (zkConnectionManagerCallback-6087-thread-1-processing-n:127.0.0.1:62904_solr) [n:127.0.0.1:62904_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3844958 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [n:127.0.0.1:62904_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 3844961 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [n:127.0.0.1:62904_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 3844964 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [n:127.0.0.1:62904_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:62904_solr [junit4] 2> 3844965 INFO (zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 3844965 INFO (zkCallback-6078-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 3844968 INFO (zkCallback-6086-thread-1-processing-n:127.0.0.1:62904_solr) [n:127.0.0.1:62904_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 3845055 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [n:127.0.0.1:62904_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62904.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861 [junit4] 2> 3845061 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [n:127.0.0.1:62904_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62904.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861 [junit4] 2> 3845061 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [n:127.0.0.1:62904_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62904.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861 [junit4] 2> 3845062 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [n:127.0.0.1:62904_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_A7318FFE63EC6385-001/tempDir-001/node3/. [junit4] 2> 3845086 INFO (qtp70527448-31083) [n:127.0.0.1:45555_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3845087 INFO (qtp1183140683-31137) [n:127.0.0.1:62904_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3845087 DEBUG (qtp70527448-31084) [n:127.0.0.1:45555_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 3845088 INFO (qtp70527448-31084) [n:127.0.0.1:45555_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=3 [junit4] 2> 3845089 DEBUG (zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 6 [junit4] 2> 3845089 DEBUG (zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:62904_solr, 127.0.0.1:45555_solr] [junit4] 2> 3845089 DEBUG (zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_triggerL instantiated with properties: {event=nodeAdded, waitFor=1, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}, {name=test1, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestDummyAction}], enabled=true} [junit4] 2> 3845089 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 6 [junit4] 2> 3845091 INFO (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init [junit4] 2> 3845092 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 3845092 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6, lastZnodeVersion 6 [junit4] 2> 3845093 DEBUG (ScheduledTrigger-10134-thread-3) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerL [junit4] 2> 3845093 DEBUG (ScheduledTrigger-10134-thread-3) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2 [junit4] 2> 3845095 INFO (qtp70527448-31084) [n:127.0.0.1:45555_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3845096 INFO (qtp1183140683-31140) [n:127.0.0.1:62904_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3845096 DEBUG (qtp1183140683-31142) [n:127.0.0.1:62904_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 3845097 INFO (qtp1183140683-31142) [n:127.0.0.1:62904_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=3 [junit4] 2> 3845097 DEBUG (zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 7 [junit4] 2> 3845098 DEBUG (zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:62904_solr, 127.0.0.1:45555_solr] [junit4] 2> 3845098 DEBUG (zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_triggerL instantiated with properties: {event=nodeAdded, waitFor=1, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}, {name=test1, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestDummyAction}], enabled=true} [junit4] 2> 3845098 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 7 [junit4] 2> 3845098 INFO (qtp70527448-31084) [n:127.0.0.1:45555_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3845098 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 3845099 INFO (qtp1183140683-31139) [n:127.0.0.1:62904_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3845099 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 7, lastZnodeVersion 7 [junit4] 2> 3845099 DEBUG (qtp70527448-31081) [n:127.0.0.1:45555_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 3845099 INFO (qtp70527448-31081) [n:127.0.0.1:45555_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=2 [junit4] 2> 3845100 DEBUG (zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 8 [junit4] 2> 3845100 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T05:27:37+08:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 3845100 DEBUG (zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:62904_solr, 127.0.0.1:45555_solr] [junit4] 2> 3845100 DEBUG (zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_triggerL instantiated with properties: {event=nodeAdded, waitFor=1, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}, {name=test1, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestDummyAction}], enabled=true} [junit4] 2> 3845100 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 8 [junit4] 2> 3845101 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 3845101 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 8, lastZnodeVersion 8 [junit4] 2> 3845101 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 3845101 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 3845101 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 3845101 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6bf8b020{/solr,null,AVAILABLE} [junit4] 2> 3845102 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@602e4338{HTTP/1.1,[http/1.1]}{127.0.0.1:34963} [junit4] 2> 3845102 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.Server Started @3852037ms [junit4] 2> 3845102 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34963} [junit4] 2> 3845102 ERROR (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 3845102 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0 [junit4] 2> 3845102 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 3845102 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 3845102 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-01-26T19:03:45.033Z [junit4] 2> 3845104 INFO (zkConnectionManagerCallback-6091-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3845104 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 3845110 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51178/solr [junit4] 2> 3845111 INFO (zkConnectionManagerCallback-6095-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3845113 INFO (zkConnectionManagerCallback-6097-thread-1-processing-n:127.0.0.1:34963_solr) [n:127.0.0.1:34963_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3845123 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [n:127.0.0.1:34963_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 3845125 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [n:127.0.0.1:34963_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 3845131 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [n:127.0.0.1:34963_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34963_solr [junit4] 2> 3845132 INFO (zkCallback-6086-thread-1-processing-n:127.0.0.1:62904_solr) [n:127.0.0.1:62904_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 3845132 INFO (zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 3845133 INFO (zkCallback-6078-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 3845136 INFO (zkCallback-6096-thread-1-processing-n:127.0.0.1:34963_solr) [n:127.0.0.1:34963_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 3845197 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [n:127.0.0.1:34963_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34963.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861 [junit4] 2> 3845204 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [n:127.0.0.1:34963_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34963.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861 [junit4] 2> 3845204 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [n:127.0.0.1:34963_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34963.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861 [junit4] 2> 3845205 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [n:127.0.0.1:34963_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_A7318FFE63EC6385-001/tempDir-001/node4/. [junit4] 2> 3846095 DEBUG (ScheduledTrigger-10134-thread-3) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerL [junit4] 2> 3846095 DEBUG (ScheduledTrigger-10134-thread-3) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3 [junit4] 2> 3846095 DEBUG (ScheduledTrigger-10134-thread-3) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:34963_solr at time 694989927416308 [junit4] 2> 3847098 DEBUG (ScheduledTrigger-10134-thread-3) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerL [junit4] 2> 3847098 DEBUG (ScheduledTrigger-10134-thread-3) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3 [junit4] 2> 3847098 DEBUG (ScheduledTrigger-10134-thread-3) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_triggerL firing registered processor for nodes: [127.0.0.1:34963_solr] added at times [694989927416308], now=694990930852309 [junit4] 2> 3847098 DEBUG (ScheduledTrigger-10134-thread-3) [n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { [junit4] 2> "id":"27816f3bf01f4T1d5ts6h4h7mqkj92livzrfjsu", [junit4] 2> "source":"node_added_triggerL", [junit4] 2> "eventTime":694989927416308, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[694989927416308], [junit4] 2> "nodeNames":["127.0.0.1:34963_solr"]}} [junit4] 2> 3847098 DEBUG (ScheduledTrigger-10134-thread-3) [n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: [node_added_triggerL] [junit4] 2> 3847100 INFO (ScheduledTrigger-10134-thread-3) [n:127.0.0.1:45555_solr ] o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling logging. [junit4] 2> 3847102 DEBUG (AutoscalingActionExecutor-10135-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions for { [junit4] 2> "id":"27816f3bf01f4T1d5ts6h4h7mqkj92livzrfjsu", [junit4] 2> "source":"node_added_triggerL", [junit4] 2> "eventTime":694989927416308, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[694989927416308], [junit4] 2> "_enqueue_time_":694990931313355, [junit4] 2> "nodeNames":["127.0.0.1:34963_solr"]}} [junit4] 2> 3847109 DEBUG (AutoscalingActionExecutor-10135-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_triggerL after 5000ms [junit4] 2> 3847109 DEBUG (AutoscalingActionExecutor-10135-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 7 ms for event id=27816f3bf01f4T1d5ts6h4h7mqkj92livzrfjsu [junit4] 2> 3849114 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T05:27:37+08:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 3849116 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 3849116 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 3849116 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 3849116 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@b7bec2c{/solr,null,AVAILABLE} [junit4] 2> 3849117 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@77b415c7{HTTP/1.1,[http/1.1]}{127.0.0.1:47782} [junit4] 2> 3849117 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.Server Started @3856054ms [junit4] 2> 3849117 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=47782} [junit4] 2> 3849118 ERROR (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 3849118 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0 [junit4] 2> 3849118 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 3849118 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 3849119 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-01-26T19:03:49.049Z [junit4] 2> 3849123 INFO (zkConnectionManagerCallback-6101-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3849124 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 3849139 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51178/solr [junit4] 2> 3849143 INFO (zkConnectionManagerCallback-6105-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3849149 INFO (zkConnectionManagerCallback-6107-thread-1-processing-n:127.0.0.1:47782_solr) [n:127.0.0.1:47782_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3849174 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [n:127.0.0.1:47782_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 3849179 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [n:127.0.0.1:47782_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 3849182 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [n:127.0.0.1:47782_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:47782_solr [junit4] 2> 3849184 INFO (zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 3849185 INFO (zkCallback-6096-thread-1-processing-n:127.0.0.1:34963_solr) [n:127.0.0.1:34963_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 3849185 INFO (zkCallback-6078-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 3849185 INFO (zkCallback-6086-thread-1-processing-n:127.0.0.1:62904_solr) [n:127.0.0.1:62904_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 3849188 INFO (zkCallback-6106-thread-1-processing-n:127.0.0.1:47782_solr) [n:127.0.0.1:47782_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 3849242 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [n:127.0.0.1:47782_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_47782.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861 [junit4] 2> 3849248 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [n:127.0.0.1:47782_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_47782.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861 [junit4] 2> 3849248 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [n:127.0.0.1:47782_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_47782.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861 [junit4] 2> 3849249 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [n:127.0.0.1:47782_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_A7318FFE63EC6385-001/tempDir-001/node5/. [junit4] 2> 3852113 DEBUG (ScheduledTrigger-10134-thread-3) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerL [junit4] 2> 3852113 DEBUG (ScheduledTrigger-10134-thread-3) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 4 [junit4] 2> 3852113 DEBUG (ScheduledTrigger-10134-thread-3) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:47782_solr at time 694995947762038 [junit4] 2> 3853117 DEBUG (ScheduledTrigger-10134-thread-3) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerL [junit4] 2> 3853117 DEBUG (ScheduledTrigger-10134-thread-3) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 4 [junit4] 2> 3853117 DEBUG (ScheduledTrigger-10134-thread-3) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_triggerL firing registered processor for nodes: [127.0.0.1:47782_solr] added at times [694995947762038], now=694996951622823 [junit4] 2> 3853117 DEBUG (ScheduledTrigger-10134-thread-3) [n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { [junit4] 2> "id":"278185a963176T1d5ts6h4h7mqkj92livzrfjsw", [junit4] 2> "source":"node_added_triggerL", [junit4] 2> "eventTime":694995947762038, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[694995947762038], [junit4] 2> "nodeNames":["127.0.0.1:47782_solr"]}} [junit4] 2> 3853117 DEBUG (ScheduledTrigger-10134-thread-3) [n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: [node_added_triggerL] [junit4] 2> 3853119 DEBUG (AutoscalingActionExecutor-10135-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions for { [junit4] 2> "id":"278185a963176T1d5ts6h4h7mqkj92livzrfjsw", [junit4] 2> "source":"node_added_triggerL", [junit4] 2> "eventTime":694995947762038, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[694995947762038], [junit4] 2> "_enqueue_time_":694996951971200, [junit4] 2> "nodeNames":["127.0.0.1:47782_solr"]}} [junit4] 2> 3853119 WARN (AutoscalingActionExecutor-10135-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers Exception executing actions [junit4] 2> java.lang.Exception: Error executing action: test1 for trigger event: { [junit4] 2> "id":"278185a963176T1d5ts6h4h7mqkj92livzrfjsw", [junit4] 2> "source":"node_added_triggerL", [junit4] 2> "eventTime":694995947762038, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[694995947762038], [junit4] 2> "_enqueue_time_":694996951971200, [junit4] 2> "nodeNames":["127.0.0.1:47782_solr"]}} [junit4] 2> at org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:303) [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> Caused by: java.lang.RuntimeException: failure [junit4] 2> at org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestDummyAction.process(TriggerIntegrationTest.java:989) [junit4] 2> at org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:300) [junit4] 2> ... 6 more [junit4] 2> 3853120 DEBUG (AutoscalingActionExecutor-10135-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_triggerL after 5000ms [junit4] 2> 3853120 DEBUG (AutoscalingActionExecutor-10135-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 1 ms for event id=278185a963176T1d5ts6h4h7mqkj92livzrfjsw [junit4] 2> 3855120 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testListeners [junit4] 2> 3855423 INFO (TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testNodeAddedTriggerRestoreState [junit4] 2> 3855424 INFO (TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385]) [ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 9 [junit4] 2> 3855425 DEBUG (zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 9 [junit4] 2> 3855425 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 9 [junit4] 2> 3855430 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 3855431 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 3855431 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 9, lastZnodeVersion 9 [junit4] 2> 3855434 INFO (qtp1890717126-31166) [n:127.0.0.1:34963_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3855435 INFO (qtp70527448-31081) [n:127.0.0.1:45555_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3855437 INFO (qtp567234657-31186) [n:127.0.0.1:47782_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3855438 INFO (qtp1183140683-31139) [n:127.0.0.1:62904_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3855438 DEBUG (qtp1890717126-31162) [n:127.0.0.1:34963_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 3855439 INFO (qtp1890717126-31162) [n:127.0.0.1:34963_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=6 [junit4] 2> 3855439 DEBUG (zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 10 [junit4] 2> 3855440 INFO (TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T05:27:37+08:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 3855440 DEBUG (zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:62904_solr, 127.0.0.1:34963_solr, 127.0.0.1:47782_solr, 127.0.0.1:45555_solr] [junit4] 2> 3855440 DEBUG (zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_restore_trigger instantiated with properties: {event=nodeAdded, waitFor=5, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}], enabled=true} [junit4] 2> 3855440 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 10 [junit4] 2> 3855441 INFO (TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 3855441 INFO (TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 3855441 INFO (TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 3855442 INFO (TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7c282908{/solr,null,AVAILABLE} [junit4] 2> 3855442 INFO (TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@451dd20f{HTTP/1.1,[http/1.1]}{127.0.0.1:33215} [junit4] 2> 3855442 INFO (TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.Server Started @3862380ms [junit4] 2> 3855442 INFO (TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33215} [junit4] 2> 3855442 ERROR (TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 3855443 INFO (TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0 [junit4] 2> 3855443 INFO (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init [junit4] 2> 3855443 INFO (TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 3855443 INFO (TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 3855443 INFO (TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-01-26T19:03:55.374Z [junit4] 2> 3855443 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 3855444 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 10, lastZnodeVersion 10 [junit4] 2> 3855445 DEBUG (ScheduledTrigger-10134-thread-4) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_restore_trigger [junit4] 2> 3855445 DEBUG (ScheduledTrigger-10134-thread-4) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 4 [junit4] 2> 3855445 INFO (zkConnectionManagerCallback-6111-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3855446 INFO (TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 3855452 INFO (TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51178/solr [junit4] 2> 3855454 INFO (zkConnectionManagerCallback-6115-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3855457 INFO (zkConnectionManagerCallback-6117-thread-1-processing-n:127.0.0.1:33215_solr) [n:127.0.0.1:33215_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3855467 INFO (TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385]) [n:127.0.0.1:33215_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 3855470 INFO (TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385]) [n:127.0.0.1:33215_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 3855473 INFO (TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385]) [n:127.0.0.1:33215_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33215_solr [junit4] 2> 3855475 INFO (zkCallback-6106-thread-1-processing-n:127.0.0.1:47782_solr) [n:127.0.0.1:47782_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 3855475 INFO (zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 3855475 INFO (zkCallback-6096-thread-1-processing-n:127.0.0.1:34963_solr) [n:127.0.0.1:34963_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 3855475 INFO (zkCallback-6078-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 3855475 INFO (zkCallback-6086-thread-1-processing-n:127.0.0.1:62904_solr) [n:127.0.0.1:62904_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 3855478 INFO (zkCallback-6116-thread-1-processing-n:127.0.0.1:33215_solr) [n:127.0.0.1:33215_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 3855550 INFO (TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385]) [n:127.0.0.1:33215_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33215.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861 [junit4] 2> 3855556 INFO (TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385]) [n:127.0.0.1:33215_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33215.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861 [junit4] 2> 3855556 INFO (TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385]) [n:127.0.0.1:33215_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33215.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861 [junit4] 2> 3855557 INFO (TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385]) [n:127.0.0.1:33215_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_A7318FFE63EC6385-001/tempDir-001/node6/. [junit4] 2> 3856447 DEBUG (ScheduledTrigger-10134-thread-4) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_restore_trigger [junit4] 2> 3856447 DEBUG (ScheduledTrigger-10134-thread-4) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 5 [junit4] 2> 3856447 DEBUG (ScheduledTrigger-10134-thread-4) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:33215_solr at time 695000282715259 [junit4] 2> 3857102 INFO (qtp284080786-31212) [n:127.0.0.1:33215_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3857103 INFO (qtp1890717126-31166) [n:127.0.0.1:34963_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3857104 INFO (qtp70527448-31083) [n:127.0.0.1:45555_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3857106 INFO (qtp567234657-31188) [n:127.0.0.1:47782_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3857107 INFO (qtp1183140683-31142) [n:127.0.0.1:62904_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 3857107 DEBUG (qtp1890717126-31164) [n:127.0.0.1:34963_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 3857109 INFO (qtp1890717126-31164) [n:127.0.0.1:34963_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=11 [junit4] 2> 3857110 DEBUG (zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 11 [junit4] 2> 3857111 DEBUG (zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:33215_solr, 127.0.0.1:62904_solr, 127.0.0.1:34963_solr, 127.0.0.1:47782_solr, 127.0.0.1:45555_solr] [junit4] 2> 3857111 DEBUG (zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_restore_trigger instantiated with properties: {event=nodeAdded, waitFor=0, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}], enabled=true} [junit4] 2> 3857111 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 11 [junit4] 2> 3857117 INFO (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init [junit4] 2> 3857119 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Adding node from marker path: 127.0.0.1:33215_solr [junit4] 2> 3857121 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 3857121 DEBUG (ScheduledTrigger-10134-thread-3) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_restore_trigger [junit4] 2> 3857121 DEBUG (ScheduledTrigger-10134-thread-3) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 5 [junit4] 2> 3857121 DEBUG (ScheduledTrigger-10134-thread-3) [n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_restore_trigger firing registered processor for nodes: [127.0.0.1:33215_solr] added at times [695000954641981], now=695000957165488 [junit4] 2> 3857121 DEBUG (ScheduledTrigger-10134-thread-3) [n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { [junit4] 2> "id":"2781985051e3dT1d5ts6h4h7mqkj92livzrfjsx", [junit4] 2> "source":"node_added_restore_trigger", [junit4] 2> "eventTime":695000954641981, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[695000954641981], [junit4] 2> "nodeNames":["127.0.0.1:33215_solr"]}} [junit4] 2> 3857122 DEBUG (ScheduledTrigger-10134-thread-3) [n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: [node_added_restore_trigger] [junit4] 2> 3857122 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 11, lastZnodeVersion 11 [junit4] 2> 3857123 INFO (ScheduledTrigger-10134-thread-3) [n:127.0.0.1:45555_solr ] o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling logging. [junit4] 2> 3857123 DEBUG (AutoscalingActionExecutor-10135-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions for { [junit4] 2> "id":"2781985051e3dT1d5ts6h4h7mqkj92livzrfjsx", [junit4] 2> "source":"node_added_restore_trigger", [junit4] 2> "eventTime":695000954641981, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[695000954641981], [junit4] 2> "_enqueue_time_":695000957639345, [junit4] 2> "nodeNames":["127.0.0.1:33215_solr"]}} [junit4] 2> 3857124 INFO (TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testNodeAddedTriggerRestoreState [junit4] 2> 3857145 DEBUG (AutoscalingActionExecutor-10135-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_restore_trigger after 5000ms [junit4] 2> 3857150 DEBUG (AutoscalingActionExecutor-10135-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 27 ms for event id=2781985051e3dT1d5ts6h4h7mqkj92livzrfjsx [junit4] 2> 3857495 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[A7318FFE63EC6385]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testNodeMarkersRegistration [junit4] 2> 3857496 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[A7318FFE63EC6385]) [ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 12 [junit4] 2> 3857496 DEBUG (zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 12 [junit4] 2> 3857496 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 12 [junit4] 2> 3857501 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 3857502 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 3857502 DEBUG (OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000) [n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 12, lastZnodeVersion 12 [junit4] 2> 3857502 INFO (qtp284080786-31216) [n:127.0.0.1:33215_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 3857511 INFO (qtp284080786-31216) [n:127.0.0.1:33215_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=8 [junit4] 2> 3857512 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T05:27:37+08:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 3857513 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 3857513 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 3857513 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[A7318FFE63EC6385]) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 3857513 INFO (TEST-TriggerIntegrationTest.testN [...truncated too long message...] nager Closing metric reporters for registry=solr.core.collection1.shard2.replica_n2, tag=926609318 [junit4] 2> 256424 INFO (coreCloseExecutor-1525-thread-1) [n:127.0.0.1:43530_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@cc7e1: rootName = solr_43530, 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@75fbf211 [junit4] 2> 256431 INFO (jetty-closer-1093-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@245dbb7e{/solr,null,UNAVAILABLE} [junit4] 2> 256433 INFO (jetty-closer-1093-thread-3) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 256440 INFO (coreCloseExecutor-1525-thread-1) [n:127.0.0.1:43530_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=926609318 [junit4] 2> 256444 INFO (jetty-closer-1093-thread-4) [ ] o.a.s.c.Overseer Overseer (id=99417744460742666-127.0.0.1:43530_solr-n_0000000000) closing [junit4] 2> 256444 INFO (OverseerStateUpdate-99417744460742666-127.0.0.1:43530_solr-n_0000000000) [n:127.0.0.1:43530_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:43530_solr [junit4] 2> 256447 WARN (OverseerAutoScalingTriggerThread-99417744460742666-127.0.0.1:43530_solr-n_0000000000) [n:127.0.0.1:43530_solr ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 256454 INFO (jetty-closer-1093-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7058845{/solr,null,UNAVAILABLE} [junit4] 2> 256456 INFO (jetty-closer-1093-thread-4) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 257923 WARN (zkCallback-1127-thread-2-processing-n:127.0.0.1:54144_solr) [n:127.0.0.1:54144_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 257924 INFO (jetty-closer-1093-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@75340131{/solr,null,UNAVAILABLE} [junit4] 2> 257925 INFO (jetty-closer-1093-thread-2) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 257929 ERROR (SUITE-StreamExpressionTest-seed#[806090A8020763D1]-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> 257930 INFO (SUITE-StreamExpressionTest-seed#[806090A8020763D1]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:49045 49045 [junit4] 2> 263444 INFO (Thread-729) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:49045 49045 [junit4] 2> 263446 WARN (Thread-729) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 100 /solr/aliases.json [junit4] 2> 13 /solr/configs/conf [junit4] 2> 7 /solr/configs/ml [junit4] 2> 4 /solr/security.json [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 100 /solr/clusterprops.json [junit4] 2> 100 /solr/clusterstate.json [junit4] 2> 40 /solr/collections/destinationCollection/state.json [junit4] 2> 26 /solr/collections/parallelDestinationCollection1/state.json [junit4] 2> 20 /solr/collections/parallelDestinationCollection/state.json [junit4] 2> 12 /solr/collections/collection1/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/workQueue1/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/99417744460742666-127.0.0.1:43530_solr-n_0000000000 [junit4] 2> 2 /solr/overseer_elect/election/99417744460742668-127.0.0.1:54144_solr-n_0000000001 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 100 /solr/live_nodes [junit4] 2> 100 /solr/collections [junit4] 2> [junit4] 2> NOTE: leaving temporary files on disk at: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_806090A8020763D1-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {expr_s=PostingsFormat(name=Memory), name_s=PostingsFormat(name=MockRandom), terms_ss=FSTOrd50, join2_s=FSTOrd50, field_s=PostingsFormat(name=LuceneVarGapFixedInterval), multiDefault=FSTOrd50, subject=FSTOrd50, a1_s=PostingsFormat(name=LuceneVarGapFixedInterval), a2_s=PostingsFormat(name=Memory), level1_s=PostingsFormat(name=Memory), body_t=PostingsFormat(name=MockRandom), s_multi=PostingsFormat(name=Memory), col_s=PostingsFormat(name=LuceneVarGapFixedInterval), level2_s=PostingsFormat(name=LuceneVarGapFixedInterval), a_ss=FSTOrd50, a_s=PostingsFormat(name=Memory), tv_text=PostingsFormat(name=LuceneVarGapFixedInterval), term_s=FSTOrd50, a_t=FSTOrd50, text_s=PostingsFormat(name=Memory), id=PostingsFormat(name=MockRandom), text=PostingsFormat(name=Memory), featureSet_s=PostingsFormat(name=LuceneVarGapFixedInterval), checkpoint_ss=PostingsFormat(name=MockRandom), test_t=PostingsFormat(name=MockRandom), ident_s=PostingsFormat(name=LuceneVarGapFixedInterval), whitetok=PostingsFormat(name=MockRandom), side_s=PostingsFormat(name=MockRandom)}, docValues:{expr_s=DocValuesFormat(name=Direct), name_s=DocValuesFormat(name=Lucene70), iteration_i=DocValuesFormat(name=Lucene70), terms_ss=DocValuesFormat(name=Asserting), join2_s=DocValuesFormat(name=Asserting), multiDefault=DocValuesFormat(name=Asserting), intDefault=DocValuesFormat(name=Memory), a_f=DocValuesFormat(name=Memory), a2_s=DocValuesFormat(name=Direct), falseNegative_i=DocValuesFormat(name=Memory), a_i=DocValuesFormat(name=Lucene70), level1_s=DocValuesFormat(name=Direct), a_s=DocValuesFormat(name=Direct), id=DocValuesFormat(name=Lucene70), alpha_d=DocValuesFormat(name=Lucene70), i_multi=DocValuesFormat(name=Lucene70), idf_d=DocValuesFormat(name=Memory), b_ls=DocValuesFormat(name=Memory), checkpoint_ss=DocValuesFormat(name=Lucene70), ident_s=DocValuesFormat(name=Memory), order_i=DocValuesFormat(name=Asserting), error_d=DocValuesFormat(name=Direct), side_s=DocValuesFormat(name=Lucene70), truePositive_i=DocValuesFormat(name=Direct), miles_i=DocValuesFormat(name=Memory), field_s=DocValuesFormat(name=Memory), price_f=DocValuesFormat(name=Asserting), a1_s=DocValuesFormat(name=Memory), join1_i=DocValuesFormat(name=Direct), join3_i=DocValuesFormat(name=Lucene70), test_i=DocValuesFormat(name=Asserting), falsePositive_i=DocValuesFormat(name=Memory), field_i=DocValuesFormat(name=Asserting), s_multi=DocValuesFormat(name=Direct), col_s=DocValuesFormat(name=Memory), level2_s=DocValuesFormat(name=Memory), a_ss=DocValuesFormat(name=Asserting), score_f=DocValuesFormat(name=Direct), term_s=DocValuesFormat(name=Asserting), text_s=DocValuesFormat(name=Direct), timestamp=DocValuesFormat(name=Asserting), idfs_ds=DocValuesFormat(name=Asserting), weights_ds=DocValuesFormat(name=Lucene70), featureSet_s=DocValuesFormat(name=Memory), trueNegative_i=DocValuesFormat(name=Direct), index_i=DocValuesFormat(name=Memory), test_dt=DocValuesFormat(name=Direct), out_i=DocValuesFormat(name=Memory), _version_=DocValuesFormat(name=Memory)}, maxPointsInLeafNode=1459, maxMBSortInHeap=6.369987389265701, sim=RandomSimilarity(queryNorm=false): {}, locale=id-ID, timezone=Europe/Athens [junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_152 (64-bit)/cpus=3,threads=1,free=76827208,total=179453952 [junit4] 2> NOTE: All tests run in this JVM: [TestToleratedUpdateError, ArrayEvaluatorTest, CloudSolrClientBuilderTest, StreamExpressionToExpessionTest, FieldAnalysisResponseTest, NotEvaluatorTest, RecursiveEvaluatorTest, UuidEvaluatorTest, TestDocumentObjectBinder, JettyWebappTest, UsingSolrJRefGuideExamplesTest, HttpSolrClientBuilderTest, ShardParamsTest, GreaterThanEvaluatorTest, LengthEvaluatorTest, TestRetryUtil, ExclusiveOrEvaluatorTest, TestHash, SolrExampleEmbeddedTest, TestCloudSolrClientConnections, NormalDistributionEvaluatorTest, TestSolrProperties, SchemaTest, TestXMLEscaping, SineEvaluatorTest, SubtractEvaluatorTest, AddEvaluatorTest, TestDelegationTokenRequest, MultiplyEvaluatorTest, TestDelegationTokenResponse, AppendEvaluatorTest, TestUpdateRequestCodec, TestSpellCheckResponse, SelectWithEvaluatorsTest, TestEmbeddedSolrServer, SolrExampleStreamingTest, SolrExampleXMLTest, SolrExampleJettyTest, TestBatchUpdate, LargeVolumeJettyTest, SolrParamTest, TermsResponseTest, MergeIndexesEmbeddedTest, QueryResponseTest, TestCoreAdmin, TestJavaBinCodec, SolrQueryTest, ModifiableSolrParamsTest, TestFastInputStream, DocumentAnalysisResponseTest, SolrDocumentTest, SolrExceptionTest, ClientUtilsTest, NamedListTest, AnlysisResponseBaseTest, GetByIdTest, TestLBHttpSolrClient, TestPolicy, BasicHttpSolrClientTest, CloudSolrClientMultiConstructorTest, ConcurrentUpdateSolrClientBuilderTest, HttpSolrClientConPoolTest, HttpSolrClientSSLAuthConPoolTest, LBHttpSolrClientBuilderTest, GraphTest, JdbcTest, StreamExpressionTest] [junit4] Completed [146/146 (1!)] on J0 in 119.49s, 153 tests, 1 failure <<< FAILURES! [...truncated 47275 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org