From dev-return-325785-archive-asf-public=cust-asf.ponee.io@lucene.apache.org Mon Jun 18 00:38:57 2018 Return-Path: X-Original-To: archive-asf-public@cust-asf.ponee.io Delivered-To: archive-asf-public@cust-asf.ponee.io Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by mx-eu-01.ponee.io (Postfix) with SMTP id 2CE2118062A for ; Mon, 18 Jun 2018 00:38:54 +0200 (CEST) Received: (qmail 26381 invoked by uid 500); 17 Jun 2018 22:38:53 -0000 Mailing-List: contact dev-help@lucene.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: dev@lucene.apache.org Delivered-To: mailing list dev@lucene.apache.org Received: (qmail 26371 invoked by uid 99); 17 Jun 2018 22:38:52 -0000 Received: from pnap-us-west-generic-nat.apache.org (HELO spamd4-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Sun, 17 Jun 2018 22:38:52 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd4-us-west.apache.org (ASF Mail Server at spamd4-us-west.apache.org) with ESMTP id 505F7C02C9 for ; Sun, 17 Jun 2018 22:38:52 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd4-us-west.apache.org X-Spam-Flag: NO X-Spam-Score: -1.501 X-Spam-Level: X-Spam-Status: No, score=-1.501 tagged_above=-999 required=6.31 tests=[KAM_ASCII_DIVIDERS=0.8, RCVD_IN_DNSWL_MED=-2.3, SPF_PASS=-0.001] autolearn=disabled Received: from mx1-lw-eu.apache.org ([10.40.0.8]) by localhost (spamd4-us-west.apache.org [10.40.0.11]) (amavisd-new, port 10024) with ESMTP id WBJi8iPug0LI for ; Sun, 17 Jun 2018 22:38:36 +0000 (UTC) Received: from serv1.sd-datasolutions.de (serv1.sd-datasolutions.de [88.99.242.108]) by mx1-lw-eu.apache.org (ASF Mail Server at mx1-lw-eu.apache.org) with ESMTP id 8D5C65F42F for ; Sun, 17 Jun 2018 22:38:35 +0000 (UTC) Received: from serv1 (ip6-localhost [IPv6:::1]) by serv1.sd-datasolutions.de (Postfix) with ESMTP id EEDC110800D0; Sun, 17 Jun 2018 22:38:28 +0000 (UTC) Date: Sun, 17 Jun 2018 22:36:44 +0000 (UTC) From: Policeman Jenkins Server To: sarowe@apache.org, simonw@apache.org, uwe@thetaphi.de, dev@lucene.apache.org Message-ID: <1593994355.33.1529275108979.JavaMail.jenkins@serv1.sd-datasolutions.de> In-Reply-To: <1189652984.17.1529221321072.JavaMail.jenkins@serv1.sd-datasolutions.de> References: <1189652984.17.1529221321072.JavaMail.jenkins@serv1.sd-datasolutions.de> Subject: [JENKINS] Lucene-Solr-7.x-Windows (32bit/jdk1.8.0_172) - Build # 639 - Still Unstable! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_32_154309601.1529275108906" X-Jenkins-Job: Lucene-Solr-7.x-Windows X-Jenkins-Result: UNSTABLE ------=_Part_32_154309601.1529275108906 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Windows/639/ Java: 32bit/jdk1.8.0_172 -client -XX:+UseParallelGC 4 tests failed. FAILED: org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration.testT= riggerThrottling Error Message: Both triggers should have fired by now Stack Trace: java.lang.AssertionError: Both triggers should have fired by now =09at __randomizedtesting.SeedInfo.seed([6BDE10934885FF2C:90FCB8B69A2F1CBE]= :0) =09at org.junit.Assert.fail(Assert.java:93) =09at org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration.testTrig= gerThrottling(TestTriggerIntegration.java:185) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.= java:62) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:498) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1737) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:934) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(Random= izedRunner.java:970) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(Rando= mizedRunner.java:984) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRu= leSetupTeardownChained.java:49) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleT= hreadAndTestName.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTa= sk(ThreadLeakControl.java:817) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(Threa= dLeakControl.java:468) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran= domizedRunner.java:943) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:829) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:879) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:890) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:41) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:53) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:54) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at java.lang.Thread.run(Thread.java:748) FAILED: org.apache.solr.common.util.TestTimeSource.testEpochTime Error Message: SimTimeSource:50.0 time diff=3D27255000 Stack Trace: java.lang.AssertionError: SimTimeSource:50.0 time diff=3D27255000 =09at __randomizedtesting.SeedInfo.seed([C210EF59AAC60F37:FA7C9C7C3E16AD71]= :0) =09at org.junit.Assert.fail(Assert.java:93) =09at org.junit.Assert.assertTrue(Assert.java:43) =09at org.apache.solr.common.util.TestTimeSource.doTestEpochTime(TestTimeSo= urce.java:52) =09at org.apache.solr.common.util.TestTimeSource.testEpochTime(TestTimeSour= ce.java:32) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.= java:62) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:498) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1737) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:934) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(Random= izedRunner.java:970) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(Rando= mizedRunner.java:984) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRu= leSetupTeardownChained.java:49) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleT= hreadAndTestName.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTa= sk(ThreadLeakControl.java:817) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(Threa= dLeakControl.java:468) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran= domizedRunner.java:943) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:829) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:879) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:890) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:41) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:53) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:54) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at java.lang.Thread.run(Thread.java:748) FAILED: org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration.testE= ventQueue Error Message: action wasn't interrupted Stack Trace: java.lang.AssertionError: action wasn't interrupted =09at __randomizedtesting.SeedInfo.seed([6BDE10934885FF2C:A26B523D41E239D9]= :0) =09at org.junit.Assert.fail(Assert.java:93) =09at org.junit.Assert.assertTrue(Assert.java:43) =09at org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration.testEven= tQueue(TestTriggerIntegration.java:657) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.= java:62) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:498) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1737) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:934) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(Random= izedRunner.java:970) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(Rando= mizedRunner.java:984) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRu= leSetupTeardownChained.java:49) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleT= hreadAndTestName.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTa= sk(ThreadLeakControl.java:817) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(Threa= dLeakControl.java:468) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran= domizedRunner.java:943) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:829) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:879) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:890) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:41) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:53) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:54) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at java.lang.Thread.run(Thread.java:748) FAILED: org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration.testE= ventQueue Error Message: action wasn't interrupted Stack Trace: java.lang.AssertionError: action wasn't interrupted =09at __randomizedtesting.SeedInfo.seed([6BDE10934885FF2C:A26B523D41E239D9]= :0) =09at org.junit.Assert.fail(Assert.java:93) =09at org.junit.Assert.assertTrue(Assert.java:43) =09at org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration.testEven= tQueue(TestTriggerIntegration.java:657) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.= java:62) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:498) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1737) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:934) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(Random= izedRunner.java:970) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(Rando= mizedRunner.java:984) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRu= leSetupTeardownChained.java:49) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleT= hreadAndTestName.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTa= sk(ThreadLeakControl.java:817) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(Threa= dLeakControl.java:468) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran= domizedRunner.java:943) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:829) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:879) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:890) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:41) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:53) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:54) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at java.lang.Thread.run(Thread.java:748) Build Log: [...truncated 13017 lines...] [junit4] Suite: org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegra= tion [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-7= .x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.sim.Tes= tTriggerIntegration_6BDE10934885FF2C-001\init-core-data-001 [junit4] 2> 1161903 WARN (SUITE-TestTriggerIntegration-seed#[6BDE1093= 4885FF2C]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOp= ens=3D11 numCloses=3D11 [junit4] 2> 1161906 INFO (SUITE-TestTriggerIntegration-seed#[6BDE1093= 4885FF2C]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_PO= INTS_SYSPROP=3Dtrue) w/NUMERIC_DOCVALUES_SYSPROP=3Dfalse [junit4] 2> 1161910 INFO (SUITE-TestTriggerIntegration-seed#[6BDE1093= 4885FF2C]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and cli= entAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=3D, value=3D= NaN, ssl=3DNaN, clientAuth=3DNaN) [junit4] 2> 1161910 INFO (SUITE-TestTriggerIntegration-seed#[6BDE1093= 4885FF2C]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: t= est.solr.allowed.securerandom=3Dnull & java.security.egd=3Dfile:/dev/./uran= dom [junit4] 2> 1161927 DEBUG (SUITE-TestTriggerIntegration-seed#[6BDE1093= 4885FF2C]-worker) [ ] o.a.s.c.a.s.SimClusterStateProvider --- new Overse= er leader: 127.0.0.1:10005_solr [junit4] 2> 1161927 INFO (SUITE-TestTriggerIntegration-seed#[6BDE1093= 4885FF2C]-worker) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collect= ion, keeping metrics history in memory. [junit4] 2> 1161927 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .sche= duled_maintenance triggers [junit4] 2> 1161927 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with z= node version 0 [junit4] 2> 1161935 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVe= rsion -1 [junit4] 2> 1161935 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znod= eVersion 0 [junit4] 2> 1161935 INFO (TEST-TestTriggerIntegration.testTriggerThro= ttling-seed#[6BDE10934885FF2C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting tes= tTriggerThrottling [junit4] 2> 1161935 DEBUG (TEST-TestTriggerIntegration.testTriggerThro= ttling-seed#[6BDE10934885FF2C]) [ ] o.a.s.c.a.OverseerTriggerThread Refr= eshing /autoscaling.json with znode version 1 [junit4] 2> 1161935 INFO (TEST-TestTriggerIntegration.testTriggerThro= ttling-seed#[6BDE10934885FF2C]) [ ] o.a.s.c.a.s.SimCloudManager =3D=3D= =3D Restarting OverseerTriggerThread and clearing object cache... [junit4] 2> 1161937 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Init= ial livenodes: [127.0.0.1:10006_solr, 127.0.0.1:10005_solr] [junit4] 2> 1161940 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 1161940 WARN (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread Interrupted [junit4] 2> java.lang.InterruptedException: null [junit4] 2> =09at java.util.concurrent.locks.AbstractQueuedSynchronize= r.acquireInterruptibly(AbstractQueuedSynchronizer.java:1220) ~[?:1.8.0_172] [junit4] 2> =09at java.util.concurrent.locks.ReentrantLock.lockInterru= ptibly(ReentrantLock.java:335) ~[?:1.8.0_172] [junit4] 2> =09at org.apache.solr.cloud.autoscaling.OverseerTriggerThr= ead.run(OverseerTriggerThread.java:177) [java/:?] [junit4] 2> =09at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172] [junit4] 2> 1161942 DEBUG (TEST-TestTriggerIntegration.testTriggerThro= ttling-seed#[6BDE10934885FF2C]) [ ] o.a.s.c.a.ScheduledTriggers Shutting= down scheduled thread pool executor now [junit4] 2> 1161943 DEBUG (TEST-TestTriggerIntegration.testTriggerThro= ttling-seed#[6BDE10934885FF2C]) [ ] o.a.s.c.a.ScheduledTriggers Shutting= down action executor now [junit4] 2> 1161943 DEBUG (TEST-TestTriggerIntegration.testTriggerThro= ttling-seed#[6BDE10934885FF2C]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting= termination for action executor [junit4] 2> 1161943 DEBUG (TEST-TestTriggerIntegration.testTriggerThro= ttling-seed#[6BDE10934885FF2C]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting= termination for scheduled thread pool executor [junit4] 2> 1161943 DEBUG (TEST-TestTriggerIntegration.testTriggerThro= ttling-seed#[6BDE10934885FF2C]) [ ] o.a.s.c.a.ScheduledTriggers Schedule= dTriggers closed completely [junit4] 2> 1161943 DEBUG (TEST-TestTriggerIntegration.testTriggerThro= ttling-seed#[6BDE10934885FF2C]) [ ] o.a.s.c.a.OverseerTriggerThread Over= seerTriggerThread has been closed explicitly [junit4] 2> 1161944 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .sche= duled_maintenance triggers [junit4] 2> 1161944 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with z= node version 2 [junit4] 2> 1161944 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVe= rsion -1 [junit4] 2> 1161944 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znod= eVersion 2 [junit4] 2> 1161945 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Init= ial livenodes: [127.0.0.1:10006_solr, 127.0.0.1:10005_solr] [junit4] 2> 1161945 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 1161945 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVe= rsion 2 [junit4] 2> 1161945 DEBUG (ScheduledTrigger-2923-thread-2) [ ] o.a.= s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curr= ently live nodes: 2 [junit4] 2> 1161966 DEBUG (ScheduledTrigger-2923-thread-2) [ ] o.a.= s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curr= ently live nodes: 2 [junit4] 2> 1161990 DEBUG (ScheduledTrigger-2923-thread-1) [ ] o.a.= s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curr= ently live nodes: 2 [junit4] 2> 1162013 DEBUG (ScheduledTrigger-2923-thread-1) [ ] o.a.= s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curr= ently live nodes: 2 [junit4] 2> 1162034 DEBUG (ScheduledTrigger-2923-thread-3) [ ] o.a.= s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curr= ently live nodes: 2 [junit4] 2> 1162046 DEBUG (simCloudManagerPool-2922-thread-1) [ ] o= .a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 1162046 DEBUG (simCloudManagerPool-2922-thread-1) [ ] o= .a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode vers= ion 3 [junit4] 2> 1162049 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znod= eVersion 3 [junit4] 2> 1162049 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 1162049 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVe= rsion 3 [junit4] 2> 1162052 DEBUG (simCloudManagerPool-2922-thread-2) [ ] o= .a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 1162052 DEBUG (simCloudManagerPool-2922-thread-2) [ ] o= .a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode vers= ion 4 [junit4] 2> 1162055 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znod= eVersion 4 [junit4] 2> 1162055 INFO (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.s.TestTriggerIntegration TestTriggerAction init [junit4] 2> 1162055 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 - I= nitial livenodes: [127.0.0.1:10006_solr, 127.0.0.1:10005_solr] [junit4] 2> 1162055 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 ins= tantiated with properties: {event=3DnodeAdded, waitFor=3D0, actions=3D[{nam= e=3Dtest, class=3Dorg.apache.solr.cloud.autoscaling.sim.TestTriggerIntegrat= ion$ThrottlingTesterAction}], enabled=3Dtrue} [junit4] 2> 1162055 DEBUG (simCloudManagerPool-2922-thread-3) [ ] o= .a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 1162055 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVe= rsion 4 [junit4] 2> 1162055 DEBUG (ScheduledTrigger-2923-thread-4) [ ] o.a.= s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1 [junit4] 2> 1162055 DEBUG (ScheduledTrigger-2923-thread-4) [ ] o.a.= s.c.a.NodeAddedTrigger Found livenodes: 2 [junit4] 2> 1162056 DEBUG (simCloudManagerPool-2922-thread-3) [ ] o= .a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode vers= ion 5 [junit4] 2> 1162056 DEBUG (ScheduledTrigger-2923-thread-1) [ ] o.a.= s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curr= ently live nodes: 2 [junit4] 2> 1162056 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znod= eVersion 5 [junit4] 2> 1162056 INFO (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.s.TestTriggerIntegration TestTriggerAction init [junit4] 2> 1162056 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger2 - I= nitial livenodes: [127.0.0.1:10006_solr, 127.0.0.1:10005_solr] [junit4] 2> 1162056 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger2 ins= tantiated with properties: {event=3DnodeAdded, waitFor=3D0, actions=3D[{nam= e=3Dtest, class=3Dorg.apache.solr.cloud.autoscaling.sim.TestTriggerIntegrat= ion$ThrottlingTesterAction}], enabled=3Dtrue} [junit4] 2> 1162056 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5, lastZnodeVe= rsion 5 [junit4] 2> 1162056 DEBUG (ScheduledTrigger-2923-thread-2) [ ] o.a.= s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2 [junit4] 2> 1162056 DEBUG (ScheduledTrigger-2923-thread-2) [ ] o.a.= s.c.a.NodeAddedTrigger Found livenodes: 2 [junit4] 2> 1162076 DEBUG (ScheduledTrigger-2923-thread-3) [ ] o.a.= s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curr= ently live nodes: 3 [junit4] 2> 1162080 DEBUG (ScheduledTrigger-2923-thread-2) [ ] o.a.= s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1 [junit4] 2> 1162081 DEBUG (ScheduledTrigger-2923-thread-1) [ ] o.a.= s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2 [junit4] 2> 1162081 DEBUG (ScheduledTrigger-2923-thread-2) [ ] o.a.= s.c.a.NodeAddedTrigger Found livenodes: 3 [junit4] 2> 1162081 DEBUG (ScheduledTrigger-2923-thread-1) [ ] o.a.= s.c.a.NodeAddedTrigger Found livenodes: 3 [junit4] 2> 1162081 DEBUG (ScheduledTrigger-2923-thread-1) [ ] o.a.= s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:10007_solr at time 3635= 02591873400 [junit4] 2> 1162081 DEBUG (ScheduledTrigger-2923-thread-2) [ ] o.a.= s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:10007_solr at time 3635= 02591873400 [junit4] 2> 1162081 DEBUG (ScheduledTrigger-2923-thread-2) [ ] o.a.= s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 firing register= ed processor for nodes: [127.0.0.1:10007_solr] added at times [363502591873= 400], now=3D363502594558400 [junit4] 2> 1162081 DEBUG (ScheduledTrigger-2923-thread-1) [ ] o.a.= s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger2 firing register= ed processor for nodes: [127.0.0.1:10007_solr] added at times [363502591873= 400], now=3D363502594558400 [junit4] 2> 1162082 DEBUG (ScheduledTrigger-2923-thread-2) [ ] o.a.= s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { [junit4] 2> "id":"14a9a8add1178T9gwpt82d4wpieod1zxqqh4scw", [junit4] 2> "source":"node_added_trigger1", [junit4] 2> "eventTime":363502591873400, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[363502591873400], [junit4] 2> "nodeNames":["127.0.0.1:10007_solr"]}} [junit4] 2> 1162082 DEBUG (ScheduledTrigger-2923-thread-1) [ ] o.a.= s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { [junit4] 2> "id":"14a9a8add1178T9gwpt82d4wpieod1zxqqh4scx", [junit4] 2> "source":"node_added_trigger2", [junit4] 2> "eventTime":363502591873400, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[363502591873400], [junit4] 2> "nodeNames":["127.0.0.1:10007_solr"]}} [junit4] 2> 1162083 DEBUG (ScheduledTrigger-2923-thread-2) [ ] o.a.= s.c.a.ScheduledTriggers Pausing all triggers: [.auto_add_replicas, node_add= ed_trigger2, node_added_trigger1] [junit4] 2> 1162109 DEBUG (simCloudManagerPool-2922-thread-9) [ ] o= .a.s.c.a.s.SimClusterStateProvider -- elected new leader for .system / shar= d1: {"core_node1":{ [junit4] 2> "core":".system_shard1_replica_n1", [junit4] 2> "leader":"true", [junit4] 2> "SEARCHER.searcher.maxDoc":0, [junit4] 2> "SEARCHER.searcher.deletedDocs":0, [junit4] 2> "INDEX.sizeInBytes":10240, [junit4] 2> "node_name":"127.0.0.1:10005_solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT", [junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6, [junit4] 2> "SEARCHER.searcher.numDocs":0, [junit4] 2> "shard":"shard1", [junit4] 2> "collection":".system"}} [junit4] 2> 1162457 INFO (TEST-TestTriggerIntegration.testTriggerThro= ttling-seed#[6BDE10934885FF2C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testT= riggerThrottling [junit4] 2> 1162459 INFO (TEST-TestTriggerIntegration.testTriggerThro= ttling-seed#[6BDE10934885FF2C]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ###= #################################### [junit4] 2> ############ CLUSTER STATE ############ [junit4] 2> ####################################### [junit4] 2> ## Live nodes:=09=093 [junit4] 2> ## Empty nodes:=092 [junit4] 2> ## Dead nodes:=09=090 [junit4] 2> ## Collections:=09[.system] [junit4] 2> ## Max replicas per node:=091 [junit4] 2> ## Min replicas per node:=090 [junit4] 2> ## Total replicas:=09=091 [junit4] 2> ## * .system=09=091 [junit4] 2> ##=09=09- active 1 [junit4] 2> ######### Solr op counts ########## [junit4] 2> ##=09=09- autoscaling 3 [junit4] 2> ##=09=09- update 2 [junit4] 2> ######### Autoscaling event counts ########### [junit4] 2> ## * Trigger: node_added_trigger2 [junit4] 2> ##=09=09- IGNORED 1 [junit4] 2>=20 [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=3DTestTriggerIn= tegration -Dtests.method=3DtestTriggerThrottling -Dtests.seed=3D6BDE1093488= 5FF2C -Dtests.slow=3Dtrue -Dtests.locale=3Dvi-VN -Dtests.timezone=3DAsia/Ni= cosia -Dtests.asserts=3Dtrue -Dtests.file.encoding=3DISO-8859-1 [junit4] FAILURE 0.53s J0 | TestTriggerIntegration.testTriggerThrottling= <<< [junit4] > Throwable #1: java.lang.AssertionError: Both triggers shou= ld have fired by now [junit4] > =09at __randomizedtesting.SeedInfo.seed([6BDE10934885FF2C:= 90FCB8B69A2F1CBE]:0) [junit4] > =09at org.apache.solr.cloud.autoscaling.sim.TestTriggerInt= egration.testTriggerThrottling(TestTriggerIntegration.java:185) [junit4] > =09at java.lang.Thread.run(Thread.java:748) [junit4] IGNOR/A 0.00s J0 | TestTriggerIntegration.testEventFromRestored= State [junit4] > Assumption #1: 'badapple' test group is disabled (@BadAppl= e(bugUrl=3Dhttps://issues.apache.org/jira/browse/SOLR-12028)) [junit4] IGNOR/A 0.00s J0 | TestTriggerIntegration.testNodeLostTriggerRe= storeState [junit4] > Assumption #1: 'badapple' test group is disabled (@BadAppl= e(bugUrl=3Dhttps://issues.apache.org/jira/browse/SOLR-12028)) [junit4] IGNOR/A 0.00s J0 | TestTriggerIntegration.testListeners [junit4] > Assumption #1: 'badapple' test group is disabled (@BadAppl= e(bugUrl=3Dhttps://issues.apache.org/jira/browse/SOLR-12028)) [junit4] IGNOR/A 0.00s J0 | TestTriggerIntegration.testCooldown [junit4] > Assumption #1: 'badapple' test group is disabled (@BadAppl= e(bugUrl=3Dhttps://issues.apache.org/jira/browse/SOLR-12028)) [junit4] IGNOR/A 0.00s J0 | TestTriggerIntegration.testSearchRate [junit4] > Assumption #1: 'badapple' test group is disabled (@BadAppl= e(bugUrl=3Dhttps://issues.apache.org/jira/browse/SOLR-12028)) [junit4] IGNOR/A 0.00s J0 | TestTriggerIntegration.testNodeLostTrigger [junit4] > Assumption #1: 'badapple' test group is disabled (@BadAppl= e(bugUrl=3Dhttps://issues.apache.org/jira/browse/SOLR-12028)) [junit4] IGNOR/A 0.00s J0 | TestTriggerIntegration.testNodeAddedTriggerR= estoreState [junit4] > Assumption #1: 'badapple' test group is disabled (@BadAppl= e(bugUrl=3Dhttps://issues.apache.org/jira/browse/SOLR-12028)) [junit4] 2> 1162492 INFO (TEST-TestTriggerIntegration.testNodeAddedTr= igger-seed#[6BDE10934885FF2C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test= NodeAddedTrigger [junit4] 2> 1162492 DEBUG (TEST-TestTriggerIntegration.testNodeAddedTr= igger-seed#[6BDE10934885FF2C]) [ ] o.a.s.c.a.OverseerTriggerThread Refre= shing /autoscaling.json with znode version 6 [junit4] 2> 1162492 DEBUG (TEST-TestTriggerIntegration.testNodeAddedTr= igger-seed#[6BDE10934885FF2C]) [ ] o.a.s.c.a.s.SimClusterStateProvider -= -- new Overseer leader: 127.0.0.1:10006_solr [junit4] 2> 1162492 DEBUG (TEST-TestTriggerIntegration.testNodeAddedTr= igger-seed#[6BDE10934885FF2C]) [ ] o.a.s.c.a.s.SimNodeStateProvider --re= moving value for 127.0.0.1:10005_solr [junit4] 2> 1162492 INFO (TEST-TestTriggerIntegration.testNodeAddedTr= igger-seed#[6BDE10934885FF2C]) [ ] o.a.s.c.a.s.SimCloudManager =3D=3D=3D= Restarting OverseerTriggerThread and clearing object cache... [junit4] 2> 1162492 DEBUG (TEST-TestTriggerIntegration.testNodeAddedTr= igger-seed#[6BDE10934885FF2C]) [ ] o.a.s.c.a.ScheduledTriggers Shutting = down scheduled thread pool executor now [junit4] 2> 1162493 DEBUG (TEST-TestTriggerIntegration.testNodeAddedTr= igger-seed#[6BDE10934885FF2C]) [ ] o.a.s.c.a.ScheduledTriggers Shutting = down action executor now [junit4] 2> 1162493 DEBUG (TEST-TestTriggerIntegration.testNodeAddedTr= igger-seed#[6BDE10934885FF2C]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting = termination for action executor [junit4] 2> 1162493 DEBUG (TEST-TestTriggerIntegration.testNodeAddedTr= igger-seed#[6BDE10934885FF2C]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting = termination for scheduled thread pool executor [junit4] 2> 1162494 WARN (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but = we are closed, exiting. [junit4] 2> 1162494 WARN (ScheduledTrigger-2923-thread-2) [ ] o.a.= s.c.a.SystemLogListener Exception sending event to collection .system [junit4] 2> java.io.IOException: java.lang.InterruptedException [junit4] 2> =09at org.apache.solr.cloud.autoscaling.sim.SimCloudManage= r.request(SimCloudManager.java:632) ~[test/:?] [junit4] 2> =09at org.apache.solr.cloud.autoscaling.SystemLogListener.= onEvent(SystemLogListener.java:118) ~[java/:?] [junit4] 2> =09at org.apache.solr.cloud.autoscaling.ScheduledTriggers$= TriggerListeners.fireListeners(ScheduledTriggers.java:790) ~[java/:?] [junit4] 2> =09at org.apache.solr.cloud.autoscaling.ScheduledTriggers$= TriggerListeners.fireListeners(ScheduledTriggers.java:757) ~[java/:?] [junit4] 2> =09at org.apache.solr.cloud.autoscaling.ScheduledTriggers.= lambda$add$4(ScheduledTriggers.java:283) ~[java/:?] [junit4] 2> =09at org.apache.solr.cloud.autoscaling.NodeAddedTrigger.r= un(NodeAddedTrigger.java:161) [java/:?] [junit4] 2> =09at org.apache.solr.cloud.autoscaling.ScheduledTriggers$= TriggerWrapper.run(ScheduledTriggers.java:589) [java/:?] [junit4] 2> =09at java.util.concurrent.Executors$RunnableAdapter.call(= Executors.java:511) [?:1.8.0_172] [junit4] 2> =09at java.util.concurrent.FutureTask.runAndReset(FutureTa= sk.java:308) [?:1.8.0_172] [junit4] 2> =09at java.util.concurrent.ScheduledThreadPoolExecutor$Sch= eduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180) [?:1.8.0_= 172] [junit4] 2> =09at java.util.concurrent.ScheduledThreadPoolExecutor$Sch= eduledFutureTask.run(ScheduledThreadPoolExecutor.java:294) [?:1.8.0_172] [junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor.runWorker(Th= readPoolExecutor.java:1149) [?:1.8.0_172] [junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor$Worker.run(T= hreadPoolExecutor.java:624) [?:1.8.0_172] [junit4] 2> =09at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172] [junit4] 2> Caused by: java.lang.InterruptedException [junit4] 2> =09at java.util.concurrent.FutureTask.awaitDone(FutureTask= .java:404) ~[?:1.8.0_172] [junit4] 2> =09at java.util.concurrent.FutureTask.get(FutureTask.java:= 191) ~[?:1.8.0_172] [junit4] 2> =09at org.apache.solr.cloud.autoscaling.sim.SimCloudManage= r.request(SimCloudManager.java:630) ~[test/:?] [junit4] 2> =09... 13 more [junit4] 2> 1162524 DEBUG (simCloudManagerPool-2922-thread-10) [ ] = o.a.s.c.a.s.SimClusterStateProvider -- no leader in shard1:{ [junit4] 2> "replicas":{"core_node3":{ [junit4] 2> "core":".system_shard1_replica_n1", [junit4] 2> "SEARCHER.searcher.maxDoc":0, [junit4] 2> "SEARCHER.searcher.deletedDocs":0, [junit4] 2> "INDEX.sizeInBytes":10240, [junit4] 2> "node_name":"127.0.0.1:10006_solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT", [junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6, [junit4] 2> "SEARCHER.searcher.numDocs":0}}, [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active"} [junit4] 2> 1162524 WARN (ScheduledTrigger-2923-thread-2) [ ] o.a.= s.c.a.ScheduledTriggers Ignoring autoscaling event { [junit4] 2> "id":"14a9a8add1178T9gwpt82d4wpieod1zxqqh4scw", [junit4] 2> "source":"node_added_trigger1", [junit4] 2> "eventTime":363502591873400, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[363502591873400], [junit4] 2> "_enqueue_time_":363502688818400, [junit4] 2> "nodeNames":["127.0.0.1:10007_solr"]}} from trigger or= g.apache.solr.cloud.autoscaling.NodeAddedTrigger@aa2b886d because the execu= tor has already been closed [junit4] 2> 1162525 DEBUG (TEST-TestTriggerIntegration.testNodeAddedTr= igger-seed#[6BDE10934885FF2C]) [ ] o.a.s.c.a.ScheduledTriggers Scheduled= Triggers closed completely [junit4] 2> 1162525 DEBUG (TEST-TestTriggerIntegration.testNodeAddedTr= igger-seed#[6BDE10934885FF2C]) [ ] o.a.s.c.a.OverseerTriggerThread Overs= eerTriggerThread has been closed explicitly [junit4] 2> 1162527 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .sche= duled_maintenance triggers [junit4] 2> 1162527 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with z= node version 7 [junit4] 2> 1162528 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 7, lastZnodeVe= rsion -1 [junit4] 2> 1162528 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znod= eVersion 7 [junit4] 2> 1162528 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Init= ial livenodes: [127.0.0.1:10006_solr, 127.0.0.1:10007_solr] [junit4] 2> 1162529 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 1162529 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 7, lastZnodeVe= rsion 7 [junit4] 2> 1162529 DEBUG (ScheduledTrigger-2926-thread-1) [ ] o.a.= s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curr= ently live nodes: 2 [junit4] 2> 1162553 DEBUG (ScheduledTrigger-2926-thread-2) [ ] o.a.= s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curr= ently live nodes: 2 [junit4] 2> 1162575 DEBUG (ScheduledTrigger-2926-thread-4) [ ] o.a.= s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curr= ently live nodes: 2 [junit4] 2> 1162598 DEBUG (ScheduledTrigger-2926-thread-2) [ ] o.a.= s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curr= ently live nodes: 2 [junit4] 2> 1162618 DEBUG (ScheduledTrigger-2926-thread-2) [ ] o.a.= s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curr= ently live nodes: 2 [junit4] 2> 1162627 DEBUG (simCloudManagerPool-2925-thread-1) [ ] o= .a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 1162627 DEBUG (simCloudManagerPool-2925-thread-1) [ ] o= .a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode vers= ion 8 [junit4] 2> 1162628 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znod= eVersion 8 [junit4] 2> 1162629 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 1162629 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 8, lastZnodeVe= rsion 8 [junit4] 2> 1162630 DEBUG (simCloudManagerPool-2925-thread-2) [ ] o= .a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 1162630 DEBUG (simCloudManagerPool-2925-thread-2) [ ] o= .a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode vers= ion 9 [junit4] 2> 1162631 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znod= eVersion 9 [junit4] 2> 1162631 INFO (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.s.TestTriggerIntegration TestTriggerAction init [junit4] 2> 1162631 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger - In= itial livenodes: [127.0.0.1:10006_solr, 127.0.0.1:10007_solr] [junit4] 2> 1162631 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger inst= antiated with properties: {event=3DnodeAdded, waitFor=3D3, actions=3D[{name= =3Dtest, class=3Dorg.apache.solr.cloud.autoscaling.sim.TestTriggerIntegrati= on$TestTriggerAction}], enabled=3Dtrue} [junit4] 2> 1162631 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger - re= moving marker path: /autoscaling/nodeAdded/127.0.0.1:10008_solr [junit4] 2> 1162632 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 9, lastZnodeVe= rsion 9 [junit4] 2> 1162632 DEBUG (ScheduledTrigger-2926-thread-1) [ ] o.a.= s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 1162632 DEBUG (ScheduledTrigger-2926-thread-1) [ ] o.a.= s.c.a.NodeAddedTrigger Found livenodes: 3 [junit4] 2> 1162632 DEBUG (ScheduledTrigger-2926-thread-1) [ ] o.a.= s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:10008_solr at time 3635= 30133888400 [junit4] 2> 1162641 DEBUG (ScheduledTrigger-2926-thread-1) [ ] o.a.= s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curr= ently live nodes: 3 [junit4] 2> 1162656 DEBUG (ScheduledTrigger-2926-thread-4) [ ] o.a.= s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 1162656 DEBUG (ScheduledTrigger-2926-thread-4) [ ] o.a.= s.c.a.NodeAddedTrigger Found livenodes: 3 [junit4] 2> 1162661 DEBUG (ScheduledTrigger-2926-thread-3) [ ] o.a.= s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curr= ently live nodes: 3 [junit4] 2> 1162677 DEBUG (ScheduledTrigger-2926-thread-2) [ ] o.a.= s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 1162677 DEBUG (ScheduledTrigger-2926-thread-2) [ ] o.a.= s.c.a.NodeAddedTrigger Found livenodes: 3 [junit4] 2> 1162683 DEBUG (ScheduledTrigger-2926-thread-1) [ ] o.a.= s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curr= ently live nodes: 3 [junit4] 2> 1162698 DEBUG (ScheduledTrigger-2926-thread-4) [ ] o.a.= s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 1162698 DEBUG (ScheduledTrigger-2926-thread-4) [ ] o.a.= s.c.a.NodeAddedTrigger Found livenodes: 3 [junit4] 2> 1162698 DEBUG (ScheduledTrigger-2926-thread-4) [ ] o.a.= s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger firing registere= d processor for nodes: [127.0.0.1:10008_solr] added at times [3635301338884= 00], now=3D363533430998400 [junit4] 2> 1162698 DEBUG (ScheduledTrigger-2926-thread-4) [ ] o.a.= s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { [junit4] 2> "id":"14aa0f47edd90T9gwpt82d4wpieod1zxqqh4sd1", [junit4] 2> "source":"node_added_trigger", [junit4] 2> "eventTime":363530133888400, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[363530133888400], [junit4] 2> "nodeNames":["127.0.0.1:10008_solr"]}} [junit4] 2> 1162699 DEBUG (ScheduledTrigger-2926-thread-4) [ ] o.a.= s.c.a.ScheduledTriggers Pausing all triggers: [.auto_add_replicas, node_add= ed_trigger] [junit4] 2> 1162702 DEBUG (simCloudManagerPool-2925-thread-3) [ ] o= .a.s.c.a.s.SimClusterStateProvider -- no leader in shard1:{ [junit4] 2> "replicas":{"core_node3":{ [junit4] 2> "core":".system_shard1_replica_n1", [junit4] 2> "SEARCHER.searcher.maxDoc":0, [junit4] 2> "SEARCHER.searcher.deletedDocs":0, [junit4] 2> "INDEX.sizeInBytes":10240, [junit4] 2> "node_name":"127.0.0.1:10006_solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT", [junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6, [junit4] 2> "SEARCHER.searcher.numDocs":0}}, [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active"} [junit4] 2> 1162702 DEBUG (ScheduledTrigger-2926-thread-4) [ ] o.a.= s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger - removing marke= r path: /autoscaling/nodeAdded/127.0.0.1:10008_solr [junit4] 2> 1162702 DEBUG (AutoscalingActionExecutor-2927-thread-1) [ = ] o.a.s.c.a.ScheduledTriggers -- processing actions for { [junit4] 2> "id":"14aa0f47edd90T9gwpt82d4wpieod1zxqqh4sd1", [junit4] 2> "source":"node_added_trigger", [junit4] 2> "eventTime":363530133888400, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[363530133888400], [junit4] 2> "_enqueue_time_":363533517083400, [junit4] 2> "nodeNames":["127.0.0.1:10008_solr"]}} [junit4] 2> 1162715 DEBUG (simCloudManagerPool-2925-thread-5) [ ] o= .a.s.c.a.s.SimClusterStateProvider -- no leader in shard1:{ [junit4] 2> "replicas":{"core_node3":{ [junit4] 2> "core":".system_shard1_replica_n1", [junit4] 2> "SEARCHER.searcher.maxDoc":0, [junit4] 2> "SEARCHER.searcher.deletedDocs":0, [junit4] 2> "INDEX.sizeInBytes":10240, [junit4] 2> "node_name":"127.0.0.1:10006_solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT", [junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6, [junit4] 2> "SEARCHER.searcher.numDocs":0}}, [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active"} [junit4] 2> 1162715 DEBUG (AutoscalingActionExecutor-2927-thread-1) [ = ] o.a.s.c.a.ScheduledTriggers Resuming trigger: .auto_add_replicas after= 100ms [junit4] 2> 1162715 DEBUG (AutoscalingActionExecutor-2927-thread-1) [ = ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger after= 100ms [junit4] 2> 1162719 DEBUG (simCloudManagerPool-2925-thread-4) [ ] o= .a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 1162723 DEBUG (simCloudManagerPool-2925-thread-4) [ ] o= .a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode vers= ion 10 [junit4] 2> 1162724 DEBUG (AutoscalingActionExecutor-2927-thread-1) [ = ] o.a.s.c.a.ScheduledTriggers -- processing took 1088 ms for event id=3D= 14aa0f47edd90T9gwpt82d4wpieod1zxqqh4sd1 [junit4] 2> 1162724 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znod= eVersion 10 [junit4] 2> 1162724 DEBUG (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 10, lastZnodeV= ersion 10 [junit4] 2> 1162766 INFO (TEST-TestTriggerIntegration.testNodeAddedTr= igger-seed#[6BDE10934885FF2C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testNo= deAddedTrigger [junit4] 2> 1162767 INFO (TEST-TestTriggerIntegration.testNodeAddedTr= igger-seed#[6BDE10934885FF2C]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase ####= ################################### [junit4] 2> ############ CLUSTER STATE ############ [junit4] 2> ####################################### [junit4] 2> ## Live nodes:=09=093 [junit4] 2> ## Empty nodes:=092 [junit4] 2> ## Dead nodes:=09=090 [junit4] 2> ## Collections:=09[.system] [junit4] 2> ## Max replicas per node:=091 [junit4] 2> ## Min replicas per node:=090 [junit4] 2> ## Total replicas:=09=091 [junit4] 2> ## * .system=09=091 [junit4] 2> ##=09=09- active 1 [junit4] 2> ######### Solr op counts ########## [junit4] 2> ##=09=09- autoscaling 3 [junit4] 2> ##=09=09- update 2 [junit4] 2> ######### Autoscaling event counts ########### [junit4] 2> ## * Trigger: node_added_trigger [junit4] 2> ##=09=09- STARTED 1 [junit4] 2> ##=09=09- SUCCEEDED 1 [junit4] 2> ## * Trigger: node_added_trigger1 [junit4] 2> ##=09=09- ABORTED 1 [junit4] 2>=20 [junit4] IGNOR/A 0.00s J0 | TestTriggerIntegration.testNodeMarkersRegist= ration [junit4] > Assumption #1: 'badapple' test group is disabled (@BadAppl= e(bugUrl=3Dhttps://issues.apache.org/jira/browse/SOLR-12028)) [junit4] IGNOR/A 0.00s J0 | TestTriggerIntegration.testEventQueue [junit4] > Assumption #1: 'badapple' test group is disabled (@BadAppl= e(bugUrl=3Dhttps://issues.apache.org/jira/browse/SOLR-12028)) [junit4] 2> 1162768 DEBUG (SUITE-TestTriggerIntegration-seed#[6BDE1093= 4885FF2C]-worker) [ ] o.a.s.c.a.ScheduledTriggers Shutting down schedule= d thread pool executor now [junit4] 2> 1162768 WARN (Simulated OverseerAutoScalingTriggerThread)= [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but = we are closed, exiting. [junit4] 2> 1162769 DEBUG (SUITE-TestTriggerIntegration-seed#[6BDE1093= 4885FF2C]-worker) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action e= xecutor now [junit4] 2> 1162769 DEBUG (SUITE-TestTriggerIntegration-seed#[6BDE1093= 4885FF2C]-worker) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination f= or action executor [junit4] 2> 1162770 DEBUG (SUITE-TestTriggerIntegration-seed#[6BDE1093= 4885FF2C]-worker) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination f= or scheduled thread pool executor [junit4] 2> 1162770 DEBUG (SUITE-TestTriggerIntegration-seed#[6BDE1093= 4885FF2C]-worker) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers clos= ed completely [junit4] 2> 1162770 DEBUG (SUITE-TestTriggerIntegration-seed#[6BDE1093= 4885FF2C]-worker) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThr= ead has been closed explicitly [junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins= \workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.c= loud.autoscaling.sim.TestTriggerIntegration_6BDE10934885FF2C-001 [junit4] 2> NOTE: test params are: codec=3DCheapBastard, sim=3DRandomS= imilarity(queryNorm=3Dtrue): {}, locale=3Dvi-VN, timezone=3DAsia/Nicosia [junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_172 (32= -bit)/cpus=3D3,threads=3D1,free=3D224937352,total=3D298057728 [junit4] 2> NOTE: All tests run in this JVM: [ZkFailoverTest, Tolerant= UpdateProcessorTest, TestMaxScoreQueryParser, NodeAddedTriggerIntegrationTe= st, TestSolrCoreSnapshots, TestComplexPhraseQParserPlugin, AssignBackwardCo= mpatibilityTest, TestConfigsApi, MoveReplicaHDFSFailoverTest, PrimUtilsTest= , CacheHeaderTest, TestManagedSynonymGraphFilterFactory, SolrIndexConfigTes= t, IndexSchemaRuntimeFieldTest, PeerSyncWithIndexFingerprintCachingTest, Te= stNumericTerms32, DocValuesNotIndexedTest, SolrIndexSplitterTest, AddReplic= aTest, TestCoreContainer, AutoscalingHistoryHandlerTest, TestBackupReposito= ryFactory, PKIAuthenticationIntegrationTest, TestLegacyTerms, TestJmxIntegr= ation, BigEndianAscendingWordSerializerTest, DocumentBuilderTest, NumericFi= eldsTest, AnalysisErrorHandlingTest, TestGenericDistributedQueue, TestConfi= gSetImmutable, TestPhraseSuggestions, BlockJoinFacetRandomTest, RegexBytesR= efFilterTest, TestNamedUpdateProcessors, LeaderFailureAfterFreshStartTest, = TestLRUCache, OverseerTaskQueueTest, TestShortCircuitedRequests, TestCloudC= onsistency, TestDistribStateManager, ShardSplitTest, HdfsRestartWhileUpdati= ngTest, BooleanFieldTest, TestReloadAndDeleteDocs, TestFoldingMultitermQuer= y, SolrXmlInZkTest, TestFieldCollectionResource, HdfsThreadLeakTest, OpenEx= changeRatesOrgProviderTest, TestPseudoReturnFields, TestSolrXml, TestMacroE= xpander, ParsingFieldUpdateProcessorsTest, TestBlobHandler, TestHighlightDe= dupGrouping, TestTrie, CreateRoutedAliasTest, AssignTest, TestPolicyCloud, = RecoveryZkTest, TestReplicationHandlerBackup, TestOrdValues, TestTestInject= ion, TestSolrConfigHandlerConcurrent, TestCollationFieldDocValues, TaggerTe= st, HighlighterTest, TestBinaryField, QueryElevationComponentTest, SuggestC= omponentTest, TestElisionMultitermQuery, RandomizedTaggerTest, TestTriggerI= ntegration] [junit4] Completed [169/817 (1!)] on J0 in 0.90s, 11 tests, 1 failure, 9= skipped <<< FAILURES! [...truncated 3322 lines...] [junit4] Suite: org.apache.solr.common.util.TestTimeSource [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-7= .x-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.util.TestTimeSour= ce_C210EF59AAC60F37-001\init-core-data-001 [junit4] 2> 474245 INFO (SUITE-TestTimeSource-seed#[C210EF59AAC60F37]= -worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSP= ROP=3Dtrue) w/NUMERIC_DOCVALUES_SYSPROP=3Dtrue [junit4] 2> 474247 INFO (SUITE-TestTimeSource-seed#[C210EF59AAC60F37]= -worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth = (true) via: @org.apache.solr.util.RandomizeSSL(reason=3D, value=3DNaN, ssl= =3DNaN, clientAuth=3DNaN) [junit4] 2> 474247 INFO (SUITE-TestTimeSource-seed#[C210EF59AAC60F37]= -worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.= allowed.securerandom=3Dnull & java.security.egd=3Dfile:/dev/./urandom [junit4] 2> 474248 INFO (TEST-TestTimeSource.testEpochTime-seed#[C210= EF59AAC60F37]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testEpochTime [junit4] 2> 484273 INFO (TEST-TestTimeSource.testEpochTime-seed#[C210= EF59AAC60F37]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testEpochTime [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=3DTestTimeSourc= e -Dtests.method=3DtestEpochTime -Dtests.seed=3DC210EF59AAC60F37 -Dtests.sl= ow=3Dtrue -Dtests.locale=3Dfr-CA -Dtests.timezone=3DEurope/Amsterdam -Dtest= s.asserts=3Dtrue -Dtests.file.encoding=3DUTF-8 [junit4] FAILURE 10.0s J0 | TestTimeSource.testEpochTime <<< [junit4] > Throwable #1: java.lang.AssertionError: SimTimeSource:50.0= time diff=3D27255000 [junit4] > =09at __randomizedtesting.SeedInfo.seed([C210EF59AAC60F37:= FA7C9C7C3E16AD71]:0) [junit4] > =09at org.apache.solr.common.util.TestTimeSource.doTestEpo= chTime(TestTimeSource.java:52) [junit4] > =09at org.apache.solr.common.util.TestTimeSource.testEpoch= Time(TestTimeSource.java:32) [junit4] > =09at java.lang.Thread.run(Thread.java:748) [junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins= \workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J0\temp\solr.= common.util.TestTimeSource_C210EF59AAC60F37-001 [junit4] 2> NOTE: test params are: codec=3DLucene70, sim=3DRandomSimil= arity(queryNorm=3Dtrue): {}, locale=3Dfr-CA, timezone=3DEurope/Amsterdam [junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_172 (32= -bit)/cpus=3D3,threads=3D1,free=3D184923328,total=3D289144832 [junit4] 2> NOTE: All tests run in this JVM: [CubedRootEvaluatorTest, = RegressionEvaluatorTest, CommonAdminParamsTest, SolrPingTest, UniformDistri= butionEvaluatorTest, TestFastWriter, TestLBHttpSolrClient, SolrZkClientTest= , TestSpellCheckResponse, TestValidatingJsonMap, HyperbolicCosineEvaluatorT= est, JDBCStreamTest, AscEvaluatorTest, AbsoluteValueEvaluatorTest, SquareRo= otEvaluatorTest, HttpSolrClientBuilderTest, RawValueEvaluatorTest, ArcTange= ntEvaluatorTest, GraphExpressionTest, SolrExampleEmbeddedTest, SolrDocument= Test, ArcCosineEvaluatorTest, NamedListTest, TestHash, HttpSolrClientSSLAut= hConPoolTest, RoundEvaluatorTest, TestBatchUpdate, TestClusteringResponse, = TestSolrJsonWriter, TestConfigSetAdminRequest, JdbcDriverTest, JettyWebappT= est, ModuloEvaluatorTest, StreamExpressionParserTest, LessThanEqualToEvalua= torTest, AnlysisResponseBaseTest, TestV2Request, FieldAnalysisResponseTest,= SolrExampleStreamingTest, SolrExampleBinaryTest, SolrExampleXMLTest, SolrE= xampleJettyTest, LargeVolumeBinaryJettyTest, LargeVolumeEmbeddedTest, TestS= olrProperties, TermsResponseTest, MergeIndexesEmbeddedTest, TestEmbeddedSol= rServer, TestCoreAdmin, TestNamedListCodec, TestJavaBinCodec, TestDocumentO= bjectBinder, ContentStreamTest, ModifiableSolrParamsTest, TestXMLEscaping, = DocumentAnalysisResponseTest, ClientUtilsTest, FacetFieldTest, UsingSolrJRe= fGuideExamplesTest, CollectionAdminRequestRequiredParamsTest, GetByIdTest, = SolrSchemalessExampleTest, TestPolicy, CloudSolrClientBuilderTest, CloudSol= rClientCacheTest, CloudSolrClientTest, SelectWithEvaluatorsTest, StreamExpr= essionTest, StreamExpressionToExpessionTest, StreamExpressionToExplanationT= est, StreamingTest, AndEvaluatorTest, AppendEvaluatorTest, CoalesceEvaluato= rTest, CorrelationEvaluatorTest, CosineEvaluatorTest, DivideEvaluatorTest, = EqualToEvaluatorTest, FieldValueEvaluatorTest, FloorEvaluatorTest, GreaterT= hanEqualToEvaluatorTest, GreaterThanEvaluatorTest, HyperbolicTangentEvaluat= orTest, LengthEvaluatorTest, NaturalLogEvaluatorTest, NormalDistributionEva= luatorTest, NormalizeEvaluatorTest, NotEvaluatorTest, OrEvaluatorTest, Recu= rsiveEvaluatorTest, ReverseEvaluatorTest, SineEvaluatorTest, TangentEvaluat= orTest, ConcatOperationTest, OperationsTest, TestV1toV2ApiMapper, TestSugge= sterResponse, TestCollectionStateWatchers, CommonParamsTest, ShardParamsTes= t, JsonValidatorTest, TestJsonRecordReader, TestPathTrie, TestRetryUtil, Te= stTimeSource] [junit4] Completed [153/154 (1!)] on J0 in 10.06s, 1 test, 1 failure <<<= FAILURES! [...truncated 45438 lines...] [repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Wi= ndows/639/consoleText [repro] Revision: e5407c5a9710247e5f728aae36224a245a51f0b1 [repro] Ant options: "-Dargs=3D-client -XX:+UseParallelGC" [repro] ant clean [...truncated 6 lines...] [repro] Test suites by module: [repro] solr\core [repro] TestTriggerIntegration [repro] solr\solrj [repro] TestTimeSource [repro] ant compile-test [...truncated 3339 lines...] [repro] ant test-nocompile -Dtests.dups=3D5 -Dtests.maxfailures=3D5 -Dtests= .class=3D"*.TestTriggerIntegration" -Dtests.showOutput=3Donerror "-Dargs=3D= -client -XX:+UseParallelGC" -Dtests.seed=3D6BDE10934885FF2C -Dtests.slow=3D= true -Dtests.locale=3Dvi-VN -Dtests.timezone=3DAsia/Nicosia -Dtests.asserts= =3Dtrue -Dtests.file.encoding=3DISO-8859-1 [...truncated 61 lines...] [junit4] Suite: org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegra= tion [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-7= .x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.sim.Tes= tTriggerIntegration_6BDE10934885FF2C-001\init-core-data-001 [junit4] 2> 15446 INFO (SUITE-TestTriggerIntegration-seed#[6BDE109348= 85FF2C]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POIN= TS_SYSPROP=3Dtrue) w/NUMERIC_DOCVALUES_SYSPROP=3Dfalse [junit4] 2> 15451 INFO (SUITE-TestTriggerIntegration-seed#[6BDE109348= 85FF2C]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clien= tAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=3D, value=3DNa= N, ssl=3DNaN, clientAuth=3DNaN) [junit4] 2> 15451 INFO (SUITE-TestTriggerIntegration-seed#[6BDE109348= 85FF2C]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: tes= t.solr.allowed.securerandom=3Dnull & java.security.egd=3Dfile:/dev/./urando= m [junit4] 2> 15465 DEBUG (SUITE-TestTriggerIntegration-seed#[6BDE109348= 85FF2C]-worker) [ ] o.a.s.c.a.s.SimClusterStateProvider --- new Overseer= leader: 127.0.0.1:10016_solr [junit4] 2> 15465 INFO (SUITE-TestTriggerIntegration-seed#[6BDE109348= 85FF2C]-worker) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collectio= n, keeping metrics history in memory. [junit4] 2> 15465 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .schedu= led_maintenance triggers [junit4] 2> 15465 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with zno= de version 0 [junit4] 2> 15465 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVers= ion -1 [junit4] 2> 15465 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeV= ersion 0 [junit4] 2> 15469 INFO (TEST-TestTriggerIntegration.testTriggerThrott= ling-seed#[6BDE10934885FF2C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testT= riggerThrottling [junit4] 2> 15469 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initia= l livenodes: [127.0.0.1:10016_solr, 127.0.0.1:10017_solr] [junit4] 2> 15469 DEBUG (TEST-TestTriggerIntegration.testTriggerThrott= ling-seed#[6BDE10934885FF2C]) [ ] o.a.s.c.a.OverseerTriggerThread Refres= hing /autoscaling.json with znode version 1 [junit4] 2> 15469 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 15469 INFO (TEST-TestTriggerIntegration.testTriggerThrott= ling-seed#[6BDE10934885FF2C]) [ ] o.a.s.c.a.s.SimCloudManager =3D=3D=3D = Restarting OverseerTriggerThread and clearing object cache... [junit4] 2> 15469 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVers= ion 0 [junit4] 2> 15469 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeV= ersion 1 [junit4] 2> 15470 DEBUG (TEST-TestTriggerIntegration.testTriggerThrott= ling-seed#[6BDE10934885FF2C]) [ ] o.a.s.c.a.ScheduledTriggers Shutting d= own scheduled thread pool executor now [junit4] 2> 15470 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 15470 DEBUG (TEST-TestTriggerIntegration.testTriggerThrott= ling-seed#[6BDE10934885FF2C]) [ ] o.a.s.c.a.ScheduledTriggers Shutting d= own action executor now [junit4] 2> 15470 DEBUG (TEST-TestTriggerIntegration.testTriggerThrott= ling-seed#[6BDE10934885FF2C]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting t= ermination for action executor [junit4] 2> 15470 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 15470 DEBUG (TEST-TestTriggerIntegration.testTriggerThrott= ling-seed#[6BDE10934885FF2C]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting t= ermination for scheduled thread pool executor [junit4] 2> 15470 DEBUG (TEST-TestTriggerIntegration.testTriggerThrott= ling-seed#[6BDE10934885FF2C]) [ ] o.a.s.c.a.ScheduledTriggers ScheduledT= riggers closed completely [junit4] 2> 15470 DEBUG (TEST-TestTriggerIntegration.testTriggerThrott= ling-seed#[6BDE10934885FF2C]) [ ] o.a.s.c.a.OverseerTriggerThread Overse= erTriggerThread has been closed explicitly [junit4] 2> 15470 WARN (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.OverseerTriggerThread Interrupted [junit4] 2> java.lang.InterruptedException: null [junit4] 2> =09at java.util.concurrent.locks.AbstractQueuedSynchronize= r.acquireInterruptibly(AbstractQueuedSynchronizer.java:1220) ~[?:1.8.0_172] [junit4] 2> =09at java.util.concurrent.locks.ReentrantLock.lockInterru= ptibly(ReentrantLock.java:335) ~[?:1.8.0_172] [junit4] 2> =09at org.apache.solr.cloud.autoscaling.OverseerTriggerThr= ead.run(OverseerTriggerThread.java:177) [java/:?] [junit4] 2> =09at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172] [junit4] 2> 15470 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .schedu= led_maintenance triggers [junit4] 2> 15470 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with zno= de version 2 [junit4] 2> 15471 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVers= ion -1 [junit4] 2> 15471 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeV= ersion 2 [junit4] 2> 15471 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initia= l livenodes: [127.0.0.1:10016_solr, 127.0.0.1:10017_solr] [junit4] 2> 15471 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 15471 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVers= ion 2 [junit4] 2> 15471 DEBUG (ScheduledTrigger-59-thread-2) [ ] o.a.s.c.= a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currentl= y live nodes: 2 [junit4] 2> 15493 DEBUG (ScheduledTrigger-59-thread-3) [ ] o.a.s.c.= a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currentl= y live nodes: 2 [junit4] 2> 15515 DEBUG (ScheduledTrigger-59-thread-4) [ ] o.a.s.c.= a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currentl= y live nodes: 2 [junit4] 2> 15539 DEBUG (ScheduledTrigger-59-thread-3) [ ] o.a.s.c.= a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currentl= y live nodes: 2 [junit4] 2> 15561 DEBUG (ScheduledTrigger-59-thread-4) [ ] o.a.s.c.= a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currentl= y live nodes: 2 [junit4] 2> 15571 DEBUG (simCloudManagerPool-58-thread-1) [ ] o.a.s= .c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 15571 DEBUG (simCloudManagerPool-58-thread-1) [ ] o.a.s= .c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version = 3 [junit4] 2> 15572 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeV= ersion 3 [junit4] 2> 15572 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 15572 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVers= ion 3 [junit4] 2> 15573 DEBUG (simCloudManagerPool-58-thread-2) [ ] o.a.s= .c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 15573 DEBUG (simCloudManagerPool-58-thread-2) [ ] o.a.s= .c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version = 4 [junit4] 2> 15574 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeV= ersion 4 [junit4] 2> 15574 INFO (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.s.TestTriggerIntegration TestTriggerAction init [junit4] 2> 15574 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 - Ini= tial livenodes: [127.0.0.1:10016_solr, 127.0.0.1:10017_solr] [junit4] 2> 15574 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 insta= ntiated with properties: {event=3DnodeAdded, waitFor=3D0, actions=3D[{name= =3Dtest, class=3Dorg.apache.solr.cloud.autoscaling.sim.TestTriggerIntegrati= on$ThrottlingTesterAction}], enabled=3Dtrue} [junit4] 2> 15574 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVers= ion 4 [junit4] 2> 15574 DEBUG (ScheduledTrigger-59-thread-3) [ ] o.a.s.c.= a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1 [junit4] 2> 15575 DEBUG (ScheduledTrigger-59-thread-3) [ ] o.a.s.c.= a.NodeAddedTrigger Found livenodes: 2 [junit4] 2> 15575 DEBUG (simCloudManagerPool-58-thread-3) [ ] o.a.s= .c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 15575 DEBUG (simCloudManagerPool-58-thread-3) [ ] o.a.s= .c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version = 5 [junit4] 2> 15575 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeV= ersion 5 [junit4] 2> 15577 INFO (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.s.TestTriggerIntegration TestTriggerAction init [junit4] 2> 15577 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger2 - Ini= tial livenodes: [127.0.0.1:10016_solr, 127.0.0.1:10017_solr] [junit4] 2> 15577 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger2 insta= ntiated with properties: {event=3DnodeAdded, waitFor=3D0, actions=3D[{name= =3Dtest, class=3Dorg.apache.solr.cloud.autoscaling.sim.TestTriggerIntegrati= on$ThrottlingTesterAction}], enabled=3Dtrue} [junit4] 2> 15577 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5, lastZnodeVers= ion 5 [junit4] 2> 15577 DEBUG (ScheduledTrigger-59-thread-1) [ ] o.a.s.c.= a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2 [junit4] 2> 15577 DEBUG (ScheduledTrigger-59-thread-1) [ ] o.a.s.c.= a.NodeAddedTrigger Found livenodes: 3 [junit4] 2> 15577 DEBUG (ScheduledTrigger-59-thread-1) [ ] o.a.s.c.= a.NodeAddedTrigger Tracking new node: 127.0.0.1:10018_solr at time 36351506= 0002200 [junit4] 2> 15577 DEBUG (ScheduledTrigger-59-thread-1) [ ] o.a.s.c.= a.NodeAddedTrigger NodeAddedTrigger node_added_trigger2 firing registered p= rocessor for nodes: [127.0.0.1:10018_solr] added at times [363515060002200]= , now=3D363515062367200 [junit4] 2> 15577 DEBUG (ScheduledTrigger-59-thread-1) [ ] o.a.s.c.= a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { [junit4] 2> "id":"14a9d72059d98T9gwpt82d4wpieod1zxqqh4rnl", [junit4] 2> "source":"node_added_trigger2", [junit4] 2> "eventTime":363515060002200, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[363515060002200], [junit4] 2> "nodeNames":["127.0.0.1:10018_solr"]}} [junit4] 2> 15577 DEBUG (ScheduledTrigger-59-thread-1) [ ] o.a.s.c.= a.ScheduledTriggers Pausing all triggers: [.auto_add_replicas, node_added_t= rigger2, node_added_trigger1] [junit4] 2> 15599 DEBUG (simCloudManagerPool-58-thread-4) [ ] o.a.s= .c.a.s.SimClusterStateProvider -- no leader in shard1:{ [junit4] 2> "replicas":{"core_node1":{ [junit4] 2> "core":".system_shard1_replica_n1", [junit4] 2> "SEARCHER.searcher.maxDoc":0, [junit4] 2> "SEARCHER.searcher.deletedDocs":0, [junit4] 2> "INDEX.sizeInBytes":10240, [junit4] 2> "node_name":"127.0.0.1:10017_solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT", [junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6, [junit4] 2> "SEARCHER.searcher.numDocs":0}}, [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active"} [junit4] 2> 15599 DEBUG (ScheduledTrigger-59-thread-1) [ ] o.a.s.c.= a.NodeAddedTrigger NodeAddedTrigger node_added_trigger2 - removing marker p= ath: /autoscaling/nodeAdded/127.0.0.1:10018_solr [junit4] 2> 15599 DEBUG (AutoscalingActionExecutor-60-thread-1) [ ]= o.a.s.c.a.ScheduledTriggers -- processing actions for { [junit4] 2> "id":"14a9d72059d98T9gwpt82d4wpieod1zxqqh4rnl", [junit4] 2> "source":"node_added_trigger2", [junit4] 2> "eventTime":363515060002200, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[363515060002200], [junit4] 2> "_enqueue_time_":363515082532200, [junit4] 2> "nodeNames":["127.0.0.1:10018_solr"]}} [junit4] 2> 15599 INFO (AutoscalingActionExecutor-60-thread-1) [ ]= o.a.s.c.a.s.TestTriggerIntegration last action at 363325939907200 time =3D= 363516192992200 [junit4] 2> 15599 INFO (AutoscalingActionExecutor-60-thread-1) [ ]= o.a.s.c.a.s.TestTriggerIntegration action executed from node_added_trigger= 2 [junit4] 2> 15600 DEBUG (simCloudManagerPool-58-thread-7) [ ] o.a.s= .c.a.s.SimClusterStateProvider -- no leader in shard1:{ [junit4] 2> "replicas":{"core_node1":{ [junit4] 2> "core":".system_shard1_replica_n1", [junit4] 2> "SEARCHER.searcher.maxDoc":0, [junit4] 2> "SEARCHER.searcher.deletedDocs":0, [junit4] 2> "INDEX.sizeInBytes":10240, [junit4] 2> "node_name":"127.0.0.1:10017_solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT", [junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6, [junit4] 2> "SEARCHER.searcher.numDocs":0}}, [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active"} [junit4] 2> 15600 DEBUG (AutoscalingActionExecutor-60-thread-1) [ ]= o.a.s.c.a.ScheduledTriggers Resuming trigger: .auto_add_replicas after 100= ms [junit4] 2> 15600 DEBUG (AutoscalingActionExecutor-60-thread-1) [ ]= o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger2 after 10= 0ms [junit4] 2> 15600 DEBUG (AutoscalingActionExecutor-60-thread-1) [ ]= o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger1 after 10= 0ms [junit4] 2> 15600 DEBUG (AutoscalingActionExecutor-60-thread-1) [ ]= o.a.s.c.a.ScheduledTriggers -- processing took 84 ms for event id=3D14a9d7= 2059d98T9gwpt82d4wpieod1zxqqh4rnl [junit4] 2> 15701 DEBUG (ScheduledTrigger-59-thread-3) [ ] o.a.s.c.= a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currentl= y live nodes: 3 [junit4] 2> 15701 DEBUG (ScheduledTrigger-59-thread-4) [ ] o.a.s.c.= a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2 [junit4] 2> 15702 DEBUG (ScheduledTrigger-59-thread-2) [ ] o.a.s.c.= a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1 [junit4] 2> 15702 DEBUG (ScheduledTrigger-59-thread-4) [ ] o.a.s.c.= a.NodeAddedTrigger Found livenodes: 3 [junit4] 2> 15702 DEBUG (ScheduledTrigger-59-thread-2) [ ] o.a.s.c.= a.NodeAddedTrigger Found livenodes: 3 [junit4] 2> 15702 DEBUG (ScheduledTrigger-59-thread-2) [ ] o.a.s.c.= a.NodeAddedTrigger Tracking new node: 127.0.0.1:10018_solr at time 36352132= 3872200 [junit4] 2> 15702 DEBUG (ScheduledTrigger-59-thread-2) [ ] o.a.s.c.= a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 firing registered p= rocessor for nodes: [127.0.0.1:10018_solr] added at times [363521323872200]= , now=3D363521327362200 [junit4] 2> 15702 DEBUG (ScheduledTrigger-59-thread-2) [ ] o.a.s.c.= a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { [junit4] 2> "id":"14a9ee760afc8T9gwpt82d4wpieod1zxqqh4rno", [junit4] 2> "source":"node_added_trigger1", [junit4] 2> "eventTime":363521323872200, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[363521323872200], [junit4] 2> "nodeNames":["127.0.0.1:10018_solr"]}} [junit4] 2> 15702 DEBUG (ScheduledTrigger-59-thread-2) [ ] o.a.s.c.= a.ScheduledTriggers Pausing all triggers: [.auto_add_replicas, node_added_t= rigger2, node_added_trigger1] [junit4] 2> 15703 DEBUG (simCloudManagerPool-58-thread-8) [ ] o.a.s= .c.a.s.SimClusterStateProvider -- no leader in shard1:{ [junit4] 2> "replicas":{"core_node1":{ [junit4] 2> "core":".system_shard1_replica_n1", [junit4] 2> "SEARCHER.searcher.maxDoc":0, [junit4] 2> "SEARCHER.searcher.deletedDocs":0, [junit4] 2> "INDEX.sizeInBytes":10240, [junit4] 2> "node_name":"127.0.0.1:10017_solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT", [junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6, [junit4] 2> "SEARCHER.searcher.numDocs":0}}, [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active"} [junit4] 2> 15703 DEBUG (ScheduledTrigger-59-thread-2) [ ] o.a.s.c.= a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 - removing marker p= ath: /autoscaling/nodeAdded/127.0.0.1:10018_solr [junit4] 2> 15703 DEBUG (AutoscalingActionExecutor-60-thread-1) [ ]= o.a.s.c.a.ScheduledTriggers -- processing actions for { [junit4] 2> "id":"14a9ee760afc8T9gwpt82d4wpieod1zxqqh4rno", [junit4] 2> "source":"node_added_trigger1", [junit4] 2> "eventTime":363521323872200, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[363521323872200], [junit4] 2> "_enqueue_time_":363521343912200, [junit4] 2> "nodeNames":["127.0.0.1:10018_solr"]}} [junit4] 2> 15703 INFO (AutoscalingActionExecutor-60-thread-1) [ ]= o.a.s.c.a.s.TestTriggerIntegration last action at 363516198762200 time =3D= 363521400127200 [junit4] 2> 15703 INFO (AutoscalingActionExecutor-60-thread-1) [ ]= o.a.s.c.a.s.TestTriggerIntegration action executed from node_added_trigger= 1 [junit4] 2> 15704 DEBUG (simCloudManagerPool-58-thread-10) [ ] o.a.= s.c.a.s.SimClusterStateProvider -- no leader in shard1:{ [junit4] 2> "replicas":{"core_node1":{ [junit4] 2> "core":".system_shard1_replica_n1", [junit4] 2> "SEARCHER.searcher.maxDoc":0, [junit4] 2> "SEARCHER.searcher.deletedDocs":0, [junit4] 2> "INDEX.sizeInBytes":10240, [junit4] 2> "node_name":"127.0.0.1:10017_solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT", [junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6, [junit4] 2> "SEARCHER.searcher.numDocs":0}}, [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active"} [junit4] 2> 15704 DEBUG (AutoscalingActionExecutor-60-thread-1) [ ]= o.a.s.c.a.ScheduledTriggers Resuming trigger: .auto_add_replicas after 100= ms [junit4] 2> 15704 DEBUG (AutoscalingActionExecutor-60-thread-1) [ ]= o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger2 after 10= 0ms [junit4] 2> 15704 DEBUG (AutoscalingActionExecutor-60-thread-1) [ ]= o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger1 after 10= 0ms [junit4] 2> 15704 DEBUG (AutoscalingActionExecutor-60-thread-1) [ ]= o.a.s.c.a.ScheduledTriggers -- processing took 51 ms for event id=3D14a9ee= 760afc8T9gwpt82d4wpieod1zxqqh4rno [junit4] 2> 15704 DEBUG (simCloudManagerPool-58-thread-9) [ ] o.a.s= .c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 15704 DEBUG (simCloudManagerPool-58-thread-9) [ ] o.a.s= .c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version = 6 [junit4] 2> 15706 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeV= ersion 6 [junit4] 2> 15706 INFO (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.s.TestTriggerIntegration TestTriggerAction init [junit4] 2> 15706 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger node_lost_trigger1 - Initia= l livenodes: [127.0.0.1:10018_solr, 127.0.0.1:10016_solr, 127.0.0.1:10017_s= olr] [junit4] 2> 15706 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6, lastZnodeVers= ion 6 [junit4] 2> 15706 DEBUG (ScheduledTrigger-59-thread-1) [ ] o.a.s.c.= a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger1 with currentl= y live nodes: 3 [junit4] 2> 15706 DEBUG (simCloudManagerPool-58-thread-11) [ ] o.a.= s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 15707 DEBUG (simCloudManagerPool-58-thread-11) [ ] o.a.= s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version= 7 [junit4] 2> 15707 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeV= ersion 7 [junit4] 2> 15707 INFO (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.s.TestTriggerIntegration TestTriggerAction init [junit4] 2> 15707 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger node_lost_trigger2 - Initia= l livenodes: [127.0.0.1:10018_solr, 127.0.0.1:10016_solr, 127.0.0.1:10017_s= olr] [junit4] 2> 15707 DEBUG (Simulated OverseerAutoScalingTriggerThread) [= ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 7, lastZnodeVers= ion 7 [junit4] 2> 15708 DEBUG (ScheduledTrigger-59-thread-3) [ ] o.a.s.c.= a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger2 with currentl= y live nodes: 2 [junit4] 2> 15708 DEBUG (ScheduledTrigger-59-thread-3) [ ] o.a.s.c.= a.NodeLostTrigger Tracking lost node: 127.0.0.1:10018_solr [junit4] 2> 15708 DEBUG (ScheduledTrigger-59-thread-3) [ ] o.a.s.c.= a.NodeLostTrigger NodeLostTrigger firing registered processor for lost node= s: [127.0.0.1:10018_solr] [junit4] 2> 15708 DEBUG (ScheduledTrigger-59-thread-3) [ ] o.a.s.c.= a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { [junit4] 2> "id":"14a9ef995dd80T9gwpt82d4wpieod1zxqqh4rnr", [junit4] 2> "source":"node_lost_trigger2", [junit4] 2> "eventTime":363521629347200, [junit4] 2> "eventType":"NODELOST", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[363521629347200], [junit4] 2> "nodeNames":["127.0.0.1:10018_solr"]}} [junit4] 2> 15708 DEBUG (ScheduledTrigger-59-thread-3) [ ] o.a.s.c.= a.ScheduledTriggers Pausing all triggers: [node_lost_trigger2, .auto_add_re= plicas, node_added_trigger2, node_lost_trigger1, node_added_trigger1] [junit4] 2> 15708 DEBUG (simCloudManagerPool-58-thread-12) [ ] o.a.= s.c.a.s.SimClusterStateProvider -- no leader in shard1:{ [junit4] 2> "replicas":{"core_node1":{ [junit4] 2> "core":".system_shard1_replica_n1", [junit4] 2> "SEARCHER.searcher.maxDoc":0, [junit4] 2> "SEARCHER.searcher.deletedDocs":0, [junit4] 2> "INDEX.sizeInBytes":10240, [junit4] 2> "node_name":"127.0.0.1:10017_solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT", [junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6, [junit4] 2> "SEARCHER.searcher.numDocs":0}}, [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active"} [junit4] 2> 15709 DEBUG (AutoscalingActionExecutor-60-thread-1) [ ]= o.a.s.c.a.ScheduledTriggers -- processing actions for { [junit4] 2> "id":"14a9ef995dd80T9gwpt82d4wpieod1zxqqh4rnr", [junit4] 2> "source":"node_lost_trigger2", [junit4] 2> "eventTime":363521629347200, [junit4] 2> "eventType":"NODELOST", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[363521629347200], [junit4] 2> "_enqueue_time_":363521640007200, [junit4] 2> "nodeNames":["127.0.0.1:10018_solr"]}} [junit4] 2> 15709 INFO (AutoscalingActionExecutor-60-thread-1) [ ]= o.a.s.c.a.s.TestTriggerIntegration action executed from node_lost_trigger2 [junit4] 2> 15709 DEBUG (simCloudManagerPool-58-thread-13) [ ] o.a.= s.c.a.s.SimClusterStateProvider -- no leader in shard1:{ [junit4] 2> "replicas":{"core_node1":{ [junit4] 2> "core":".system_shard1_replica_n1", [junit4] 2> "SEARCHER.searcher.maxDoc":0, [junit4] 2> "SEARCHER.searcher.deletedDocs":0, [junit4] 2> "INDEX.sizeInBytes":10240, [junit4] 2> "node_name":"127.0.0.1:10017_solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT", [junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6, [junit4] 2> "SEARCHER.searcher.numDocs":0}}, [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active"} [junit4] 2> 15710 DEBUG (AutoscalingActionExecutor-60-thread-1) [ ]= o.a.s.c.a.ScheduledTriggers Resuming trigger: node_lost_trigger2 after 100= ms [junit4] 2> 15710 DEBUG (AutoscalingActionExecutor-60-thread-1) [ ]= o.a.s.c.a.ScheduledTriggers Resuming trigger: .auto_add_replicas after 100= ms [junit4] 2> 15710 DEBUG (AutoscalingActionExecutor-60-thread-1) [ ]= o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger2 after 10= 0ms [junit4] 2> 15710 DEBUG (AutoscalingActionExecutor-60-thread-1) [ ]= o.a.s.c.a.ScheduledTriggers Resuming trigger: node_lost_trigger1 after 100= ms [junit4] 2> 15710 DEBUG (AutoscalingActionExecutor-60-thread-1) [ ]= o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger1 after 10= 0ms [junit4] 2> 15710 DEBUG (AutoscalingActionExecutor-60-thread-1) [ ]= o.a.s.c.a.ScheduledTriggers -- processing took 60 ms for event id=3D14a9ef= 995dd80T9gwpt82d4wpieod1zxqqh4rnr [junit4] 2> 15812 DEBUG (ScheduledTrigger-59-thread-2) [ ] o.a.s.c.= a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currentl= y live nodes: 2 [junit4] 2> 15812 DEBUG (ScheduledTrigger-59-thread-3) [ ] o.a.s.c.= a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2 [junit4] 2> 15812 DEBUG (ScheduledTrigger-59-thread-1) [ ] o.a.s.c.= a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger2 with currentl= y live [...truncated too long message...] rs: resolve-example: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D C:\Users\jenkins\workspace\= Lucene-Solr-7.x-Windows\lucene\top-level-ivy-settings.xml resolve: resolve-server: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D C:\Users\jenkins\workspace\= Lucene-Solr-7.x-Windows\lucene\top-level-ivy-settings.xml resolve: -check-git-state: -git-cleanroot: -copy-git-state: git-autoclean: ivy-fail: resolve: common.init: compile-lucene-core: -check-git-state: -git-cleanroot: -copy-git-state: git-autoclean: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D C:\Users\jenkins\workspace\= Lucene-Solr-7.x-Windows\lucene\top-level-ivy-settings.xml resolve: init: -clover.disable: -clover.load: -clover.classpath: -clover.setup: clover: compile-core: init: -clover.disable: -clover.load: -clover.classpath: -clover.setup: clover: common.compile-core: compile-core: compile-test-framework: -check-git-state: -git-cleanroot: -copy-git-state: git-autoclean: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D C:\Users\jenkins\workspace\= Lucene-Solr-7.x-Windows\lucene\top-level-ivy-settings.xml resolve: init: compile-lucene-core: compile-codecs: -check-git-state: -git-cleanroot: -copy-git-state: git-autoclean: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D C:\Users\jenkins\workspace\= Lucene-Solr-7.x-Windows\lucene\top-level-ivy-settings.xml resolve: common.init: compile-lucene-core: init: -clover.disable: -clover.load: -clover.classpath: -clover.setup: clover: compile-core: -clover.disable: -clover.load: -clover.classpath: -clover.setup: clover: common.compile-core: compile-core: common.compile-test: [mkdir] Created dir: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows= \solr\build\solr-solrj\classes\test [javac] Compiling 161 source files to C:\Users\jenkins\workspace\Lucene= -Solr-7.x-Windows\solr\build\solr-solrj\classes\test [javac] Note: Some input files use or override a deprecated API. [javac] Note: Recompile with -Xlint:deprecation for details. [javac] Note: Some input files use unchecked or unsafe operations. [javac] Note: Recompile with -Xlint:unchecked for details. compile-test: BUILD SUCCESSFUL Total time: 10 seconds [repro] ant test-nocompile -Dtests.dups=3D5 -Dtests.maxfailures=3D5 -Dtests= .class=3D"*.TestTimeSource" -Dtests.showOutput=3Donerror "-Dargs=3D-client = -XX:+UseParallelGC" -Dtests.seed=3DC210EF59AAC60F37 -Dtests.slow=3Dtrue -Dt= ests.locale=3Dfr-CA -Dtests.timezone=3DEurope/Amsterdam -Dtests.asserts=3Dt= rue -Dtests.file.encoding=3DUTF-8 Buildfile: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\solrj\bu= ild.xml -clover.disable: ivy-configure: [ivy:configure] :: Apache Ivy 2.4.0 - 20141213170938 :: http://ant.apache.o= rg/ivy/ :: [ivy:configure] :: loading settings :: file =3D C:\Users\jenkins\workspace\= Lucene-Solr-7.x-Windows\lucene\top-level-ivy-settings.xml install-junit4-taskdef: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: resolve-groovy: [ivy:cachepath] :: resolving dependencies :: org.codehaus.groovy#groovy-all= -caller;working [ivy:cachepath] =09confs: [default] [ivy:cachepath] =09found org.codehaus.groovy#groovy-all;2.4.15 in public [ivy:cachepath] :: resolution report :: resolve 15ms :: artifacts dl 1ms =09--------------------------------------------------------------------- =09| | modules || artifacts | =09| conf | number| search|dwnlded|evicted|| number|dwnlded| =09--------------------------------------------------------------------- =09| default | 1 | 0 | 0 | 0 || 1 | 0 | =09--------------------------------------------------------------------- -init-totals: -test: [mkdir] Created dir: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows= \solr\build\solr-solrj\test [junit4:pickseed] Seed property 'tests.seed' already defined: C210EF59AAC60= F37 [mkdir] Created dir: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows= \solr\build\solr-solrj\test\temp [junit4] says g'day! Master seed: C210EF59AAC60F37 [junit4] Your default console's encoding may not display certain unicode= glyphs: windows-1252 [junit4] Executing 5 suites with 2 JVMs. [junit4]=20 [junit4] Started J1 PID(17252@serv1-vm1). [junit4] Started J0 PID(13180@serv1-vm1). [junit4] Suite: org.apache.solr.common.util.TestTimeSource [junit4] OK 10.2s J0 | TestTimeSource.testEpochTime [junit4] Completed [1/5] on J0 in 18.71s, 1 test [junit4]=20 [junit4] Suite: org.apache.solr.common.util.TestTimeSource [junit4] OK 10.2s J1 | TestTimeSource.testEpochTime [junit4] Completed [2/5] on J1 in 18.83s, 1 test [junit4]=20 [junit4] Duplicate suite name used with XML reports: org.apache.solr.com= mon.util.TestTimeSource. This may confuse tools that process XML reports. S= et 'ignoreDuplicateSuites' to true to skip this message. [junit4] Suite: org.apache.solr.common.util.TestTimeSource [junit4] OK 10.1s J0 | TestTimeSource.testEpochTime [junit4] Completed [3/5] on J0 in 10.14s, 1 test [junit4]=20 [junit4] Suite: org.apache.solr.common.util.TestTimeSource [junit4] OK 10.1s J1 | TestTimeSource.testEpochTime [junit4] Completed [4/5] on J1 in 10.16s, 1 test [junit4]=20 [junit4] Suite: org.apache.solr.common.util.TestTimeSource [junit4] OK 10.1s J0 | TestTimeSource.testEpochTime [junit4] Completed [5/5] on J0 in 10.17s, 1 test [junit4]=20 [junit4] JVM J0: 0.63 .. 40.61 =3D 39.99s [junit4] JVM J1: 0.62 .. 30.73 =3D 30.10s [junit4] Execution time total: 40 seconds [junit4] Tests summary: 5 suites, 5 tests [junit4] Could not remove temporary path: C:\Users\jenkins\workspace\Luc= ene-Solr-7.x-Windows\solr\build\solr-solrj\test\J0 (java.nio.file.Directory= NotEmptyException: Remaining files: [C:\Users\jenkins\workspace\Lucene-Solr= -7.x-Windows\solr\build\solr-solrj\test\J0\temp]) [junit4] Could not remove temporary path: C:\Users\jenkins\workspace\Luc= ene-Solr-7.x-Windows\solr\build\solr-solrj\test\J1 (java.nio.file.Directory= NotEmptyException: Remaining files: [C:\Users\jenkins\workspace\Lucene-Solr= -7.x-Windows\solr\build\solr-solrj\test\J1\temp]) [echo] 5 slowest tests: [junit4:tophints] 159.03s | org.apache.solr.client.solrj.request.SchemaTest [junit4:tophints] 89.81s | org.apache.solr.client.solrj.io.stream.StreamDe= coratorTest [junit4:tophints] 57.37s | org.apache.solr.common.cloud.TestCloudCollectio= nsListeners [junit4:tophints] 52.34s | org.apache.solr.client.solrj.impl.CloudSolrClie= ntTest [junit4:tophints] 43.18s | org.apache.solr.client.solrj.request.TestCoreAd= min -check-totals: test-nocompile: BUILD SUCCESSFUL Total time: 42 seconds [repro] Failures: [repro] 0/5 failed: org.apache.solr.common.util.TestTimeSource [repro] 2/5 failed: org.apache.solr.cloud.autoscaling.sim.TestTriggerInte= gration [repro] Exiting with code 1 C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows>IF EXIST lucene\build mo= ve /y lucene\build lucene\build.repro=20 1 dir(s) moved. C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows>IF EXIST solr\build move= /y solr\build solr\build.repro=20 1 dir(s) moved. C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows>IF EXIST lucene\build.or= ig move /y lucene\build.orig lucene\build=20 1 dir(s) moved. C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows>IF EXIST solr\build.orig= move /y solr\build.orig solr\build=20 1 dir(s) moved. C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows>exit 0=20 Archiving artifacts Setting ANT_1_8_2_HOME=3DC:\Users\jenkins\tools\hudson.tasks.Ant_AntInstall= ation\ANT_1.8.2 [WARNINGS] Parsing warnings in console log with parser Java Compiler (javac= ) Setting ANT_1_8_2_HOME=3DC:\Users\jenkins\tools\hudson.tasks.Ant_AntInstall= ation\ANT_1.8.2 Setting ANT_1_8_2_HOME=3DC:\Users\jenkins\tools\hudson.tasks.Ant_AntInstall= ation\ANT_1.8.2 Using GitBlamer to create author and commit information for al= l warnings. GIT_COMMIT=3De5407c5a9710247e5f728aae36224a245a51f0b1, workspa= ce=3DC:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows [WARNINGS] Computing warning deltas based on reference build #638 Recording test results Setting ANT_1_8_2_HOME=3DC:\Users\jenkins\tools\hudson.tasks.Ant_AntInstall= ation\ANT_1.8.2 Build step 'Publish JUnit test result report' changed build result to UNSTA= BLE Email was triggered for: Unstable (Test Failures) Sending email for trigger: Unstable (Test Failures) Setting ANT_1_8_2_HOME=3DC:\Users\jenkins\tools\hudson.tasks.Ant_AntInstall= ation\ANT_1.8.2 Setting ANT_1_8_2_HOME=3DC:\Users\jenkins\tools\hudson.tasks.Ant_AntInstall= ation\ANT_1.8.2 Setting ANT_1_8_2_HOME=3DC:\Users\jenkins\tools\hudson.tasks.Ant_AntInstall= ation\ANT_1.8.2 Setting ANT_1_8_2_HOME=3DC:\Users\jenkins\tools\hudson.tasks.Ant_AntInstall= ation\ANT_1.8.2 ------=_Part_32_154309601.1529275108906 Content-Type: text/plain; charset=us-ascii --------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org For additional commands, e-mail: dev-help@lucene.apache.org ------=_Part_32_154309601.1529275108906--