Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2226/
4 tests failed. FAILED: org.apache.solr.cloud.AssignBackwardCompatibilityTest.test Error Message: Stack Trace: java.lang.NullPointerException at __randomizedtesting.SeedInfo.seed([5CB23C8819CBB48B:D4E60352B737D973]:0) at org.apache.solr.cloud.SolrCloudTestCase.waitForState(SolrCloudTestCase.java:269) at org.apache.solr.cloud.AssignBackwardCompatibilityTest.test(AssignBackwardCompatibilityTest.java:92) 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.cloud.MoveReplicaHDFSTest.testNormalMove Error Message: Could not load collection from ZK: MoveReplicaHDFSTest_coll_false Stack Trace: org.apache.solr.common.SolrException: Could not load collection from ZK: MoveReplicaHDFSTest_coll_false at __randomizedtesting.SeedInfo.seed([5CB23C8819CBB48B:FA6A8B2F9C2A7691]:0) at org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1108) at org.apache.solr.common.cloud.ZkStateReader$LazyCollectionRef.get(ZkStateReader.java:647) at org.apache.solr.common.cloud.ClusterState.getCollectionOrNull(ClusterState.java:137) at org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:117) at org.apache.solr.cloud.SolrCloudTestCase.getCollectionState(SolrCloudTestCase.java:247) at org.apache.solr.cloud.MoveReplicaTest.test(MoveReplicaTest.java:214) at org.apache.solr.cloud.MoveReplicaHDFSTest.testNormalMove(MoveReplicaHDFSTest.java:63) 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) Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/MoveReplicaHDFSTest_coll_false/state.json at org.apache.zookeeper.KeeperException.create(KeeperException.java:127) at org.apache.zookeeper.KeeperException.create(KeeperException.java:51) at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1212) at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:339) at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60) at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:339) at org.apache.solr.common.cloud.ZkStateReader.fetchCollectionState(ZkStateReader.java:1120) at org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1106) ... 45 more FAILED: org.apache.solr.update.processor.TimeRoutedAliasUpdateProcessorTest.test Error Message: expected:<1> but was:<0> Stack Trace: java.lang.AssertionError: expected:<1> but was:<0> at __randomizedtesting.SeedInfo.seed([5CB23C8819CBB48B:D4E60352B737D973]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.failNotEquals(Assert.java:647) at org.junit.Assert.assertEquals(Assert.java:128) at org.junit.Assert.assertEquals(Assert.java:472) at org.junit.Assert.assertEquals(Assert.java:456) at org.apache.solr.update.processor.TimeRoutedAliasUpdateProcessorTest.assertInvariants(TimeRoutedAliasUpdateProcessorTest.java:245) at org.apache.solr.update.processor.TimeRoutedAliasUpdateProcessorTest.test(TimeRoutedAliasUpdateProcessorTest.java:123) 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.cloud.autoscaling.TriggerIntegrationTest.testNodeMarkersRegistration Error Message: Path /autoscaling/nodeAdded/127.0.0.1:39913_solr wasn't created Stack Trace: java.lang.AssertionError: Path /autoscaling/nodeAdded/127.0.0.1:39913_solr wasn't created at __randomizedtesting.SeedInfo.seed([5CB23C8819CBB48B:4408B48417FE7964]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testNodeMarkersRegistration(TriggerIntegrationTest.java:932) 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 12069 lines...] [junit4] Suite: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest [junit4] 2> 908665 INFO (SUITE-TriggerIntegrationTest-seed#[5CB23C8819CBB48B]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> Creating dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_5CB23C8819CBB48B-001/init-core-data-001 [junit4] 2> 908668 WARN (SUITE-TriggerIntegrationTest-seed#[5CB23C8819CBB48B]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=7 numCloses=7 [junit4] 2> 908668 INFO (SUITE-TriggerIntegrationTest-seed#[5CB23C8819CBB48B]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 908702 INFO (SUITE-TriggerIntegrationTest-seed#[5CB23C8819CBB48B]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN) [junit4] 2> 908702 INFO (SUITE-TriggerIntegrationTest-seed#[5CB23C8819CBB48B]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_5CB23C8819CBB48B-001/tempDir-001 [junit4] 2> 908702 INFO (SUITE-TriggerIntegrationTest-seed#[5CB23C8819CBB48B]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 908722 INFO (Thread-438) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 908722 INFO (Thread-438) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 908787 ERROR (Thread-438) [ ] 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> 908852 INFO (SUITE-TriggerIntegrationTest-seed#[5CB23C8819CBB48B]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:33262 [junit4] 2> 909059 INFO (zkConnectionManagerCallback-3058-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 909081 INFO (jetty-launcher-3055-thread-1) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 909081 INFO (jetty-launcher-3055-thread-2) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 909165 INFO (jetty-launcher-3055-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4255734a{/solr,null,AVAILABLE} [junit4] 2> 909168 INFO (jetty-launcher-3055-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@342863a1{HTTP/1.1,[http/1.1]}{127.0.0.1:37692} [junit4] 2> 909168 INFO (jetty-launcher-3055-thread-1) [ ] o.e.j.s.Server Started @921113ms [junit4] 2> 909168 INFO (jetty-launcher-3055-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=37692} [junit4] 2> 909181 ERROR (jetty-launcher-3055-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 909181 INFO (jetty-launcher-3055-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 909182 INFO (jetty-launcher-3055-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 909182 INFO (jetty-launcher-3055-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 909182 INFO (jetty-launcher-3055-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-21T02:55:09.375Z [junit4] 2> 909214 INFO (jetty-launcher-3055-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@78c64d9f{/solr,null,AVAILABLE} [junit4] 2> 909215 INFO (jetty-launcher-3055-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7dd07f35{HTTP/1.1,[http/1.1]}{127.0.0.1:39507} [junit4] 2> 909215 INFO (jetty-launcher-3055-thread-2) [ ] o.e.j.s.Server Started @921160ms [junit4] 2> 909215 INFO (jetty-launcher-3055-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=39507} [junit4] 2> 909215 ERROR (jetty-launcher-3055-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 909215 INFO (jetty-launcher-3055-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 909215 INFO (jetty-launcher-3055-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 909215 INFO (jetty-launcher-3055-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 909312 INFO (jetty-launcher-3055-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-21T02:55:09.408Z [junit4] 2> 909373 INFO (zkConnectionManagerCallback-3060-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 909377 INFO (zkConnectionManagerCallback-3062-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 909381 INFO (jetty-launcher-3055-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 909381 INFO (jetty-launcher-3055-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 909556 INFO (jetty-launcher-3055-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33262/solr [junit4] 2> 909565 INFO (jetty-launcher-3055-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33262/solr [junit4] 2> 909641 INFO (zkConnectionManagerCallback-3070-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 909649 INFO (zkConnectionManagerCallback-3067-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 909667 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x16076fef6870003, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> 909767 INFO (zkConnectionManagerCallback-3074-thread-1-processing-n:127.0.0.1:37692_solr) [n:127.0.0.1:37692_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 909792 INFO (zkConnectionManagerCallback-3072-thread-1-processing-n:127.0.0.1:39507_solr) [n:127.0.0.1:39507_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 910321 INFO (jetty-launcher-3055-thread-2) [n:127.0.0.1:39507_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 910325 INFO (jetty-launcher-3055-thread-2) [n:127.0.0.1:39507_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:39507_solr [junit4] 2> 910326 INFO (jetty-launcher-3055-thread-2) [n:127.0.0.1:39507_solr ] o.a.s.c.Overseer Overseer (id=99210029231964165-127.0.0.1:39507_solr-n_0000000000) starting [junit4] 2> 910437 INFO (jetty-launcher-3055-thread-2) [n:127.0.0.1:39507_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39507_solr [junit4] 2> 910437 DEBUG (OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000) [n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger [junit4] 2> 910545 DEBUG (OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000) [n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1 [junit4] 2> 910582 DEBUG (OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [] [junit4] 2> 910582 DEBUG (OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000) [n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion -1 [junit4] 2> 910582 DEBUG (OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000) [n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1 [junit4] 2> 910618 INFO (OverseerStateUpdate-99210029231964165-127.0.0.1:39507_solr-n_0000000000) [n:127.0.0.1:39507_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 910760 DEBUG (OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000) [n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 910907 DEBUG (OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000) [n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1 [junit4] 2> 910912 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 [junit4] 2> 911189 INFO (jetty-launcher-3055-thread-2) [n:127.0.0.1:39507_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39507.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f [junit4] 2> 911264 INFO (jetty-launcher-3055-thread-2) [n:127.0.0.1:39507_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39507.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f [junit4] 2> 911264 INFO (jetty-launcher-3055-thread-2) [n:127.0.0.1:39507_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39507.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f [junit4] 2> 911301 INFO (jetty-launcher-3055-thread-2) [n:127.0.0.1:39507_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_5CB23C8819CBB48B-001/tempDir-001/node2/. [junit4] 2> 911913 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 [junit4] 2> 912870 INFO (jetty-launcher-3055-thread-1) [n:127.0.0.1:37692_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 912871 INFO (jetty-launcher-3055-thread-1) [n:127.0.0.1:37692_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 912929 DEBUG (ScheduledTrigger-1346-thread-2) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 [junit4] 2> 913039 INFO (jetty-launcher-3055-thread-1) [n:127.0.0.1:37692_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37692_solr [junit4] 2> 913096 INFO (zkCallback-3071-thread-1-processing-n:127.0.0.1:39507_solr) [n:127.0.0.1:39507_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 913148 INFO (zkCallback-3073-thread-1-processing-n:127.0.0.1:37692_solr) [n:127.0.0.1:37692_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 913407 INFO (jetty-launcher-3055-thread-1) [n:127.0.0.1:37692_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37692.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f [junit4] 2> 913501 INFO (jetty-launcher-3055-thread-1) [n:127.0.0.1:37692_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37692.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f [junit4] 2> 913502 INFO (jetty-launcher-3055-thread-1) [n:127.0.0.1:37692_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37692.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f [junit4] 2> 913536 INFO (jetty-launcher-3055-thread-1) [n:127.0.0.1:37692_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_5CB23C8819CBB48B-001/tempDir-001/node1/. [junit4] 2> 913779 INFO (zkConnectionManagerCallback-3080-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 913808 INFO (zkConnectionManagerCallback-3084-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 913816 INFO (SUITE-TriggerIntegrationTest-seed#[5CB23C8819CBB48B]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 913817 INFO (SUITE-TriggerIntegrationTest-seed#[5CB23C8819CBB48B]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33262/solr ready [junit4] 2> 913929 DEBUG (ScheduledTrigger-1346-thread-2) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 914088 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[5CB23C8819CBB48B]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testNodeLostTrigger [junit4] 2> 914101 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[5CB23C8819CBB48B]) [ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 2 [junit4] 2> 914101 DEBUG (zkCallback-3071-thread-1-processing-n:127.0.0.1:39507_solr) [n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2 [junit4] 2> 914101 DEBUG (OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000) [n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2 [junit4] 2> 914140 DEBUG (OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000) [n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 914141 DEBUG (OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000) [n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 914158 DEBUG (OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000) [n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2 [junit4] 2> 914200 INFO (qtp650767471-6983) [n:127.0.0.1:37692_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 914521 INFO (qtp650767471-6983) [n:127.0.0.1:37692_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=321 [junit4] 2> 914630 INFO (qtp650767471-6981) [n:127.0.0.1:37692_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=106 [junit4] 2> 914651 INFO (qtp1013721322-6989) [n:127.0.0.1:39507_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0 [junit4] 2> 914651 DEBUG (qtp650767471-6984) [n:127.0.0.1:37692_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 914652 INFO (qtp650767471-6984) [n:127.0.0.1:37692_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=129 [junit4] 2> 914678 DEBUG (zkCallback-3071-thread-1-processing-n:127.0.0.1:39507_solr) [n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3 [junit4] 2> 914699 DEBUG (zkCallback-3071-thread-1-processing-n:127.0.0.1:39507_solr) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:37692_solr, 127.0.0.1:39507_solr] [junit4] 2> 914699 DEBUG (OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000) [n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3 [junit4] 2> 914740 INFO (OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000) [n:127.0.0.1:39507_solr ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init [junit4] 2> 914740 DEBUG (OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000) [n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 914741 DEBUG (OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000) [n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3 [junit4] 2> 914743 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[5CB23C8819CBB48B]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@342863a1{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 914776 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[5CB23C8819CBB48B]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1050644109 [junit4] 2> 914776 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[5CB23C8819CBB48B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 914777 DEBUG (ScheduledTrigger-1346-thread-4) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 2 [junit4] 2> 914777 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[5CB23C8819CBB48B]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@292ae24: rootName = solr_37692, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@425de3ae [junit4] 2> 914795 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[5CB23C8819CBB48B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 914795 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[5CB23C8819CBB48B]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1af5408b: rootName = solr_37692, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@29cf17b2 [junit4] 2> 914852 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[5CB23C8819CBB48B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 914852 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[5CB23C8819CBB48B]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@62f548e3: rootName = solr_37692, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@1843f368 [junit4] 2> 914854 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[5CB23C8819CBB48B]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:37692_solr [junit4] 2> 914855 INFO (zkCallback-3071-thread-1-processing-n:127.0.0.1:39507_solr) [n:127.0.0.1:39507_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 914856 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[5CB23C8819CBB48B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 914878 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[5CB23C8819CBB48B]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 914878 INFO (zkCallback-3073-thread-1-processing-n:127.0.0.1:37692_solr) [n:127.0.0.1:37692_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 914898 INFO (zkCallback-3083-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 914899 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[5CB23C8819CBB48B]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4255734a{/solr,null,UNAVAILABLE} [junit4] 2> 915786 DEBUG (ScheduledTrigger-1346-thread-4) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 1 [junit4] 2> 915786 DEBUG (ScheduledTrigger-1346-thread-4) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeLostTrigger Tracking lost node: 127.0.0.1:37692_solr [junit4] 2> 916371 INFO (OverseerCollectionConfigSetProcessor-99210029231964165-127.0.0.1:39507_solr-n_0000000000) [n:127.0.0.1:39507_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> 916846 DEBUG (ScheduledTrigger-1346-thread-4) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 1 [junit4] 2> 916847 DEBUG (ScheduledTrigger-1346-thread-4) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger firing registered processor for lost nodes: [127.0.0.1:37692_solr] [junit4] 2> 916847 DEBUG (ScheduledTrigger-1346-thread-4) [n:127.0.0.1:39507_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { [junit4] 2> "id":"31811aa2b380a9Td35vl06j8prvgrjd19w7z241p", [junit4] 2> "source":"node_lost_trigger", [junit4] 2> "eventTime":13934225257627817, [junit4] 2> "eventType":"NODELOST", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[13934225257627817], [junit4] 2> "nodeNames":["127.0.0.1:37692_solr"]}} [junit4] 2> 916848 INFO (ScheduledTrigger-1346-thread-4) [n:127.0.0.1:39507_solr ] o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling logging. [junit4] 2> 916868 DEBUG (AutoscalingActionExecutor-1347-thread-1-processing-n:127.0.0.1:39507_solr) [n:127.0.0.1:39507_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions for { [junit4] 2> "id":"31811aa2b380a9Td35vl06j8prvgrjd19w7z241p", [junit4] 2> "source":"node_lost_trigger", [junit4] 2> "eventTime":13934225257627817, [junit4] 2> "eventType":"NODELOST", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[13934225257627817], [junit4] 2> "_enqueue_time_":13934226318610591, [junit4] 2> "nodeNames":["127.0.0.1:37692_solr"]}} [junit4] 2> 916870 DEBUG (AutoscalingActionExecutor-1347-thread-1-processing-n:127.0.0.1:39507_solr) [n:127.0.0.1:39507_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 2 ms for event id=31811aa2b380a9Td35vl06j8prvgrjd19w7z241p [junit4] 2> 916893 INFO (qtp1013721322-6989) [n:127.0.0.1:39507_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0 [junit4] 2> 916893 DEBUG (qtp1013721322-6991) [n:127.0.0.1:39507_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 916904 DEBUG (zkCallback-3071-thread-1-processing-n:127.0.0.1:39507_solr) [n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 4 [junit4] 2> 916904 DEBUG (zkCallback-3071-thread-1-processing-n:127.0.0.1:39507_solr) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:39507_solr] [junit4] 2> 916904 DEBUG (OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000) [n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 4 [junit4] 2> 916904 DEBUG (OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000) [n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 916905 DEBUG (OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000) [n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVersion 4 [junit4] 2> 916905 INFO (qtp1013721322-6991) [n:127.0.0.1:39507_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=33 [junit4] 2> 917872 DEBUG (ScheduledTrigger-1346-thread-4) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 1 [junit4] 2> 918883 DEBUG (ScheduledTrigger-1346-thread-4) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 1 [junit4] 2> 918920 INFO (TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[5CB23C8819CBB48B]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testNodeLostTrigger [junit4] 2> 918996 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCooldown [junit4] 2> 919050 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 5 [junit4] 2> 919066 DEBUG (zkCallback-3071-thread-1-processing-n:127.0.0.1:39507_solr) [n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 5 [junit4] 2> 919066 DEBUG (OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000) [n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 5 [junit4] 2> 919068 DEBUG (OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000) [n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 919068 DEBUG (OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000) [n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 919068 DEBUG (OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000) [n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5, lastZnodeVersion 5 [junit4] 2> 919069 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 919123 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@47f4e6fd{/solr,null,AVAILABLE} [junit4] 2> 919124 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@30c790c3{HTTP/1.1,[http/1.1]}{127.0.0.1:40550} [junit4] 2> 919124 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.e.j.s.Server Started @931069ms [junit4] 2> 919124 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=40550} [junit4] 2> 919124 ERROR (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 919126 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 919126 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 919126 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 919126 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-21T02:55:19.319Z [junit4] 2> 919200 INFO (zkConnectionManagerCallback-3086-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 919233 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 919380 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33262/solr [junit4] 2> 919408 INFO (zkConnectionManagerCallback-3090-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 919432 INFO (zkConnectionManagerCallback-3092-thread-1-processing-n:127.0.0.1:40550_solr) [n:127.0.0.1:40550_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 919469 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [n:127.0.0.1:40550_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 919470 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [n:127.0.0.1:40550_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 919472 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [n:127.0.0.1:40550_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40550_solr [junit4] 2> 919542 INFO (zkCallback-3083-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 919542 INFO (zkCallback-3071-thread-1-processing-n:127.0.0.1:39507_solr) [n:127.0.0.1:39507_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 919579 INFO (zkCallback-3091-thread-1-processing-n:127.0.0.1:40550_solr) [n:127.0.0.1:40550_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 919945 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [n:127.0.0.1:40550_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40550.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f [junit4] 2> 920019 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [n:127.0.0.1:40550_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40550.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f [junit4] 2> 920020 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [n:127.0.0.1:40550_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40550.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f [junit4] 2> 920021 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [n:127.0.0.1:40550_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_5CB23C8819CBB48B-001/tempDir-001/node3/. [junit4] 2> 920333 INFO (qtp1013721322-6989) [n:127.0.0.1:39507_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0 [junit4] 2> 920401 INFO (qtp1418590417-7051) [n:127.0.0.1:40550_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0 [junit4] 2> 920402 DEBUG (qtp1013721322-6990) [n:127.0.0.1:39507_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 920402 DEBUG (zkCallback-3071-thread-1-processing-n:127.0.0.1:39507_solr) [n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 6 [junit4] 2> 920420 INFO (qtp1013721322-6990) [n:127.0.0.1:39507_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=129 [junit4] 2> 920441 INFO (qtp1013721322-6996) [n:127.0.0.1:39507_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0 [junit4] 2> 920458 INFO (qtp1418590417-7054) [n:127.0.0.1:40550_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0 [junit4] 2> 920473 DEBUG (qtp1418590417-7053) [n:127.0.0.1:40550_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 920494 INFO (qtp1418590417-7053) [n:127.0.0.1:40550_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=69 [junit4] 2> 920530 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 920568 DEBUG (zkCallback-3071-thread-1-processing-n:127.0.0.1:39507_solr) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:39507_solr, 127.0.0.1:40550_solr] [junit4] 2> 920568 DEBUG (zkCallback-3071-thread-1-processing-n:127.0.0.1:39507_solr) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_cooldown_trigger instantiated with properties: {event=nodeAdded, waitFor=1, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}], enabled=true} [junit4] 2> 920568 DEBUG (zkCallback-3071-thread-2-processing-n:127.0.0.1:39507_solr) [n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 7 [junit4] 2> 920569 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1f9399ce{/solr,null,AVAILABLE} [junit4] 2> 920569 DEBUG (zkCallback-3071-thread-2-processing-n:127.0.0.1:39507_solr) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:39507_solr, 127.0.0.1:40550_solr] [junit4] 2> 920569 DEBUG (zkCallback-3071-thread-2-processing-n:127.0.0.1:39507_solr) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_cooldown_trigger instantiated with properties: {event=nodeAdded, waitFor=1, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}], enabled=true} [junit4] 2> 920569 DEBUG (OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000) [n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 7 [junit4] 2> 920569 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@219a333f{HTTP/1.1,[http/1.1]}{127.0.0.1:36236} [junit4] 2> 920569 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.e.j.s.Server Started @932514ms [junit4] 2> 920569 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=36236} [junit4] 2> 920569 ERROR (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 920569 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 920570 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 920570 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 920570 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-21T02:55:20.763Z [junit4] 2> 920570 INFO (OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000) [n:127.0.0.1:39507_solr ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init [junit4] 2> 920604 DEBUG (OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000) [n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 920605 DEBUG (OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000) [n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 7, lastZnodeVersion 7 [junit4] 2> 920605 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_cooldown_trigger [junit4] 2> 920605 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2 [junit4] 2> 920640 INFO (zkConnectionManagerCallback-3096-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 920642 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 920787 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33262/solr [junit4] 2> 920840 INFO (zkConnectionManagerCallback-3100-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 920949 INFO (zkConnectionManagerCallback-3102-thread-1-processing-n:127.0.0.1:36236_solr) [n:127.0.0.1:36236_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 920957 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [n:127.0.0.1:36236_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 921007 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [n:127.0.0.1:36236_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 921008 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [n:127.0.0.1:36236_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36236_solr [junit4] 2> 921010 INFO (zkCallback-3071-thread-2-processing-n:127.0.0.1:39507_solr) [n:127.0.0.1:39507_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 921010 INFO (zkCallback-3083-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 921010 INFO (zkCallback-3091-thread-1-processing-n:127.0.0.1:40550_solr) [n:127.0.0.1:40550_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 921080 INFO (zkCallback-3101-thread-1-processing-n:127.0.0.1:36236_solr) [n:127.0.0.1:36236_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 921462 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [n:127.0.0.1:36236_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36236.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f [junit4] 2> 921519 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [n:127.0.0.1:36236_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36236.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f [junit4] 2> 921520 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [n:127.0.0.1:36236_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36236.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f [junit4] 2> 921521 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [n:127.0.0.1:36236_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_5CB23C8819CBB48B-001/tempDir-001/node4/. [junit4] 2> 921606 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_cooldown_trigger [junit4] 2> 921606 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3 [junit4] 2> 921606 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:36236_solr at time 13934231078001578 [junit4] 2> 922620 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_cooldown_trigger [junit4] 2> 922621 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3 [junit4] 2> 922621 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_cooldown_trigger firing registered processor for nodes: [127.0.0.1:36236_solr] added at times [13934231078001578], now=13934232092561058 [junit4] 2> 922621 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { [junit4] 2> "id":"31811bfd9f5baaTd35vl06j8prvgrjd19w7z241r", [junit4] 2> "source":"node_added_cooldown_trigger", [junit4] 2> "eventTime":13934231078001578, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[13934231078001578], [junit4] 2> "nodeNames":["127.0.0.1:36236_solr"]}} [junit4] 2> 922629 INFO (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling logging. [junit4] 2> 922641 DEBUG (AutoscalingActionExecutor-1347-thread-1-processing-n:127.0.0.1:39507_solr) [n:127.0.0.1:39507_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions for { [junit4] 2> "id":"31811bfd9f5baaTd35vl06j8prvgrjd19w7z241r", [junit4] 2> "source":"node_added_cooldown_trigger", [junit4] 2> "eventTime":13934231078001578, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[13934231078001578], [junit4] 2> "_enqueue_time_":13934232092948207, [junit4] 2> "nodeNames":["127.0.0.1:36236_solr"]}} [junit4] 2> 922703 DEBUG (AutoscalingActionExecutor-1347-thread-1-processing-n:127.0.0.1:39507_solr) [n:127.0.0.1:39507_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 62 ms for event id=31811bfd9f5baaTd35vl06j8prvgrjd19w7z241r [junit4] 2> 923654 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 923654 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_cooldown_trigger [junit4] 2> 923654 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3 [junit4] 2> 923759 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7209c5fe{/solr,null,AVAILABLE} [junit4] 2> 923760 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@60108384{HTTP/1.1,[http/1.1]}{127.0.0.1:44985} [junit4] 2> 923760 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.e.j.s.Server Started @935705ms [junit4] 2> 923760 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44985} [junit4] 2> 923760 ERROR (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 923760 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 923760 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 923760 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 923760 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-21T02:55:23.953Z [junit4] 2> 923779 INFO (zkConnectionManagerCallback-3106-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 923796 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 923869 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33262/solr [junit4] 2> 923926 INFO (zkConnectionManagerCallback-3110-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 923991 INFO (zkConnectionManagerCallback-3112-thread-1-processing-n:127.0.0.1:44985_solr) [n:127.0.0.1:44985_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 924003 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [n:127.0.0.1:44985_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 924037 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [n:127.0.0.1:44985_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 924073 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [n:127.0.0.1:44985_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44985_solr [junit4] 2> 924075 INFO (zkCallback-3101-thread-1-processing-n:127.0.0.1:36236_solr) [n:127.0.0.1:36236_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 924075 INFO (zkCallback-3083-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 924075 INFO (zkCallback-3091-thread-1-processing-n:127.0.0.1:40550_solr) [n:127.0.0.1:40550_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 924075 INFO (zkCallback-3071-thread-2-processing-n:127.0.0.1:39507_solr) [n:127.0.0.1:39507_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 924177 INFO (zkCallback-3111-thread-1-processing-n:127.0.0.1:44985_solr) [n:127.0.0.1:44985_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 924624 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [n:127.0.0.1:44985_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44985.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f [junit4] 2> 924657 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_cooldown_trigger [junit4] 2> 924657 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 4 [junit4] 2> 924657 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:44985_solr at time 13934234129249397 [junit4] 2> 924713 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [n:127.0.0.1:44985_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44985.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f [junit4] 2> 924714 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [n:127.0.0.1:44985_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44985.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f [junit4] 2> 924731 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [n:127.0.0.1:44985_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_5CB23C8819CBB48B-001/tempDir-001/node5/. [junit4] 2> 925661 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_cooldown_trigger [junit4] 2> 925661 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 4 [junit4] 2> 925661 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_cooldown_trigger firing registered processor for nodes: [127.0.0.1:44985_solr] added at times [13934234129249397], now=13934235132904988 [junit4] 2> 925661 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.ScheduledTriggers -------- Cooldown period - rejecting event: { [junit4] 2> "id":"31811cb37db475Td35vl06j8prvgrjd19w7z241t", [junit4] 2> "source":"node_added_cooldown_trigger", [junit4] 2> "eventTime":13934234129249397, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[13934234129249397], [junit4] 2> "nodeNames":["127.0.0.1:44985_solr"]}} [junit4] 2> 926676 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_cooldown_trigger [junit4] 2> 926676 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 4 [junit4] 2> 926676 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_cooldown_trigger firing registered processor for nodes: [127.0.0.1:44985_solr] added at times [13934234129249397], now=13934236147628314 [junit4] 2> 926676 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.ScheduledTriggers -------- Cooldown period - rejecting event: { [junit4] 2> "id":"31811cb37db475Td35vl06j8prvgrjd19w7z241u", [junit4] 2> "source":"node_added_cooldown_trigger", [junit4] 2> "eventTime":13934234129249397, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[13934234129249397], [junit4] 2> "nodeNames":["127.0.0.1:44985_solr"]}} [junit4] 2> 927695 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_cooldown_trigger [junit4] 2> 927695 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 4 [junit4] 2> 927695 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_cooldown_trigger firing registered processor for nodes: [127.0.0.1:44985_solr] added at times [13934234129249397], now=13934237167260186 [junit4] 2> 927695 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.ScheduledTriggers -------- Cooldown period - rejecting event: { [junit4] 2> "id":"31811cb37db475Td35vl06j8prvgrjd19w7z241v", [junit4] 2> "source":"node_added_cooldown_trigger", [junit4] 2> "eventTime":13934234129249397, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[13934234129249397], [junit4] 2> "nodeNames":["127.0.0.1:44985_solr"]}} [junit4] 2> 928703 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_cooldown_trigger [junit4] 2> 928703 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 4 [junit4] 2> 928703 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_cooldown_trigger firing registered processor for nodes: [127.0.0.1:44985_solr] added at times [13934234129249397], now=13934238175203967 [junit4] 2> 928703 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { [junit4] 2> "id":"31811cb37db475Td35vl06j8prvgrjd19w7z241w", [junit4] 2> "source":"node_added_cooldown_trigger", [junit4] 2> "eventTime":13934234129249397, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[13934234129249397], [junit4] 2> "nodeNames":["127.0.0.1:44985_solr"]}} [junit4] 2> 928704 DEBUG (AutoscalingActionExecutor-1347-thread-1-processing-n:127.0.0.1:39507_solr) [n:127.0.0.1:39507_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions for { [junit4] 2> "id":"31811cb37db475Td35vl06j8prvgrjd19w7z241w", [junit4] 2> "source":"node_added_cooldown_trigger", [junit4] 2> "eventTime":13934234129249397, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[13934234129249397], [junit4] 2> "_enqueue_time_":13934238175384210, [junit4] 2> "nodeNames":["127.0.0.1:44985_solr"]}} [junit4] 2> 928704 INFO (AutoscalingActionExecutor-1347-thread-1-processing-n:127.0.0.1:39507_solr) [n:127.0.0.1:39507_solr ] o.a.s.c.ActionThrottle The last action attempt started 6063ms ago. [junit4] 2> 928705 DEBUG (AutoscalingActionExecutor-1347-thread-1-processing-n:127.0.0.1:39507_solr) [n:127.0.0.1:39507_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 0 ms for event id=31811cb37db475Td35vl06j8prvgrjd19w7z241w [junit4] 2> 929706 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_cooldown_trigger [junit4] 2> 929707 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 4 [junit4] 2> 930712 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_cooldown_trigger [junit4] 2> 930712 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 4 [junit4] 2> 930895 INFO (qtp1602401251-7077) [n:127.0.0.1:36236_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0 [junit4] 2> 930897 INFO (qtp1013721322-6989) [n:127.0.0.1:39507_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0 [junit4] 2> 930938 INFO (qtp1418590417-7052) [n:127.0.0.1:40550_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0 [junit4] 2> 931030 INFO (qtp1567485535-7101) [n:127.0.0.1:44985_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0 [junit4] 2> 931030 DEBUG (qtp1013721322-6996) [n:127.0.0.1:39507_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 931031 DEBUG (zkCallback-3071-thread-2-processing-n:127.0.0.1:39507_solr) [n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 8 [junit4] 2> 931031 INFO (qtp1013721322-6996) [n:127.0.0.1:39507_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=309 [junit4] 2> 931033 DEBUG (zkCallback-3071-thread-2-processing-n:127.0.0.1:39507_solr) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:39507_solr, 127.0.0.1:44985_solr, 127.0.0.1:40550_solr, 127.0.0.1:36236_solr] [junit4] 2> 931098 DEBUG (zkCallback-3071-thread-2-processing-n:127.0.0.1:39507_solr) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_cooldown_trigger instantiated with properties: {event=nodeAdded, waitFor=1, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}], enabled=true} [junit4] 2> 931044 INFO (qtp1567485535-7099) [n:127.0.0.1:44985_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=11 [junit4] 2> 931102 DEBUG (OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000) [n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 8 [junit4] 2> 931102 DEBUG (OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000) [n:127.0.0.1:39507_solr ] o.a.s.c.a.ScheduledTriggers Changing value of autoscaling property: triggerCooldownPeriodSeconds from: 5 to: 7 [junit4] 2> 931103 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 931103 DEBUG (OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000) [n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 931104 DEBUG (OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000) [n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 8, lastZnodeVersion 8 [junit4] 2> 931167 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@e028aec{/solr,null,AVAILABLE} [junit4] 2> 931168 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@38f91e1{HTTP/1.1,[http/1.1]}{127.0.0.1:33807} [junit4] 2> 931168 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.e.j.s.Server Started @943113ms [junit4] 2> 931168 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33807} [junit4] 2> 931168 ERROR (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 931168 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 931168 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 931168 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 931168 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-21T02:55:31.361Z [junit4] 2> 931305 INFO (zkConnectionManagerCallback-3116-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 931306 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 931379 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33262/solr [junit4] 2> 931395 INFO (zkConnectionManagerCallback-3120-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 931448 INFO (zkConnectionManagerCallback-3122-thread-1-processing-n:127.0.0.1:33807_solr) [n:127.0.0.1:33807_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 931455 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [n:127.0.0.1:33807_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 931506 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [n:127.0.0.1:33807_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 931507 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [n:127.0.0.1:33807_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33807_solr [junit4] 2> 931525 INFO (zkCallback-3083-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 931525 INFO (zkCallback-3071-thread-1-processing-n:127.0.0.1:39507_solr) [n:127.0.0.1:39507_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 931544 INFO (zkCallback-3101-thread-1-processing-n:127.0.0.1:36236_solr) [n:127.0.0.1:36236_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 931545 INFO (zkCallback-3091-thread-1-processing-n:127.0.0.1:40550_solr) [n:127.0.0.1:40550_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 931545 INFO (zkCallback-3111-thread-1-processing-n:127.0.0.1:44985_solr) [n:127.0.0.1:44985_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 931619 INFO (zkCallback-3121-thread-1-processing-n:127.0.0.1:33807_solr) [n:127.0.0.1:33807_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 931716 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_cooldown_trigger [junit4] 2> 931716 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 5 [junit4] 2> 931716 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:33807_solr at time 13934241187982705 [junit4] 2> 931895 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [n:127.0.0.1:33807_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33807.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f [junit4] 2> 931949 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [n:127.0.0.1:33807_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33807.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f [junit4] 2> 931949 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [n:127.0.0.1:33807_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33807.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f [junit4] 2> 931950 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [n:127.0.0.1:33807_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_5CB23C8819CBB48B-001/tempDir-001/node6/. [junit4] 2> 932761 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_cooldown_trigger [junit4] 2> 932761 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 5 [junit4] 2> 932761 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_cooldown_trigger firing registered processor for nodes: [127.0.0.1:33807_solr] added at times [13934241187982705], now=13934242233246367 [junit4] 2> 932761 DEBUG (ScheduledTrigger-1346-thread-1) [n:127.0.0.1:39507_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { [junit4] 2> "id":"31811e58396d71Td35vl06j8prvgrjd19w7z241x", [junit4] 2> "source":"node_added_cooldown_trigger", [junit4] 2> "eventTime":13934241187982705, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[13934241187982705], [junit4] 2> "nodeNames":["127.0.0.1:33807_solr"]}} [junit4] 2> 932770 DEBUG (AutoscalingActionExecutor-1347-thread-1-processing-n:127.0.0.1:39507_solr) [n:127.0.0.1:39507_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions for { [junit4] 2> "id":"31811e58396d71Td35vl06j8prvgrjd19w7z241x", [junit4] 2> "source":"node_added_cooldown_trigger", [junit4] 2> "eventTime":13934241187982705, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[13934241187982705], [junit4] 2> "_enqueue_time_":13934242233448621, [junit4] 2> "nodeNames":["127.0.0.1:33807_solr"]}} [junit4] 2> 932771 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 932772 DEBUG (AutoscalingActionExecutor-1347-thread-1-processing-n:127.0.0.1:39507_solr) [n:127.0.0.1:39507_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 1 ms for event id=31811e58396d71Td35vl06j8prvgrjd19w7z241x [junit4] 2> 932864 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@26633b9f{/solr,null,AVAILABLE} [junit4] 2> 932864 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7d0b9b35{HTTP/1.1,[http/1.1]}{127.0.0.1:34545} [junit4] 2> 932864 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.e.j.s.Server Started @944809ms [junit4] 2> 932864 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34545} [junit4] 2> 932864 ERROR (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 932864 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 932865 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 932865 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 932865 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-21T02:55:33.058Z [junit4] 2> 932887 INFO (zkConnectionManagerCallback-3126-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 932896 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 933006 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33262/solr [junit4] 2> 933050 INFO (zkConnectionManagerCallback-3130-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 933079 INFO (zkConnectionManagerCallback-3132-thread-1-processing-n:127.0.0.1:34545_solr) [n:127.0.0.1:34545_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 933082 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [n:127.0.0.1:34545_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5) [junit4] 2> 933100 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [n:127.0.0.1:34545_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 933102 INFO (TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [n:127.0.0.1:34545_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34545_solr [junit4] 2> 933124 INFO (zkCallback-3111-thread-1-processing-n:127.0.0.1:44985_solr) [n:127.0.0.1:44985_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) [...truncated too long message...] 7-10-23_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4fd6c3df: rootName = solr_44218, domain = solr.core.myalias_2017-10-23.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.myalias_2017-10-23.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@7c2cfefa [junit4] 2> 7390271 INFO (zkCallback-7718-thread-1-processing-n:127.0.0.1:44218_solr) [n:127.0.0.1:44218_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 7390318 INFO (coreCloseExecutor-10323-thread-1) [n:127.0.0.1:44218_solr c:myalias_2017-10-23 s:shard1 r:core_node2 x:myalias_2017-10-23_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.myalias_2017-10-23.shard1.leader, tag=699230150 [junit4] 2> 7390452 INFO (jetty-closer-7700-thread-1) [ ] o.a.s.c.Overseer Overseer (id=99210452971159558-127.0.0.1:44218_solr-n_0000000000) closing [junit4] 2> 7390452 INFO (OverseerStateUpdate-99210452971159558-127.0.0.1:44218_solr-n_0000000000) [n:127.0.0.1:44218_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:44218_solr [junit4] 2> 7390487 WARN (OverseerAutoScalingTriggerThread-99210452971159558-127.0.0.1:44218_solr-n_0000000000) [n:127.0.0.1:44218_solr ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 7391994 INFO (jetty-closer-7700-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1e733f44{/solr,null,UNAVAILABLE} [junit4] 2> 7391995 ERROR (SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[5CB23C8819CBB48B]-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> 7391996 INFO (SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[5CB23C8819CBB48B]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:45078 45078 [junit4] 2> 7392050 INFO (Thread-7623) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:45078 45078 [junit4] 2> 7392070 WARN (Thread-7623) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 4 /solr/aliases.json [junit4] 2> 4 /solr/configs/timeConfig [junit4] 2> 3 /solr/configs/timeConfig/managed-schema [junit4] 2> 2 /solr/security.json [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 6 /solr/collections/myalias_2017-10-23/state.json [junit4] 2> 5 /solr/collections/timeConfig/state.json [junit4] 2> 4 /solr/clusterstate.json [junit4] 2> 4 /solr/clusterprops.json [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 4 /solr/live_nodes [junit4] 2> 4 /solr/collections [junit4] 2> [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_5CB23C8819CBB48B-001 [junit4] 2> Dec 21, 2017 4:43:12 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=FastCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST, chunkSize=2, maxDocsPerChunk=913, blockSize=705), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST, chunkSize=2, blockSize=705)), sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@68832b6e), locale=zh, timezone=Australia/Lindeman [junit4] 2> NOTE: Linux 4.4.0-83-generic amd64/Oracle Corporation 1.8.0_152 (64-bit)/cpus=4,threads=1,free=142366768,total=525860864 [junit4] 2> NOTE: All tests run in this JVM: [TestObjectReleaseTracker, TestPullReplica, TestFastOutputStream, BigEndianAscendingWordDeserializerTest, TestStressLiveNodes, CdcrVersionReplicationTest, PeerSyncTest, DeleteReplicaTest, SolrCloudExampleTest, BaseCdcrDistributedZkTest, URLClassifyProcessorTest, TestSerializedLuceneMatchVersion, VersionInfoTest, TestFieldTypeCollectionResource, TestXmlQParser, AnalyticsQueryTest, ClassificationUpdateProcessorTest, SpellCheckComponentTest, RulesTest, TestMacros, CachingDirectoryFactoryTest, SyncSliceTest, TestSimpleTextCodec, TestComponentsName, DocumentBuilderTest, DistributedFacetPivotLongTailTest, TriggerIntegrationTest, PropertiesRequestHandlerTest, TestSearcherReuse, ActionThrottleTest, UpdateParamsTest, BasicAuthIntegrationTest, TestManagedSchema, HttpTriggerListenerTest, TestSolrJ, TestMergePolicyConfig, SoftAutoCommitTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, FastVectorHighlighterTest, AssignBackwardCompatibilityTest, SearchRateTriggerTest, RAMDirectoryFactoryTest, ExternalFileFieldSortTest, AtomicUpdatesTest, TestInitQParser, TestPointFields, SearchHandlerTest, SpellCheckCollatorTest, TestSolr4Spatial2, ManagedSchemaRoundRobinCloudTest, DistributedMLTComponentTest, CleanupOldIndexTest, TestSolrCloudWithHadoopAuthPlugin, MultiTermTest, PrimitiveFieldTypeTest, DataDrivenBlockJoinTest, DistribCursorPagingTest, TestSubQueryTransformer, HdfsChaosMonkeyNothingIsSafeTest, DirectUpdateHandlerOptimizeTest, TestSolrIndexConfig, TestBadConfig, TestRequestForwarding, TestSegmentSorting, TestFoldingMultitermQuery, ForceLeaderTest, BasicZkTest, TestRecovery, HLLUtilTest, TestCrossCoreJoin, RecoveryAfterSoftCommitTest, TestQueryWrapperFilter, TestSolrCloudSnapshots, DocValuesTest, TermsComponentTest, StressHdfsTest, TestOverriddenPrefixQueryForCustomFieldType, TestManagedSynonymFilterFactory, ReplicationFactorTest, TestPostingsSolrHighlighter, TestCoreBackup, TestSkipOverseerOperations, TestReRankQParserPlugin, TestBulkSchemaAPI, QueryResultKeyTest, TestConfigsApi, TestLuceneIndexBackCompat, NoCacheHeaderTest, TestRandomRequestDistribution, TestReqParamsAPI, TestBinaryResponseWriter, TestAuthorizationFramework, TestPolicyCloud, DeleteInactiveReplicaTest, TestStressVersions, TestNRTOpen, ZkStateWriterTest, TestDocBasedVersionConstraints, LeaderInitiatedRecoveryOnCommitTest, SpatialRPTFieldTypeTest, TestNumericTokenStream, TestScoreJoinQPNoScore, TestExpandComponent, HdfsTlogReplayBufferedWhileIndexingTest, TestLeaderInitiatedRecoveryThread, TestExactSharedStatsCache, GraphQueryTest, TestConfigSetsAPIZkFailure, TestSolrDeletionPolicy1, TestSolrCloudWithSecureImpersonation, TestGeoJSONResponseWriter, DateMathParserTest, TestAddFieldRealTimeGet, LeaderElectionIntegrationTest, MetricsConfigTest, TestMiniSolrCloudClusterSSL, LeaderElectionContextKeyTest, JsonLoaderTest, BlockJoinFacetDistribTest, SolrPluginUtilsTest, SpatialFilterTest, BlockDirectoryTest, SSLMigrationTest, BasicFunctionalityTest, TestLargeCluster, TestRandomFaceting, ShardRoutingCustomTest, TestDistributedSearch, ZkControllerTest, TestStressReorder, TestReload, TestCoreContainer, TestSolr4Spatial, SolrCmdDistributorTest, TestFiltering, TestIndexSearcher, HighlighterTest, ShowFileRequestHandlerTest, SolrIndexSplitterTest, SimplePostToolTest, TestCoreDiscovery, SuggesterTest, SuggesterWFSTTest, PolyFieldTest, TestUpdate, TestWordDelimiterFilterFactory, SortByFunctionTest, TestRemoteStreaming, DocValuesMultiTest, SolrInfoBeanTest, CacheHeaderTest, DisMaxRequestHandlerTest, TestQueryUtils, TestWriterPerf, FileBasedSpellCheckerTest, TestValueSourceCache, TermVectorComponentTest, IndexSchemaRuntimeFieldTest, RegexBoostProcessorTest, ReturnFieldsTest, MBeansHandlerTest, TestDocSet, TestSearchPerf, TestElisionMultitermQuery, OutputWriterTest, NotRequiredUniqueKeyTest, TestCharFilters, TestCodecSupport, TestLMDirichletSimilarityFactory, TestIBSimilarityFactory, TestSuggestSpellingConverter, SpellingQueryConverterTest, DOMUtilTest, TestUtils, ZkNodePropsTest, FileUtilsTest, SolrTestCaseJ4Test, TestCursorMarkWithoutUniqueKey, TestHighlightDedupGrouping, TestSimpleTrackingShardHandler, AssignTest, CollectionsAPIAsyncDistributedZkTest, ConnectionManagerTest, DeleteLastCustomShardedReplicaTest, DeleteShardTest, DeleteStatusTest, DistribDocExpirationUpdateProcessorTest, MoveReplicaHDFSTest, TestCloudDeleteByQuery, TestCloudPseudoReturnFields, TestCollectionAPI, TestCollectionsAPIViaSolrCloudCluster, TestDeleteCollectionOnDownNodes, TestDistribDocBasedVersion, TestHdfsCloudBackupRestore, TestPullReplicaErrorHandling, TestReplicaProperties, TestSizeLimitedDistributedMap, TestTolerantUpdateProcessorCloud, ZkFailoverTest, SystemLogListenerTest, TestNodeLostTrigger, TestTriggerIntegration, CdcrBootstrapTest, HdfsRecoverLeaseTest, TestCorePropertiesReload, TestImplicitCoreProperties, TestInfoStreamLogging, TestReloadAndDeleteDocs, RequestLoggingTest, SearchHandlerTest, TestConfigReload, TestSQLHandlerNonCloud, TestSystemCollAutoCreate, AutoscalingHistoryHandlerTest, CoreAdminCreateDiscoverTest, CoreAdminRequestStatusTest, CoreMergeIndexesAdminHandlerTest, SecurityConfHandlerTest, StatsReloadRaceTest, TestCoreAdminApis, DistributedExpandComponentTest, DistributedFacetPivotLargeTest, FacetPivotSmallTest, InfixSuggestersTest, SuggestComponentContextFilterQueryTest, TestDistributedStatsComponentCardinality, DateRangeFieldTest, DocValuesMissingTest, TestCloudSchemaless, TestNestedDocsSort, TestScoreJoinQPScore, CloudMLTQParserTest, TestDistribIDF, BasicAuthStandaloneTest, PKIAuthenticationIntegrationTest, TestRuleBasedAuthorizationPlugin, TestSha256AuthenticationProvider, TestDelegationWithHadoopAuth, TestFreeTextSuggestions, BlockCacheTest, TestDocTermOrds, TestFieldCacheReopen, TestFieldCacheSortRandom, TestFieldCacheVsDocValues, TestHdfsUpdateLog, TimeRoutedAliasUpdateProcessorTest] [junit4] Completed [753/764 (4!)] on J0 in 18.69s, 2 tests, 1 failure <<< FAILURES! [...truncated 48049 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org