You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2018/05/24 04:12:22 UTC
[JENKINS] Lucene-Solr-master-MacOSX (64bit/jdk-9) - Build # 4654 -
Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/4654/
Java: 64bit/jdk-9 -XX:+UseCompressedOops -XX:+UseParallelGC
5 tests failed.
FAILED: org.apache.lucene.analysis.core.TestRandomChains.testRandomChainsWithLargeStrings
Error Message:
last stage: inconsistent endOffset at pos=21: 67 vs 71; token=lnhsmof ypvaz dbh
Stack Trace:
java.lang.IllegalStateException: last stage: inconsistent endOffset at pos=21: 67 vs 71; token=lnhsmof ypvaz dbh
at __randomizedtesting.SeedInfo.seed([4B2538DE118B4F1E:217E87CF48C56FED]:0)
at org.apache.lucene.analysis.ValidatingTokenFilter.incrementToken(ValidatingTokenFilter.java:122)
at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkAnalysisConsistency(BaseTokenStreamTestCase.java:746)
at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:657)
at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:559)
at org.apache.lucene.analysis.core.TestRandomChains.testRandomChainsWithLargeStrings(TestRandomChains.java:882)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
FAILED: org.apache.lucene.analysis.core.TestRandomChains.testRandomChainsWithLargeStrings
Error Message:
last stage: inconsistent endOffset at pos=21: 67 vs 71; token=lnhsmof ypvaz dbh
Stack Trace:
java.lang.IllegalStateException: last stage: inconsistent endOffset at pos=21: 67 vs 71; token=lnhsmof ypvaz dbh
at __randomizedtesting.SeedInfo.seed([4B2538DE118B4F1E:217E87CF48C56FED]:0)
at org.apache.lucene.analysis.ValidatingTokenFilter.incrementToken(ValidatingTokenFilter.java:122)
at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkAnalysisConsistency(BaseTokenStreamTestCase.java:746)
at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:657)
at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:559)
at org.apache.lucene.analysis.core.TestRandomChains.testRandomChainsWithLargeStrings(TestRandomChains.java:882)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
FAILED: org.apache.lucene.analysis.core.TestRandomChains.testRandomChainsWithLargeStrings
Error Message:
last stage: inconsistent endOffset at pos=21: 67 vs 71; token=lnhsmof ypvaz dbh
Stack Trace:
java.lang.IllegalStateException: last stage: inconsistent endOffset at pos=21: 67 vs 71; token=lnhsmof ypvaz dbh
at __randomizedtesting.SeedInfo.seed([4B2538DE118B4F1E:217E87CF48C56FED]:0)
at org.apache.lucene.analysis.ValidatingTokenFilter.incrementToken(ValidatingTokenFilter.java:122)
at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkAnalysisConsistency(BaseTokenStreamTestCase.java:746)
at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:657)
at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:559)
at org.apache.lucene.analysis.core.TestRandomChains.testRandomChainsWithLargeStrings(TestRandomChains.java:882)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
FAILED: org.apache.lucene.analysis.core.TestRandomChains.testRandomChainsWithLargeStrings
Error Message:
last stage: inconsistent endOffset at pos=21: 67 vs 71; token=lnhsmof ypvaz dbh
Stack Trace:
java.lang.IllegalStateException: last stage: inconsistent endOffset at pos=21: 67 vs 71; token=lnhsmof ypvaz dbh
at __randomizedtesting.SeedInfo.seed([4B2538DE118B4F1E:217E87CF48C56FED]:0)
at org.apache.lucene.analysis.ValidatingTokenFilter.incrementToken(ValidatingTokenFilter.java:122)
at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkAnalysisConsistency(BaseTokenStreamTestCase.java:746)
at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:657)
at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:559)
at org.apache.lucene.analysis.core.TestRandomChains.testRandomChainsWithLargeStrings(TestRandomChains.java:882)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
FAILED: org.apache.lucene.analysis.core.TestRandomChains.testRandomChainsWithLargeStrings
Error Message:
last stage: inconsistent endOffset at pos=21: 67 vs 71; token=lnhsmof ypvaz dbh
Stack Trace:
java.lang.IllegalStateException: last stage: inconsistent endOffset at pos=21: 67 vs 71; token=lnhsmof ypvaz dbh
at __randomizedtesting.SeedInfo.seed([4B2538DE118B4F1E:217E87CF48C56FED]:0)
at org.apache.lucene.analysis.ValidatingTokenFilter.incrementToken(ValidatingTokenFilter.java:122)
at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkAnalysisConsistency(BaseTokenStreamTestCase.java:746)
at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:657)
at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:559)
at org.apache.lucene.analysis.core.TestRandomChains.testRandomChainsWithLargeStrings(TestRandomChains.java:882)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
Build Log:
[...truncated 2693 lines...]
[junit4] Suite: org.apache.lucene.analysis.core.TestRandomChains
[junit4] 2> TEST FAIL: useCharFilter=false text='\u0719\u4131\ud44b\u6c69\u0640 \u9930\udbd0\udd14\u1bc7 uwlq buso \u169a\u168c\u169d\u1697 \ud800\udd23 cJI \u17af\u17eb \ud800\udfad \u03ad\uf560z z b \uacad\nX lnhsmof ypvaz dbh hihgk'
[junit4] 2> Exception from random analyzer:
[junit4] 2> charfilters=
[junit4] 2> tokenizer=
[junit4] 2> org.apache.lucene.analysis.standard.ClassicTokenizer()
[junit4] 2> filters=
[junit4] 2> org.apache.lucene.analysis.MockSynonymFilter(ValidatingTokenFilter@2a0aa2a5 term=,bytes=[],startOffset=0,endOffset=0,positionIncrement=1,positionLength=1,type=word,termFrequency=1)ConditionalTokenFilter:
[junit4] 2> org.apache.lucene.analysis.shingle.ShingleFilter(OneTimeWrapper@7eb4cb78 term=,bytes=[],startOffset=0,endOffset=0,positionIncrement=1,positionLength=1,type=word,termFrequency=1, 8)
[junit4] 2> org.apache.lucene.analysis.ar.ArabicStemFilter(ValidatingTokenFilter@2853f327 term=,bytes=[],startOffset=0,endOffset=0,positionIncrement=1,positionLength=1,type=word,termFrequency=1,keyword=false)ConditionalTokenFilter:
[junit4] 2> org.apache.lucene.analysis.shingle.ShingleFilter(OneTimeWrapper@32562616 term=,bytes=[],startOffset=0,endOffset=0,positionIncrement=1,positionLength=1,type=word,termFrequency=1,keyword=false, vesj)
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestRandomChains -Dtests.method=testRandomChainsWithLargeStrings -Dtests.seed=4B2538DE118B4F1E -Dtests.slow=true -Dtests.locale=mk-MK -Dtests.timezone=Indian/Comoro -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.14s J1 | TestRandomChains.testRandomChainsWithLargeStrings <<<
[junit4] > Throwable #1: java.lang.IllegalStateException: last stage: inconsistent endOffset at pos=21: 67 vs 71; token=lnhsmof ypvaz dbh
[junit4] > at __randomizedtesting.SeedInfo.seed([4B2538DE118B4F1E:217E87CF48C56FED]:0)
[junit4] > at org.apache.lucene.analysis.ValidatingTokenFilter.incrementToken(ValidatingTokenFilter.java:122)
[junit4] > at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkAnalysisConsistency(BaseTokenStreamTestCase.java:746)
[junit4] > at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:657)
[junit4] > at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:559)
[junit4] > at org.apache.lucene.analysis.core.TestRandomChains.testRandomChainsWithLargeStrings(TestRandomChains.java:882)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {dummy=BlockTreeOrds(blocksize=128)}, docValues:{}, maxPointsInLeafNode=1468, maxMBSortInHeap=6.910135125784358, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@6fc32a30), locale=mk-MK, timezone=Indian/Comoro
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 9 (64-bit)/cpus=3,threads=1,free=128110608,total=218628096
[junit4] 2> NOTE: All tests run in this JVM: [TestCharTokenizers, TestHindiAnalyzer, TestCzechStemFilterFactory, TestGermanMinimalStemFilterFactory, TestPatternTokenizerFactory, TestLatvianStemmer, TestTrimFilterFactory, TestCollationKeyAnalyzer, TestTwoFold, TestClassicAnalyzer, TestNorwegianAnalyzer, TestBengaliFilters, TestPortugueseMinimalStemFilterFactory, TestNGramFilters, TestSwedishAnalyzer, TestKeywordMarkerFilter, TestHindiFilters, TestPorterStemFilter, TestStemmer, TestAnalyzers, TestAnalysisSPILoader, TestCondition2, TestFlagLong, TestDictionaryCompoundWordTokenFilterFactory, TestReversePathHierarchyTokenizer, TestPatternReplaceFilter, TestGermanNormalizationFilter, TestWordDelimiterFilter, TestLithuanianAnalyzer, TestTurkishLowerCaseFilterFactory, TestStandardFactories, TestCommonGramsFilterFactory, TestSpanishAnalyzer, TestCJKBigramFilterFactory, TestGermanStemFilterFactory, TestPersianNormalizationFilterFactory, TestGalicianMinimalStemFilter, TestSynonymMapFilter, TestCodepointCountFilterFactory, TestProtectedTermFilter, TestDecimalDigitFilterFactory, TestKeepWordFilter, HTMLStripCharFilterTest, TestLimitTokenPositionFilter, TestAsciiFoldingFilterFactory, TestRandomChains]
[junit4] Completed [107/289 (1!)] on J1 in 2.10s, 2 tests, 1 error <<< FAILURES!
[...truncated 56126 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/4654/consoleText
[repro] Revision: 48bd259516b8d78c991239fe7cf3340c90f582e5
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseParallelGC"
[repro] Repro line: ant test -Dtestcase=TestRandomChains -Dtests.method=testRandomChainsWithLargeStrings -Dtests.seed=4B2538DE118B4F1E -Dtests.slow=true -Dtests.locale=mk-MK -Dtests.timezone=Indian/Comoro -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] lucene/analysis/common
[repro] TestRandomChains
[repro] ant compile-test
[...truncated 222 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestRandomChains" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseParallelGC" -Dtests.seed=4B2538DE118B4F1E -Dtests.slow=true -Dtests.locale=mk-MK -Dtests.timezone=Indian/Comoro -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 31 lines...]
[junit4] Suite: org.apache.lucene.analysis.core.TestRandomChains
[junit4] 2> TEST FAIL: useCharFilter=false text='\u0719\u4131\ud44b\u6c69\u0640 \u9930\udbd0\udd14\u1bc7 uwlq buso \u169a\u168c\u169d\u1697 \ud800\udd23 cJI \u17af\u17eb \ud800\udfad \u03ad\uf560z z b \uacad\nX lnhsmof ypvaz dbh hihgk'
[junit4] 2> Exception from random analyzer:
[junit4] 2> charfilters=
[junit4] 2> tokenizer=
[junit4] 2> org.apache.lucene.analysis.standard.ClassicTokenizer()
[junit4] 2> filters=
[junit4] 2> org.apache.lucene.analysis.MockSynonymFilter(ValidatingTokenFilter@19f425f0 term=,bytes=[],startOffset=0,endOffset=0,positionIncrement=1,positionLength=1,type=word,termFrequency=1)ConditionalTokenFilter:
[junit4] 2> org.apache.lucene.analysis.shingle.ShingleFilter(OneTimeWrapper@42568549 term=,bytes=[],startOffset=0,endOffset=0,positionIncrement=1,positionLength=1,type=word,termFrequency=1, 8)
[junit4] 2> org.apache.lucene.analysis.ar.ArabicStemFilter(ValidatingTokenFilter@4fbbe37d term=,bytes=[],startOffset=0,endOffset=0,positionIncrement=1,positionLength=1,type=word,termFrequency=1,keyword=false)ConditionalTokenFilter:
[junit4] 2> org.apache.lucene.analysis.shingle.ShingleFilter(OneTimeWrapper@41fc130e term=,bytes=[],startOffset=0,endOffset=0,positionIncrement=1,positionLength=1,type=word,termFrequency=1,keyword=false, vesj)
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestRandomChains -Dtests.method=testRandomChainsWithLargeStrings -Dtests.seed=4B2538DE118B4F1E -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=mk-MK -Dtests.timezone=Indian/Comoro -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.17s J1 | TestRandomChains.testRandomChainsWithLargeStrings <<<
[junit4] > Throwable #1: java.lang.IllegalStateException: last stage: inconsistent endOffset at pos=21: 67 vs 71; token=lnhsmof ypvaz dbh
[junit4] > at __randomizedtesting.SeedInfo.seed([4B2538DE118B4F1E:217E87CF48C56FED]:0)
[junit4] > at org.apache.lucene.analysis.ValidatingTokenFilter.incrementToken(ValidatingTokenFilter.java:122)
[junit4] > at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkAnalysisConsistency(BaseTokenStreamTestCase.java:746)
[junit4] > at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:657)
[junit4] > at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:559)
[junit4] > at org.apache.lucene.analysis.core.TestRandomChains.testRandomChainsWithLargeStrings(TestRandomChains.java:882)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] OK 1.26s J1 | TestRandomChains.testRandomChains
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {dummy=BlockTreeOrds(blocksize=128)}, docValues:{}, maxPointsInLeafNode=1468, maxMBSortInHeap=6.910135125784358, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@3ad386cd), locale=mk-MK, timezone=Indian/Comoro
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 9 (64-bit)/cpus=3,threads=1,free=89766960,total=201850880
[junit4] 2> NOTE: All tests run in this JVM: [TestRandomChains]
[junit4] Completed [1/5 (1!)] on J1 in 2.51s, 2 tests, 1 error <<< FAILURES!
[...truncated 1 lines...]
[junit4] Suite: org.apache.lucene.analysis.core.TestRandomChains
[junit4] 2> TEST FAIL: useCharFilter=false text='\u0719\u4131\ud44b\u6c69\u0640 \u9930\udbd0\udd14\u1bc7 uwlq buso \u169a\u168c\u169d\u1697 \ud800\udd23 cJI \u17af\u17eb \ud800\udfad \u03ad\uf560z z b \uacad\nX lnhsmof ypvaz dbh hihgk'
[junit4] 2> Exception from random analyzer:
[junit4] 2> charfilters=
[junit4] 2> tokenizer=
[junit4] 2> org.apache.lucene.analysis.standard.ClassicTokenizer()
[junit4] 2> filters=
[junit4] 2> org.apache.lucene.analysis.MockSynonymFilter(ValidatingTokenFilter@19f425f0 term=,bytes=[],startOffset=0,endOffset=0,positionIncrement=1,positionLength=1,type=word,termFrequency=1)ConditionalTokenFilter:
[junit4] 2> org.apache.lucene.analysis.shingle.ShingleFilter(OneTimeWrapper@42568549 term=,bytes=[],startOffset=0,endOffset=0,positionIncrement=1,positionLength=1,type=word,termFrequency=1, 8)
[junit4] 2> org.apache.lucene.analysis.ar.ArabicStemFilter(ValidatingTokenFilter@4fbbe37d term=,bytes=[],startOffset=0,endOffset=0,positionIncrement=1,positionLength=1,type=word,termFrequency=1,keyword=false)ConditionalTokenFilter:
[junit4] 2> org.apache.lucene.analysis.shingle.ShingleFilter(OneTimeWrapper@41fc130e term=,bytes=[],startOffset=0,endOffset=0,positionIncrement=1,positionLength=1,type=word,termFrequency=1,keyword=false, vesj)
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestRandomChains -Dtests.method=testRandomChainsWithLargeStrings -Dtests.seed=4B2538DE118B4F1E -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=mk-MK -Dtests.timezone=Indian/Comoro -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.36s J0 | TestRandomChains.testRandomChainsWithLargeStrings <<<
[junit4] > Throwable #1: java.lang.IllegalStateException: last stage: inconsistent endOffset at pos=21: 67 vs 71; token=lnhsmof ypvaz dbh
[junit4] > at __randomizedtesting.SeedInfo.seed([4B2538DE118B4F1E:217E87CF48C56FED]:0)
[junit4] > at org.apache.lucene.analysis.ValidatingTokenFilter.incrementToken(ValidatingTokenFilter.java:122)
[junit4] > at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkAnalysisConsistency(BaseTokenStreamTestCase.java:746)
[junit4] > at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:657)
[junit4] > at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:559)
[junit4] > at org.apache.lucene.analysis.core.TestRandomChains.testRandomChainsWithLargeStrings(TestRandomChains.java:882)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] OK 1.59s J0 | TestRandomChains.testRandomChains
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {dummy=BlockTreeOrds(blocksize=128)}, docValues:{}, maxPointsInLeafNode=1468, maxMBSortInHeap=6.910135125784358, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@3ad386cd), locale=mk-MK, timezone=Indian/Comoro
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 9 (64-bit)/cpus=3,threads=1,free=66688016,total=203423744
[junit4] 2> NOTE: All tests run in this JVM: [TestRandomChains]
[junit4] Completed [2/5 (2!)] on J0 in 3.28s, 2 tests, 1 error <<< FAILURES!
[...truncated 2 lines...]
[junit4] Suite: org.apache.lucene.analysis.core.TestRandomChains
[junit4] 2> TEST FAIL: useCharFilter=false text='\u0719\u4131\ud44b\u6c69\u0640 \u9930\udbd0\udd14\u1bc7 uwlq buso \u169a\u168c\u169d\u1697 \ud800\udd23 cJI \u17af\u17eb \ud800\udfad \u03ad\uf560z z b \uacad\nX lnhsmof ypvaz dbh hihgk'
[junit4] 2> Exception from random analyzer:
[junit4] 2> charfilters=
[junit4] 2> tokenizer=
[junit4] 2> org.apache.lucene.analysis.standard.ClassicTokenizer()
[junit4] 2> filters=
[junit4] 2> org.apache.lucene.analysis.MockSynonymFilter(ValidatingTokenFilter@60ec5272 term=,bytes=[],startOffset=0,endOffset=0,positionIncrement=1,positionLength=1,type=word,termFrequency=1)ConditionalTokenFilter:
[junit4] 2> org.apache.lucene.analysis.shingle.ShingleFilter(OneTimeWrapper@1cb463c1 term=,bytes=[],startOffset=0,endOffset=0,positionIncrement=1,positionLength=1,type=word,termFrequency=1, 8)
[junit4] 2> org.apache.lucene.analysis.ar.ArabicStemFilter(ValidatingTokenFilter@3b4f210e term=,bytes=[],startOffset=0,endOffset=0,positionIncrement=1,positionLength=1,type=word,termFrequency=1,keyword=false)ConditionalTokenFilter:
[junit4] 2> org.apache.lucene.analysis.shingle.ShingleFilter(OneTimeWrapper@743ef8be term=,bytes=[],startOffset=0,endOffset=0,positionIncrement=1,positionLength=1,type=word,termFrequency=1,keyword=false, vesj)
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestRandomChains -Dtests.method=testRandomChainsWithLargeStrings -Dtests.seed=4B2538DE118B4F1E -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=mk-MK -Dtests.timezone=Indian/Comoro -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.20s J1 | TestRandomChains.testRandomChainsWithLargeStrings <<<
[junit4] > Throwable #1: java.lang.IllegalStateException: last stage: inconsistent endOffset at pos=21: 67 vs 71; token=lnhsmof ypvaz dbh
[junit4] > at __randomizedtesting.SeedInfo.seed([4B2538DE118B4F1E:217E87CF48C56FED]:0)
[junit4] > at org.apache.lucene.analysis.ValidatingTokenFilter.incrementToken(ValidatingTokenFilter.java:122)
[junit4] > at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkAnalysisConsistency(BaseTokenStreamTestCase.java:746)
[junit4] > at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:657)
[junit4] > at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:559)
[junit4] > at org.apache.lucene.analysis.core.TestRandomChains.testRandomChainsWithLargeStrings(TestRandomChains.java:882)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] OK 0.73s J1 | TestRandomChains.testRandomChains
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {dummy=BlockTreeOrds(blocksize=128)}, docValues:{}, maxPointsInLeafNode=1468, maxMBSortInHeap=6.910135125784358, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@7d2061c8), locale=mk-MK, timezone=Indian/Comoro
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 9 (64-bit)/cpus=3,threads=1,free=124908248,total=202899456
[junit4] 2> NOTE: All tests run in this JVM: [TestRandomChains, TestRandomChains]
[junit4] Completed [3/5 (3!)] on J1 in 1.37s, 2 tests, 1 error <<< FAILURES!
[...truncated 1 lines...]
[junit4] Suite: org.apache.lucene.analysis.core.TestRandomChains
[junit4] 2> TEST FAIL: useCharFilter=false text='\u0719\u4131\ud44b\u6c69\u0640 \u9930\udbd0\udd14\u1bc7 uwlq buso \u169a\u168c\u169d\u1697 \ud800\udd23 cJI \u17af\u17eb \ud800\udfad \u03ad\uf560z z b \uacad\nX lnhsmof ypvaz dbh hihgk'
[junit4] 2> Exception from random analyzer:
[junit4] 2> charfilters=
[junit4] 2> tokenizer=
[junit4] 2> org.apache.lucene.analysis.standard.ClassicTokenizer()
[junit4] 2> filters=
[junit4] 2> org.apache.lucene.analysis.MockSynonymFilter(ValidatingTokenFilter@4473e57c term=,bytes=[],startOffset=0,endOffset=0,positionIncrement=1,positionLength=1,type=word,termFrequency=1)ConditionalTokenFilter:
[junit4] 2> org.apache.lucene.analysis.shingle.ShingleFilter(OneTimeWrapper@ea031af term=,bytes=[],startOffset=0,endOffset=0,positionIncrement=1,positionLength=1,type=word,termFrequency=1, 8)
[junit4] 2> org.apache.lucene.analysis.ar.ArabicStemFilter(ValidatingTokenFilter@784b4ed0 term=,bytes=[],startOffset=0,endOffset=0,positionIncrement=1,positionLength=1,type=word,termFrequency=1,keyword=false)ConditionalTokenFilter:
[junit4] 2> org.apache.lucene.analysis.shingle.ShingleFilter(OneTimeWrapper@335fd98e term=,bytes=[],startOffset=0,endOffset=0,positionIncrement=1,positionLength=1,type=word,termFrequency=1,keyword=false, vesj)
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestRandomChains -Dtests.method=testRandomChainsWithLargeStrings -Dtests.seed=4B2538DE118B4F1E -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=mk-MK -Dtests.timezone=Indian/Comoro -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.11s J0 | TestRandomChains.testRandomChainsWithLargeStrings <<<
[junit4] > Throwable #1: java.lang.IllegalStateException: last stage: inconsistent endOffset at pos=21: 67 vs 71; token=lnhsmof ypvaz dbh
[junit4] > at __randomizedtesting.SeedInfo.seed([4B2538DE118B4F1E:217E87CF48C56FED]:0)
[junit4] > at org.apache.lucene.analysis.ValidatingTokenFilter.incrementToken(ValidatingTokenFilter.java:122)
[junit4] > at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkAnalysisConsistency(BaseTokenStreamTestCase.java:746)
[junit4] > at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:657)
[junit4] > at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:559)
[junit4] > at org.apache.lucene.analysis.core.TestRandomChains.testRandomChainsWithLargeStrings(TestRandomChains.java:882)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] OK 0.66s J0 | TestRandomChains.testRandomChains
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {dummy=BlockTreeOrds(blocksize=128)}, docValues:{}, maxPointsInLeafNode=1468, maxMBSortInHeap=6.910135125784358, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@7e0635ed), locale=mk-MK, timezone=Indian/Comoro
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 9 (64-bit)/cpus=3,threads=1,free=94378584,total=218628096
[junit4] 2> NOTE: All tests run in this JVM: [TestRandomChains, TestRandomChains]
[junit4] Completed [4/5 (4!)] on J0 in 1.10s, 2 tests, 1 error <<< FAILURES!
[...truncated 1 lines...]
[junit4] Suite: org.apache.lucene.analysis.core.TestRandomChains
[junit4] 2> TEST FAIL: useCharFilter=false text='\u0719\u4131\ud44b\u6c69\u0640 \u9930\udbd0\udd14\u1bc7 uwlq buso \u169a\u168c\u169d\u1697 \ud800\udd23 cJI \u17af\u17eb \ud800\udfad \u03ad\uf560z z b \uacad\nX lnhsmof ypvaz dbh hihgk'
[junit4] 2> Exception from random analyzer:
[junit4] 2> charfilters=
[junit4] 2> tokenizer=
[junit4] 2> org.apache.lucene.analysis.standard.ClassicTokenizer()
[junit4] 2> filters=
[junit4] 2> org.apache.lucene.analysis.MockSynonymFilter(ValidatingTokenFilter@7543639 term=,bytes=[],startOffset=0,endOffset=0,positionIncrement=1,positionLength=1,type=word,termFrequency=1)ConditionalTokenFilter:
[junit4] 2> org.apache.lucene.analysis.shingle.ShingleFilter(OneTimeWrapper@76528e9e term=,bytes=[],startOffset=0,endOffset=0,positionIncrement=1,positionLength=1,type=word,termFrequency=1, 8)
[junit4] 2> org.apache.lucene.analysis.ar.ArabicStemFilter(ValidatingTokenFilter@3d77698d term=,bytes=[],startOffset=0,endOffset=0,positionIncrement=1,positionLength=1,type=word,termFrequency=1,keyword=false)ConditionalTokenFilter:
[junit4] 2> org.apache.lucene.analysis.shingle.ShingleFilter(OneTimeWrapper@2f5302a1 term=,bytes=[],startOffset=0,endOffset=0,positionIncrement=1,positionLength=1,type=word,termFrequency=1,keyword=false, vesj)
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestRandomChains -Dtests.method=testRandomChainsWithLargeStrings -Dtests.seed=4B2538DE118B4F1E -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=mk-MK -Dtests.timezone=Indian/Comoro -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.05s J1 | TestRandomChains.testRandomChainsWithLargeStrings <<<
[junit4] > Throwable #1: java.lang.IllegalStateException: last stage: inconsistent endOffset at pos=21: 67 vs 71; token=lnhsmof ypvaz dbh
[junit4] > at __randomizedtesting.SeedInfo.seed([4B2538DE118B4F1E:217E87CF48C56FED]:0)
[junit4] > at org.apache.lucene.analysis.ValidatingTokenFilter.incrementToken(ValidatingTokenFilter.java:122)
[junit4] > at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkAnalysisConsistency(BaseTokenStreamTestCase.java:746)
[junit4] > at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:657)
[junit4] > at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:559)
[junit4] > at org.apache.lucene.analysis.core.TestRandomChains.testRandomChainsWithLargeStrings(TestRandomChains.java:882)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] OK 0.41s J1 | TestRandomChains.testRandomChains
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {dummy=BlockTreeOrds(blocksize=128)}, docValues:{}, maxPointsInLeafNode=1468, maxMBSortInHeap=6.910135125784358, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@33ae6b9), locale=mk-MK, timezone=Indian/Comoro
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 9 (64-bit)/cpus=3,threads=1,free=44049344,total=206045184
[junit4] 2> NOTE: All tests run in this JVM: [TestRandomChains, TestRandomChains, TestRandomChains]
[junit4] Completed [5/5 (5!)] on J1 in 0.60s, 2 tests, 1 error <<< FAILURES!
[...truncated 14 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/common-build.xml:1568: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/common-build.xml:1092: There were test failures: 5 suites, 10 tests, 5 errors [seed: 4B2538DE118B4F1E]
Total time: 9 seconds
[repro] Setting last failure code to 256
[repro] Failures:
[repro] 5/5 failed: org.apache.lucene.analysis.core.TestRandomChains
[repro] Exiting with code 256
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
mv: rename solr/build to solr/build.repro: No such file or directory
+ true
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all warnings.
<Git Blamer> GIT_COMMIT=48bd259516b8d78c991239fe7cf3340c90f582e5, workspace=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX
[WARNINGS] Computing warning deltas based on reference build #4653
Recording test results
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[JENKINS] Lucene-Solr-master-MacOSX (64bit/jdk1.8.0) - Build # 4656
- Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/4656/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseSerialGC
2 tests failed.
FAILED: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection
Error Message:
Timeout waiting for new leader null Live Nodes: [127.0.0.1:54576_solr, 127.0.0.1:54582_solr, 127.0.0.1:54588_solr] Last available state: DocCollection(collection1//collections/collection1/state.json/14)={ "pullReplicas":"0", "replicationFactor":"3", "shards":{"shard1":{ "range":"80000000-7fffffff", "state":"active", "replicas":{ "core_node62":{ "core":"collection1_shard1_replica_n61", "base_url":"http://127.0.0.1:54568/solr", "node_name":"127.0.0.1:54568_solr", "state":"down", "type":"NRT", "force_set_state":"false"}, "core_node64":{ "core":"collection1_shard1_replica_n63", "base_url":"http://127.0.0.1:54576/solr", "node_name":"127.0.0.1:54576_solr", "state":"active", "type":"NRT", "force_set_state":"false"}, "core_node66":{ "core":"collection1_shard1_replica_n65", "base_url":"http://127.0.0.1:54582/solr", "node_name":"127.0.0.1:54582_solr", "state":"active", "type":"NRT", "force_set_state":"false"}}}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"1", "autoAddReplicas":"false", "nrtReplicas":"3", "tlogReplicas":"0"}
Stack Trace:
java.lang.AssertionError: Timeout waiting for new leader
null
Live Nodes: [127.0.0.1:54576_solr, 127.0.0.1:54582_solr, 127.0.0.1:54588_solr]
Last available state: DocCollection(collection1//collections/collection1/state.json/14)={
"pullReplicas":"0",
"replicationFactor":"3",
"shards":{"shard1":{
"range":"80000000-7fffffff",
"state":"active",
"replicas":{
"core_node62":{
"core":"collection1_shard1_replica_n61",
"base_url":"http://127.0.0.1:54568/solr",
"node_name":"127.0.0.1:54568_solr",
"state":"down",
"type":"NRT",
"force_set_state":"false"},
"core_node64":{
"core":"collection1_shard1_replica_n63",
"base_url":"http://127.0.0.1:54576/solr",
"node_name":"127.0.0.1:54576_solr",
"state":"active",
"type":"NRT",
"force_set_state":"false"},
"core_node66":{
"core":"collection1_shard1_replica_n65",
"base_url":"http://127.0.0.1:54582/solr",
"node_name":"127.0.0.1:54582_solr",
"state":"active",
"type":"NRT",
"force_set_state":"false"}}}},
"router":{"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"false",
"nrtReplicas":"3",
"tlogReplicas":"0"}
at __randomizedtesting.SeedInfo.seed([E2917A40304F9AD3:4A8D66FAF20FAEF9]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.SolrCloudTestCase.waitForState(SolrCloudTestCase.java:278)
at org.apache.solr.cloud.LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection(LeaderVoteWaitTimeoutTest.java:187)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest.basicTest
Error Message:
Stack Trace:
java.lang.NullPointerException
at __randomizedtesting.SeedInfo.seed([E2917A40304F9AD3:10656D2274EA97E0]:0)
at org.apache.solr.client.solrj.embedded.JettySolrRunner.getNodeName(JettySolrRunner.java:347)
at org.apache.solr.cloud.LeaderVoteWaitTimeoutTest.basicTest(LeaderVoteWaitTimeoutTest.java:93)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 12302 lines...]
[junit4] Suite: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest
[junit4] 2> 30039 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_E2917A40304F9AD3-001/init-core-data-001
[junit4] 2> 30040 WARN (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=4 numCloses=4
[junit4] 2> 30040 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 30044 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 30054 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_E2917A40304F9AD3-001/tempDir-001
[junit4] 2> 30073 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 30078 INFO (Thread-5) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 30079 INFO (Thread-5) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 30289 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:54539
[junit4] 2> 30410 ERROR (Thread-5) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 30540 INFO (zkConnectionManagerCallback-12-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 31235 INFO (jetty-launcher-9-thread-1) [ ] o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git: daa59876e6f384329b122929e70a80934569428c; jvm 1.8.0_172-b11
[junit4] 2> 31235 INFO (jetty-launcher-9-thread-4) [ ] o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git: daa59876e6f384329b122929e70a80934569428c; jvm 1.8.0_172-b11
[junit4] 2> 31235 INFO (jetty-launcher-9-thread-3) [ ] o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git: daa59876e6f384329b122929e70a80934569428c; jvm 1.8.0_172-b11
[junit4] 2> 31235 INFO (jetty-launcher-9-thread-2) [ ] o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git: daa59876e6f384329b122929e70a80934569428c; jvm 1.8.0_172-b11
[junit4] 2> 31297 INFO (jetty-launcher-9-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 31297 INFO (jetty-launcher-9-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 31309 INFO (jetty-launcher-9-thread-4) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 31310 INFO (jetty-launcher-9-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 31310 INFO (jetty-launcher-9-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 31310 INFO (jetty-launcher-9-thread-3) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 31315 INFO (jetty-launcher-9-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 31316 INFO (jetty-launcher-9-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 31316 INFO (jetty-launcher-9-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 31331 INFO (jetty-launcher-9-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 31380 INFO (jetty-launcher-9-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 31380 INFO (jetty-launcher-9-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 31385 INFO (jetty-launcher-9-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3828b777{/solr,null,AVAILABLE}
[junit4] 2> 31386 INFO (jetty-launcher-9-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1b895281{/solr,null,AVAILABLE}
[junit4] 2> 31392 INFO (jetty-launcher-9-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6bcb7cee{/solr,null,AVAILABLE}
[junit4] 2> 31393 INFO (jetty-launcher-9-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4ab4a7ec{/solr,null,AVAILABLE}
[junit4] 2> 31440 INFO (jetty-launcher-9-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6c25f2d7{HTTP/1.1,[http/1.1]}{127.0.0.1:54543}
[junit4] 2> 31441 INFO (jetty-launcher-9-thread-4) [ ] o.e.j.s.Server Started @31523ms
[junit4] 2> 31441 INFO (jetty-launcher-9-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=54543}
[junit4] 2> 31447 INFO (jetty-launcher-9-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@719fa609{HTTP/1.1,[http/1.1]}{127.0.0.1:54544}
[junit4] 2> 31447 INFO (jetty-launcher-9-thread-2) [ ] o.e.j.s.Server Started @31529ms
[junit4] 2> 31447 INFO (jetty-launcher-9-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=54544}
[junit4] 2> 31449 INFO (jetty-launcher-9-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@324a31f4{HTTP/1.1,[http/1.1]}{127.0.0.1:54545}
[junit4] 2> 31450 INFO (jetty-launcher-9-thread-1) [ ] o.e.j.s.Server Started @31532ms
[junit4] 2> 31450 INFO (jetty-launcher-9-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=54545}
[junit4] 2> 31473 INFO (jetty-launcher-9-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@477795c4{HTTP/1.1,[http/1.1]}{127.0.0.1:54542}
[junit4] 2> 31474 INFO (jetty-launcher-9-thread-3) [ ] o.e.j.s.Server Started @31556ms
[junit4] 2> 31474 INFO (jetty-launcher-9-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=54542}
[junit4] 2> 31535 INFO (jetty-launcher-9-thread-4) [ ] o.a.s.u.c.SSLCredentialProviderFactory Processing SSL Credential Provider chain: env;sysprop
[junit4] 2> 31553 ERROR (jetty-launcher-9-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 31553 INFO (jetty-launcher-9-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 31553 INFO (jetty-launcher-9-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 31554 INFO (jetty-launcher-9-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 31554 INFO (jetty-launcher-9-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 31554 INFO (jetty-launcher-9-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-05-25T02:22:32.974Z
[junit4] 2> 31555 ERROR (jetty-launcher-9-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 31555 INFO (jetty-launcher-9-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 31555 INFO (jetty-launcher-9-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 31555 INFO (jetty-launcher-9-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 31555 INFO (jetty-launcher-9-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 31555 INFO (jetty-launcher-9-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-05-25T02:22:32.975Z
[junit4] 2> 31556 ERROR (jetty-launcher-9-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 31556 INFO (jetty-launcher-9-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 31556 ERROR (jetty-launcher-9-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 31556 INFO (jetty-launcher-9-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 31556 INFO (jetty-launcher-9-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 31556 INFO (jetty-launcher-9-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 31556 INFO (jetty-launcher-9-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 31556 INFO (jetty-launcher-9-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-05-25T02:22:32.976Z
[junit4] 2> 31556 INFO (jetty-launcher-9-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 31556 INFO (jetty-launcher-9-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 31556 INFO (jetty-launcher-9-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 31556 INFO (jetty-launcher-9-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-05-25T02:22:32.976Z
[junit4] 2> 31564 INFO (zkConnectionManagerCallback-14-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 31564 INFO (zkConnectionManagerCallback-18-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 31564 INFO (zkConnectionManagerCallback-16-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 31581 INFO (jetty-launcher-9-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 31581 INFO (zkConnectionManagerCallback-20-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 31584 INFO (jetty-launcher-9-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 31607 INFO (jetty-launcher-9-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 31619 INFO (jetty-launcher-9-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 31670 INFO (jetty-launcher-9-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54539/solr
[junit4] 2> 31671 INFO (jetty-launcher-9-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54539/solr
[junit4] 2> 31671 INFO (jetty-launcher-9-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54539/solr
[junit4] 2> 31682 INFO (jetty-launcher-9-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54539/solr
[junit4] 2> 31726 INFO (zkConnectionManagerCallback-32-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 31726 INFO (zkConnectionManagerCallback-34-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 31726 INFO (zkConnectionManagerCallback-36-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 31727 INFO (zkConnectionManagerCallback-30-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 31745 INFO (zkConnectionManagerCallback-38-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 31745 INFO (zkConnectionManagerCallback-40-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 31747 INFO (zkConnectionManagerCallback-44-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 31747 INFO (zkConnectionManagerCallback-42-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 32232 INFO (jetty-launcher-9-thread-3) [n:127.0.0.1:54542_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 32232 INFO (jetty-launcher-9-thread-4) [n:127.0.0.1:54543_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 32233 INFO (jetty-launcher-9-thread-1) [n:127.0.0.1:54545_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 32233 INFO (jetty-launcher-9-thread-2) [n:127.0.0.1:54544_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 32235 INFO (jetty-launcher-9-thread-3) [n:127.0.0.1:54542_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:54542_solr
[junit4] 2> 32239 INFO (jetty-launcher-9-thread-3) [n:127.0.0.1:54542_solr ] o.a.s.c.Overseer Overseer (id=72083277747257354-127.0.0.1:54542_solr-n_0000000000) starting
[junit4] 2> 32240 INFO (jetty-launcher-9-thread-1) [n:127.0.0.1:54545_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54545_solr
[junit4] 2> 32240 INFO (jetty-launcher-9-thread-2) [n:127.0.0.1:54544_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54544_solr
[junit4] 2> 32240 INFO (jetty-launcher-9-thread-4) [n:127.0.0.1:54543_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54543_solr
[junit4] 2> 32298 INFO (zkCallback-39-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 32302 INFO (zkCallback-41-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 32303 INFO (zkCallback-43-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 32303 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 32604 INFO (zkConnectionManagerCallback-57-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 32621 INFO (jetty-launcher-9-thread-3) [n:127.0.0.1:54542_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 32627 INFO (jetty-launcher-9-thread-3) [n:127.0.0.1:54542_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:54539/solr ready
[junit4] 2> 33017 INFO (jetty-launcher-9-thread-3) [n:127.0.0.1:54542_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 33017 INFO (jetty-launcher-9-thread-3) [n:127.0.0.1:54542_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54542_solr
[junit4] 2> 33022 INFO (zkCallback-43-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 33022 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 33023 INFO (zkCallback-41-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 33024 INFO (zkCallback-56-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 33025 INFO (OverseerStateUpdate-72083277747257354-127.0.0.1:54542_solr-n_0000000000) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 33492 INFO (jetty-launcher-9-thread-1) [n:127.0.0.1:54545_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54545.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 33493 INFO (jetty-launcher-9-thread-1) [n:127.0.0.1:54545_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54545.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 33493 INFO (jetty-launcher-9-thread-1) [n:127.0.0.1:54545_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54545.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 33499 INFO (jetty-launcher-9-thread-1) [n:127.0.0.1:54545_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_E2917A40304F9AD3-001/tempDir-001/node1/.
[junit4] 2> 33505 INFO (jetty-launcher-9-thread-2) [n:127.0.0.1:54544_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54544.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 33518 INFO (jetty-launcher-9-thread-2) [n:127.0.0.1:54544_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54544.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 33518 INFO (jetty-launcher-9-thread-2) [n:127.0.0.1:54544_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54544.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 33520 INFO (jetty-launcher-9-thread-2) [n:127.0.0.1:54544_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_E2917A40304F9AD3-001/tempDir-001/node2/.
[junit4] 2> 33562 INFO (zkConnectionManagerCallback-65-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 33565 INFO (zkConnectionManagerCallback-67-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 33574 INFO (jetty-launcher-9-thread-1) [n:127.0.0.1:54545_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 33575 INFO (jetty-launcher-9-thread-2) [n:127.0.0.1:54544_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 33577 INFO (jetty-launcher-9-thread-1) [n:127.0.0.1:54545_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:54539/solr ready
[junit4] 2> 33579 INFO (jetty-launcher-9-thread-2) [n:127.0.0.1:54544_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:54539/solr ready
[junit4] 2> 33677 INFO (jetty-launcher-9-thread-4) [n:127.0.0.1:54543_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54543.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 33681 INFO (jetty-launcher-9-thread-4) [n:127.0.0.1:54543_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54543.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 33681 INFO (jetty-launcher-9-thread-4) [n:127.0.0.1:54543_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54543.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 33704 INFO (jetty-launcher-9-thread-3) [n:127.0.0.1:54542_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54542.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 33708 INFO (jetty-launcher-9-thread-3) [n:127.0.0.1:54542_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54542.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 33708 INFO (jetty-launcher-9-thread-3) [n:127.0.0.1:54542_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54542.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 33709 INFO (jetty-launcher-9-thread-4) [n:127.0.0.1:54543_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_E2917A40304F9AD3-001/tempDir-001/node4/.
[junit4] 2> 33709 INFO (jetty-launcher-9-thread-3) [n:127.0.0.1:54542_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_E2917A40304F9AD3-001/tempDir-001/node3/.
[junit4] 2> 33742 INFO (zkConnectionManagerCallback-72-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 33746 INFO (jetty-launcher-9-thread-4) [n:127.0.0.1:54543_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 33748 INFO (jetty-launcher-9-thread-4) [n:127.0.0.1:54543_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:54539/solr ready
[junit4] 2> 34154 INFO (zkConnectionManagerCallback-74-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 34164 INFO (zkConnectionManagerCallback-79-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 34168 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 34172 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:54539/solr ready
[junit4] 2> 34206 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@6c25f2d7{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 34207 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=887163033
[junit4] 2> 34207 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 34208 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1fafba28: rootName = solr_54543, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@3b7be84c
[junit4] 2> 34215 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 34215 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6ba5aba2: rootName = solr_54543, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@2bfee2d9
[junit4] 2> 34227 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 34227 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5b19aeb9: rootName = solr_54543, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@2225df86
[junit4] 2> 34231 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:54543_solr
[junit4] 2> 34236 INFO (zkCallback-56-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34236 INFO (zkCallback-41-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34236 INFO (zkCallback-43-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34237 INFO (zkCallback-39-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34236 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34241 INFO (zkCallback-71-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34241 INFO (zkCallback-66-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34241 INFO (zkCallback-78-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34242 INFO (zkCallback-64-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34243 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 34243 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 34260 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1b895281{/solr,null,UNAVAILABLE}
[junit4] 2> 34260 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 34265 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git: daa59876e6f384329b122929e70a80934569428c; jvm 1.8.0_172-b11
[junit4] 2> 34267 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 34267 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 34267 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 34267 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@c2cf71b{/solr,null,AVAILABLE}
[junit4] 2> 34269 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@58eb41e6{HTTP/1.1,[http/1.1]}{127.0.0.1:54569}
[junit4] 2> 34269 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.Server Started @34351ms
[junit4] 2> 34269 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=54568}
[junit4] 2> 34269 ERROR (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 34269 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 34270 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 34270 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 34270 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 34270 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-05-25T02:22:35.690Z
[junit4] 2> 34275 INFO (zkConnectionManagerCallback-81-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 34277 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 34299 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54539/solr
[junit4] 2> 34305 INFO (zkConnectionManagerCallback-85-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 34313 INFO (zkConnectionManagerCallback-87-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 34334 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54568_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 34341 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54568_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 34347 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54568_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 34347 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54568_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54568_solr
[junit4] 2> 34351 INFO (zkCallback-41-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 34351 INFO (zkCallback-64-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 34351 INFO (zkCallback-39-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 34351 INFO (zkCallback-56-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 34351 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 34351 INFO (zkCallback-78-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 34351 INFO (zkCallback-66-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 34352 INFO (zkCallback-86-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 34587 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54568_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54568.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 34649 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54568_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54568.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 34649 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54568_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54568.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 34651 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54568_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_E2917A40304F9AD3-001/tempDir-001/node4/.
[junit4] 2> 34659 INFO (zkConnectionManagerCallback-94-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 34663 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54568_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 34666 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54568_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:54539/solr ready
[junit4] 2> 34781 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL: http://127.0.0.1:54569/solr. Proxy: http://127.0.0.1:54568/solr
[junit4] 2> 34783 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@324a31f4{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 34783 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=50675663
[junit4] 2> 34788 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 34789 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4cce411: rootName = solr_54545, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@5165cb76
[junit4] 2> 34798 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 34798 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@43039fba: rootName = solr_54545, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@2bfee2d9
[junit4] 2> 34803 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 34803 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7fc21a8d: rootName = solr_54545, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@2225df86
[junit4] 2> 34807 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:54545_solr
[junit4] 2> 34811 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34811 INFO (zkCallback-39-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34811 INFO (zkCallback-56-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34811 INFO (zkCallback-41-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34811 INFO (zkCallback-78-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34811 INFO (zkCallback-66-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34811 INFO (zkCallback-86-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34811 INFO (zkCallback-64-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34812 INFO (zkCallback-93-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34814 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 34816 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 34830 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4ab4a7ec{/solr,null,UNAVAILABLE}
[junit4] 2> 34830 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 34832 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git: daa59876e6f384329b122929e70a80934569428c; jvm 1.8.0_172-b11
[junit4] 2> 34834 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 34834 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 34834 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 34834 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@59faf4a1{/solr,null,AVAILABLE}
[junit4] 2> 34835 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1853c2b9{HTTP/1.1,[http/1.1]}{127.0.0.1:54577}
[junit4] 2> 34836 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.Server Started @34918ms
[junit4] 2> 34836 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=54576}
[junit4] 2> 34836 ERROR (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 34836 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 34836 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 34836 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 34837 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 34837 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-05-25T02:22:36.257Z
[junit4] 2> 34840 INFO (zkConnectionManagerCallback-96-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 34842 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 34863 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54539/solr
[junit4] 2> 34866 INFO (zkConnectionManagerCallback-100-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 34873 INFO (zkConnectionManagerCallback-102-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 34897 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54576_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 34906 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54576_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 34913 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54576_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 34913 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54576_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54576_solr
[junit4] 2> 34917 INFO (zkCallback-56-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 34917 INFO (zkCallback-66-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 34917 INFO (zkCallback-78-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 34917 INFO (zkCallback-86-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 34918 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 34918 INFO (zkCallback-93-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 34920 INFO (zkCallback-101-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 34921 INFO (zkCallback-39-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 35208 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54576_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54576.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 35221 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54576_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54576.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 35222 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54576_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54576.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 35229 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54576_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_E2917A40304F9AD3-001/tempDir-001/node1/.
[junit4] 2> 35236 INFO (zkConnectionManagerCallback-109-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 35240 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54576_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 35244 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54576_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:54539/solr ready
[junit4] 2> 35340 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL: http://127.0.0.1:54577/solr. Proxy: http://127.0.0.1:54576/solr
[junit4] 2> 35341 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@477795c4{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 35342 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1719358010
[junit4] 2> 35342 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 35343 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@f0b4e49: rootName = solr_54542, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@2b2628c1
[junit4] 2> 35352 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 35352 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1e206cb5: rootName = solr_54542, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@2bfee2d9
[junit4] 2> 35358 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 35359 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5fd12d24: rootName = solr_54542, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@2225df86
[junit4] 2> 35367 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:54542_solr
[junit4] 2> 35372 INFO (zkCallback-66-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 35373 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 35373 INFO (zkCallback-39-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 35373 INFO (zkCallback-93-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 35373 INFO (zkCallback-78-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 35373 INFO (zkCallback-86-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 35373 INFO (zkCallback-56-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 35373 INFO (zkCallback-101-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 35377 INFO (zkCallback-108-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 35388 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 35394 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.Overseer Overseer (id=72083277747257354-127.0.0.1:54542_solr-n_0000000000) closing
[junit4] 2> 35395 INFO (OverseerStateUpdate-72083277747257354-127.0.0.1:54542_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:54542_solr
[junit4] 2> 35426 WARN (OverseerAutoScalingTriggerThread-72083277747257354-127.0.0.1:54542_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 35434 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:54544_solr
[junit4] 2> 35435 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3828b777{/solr,null,UNAVAILABLE}
[junit4] 2> 35435 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 35437 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72083277747257353-127.0.0.1:54544_solr-n_0000000003) starting
[junit4] 2> 35438 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git: daa59876e6f384329b122929e70a80934569428c; jvm 1.8.0_172-b11
[junit4] 2> 35440 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 35440 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 35440 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 35441 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@482a1383{/solr,null,AVAILABLE}
[junit4] 2> 35450 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2ca59ec8{HTTP/1.1,[http/1.1]}{127.0.0.1:54583}
[junit4] 2> 35450 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.Server Started @35533ms
[junit4] 2> 35451 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=54582}
[junit4] 2> 35466 ERROR (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 35538 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 35539 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 35540 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 35540 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 35540 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-05-25T02:22:36.960Z
[junit4] 2> 35544 INFO (zkConnectionManagerCallback-111-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 35547 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 35564 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54539/solr
[junit4] 2> 35571 INFO (zkConnectionManagerCallback-115-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 35583 INFO (zkConnectionManagerCallback-117-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 35601 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54582_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 35607 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54582_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 35617 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54582_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 35618 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54582_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54582_solr
[junit4] 2> 35623 INFO (zkCallback-101-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 35624 INFO (zkCallback-108-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 35624 INFO (zkCallback-86-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 35624 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 35624 INFO (zkCallback-93-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 35624 INFO (zkCallback-66-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 35624 INFO (zkCallback-78-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 35625 INFO (zkCallback-116-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 35856 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54582_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54582.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 35870 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54582_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54582.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 35871 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54582_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54582.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 35873 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54582_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_E2917A40304F9AD3-001/tempDir-001/node3/.
[junit4] 2> 35886 INFO (zkConnectionManagerCallback-124-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 35890 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54582_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 35893 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54582_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:54539/solr ready
[junit4] 2> 36028 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL: http://127.0.0.1:54583/solr. Proxy: http://127.0.0.1:54582/solr
[junit4] 2> 36037 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@719fa609{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 36038 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=539750970
[junit4] 2> 36039 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 36039 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3ef1c96: rootName = solr_54544, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@3fd33e96
[junit4] 2> 36048 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 36048 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3c7a42c8: rootName = solr_54544, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@2bfee2d9
[junit4] 2> 36058 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 36058 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5927816a: rootName = solr_54544, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@2225df86
[junit4] 2> 36062 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:54544_solr
[junit4] 2> 36066 INFO (zkCallback-108-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 36067 INFO (zkCallback-66-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 36067 INFO (zkCallback-116-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 36067 INFO (zkCallback-93-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 36067 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 36067 INFO (zkCallback-101-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 36067 INFO (zkCallback-86-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 36067 INFO (zkCallback-78-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 36068 INFO (zkCallback-123-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 36070 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 36073 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 36080 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.Overseer Overseer (id=72083277747257353-127.0.0.1:54544_solr-n_0000000003) closing
[junit4] 2> 36081 INFO (OverseerStateUpdate-72083277747257353-127.0.0.1:54544_solr-n_0000000003) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:54544_solr
[junit4] 2> 36081 WARN (OverseerAutoScalingTriggerThread-72083277747257353-127.0.0.1:54544_solr-n_0000000003) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 36090 INFO (zkCallback-86-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:54568_solr
[junit4] 2> 36093 INFO (zkCallback-86-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72083277747257365-127.0.0.1:54568_solr-n_0000000004) starting
[junit4] 2> 36094 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6bcb7cee{/solr,null,UNAVAILABLE}
[junit4] 2> 36095 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 36124 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git: daa59876e6f384329b122929e70a80934569428c; jvm 1.8.0_172-b11
[junit4] 2> 36144 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 36145 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 36145 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 36150 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1277798b{/solr,null,AVAILABLE}
[junit4] 2> 36154 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1078d988{HTTP/1.1,[http/1.1]}{127.0.0.1:54589}
[junit4] 2> 36154 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.Server Started @36237ms
[junit4] 2> 36154 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=54588}
[junit4] 2> 36156 ERROR (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 36156 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 36158 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 36158 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 36158 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 36158 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-05-25T02:22:37.578Z
[junit4] 2> 36163 INFO (zkConnectionManagerCallback-126-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 36165 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 36206 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54539/solr
[junit4] 2> 36240 INFO (zkConnectionManagerCallback-130-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 36251 INFO (zkConnectionManagerCallback-132-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 36296 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54588_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 36302 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54588_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 36312 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54588_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 36312 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54588_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54588_solr
[junit4] 2> 36320 INFO (zkCallback-123-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 36320 INFO (zkCallback-93-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 36320 INFO (zkCallback-108-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 36320 INFO (zkCallback-101-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 36320 INFO (zkCallback-86-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 36320 INFO (zkCallback-78-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 36321 INFO (zkCallback-116-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 36321 INFO (zkCallback-131-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 36638 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54588_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54588.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 36673 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54588_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54588.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 36673 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54588_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54588.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 36694 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54588_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_E2917A40304F9AD3-001/tempDir-001/node2/.
[junit4] 2> 36708 INFO (zkConnectionManagerCallback-139-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 36714 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54588_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 36721 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54588_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:54539/solr ready
[junit4] 2> 36847 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL: http://127.0.0.1:54589/solr. Proxy: http://127.0.0.1:54588/solr
[junit4] 2> 36938 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[E2917A40304F9AD3]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMostInSyncReplicasCanWinElection
[junit4] 2> 37447 INFO (SocketProxy-Acceptor-54568) [ ] o.a.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=54594,localport=54568], receiveBufferSize:408300
[junit4] 2> 37498 INFO (SocketProxy-Acceptor-54568) [ ] o.a.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=54569,localport=54595], receiveBufferSize=408300
[junit4] 2> 38538 INFO (qtp657487167-221) [n:127.0.0.1:54568_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&replicationFactor=3&name=collection1&nrtReplicas=3&action=CREATE&numShards=1&tlogReplicas=0&createNodeSet=&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 38707 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 38710 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/collection1.AUTOCREATED/managed-schema
[junit4] 2> 38716 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/collection1.AUTOCREATED/protwords.txt
[junit4] 2> 38721 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/collection1.AUTOCREATED/solrconfig.xml
[junit4] 2> 38725 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/collection1.AUTOCREATED/synonyms.txt
[junit4] 2> 38729 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/collection1.AUTOCREATED/stopwords.txt
[junit4] 2> 38756 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/collection1.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 38765 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/collection1.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 38774 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/collection1.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 38784 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/collection1.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 38790 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/collection1.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 38796 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/collection1.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 38801 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/collection1.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 38831 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/collection1.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 38835 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/collection1.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 38840 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/collection1.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 38844 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/collection1.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 38855 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/collection1.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 38860 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/collection1.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 38864 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/collection1.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 38868 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/collection1.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 38873 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/collection1.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 38877 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/collection1.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 38881 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/collection1.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 38885 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/collection1.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 38889 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/collection1.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 38893 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/collection1.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 38897 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/collection1.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 38901 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/collection1.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 38905 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/collection1.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 38910 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/collection1.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 38914 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/collection1.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 38918 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/collection1.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 38922 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/collection1.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 38926 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/collection1.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 38930 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/collection1.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 38934 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/collection1.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 38938 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/collection1.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 38942 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/collection1.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 38946 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/collection1.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 38950 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/collection1.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 38956 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/collection1.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 38961 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/collection1.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 38965 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/collection1.AUTOCREATED/lang/stopwords_gl.txt
[junit4] 2> 38969 INFO (OverseerThreadFactory-120-thread-1) [ ]
[...truncated too long message...]
s/test
common-solr.compile-test:
compile-test:
BUILD SUCCESSFUL
Total time: 1 minute 54 seconds
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.LeaderVoteWaitTimeoutTest" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=E2917A40304F9AD3 -Dtests.slow=true -Dtests.locale=zh-TW -Dtests.timezone=US/Indiana-Starke -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
Buildfile: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/build.xml
-clover.disable:
ivy-configure:
[ivy:configure] :: Apache Ivy 2.4.0 - 20141213170938 :: http://ant.apache.org/ivy/ ::
[ivy:configure] :: loading settings :: file = /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/top-level-ivy-settings.xml
install-junit4-taskdef:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
resolve-groovy:
[ivy:cachepath] :: resolving dependencies :: org.codehaus.groovy#groovy-all-caller;working
[ivy:cachepath] confs: [default]
[ivy:cachepath] found org.codehaus.groovy#groovy-all;2.4.15 in public
[ivy:cachepath] :: resolution report :: resolve 31ms :: artifacts dl 1ms
---------------------------------------------------------------------
| | modules || artifacts |
| conf | number| search|dwnlded|evicted|| number|dwnlded|
---------------------------------------------------------------------
| default | 1 | 0 | 0 | 0 || 1 | 0 |
---------------------------------------------------------------------
-init-totals:
-test:
[mkdir] Created dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test
[junit4:pickseed] Seed property 'tests.seed' already defined: E2917A40304F9AD3
[mkdir] Created dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/temp
[junit4] <JUnit4> says ¡Hola! Master seed: E2917A40304F9AD3
[junit4] Executing 5 suites with 2 JVMs.
[junit4]
[junit4] Started J1 PID(77952@serv1-vm2.sd-datasolutions.de).
[junit4] Started J0 PID(77951@serv1-vm2.sd-datasolutions.de).
[junit4] Suite: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest
[junit4] OK 40.0s J1 | LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection
[junit4] OK 12.3s J1 | LeaderVoteWaitTimeoutTest.basicTest
[junit4] Completed [1/5] on J1 in 75.68s, 2 tests
[junit4]
[junit4] Suite: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest
[junit4] OK 40.1s J0 | LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection
[junit4] OK 14.6s J0 | LeaderVoteWaitTimeoutTest.basicTest
[junit4] Completed [2/5] on J0 in 78.10s, 2 tests
[junit4]
[junit4] Duplicate suite name used with XML reports: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest. This may confuse tools that process XML reports. Set 'ignoreDuplicateSuites' to true to skip this message.
[junit4] Suite: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest
[junit4] OK 18.6s J0 | LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection
[junit4] OK 11.7s J0 | LeaderVoteWaitTimeoutTest.basicTest
[junit4] Completed [3/5] on J0 in 36.23s, 2 tests
[junit4]
[junit4] Suite: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest
[junit4] OK 19.0s J1 | LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection
[junit4] OK 16.0s J1 | LeaderVoteWaitTimeoutTest.basicTest
[junit4] Completed [4/5] on J1 in 43.21s, 2 tests
[junit4]
[junit4] Suite: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest
[junit4] OK 17.0s J0 | LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection
[junit4] OK 11.1s J0 | LeaderVoteWaitTimeoutTest.basicTest
[junit4] Completed [5/5] on J0 in 33.78s, 2 tests
[junit4]
[junit4] JVM J0: 1.01 .. 151.62 = 150.62s
[junit4] JVM J1: 1.00 .. 122.78 = 121.77s
[junit4] Execution time total: 2 minutes 31 seconds
[junit4] Tests summary: 5 suites, 10 tests
[junit4] Could not remove temporary path: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp])
[junit4] Could not remove temporary path: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp])
[echo] 5 slowest tests:
[junit4:tophints] 177.61s | org.apache.solr.cloud.autoscaling.SearchRateTriggerIntegrationTest
[junit4:tophints] 143.18s | org.apache.solr.cloud.api.collections.AssignTest
[junit4:tophints] 133.38s | org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest
[junit4:tophints] 117.48s | org.apache.solr.update.SoftAutoCommitTest
[junit4:tophints] 113.63s | org.apache.solr.cloud.TestTlogReplica
-check-totals:
test-nocompile:
BUILD SUCCESSFUL
Total time: 2 minutes 36 seconds
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest
[repro] Exiting with code 0
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath$34.hasMatch(FilePath.java:2678)
at hudson.FilePath$34.invoke(FilePath.java:2557)
at hudson.FilePath$34.invoke(FilePath.java:2547)
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2918)
Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to MacOSX VBOX
at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1741)
at hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357)
at hudson.remoting.Channel.call(Channel.java:955)
at hudson.FilePath.act(FilePath.java:1036)
at hudson.FilePath.act(FilePath.java:1025)
at hudson.FilePath.validateAntFileMask(FilePath.java:2547)
at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
at hudson.model.Build$BuildExecution.post2(Build.java:186)
at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
at hudson.model.Run.execute(Run.java:1819)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
Caused: hudson.FilePath$TunneledInterruptedException
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2920)
at hudson.remoting.UserRequest.perform(UserRequest.java:212)
at hudson.remoting.UserRequest.perform(UserRequest.java:54)
at hudson.remoting.Request$2.run(Request.java:369)
at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
Caused: java.lang.InterruptedException: java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath.act(FilePath.java:1038)
at hudson.FilePath.act(FilePath.java:1025)
at hudson.FilePath.validateAntFileMask(FilePath.java:2547)
at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
at hudson.model.Build$BuildExecution.post2(Build.java:186)
at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
at hudson.model.Run.execute(Run.java:1819)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
No artifacts found that match the file pattern "**/*.events,heapdumps/**,**/*_pid*.log". Configuration error?
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all warnings.
<Git Blamer> GIT_COMMIT=ad143a1ceceb1e1b40780db2c65bf437e2abc87d, workspace=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX
[WARNINGS] Computing warning deltas based on reference build #4655
Recording test results
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[JENKINS] Lucene-Solr-master-MacOSX (64bit/jdk1.8.0) - Build # 4655
- Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/4655/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseSerialGC
4 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testTrigger
Error Message:
waitFor not elapsed but produced an event
Stack Trace:
java.lang.AssertionError: waitFor not elapsed but produced an event
at __randomizedtesting.SeedInfo.seed([DC94820ADC48E4C3:BF5FB488458797EE]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testTrigger(IndexSizeTriggerTest.java:180)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testTrigger
Error Message:
waitFor not elapsed but produced an event
Stack Trace:
java.lang.AssertionError: waitFor not elapsed but produced an event
at __randomizedtesting.SeedInfo.seed([DC94820ADC48E4C3:BF5FB488458797EE]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testTrigger(IndexSizeTriggerTest.java:180)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testMergeIntegration
Error Message:
did not finish processing in time
Stack Trace:
java.lang.AssertionError: did not finish processing in time
at __randomizedtesting.SeedInfo.seed([DC94820ADC48E4C3:8F2DC0BA3E597139]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testMergeIntegration(IndexSizeTriggerTest.java:425)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testSplitIntegration
Error Message:
last state: DocCollection(testSplitIntegration_collection//clusterstate.json/108)={ "replicationFactor":"2", "pullReplicas":"0", "router":{"name":"compositeId"}, "maxShardsPerNode":"2", "autoAddReplicas":"false", "nrtReplicas":"2", "tlogReplicas":"0", "autoCreated":"true", "shards":{ "shard1_1_0":{ "state":"active", "replicas":{ "core_node14":{ "core":"testSplitIntegration_collection_shard1_1_0_replica1", "SEARCHER.searcher.maxDoc":6, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":1, "node_name":"127.0.0.1:10016_solr", "base_url":"http://127.0.0.1:10016/solr", "state":"active", "type":"NRT", "SEARCHER.searcher.numDocs":6}, "core_node13":{ "core":"testSplitIntegration_collection_shard1_1_0_replica0", "SEARCHER.searcher.maxDoc":6, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":1, "node_name":"127.0.0.1:10017_solr", "base_url":"http://127.0.0.1:10017/solr", "state":"active", "type":"NRT", "SEARCHER.searcher.numDocs":6}}}, "shard2":{ "replicas":{ "core_node3":{ "core":"testSplitIntegration_collection_shard2_replica_n3", "leader":"true", "SEARCHER.searcher.maxDoc":25, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":1, "node_name":"127.0.0.1:10017_solr", "state":"active", "type":"NRT", "SEARCHER.searcher.numDocs":25}, "core_node4":{ "core":"testSplitIntegration_collection_shard2_replica_n4", "SEARCHER.searcher.maxDoc":25, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":1, "node_name":"127.0.0.1:10016_solr", "state":"active", "type":"NRT", "SEARCHER.searcher.numDocs":25}}, "range":"0-7fffffff", "state":"active"}, "shard1":{ "stateTimestamp":"1527284892210357600", "replicas":{ "core_node1":{ "core":"testSplitIntegration_collection_shard1_replica_n1", "leader":"true", "SEARCHER.searcher.maxDoc":25, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":1, "node_name":"127.0.0.1:10017_solr", "state":"active", "type":"NRT", "SEARCHER.searcher.numDocs":25}, "core_node2":{ "core":"testSplitIntegration_collection_shard1_replica_n2", "SEARCHER.searcher.maxDoc":25, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":1, "node_name":"127.0.0.1:10016_solr", "state":"active", "type":"NRT", "SEARCHER.searcher.numDocs":25}}, "range":"80000000-ffffffff", "state":"inactive"}, "shard1_1":{ "parent":"shard1", "stateTimestamp":"1527284892230065650", "range":"c0000000-ffffffff", "state":"active", "replicas":{ "core_node10":{ "leader":"true", "core":"testSplitIntegration_collection_shard1_1_replica1", "SEARCHER.searcher.maxDoc":12, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":1, "node_name":"127.0.0.1:10016_solr", "base_url":"http://127.0.0.1:10016/solr", "state":"active", "type":"NRT", "SEARCHER.searcher.numDocs":12}, "core_node9":{ "core":"testSplitIntegration_collection_shard1_1_replica0", "SEARCHER.searcher.maxDoc":12, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":1, "node_name":"127.0.0.1:10017_solr", "base_url":"http://127.0.0.1:10017/solr", "state":"active", "type":"NRT", "SEARCHER.searcher.numDocs":12}}}, "shard1_0":{ "parent":"shard1", "stateTimestamp":"1527284892229879900", "range":"80000000-bfffffff", "state":"active", "replicas":{ "core_node7":{ "leader":"true", "core":"testSplitIntegration_collection_shard1_0_replica0", "SEARCHER.searcher.maxDoc":13, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":1, "node_name":"127.0.0.1:10017_solr", "base_url":"http://127.0.0.1:10017/solr", "state":"active", "type":"NRT", "SEARCHER.searcher.numDocs":13}, "core_node8":{ "core":"testSplitIntegration_collection_shard1_0_replica1", "SEARCHER.searcher.maxDoc":13, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":1, "node_name":"127.0.0.1:10016_solr", "base_url":"http://127.0.0.1:10016/solr", "state":"active", "type":"NRT", "SEARCHER.searcher.numDocs":13}}}}}
Stack Trace:
java.util.concurrent.TimeoutException: last state: DocCollection(testSplitIntegration_collection//clusterstate.json/108)={
"replicationFactor":"2",
"pullReplicas":"0",
"router":{"name":"compositeId"},
"maxShardsPerNode":"2",
"autoAddReplicas":"false",
"nrtReplicas":"2",
"tlogReplicas":"0",
"autoCreated":"true",
"shards":{
"shard1_1_0":{
"state":"active",
"replicas":{
"core_node14":{
"core":"testSplitIntegration_collection_shard1_1_0_replica1",
"SEARCHER.searcher.maxDoc":6,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":1,
"node_name":"127.0.0.1:10016_solr",
"base_url":"http://127.0.0.1:10016/solr",
"state":"active",
"type":"NRT",
"SEARCHER.searcher.numDocs":6},
"core_node13":{
"core":"testSplitIntegration_collection_shard1_1_0_replica0",
"SEARCHER.searcher.maxDoc":6,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":1,
"node_name":"127.0.0.1:10017_solr",
"base_url":"http://127.0.0.1:10017/solr",
"state":"active",
"type":"NRT",
"SEARCHER.searcher.numDocs":6}}},
"shard2":{
"replicas":{
"core_node3":{
"core":"testSplitIntegration_collection_shard2_replica_n3",
"leader":"true",
"SEARCHER.searcher.maxDoc":25,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":1,
"node_name":"127.0.0.1:10017_solr",
"state":"active",
"type":"NRT",
"SEARCHER.searcher.numDocs":25},
"core_node4":{
"core":"testSplitIntegration_collection_shard2_replica_n4",
"SEARCHER.searcher.maxDoc":25,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":1,
"node_name":"127.0.0.1:10016_solr",
"state":"active",
"type":"NRT",
"SEARCHER.searcher.numDocs":25}},
"range":"0-7fffffff",
"state":"active"},
"shard1":{
"stateTimestamp":"1527284892210357600",
"replicas":{
"core_node1":{
"core":"testSplitIntegration_collection_shard1_replica_n1",
"leader":"true",
"SEARCHER.searcher.maxDoc":25,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":1,
"node_name":"127.0.0.1:10017_solr",
"state":"active",
"type":"NRT",
"SEARCHER.searcher.numDocs":25},
"core_node2":{
"core":"testSplitIntegration_collection_shard1_replica_n2",
"SEARCHER.searcher.maxDoc":25,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":1,
"node_name":"127.0.0.1:10016_solr",
"state":"active",
"type":"NRT",
"SEARCHER.searcher.numDocs":25}},
"range":"80000000-ffffffff",
"state":"inactive"},
"shard1_1":{
"parent":"shard1",
"stateTimestamp":"1527284892230065650",
"range":"c0000000-ffffffff",
"state":"active",
"replicas":{
"core_node10":{
"leader":"true",
"core":"testSplitIntegration_collection_shard1_1_replica1",
"SEARCHER.searcher.maxDoc":12,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":1,
"node_name":"127.0.0.1:10016_solr",
"base_url":"http://127.0.0.1:10016/solr",
"state":"active",
"type":"NRT",
"SEARCHER.searcher.numDocs":12},
"core_node9":{
"core":"testSplitIntegration_collection_shard1_1_replica0",
"SEARCHER.searcher.maxDoc":12,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":1,
"node_name":"127.0.0.1:10017_solr",
"base_url":"http://127.0.0.1:10017/solr",
"state":"active",
"type":"NRT",
"SEARCHER.searcher.numDocs":12}}},
"shard1_0":{
"parent":"shard1",
"stateTimestamp":"1527284892229879900",
"range":"80000000-bfffffff",
"state":"active",
"replicas":{
"core_node7":{
"leader":"true",
"core":"testSplitIntegration_collection_shard1_0_replica0",
"SEARCHER.searcher.maxDoc":13,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":1,
"node_name":"127.0.0.1:10017_solr",
"base_url":"http://127.0.0.1:10017/solr",
"state":"active",
"type":"NRT",
"SEARCHER.searcher.numDocs":13},
"core_node8":{
"core":"testSplitIntegration_collection_shard1_0_replica1",
"SEARCHER.searcher.maxDoc":13,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":1,
"node_name":"127.0.0.1:10016_solr",
"base_url":"http://127.0.0.1:10016/solr",
"state":"active",
"type":"NRT",
"SEARCHER.searcher.numDocs":13}}}}}
at __randomizedtesting.SeedInfo.seed([DC94820ADC48E4C3:E51A3B4AF3B72D3D]:0)
at org.apache.solr.cloud.CloudTestUtils.waitForState(CloudTestUtils.java:111)
at org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testSplitIntegration(IndexSizeTriggerTest.java:310)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 14433 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.IndexSizeTriggerTest_DC94820ADC48E4C3-001/init-core-data-001
[junit4] 2> 2940719 WARN (SUITE-IndexSizeTriggerTest-seed#[DC94820ADC48E4C3]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=9 numCloses=9
[junit4] 2> 2940719 INFO (SUITE-IndexSizeTriggerTest-seed#[DC94820ADC48E4C3]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 2940724 INFO (SUITE-IndexSizeTriggerTest-seed#[DC94820ADC48E4C3]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 2940724 INFO (SUITE-IndexSizeTriggerTest-seed#[DC94820ADC48E4C3]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 2940724 INFO (SUITE-IndexSizeTriggerTest-seed#[DC94820ADC48E4C3]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.IndexSizeTriggerTest_DC94820ADC48E4C3-001/tempDir-001
[junit4] 2> 2940724 INFO (SUITE-IndexSizeTriggerTest-seed#[DC94820ADC48E4C3]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2940725 INFO (Thread-4066) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2940725 INFO (Thread-4066) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2940728 ERROR (Thread-4066) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 2940835 INFO (SUITE-IndexSizeTriggerTest-seed#[DC94820ADC48E4C3]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:55723
[junit4] 2> 2940844 INFO (zkConnectionManagerCallback-8455-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2940850 INFO (jetty-launcher-8452-thread-1) [ ] o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git: daa59876e6f384329b122929e70a80934569428c; jvm 1.8.0_172-b11
[junit4] 2> 2940850 INFO (jetty-launcher-8452-thread-2) [ ] o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git: daa59876e6f384329b122929e70a80934569428c; jvm 1.8.0_172-b11
[junit4] 2> 2940851 INFO (jetty-launcher-8452-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2940851 INFO (jetty-launcher-8452-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2940851 INFO (jetty-launcher-8452-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2940852 INFO (jetty-launcher-8452-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1d00e221{/solr,null,AVAILABLE}
[junit4] 2> 2940852 INFO (jetty-launcher-8452-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@53ae7a93{HTTP/1.1,[http/1.1]}{127.0.0.1:55725}
[junit4] 2> 2940852 INFO (jetty-launcher-8452-thread-1) [ ] o.e.j.s.Server Started @2940925ms
[junit4] 2> 2940852 INFO (jetty-launcher-8452-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=55725}
[junit4] 2> 2940852 ERROR (jetty-launcher-8452-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2940852 INFO (jetty-launcher-8452-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2940852 INFO (jetty-launcher-8452-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 2940852 INFO (jetty-launcher-8452-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2940852 INFO (jetty-launcher-8452-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2940852 INFO (jetty-launcher-8452-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-05-24T15:07:54.329Z
[junit4] 2> 2940852 INFO (jetty-launcher-8452-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2940852 INFO (jetty-launcher-8452-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2940852 INFO (jetty-launcher-8452-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2940853 INFO (jetty-launcher-8452-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@ef185c7{/solr,null,AVAILABLE}
[junit4] 2> 2940854 INFO (jetty-launcher-8452-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@449b6299{HTTP/1.1,[http/1.1]}{127.0.0.1:55726}
[junit4] 2> 2940854 INFO (jetty-launcher-8452-thread-2) [ ] o.e.j.s.Server Started @2940926ms
[junit4] 2> 2940854 INFO (jetty-launcher-8452-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=55726}
[junit4] 2> 2940854 ERROR (jetty-launcher-8452-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2940854 INFO (jetty-launcher-8452-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2940854 INFO (jetty-launcher-8452-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 2940854 INFO (jetty-launcher-8452-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2940854 INFO (jetty-launcher-8452-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2940854 INFO (jetty-launcher-8452-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-05-24T15:07:54.331Z
[junit4] 2> 2940855 INFO (zkConnectionManagerCallback-8457-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2940855 INFO (jetty-launcher-8452-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2940856 INFO (zkConnectionManagerCallback-8459-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2940857 INFO (jetty-launcher-8452-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2940886 INFO (jetty-launcher-8452-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55723/solr
[junit4] 2> 2940886 INFO (jetty-launcher-8452-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55723/solr
[junit4] 2> 2940888 INFO (zkConnectionManagerCallback-8465-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2940888 INFO (zkConnectionManagerCallback-8467-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2940890 INFO (zkConnectionManagerCallback-8469-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2940891 INFO (zkConnectionManagerCallback-8471-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2941051 INFO (jetty-launcher-8452-thread-1) [n:127.0.0.1:55725_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2941051 INFO (jetty-launcher-8452-thread-1) [n:127.0.0.1:55725_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:55725_solr
[junit4] 2> 2941052 INFO (jetty-launcher-8452-thread-2) [n:127.0.0.1:55726_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2941053 INFO (jetty-launcher-8452-thread-1) [n:127.0.0.1:55725_solr ] o.a.s.c.Overseer Overseer (id=72080625011523589-127.0.0.1:55725_solr-n_0000000000) starting
[junit4] 2> 2941054 INFO (jetty-launcher-8452-thread-2) [n:127.0.0.1:55726_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55726_solr
[junit4] 2> 2941056 INFO (zkCallback-8468-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2941056 INFO (zkCallback-8470-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2941064 INFO (zkConnectionManagerCallback-8480-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2941066 INFO (jetty-launcher-8452-thread-1) [n:127.0.0.1:55725_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2941068 INFO (jetty-launcher-8452-thread-1) [n:127.0.0.1:55725_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55723/solr ready
[junit4] 2> 2941069 DEBUG (OverseerAutoScalingTriggerThread-72080625011523589-127.0.0.1:55725_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 2941071 DEBUG (OverseerAutoScalingTriggerThread-72080625011523589-127.0.0.1:55725_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 2941071 DEBUG (OverseerAutoScalingTriggerThread-72080625011523589-127.0.0.1:55725_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion -1
[junit4] 2> 2941071 DEBUG (OverseerAutoScalingTriggerThread-72080625011523589-127.0.0.1:55725_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 2941071 INFO (jetty-launcher-8452-thread-1) [n:127.0.0.1:55725_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2941071 INFO (jetty-launcher-8452-thread-1) [n:127.0.0.1:55725_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55725_solr
[junit4] 2> 2941074 INFO (zkCallback-8479-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2941074 INFO (zkCallback-8468-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2941074 INFO (zkCallback-8470-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2941079 DEBUG (OverseerAutoScalingTriggerThread-72080625011523589-127.0.0.1:55725_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:55725_solr, 127.0.0.1:55726_solr]
[junit4] 2> 2941079 DEBUG (OverseerAutoScalingTriggerThread-72080625011523589-127.0.0.1:55725_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2941080 DEBUG (OverseerAutoScalingTriggerThread-72080625011523589-127.0.0.1:55725_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 2941080 DEBUG (ScheduledTrigger-9142-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 2941319 INFO (jetty-launcher-8452-thread-2) [n:127.0.0.1:55726_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55726.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1dbe5468
[junit4] 2> 2941325 INFO (jetty-launcher-8452-thread-2) [n:127.0.0.1:55726_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55726.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1dbe5468
[junit4] 2> 2941325 INFO (jetty-launcher-8452-thread-2) [n:127.0.0.1:55726_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55726.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1dbe5468
[junit4] 2> 2941326 INFO (jetty-launcher-8452-thread-2) [n:127.0.0.1:55726_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.IndexSizeTriggerTest_DC94820ADC48E4C3-001/tempDir-001/node2/.
[junit4] 2> 2941334 INFO (zkConnectionManagerCallback-8485-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2941336 INFO (jetty-launcher-8452-thread-2) [n:127.0.0.1:55726_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2941338 INFO (jetty-launcher-8452-thread-2) [n:127.0.0.1:55726_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55723/solr ready
[junit4] 2> 2941368 INFO (jetty-launcher-8452-thread-1) [n:127.0.0.1:55725_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55725.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1dbe5468
[junit4] 2> 2941373 INFO (jetty-launcher-8452-thread-1) [n:127.0.0.1:55725_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55725.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1dbe5468
[junit4] 2> 2941373 INFO (jetty-launcher-8452-thread-1) [n:127.0.0.1:55725_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55725.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1dbe5468
[junit4] 2> 2941374 INFO (jetty-launcher-8452-thread-1) [n:127.0.0.1:55725_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.IndexSizeTriggerTest_DC94820ADC48E4C3-001/tempDir-001/node1/.
[junit4] 2> 2941387 INFO (zkConnectionManagerCallback-8487-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2941391 INFO (zkConnectionManagerCallback-8492-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2941393 INFO (SUITE-IndexSizeTriggerTest-seed#[DC94820ADC48E4C3]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2941395 INFO (SUITE-IndexSizeTriggerTest-seed#[DC94820ADC48E4C3]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55723/solr ready
[junit4] 2> 2941401 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 2941401 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 0
[junit4] 2> 2941402 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion -1
[junit4] 2> 2941402 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 0
[junit4] 2> 2941402 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:10016_solr, 127.0.0.1:10017_solr]
[junit4] 2> 2941402 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2941402 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion 0
[junit4] 2> 2941402 DEBUG (ScheduledTrigger-9151-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 2941427 DEBUG (ScheduledTrigger-9151-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 2941451 DEBUG (ScheduledTrigger-9151-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 2941478 DEBUG (ScheduledTrigger-9151-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 2941499 DEBUG (ScheduledTrigger-9151-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 2941520 DEBUG (ScheduledTrigger-9151-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 2941541 DEBUG (ScheduledTrigger-9151-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 2941563 DEBUG (ScheduledTrigger-9151-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 2941592 DEBUG (ScheduledTrigger-9151-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 2941613 DEBUG (ScheduledTrigger-9151-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 2941631 INFO (TEST-IndexSizeTriggerTest.testMixedBounds-seed#[DC94820ADC48E4C3]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMixedBounds
[junit4] 2> 2941631 WARN (TEST-IndexSizeTriggerTest.testMixedBounds-seed#[DC94820ADC48E4C3]) [ ] o.a.s.c.a.IndexSizeTriggerTest Requires SOLR-12208
[junit4] 2> 2941632 INFO (TEST-IndexSizeTriggerTest.testMixedBounds-seed#[DC94820ADC48E4C3]) [ ] o.a.s.c.a.IndexSizeTriggerTest #######################################
[junit4] 2> ############ CLUSTER STATE ############
[junit4] 2> #######################################
[junit4] 2> ## Live nodes: 2
[junit4] 2> ## Empty nodes: 2
[junit4] 2> ## Dead nodes: 0
[junit4] 2> ## Collections: []
[junit4] 2> ## Max replicas per node: 0
[junit4] 2> ## Min replicas per node: 0
[junit4] 2> ## Total replicas: 0
[junit4] 2> ######### Solr op counts ##########
[junit4] 2> ######### Autoscaling event counts ###########
[junit4] 2>
[junit4] 2> 2941632 DEBUG (TEST-IndexSizeTriggerTest.testMixedBounds-seed#[DC94820ADC48E4C3]) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 2941632 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 2941632 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 2941632 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2941632 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 2941732 INFO (TEST-IndexSizeTriggerTest.testMixedBounds-seed#[DC94820ADC48E4C3]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMixedBounds
[junit4] 2> 2941757 INFO (TEST-IndexSizeTriggerTest.testMergeIntegration-seed#[DC94820ADC48E4C3]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMergeIntegration
[junit4] 2> 2941792 DEBUG (simCloudManagerPool-9150-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testMergeIntegration_collection / shard1: {"core_node1":{
[junit4] 2> "core":"testMergeIntegration_collection_shard1_replica_n1",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":1,
[junit4] 2> "node_name":"127.0.0.1:10016_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "SEARCHER.searcher.numDocs":0,
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testMergeIntegration_collection"}}
[junit4] 2> 2941839 DEBUG (simCloudManagerPool-9150-thread-19) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2941839 DEBUG (simCloudManagerPool-9150-thread-19) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 2941840 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 2941840 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 2941840 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2941840 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 2941842 DEBUG (simCloudManagerPool-9150-thread-20) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2941842 DEBUG (simCloudManagerPool-9150-thread-20) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3
[junit4] 2> 2941843 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 2941843 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 2941843 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2941843 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 2941844 DEBUG (simCloudManagerPool-9150-thread-21) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2941844 DEBUG (simCloudManagerPool-9150-thread-21) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 4
[junit4] 2> 2941844 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 4
[junit4] 2> 2941845 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 2941845 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2941845 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVersion 4
[junit4] 2> 2941848 DEBUG (simCloudManagerPool-9150-thread-28) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2941848 DEBUG (simCloudManagerPool-9150-thread-28) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 5
[junit4] 2> 2941848 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 5
[junit4] 2> 2941848 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 2941848 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2941848 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5, lastZnodeVersion 5
[junit4] 2> 2942088 DEBUG (ScheduledTrigger-9142-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 2943091 DEBUG (ScheduledTrigger-9142-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 2943730 INFO (TEST-IndexSizeTriggerTest.testMergeIntegration-seed#[DC94820ADC48E4C3]) [ ] o.a.s.c.a.IndexSizeTriggerTest #######################################
[junit4] 2> ############ CLUSTER STATE ############
[junit4] 2> #######################################
[junit4] 2> ## Live nodes: 2
[junit4] 2> ## Empty nodes: 0
[junit4] 2> ## Dead nodes: 0
[junit4] 2> ## Collections: [testMergeIntegration_collection]
[junit4] 2> DocCollection(testMergeIntegration_collection//clusterstate.json/20)={
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"2",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "autoCreated":"true",
[junit4] 2> "shards":{
[junit4] 2> "shard2":{
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testMergeIntegration_collection_shard2_replica_n3",
[junit4] 2> "SEARCHER.searcher.maxDoc":3,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":1,
[junit4] 2> "node_name":"127.0.0.1:10016_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "SEARCHER.searcher.numDocs":3},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testMergeIntegration_collection_shard2_replica_n4",
[junit4] 2> "SEARCHER.searcher.maxDoc":3,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":1,
[junit4] 2> "node_name":"127.0.0.1:10017_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "SEARCHER.searcher.numDocs":3}},
[junit4] 2> "range":"0-7fffffff",
[junit4] 2> "state":"active"},
[junit4] 2> "shard1":{
[junit4] 2> "replicas":{
[junit4] 2> "core_node1":{
[junit4] 2> "core":"testMergeIntegration_collection_shard1_replica_n1",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":7,
[junit4] 2> "SEARCHER.searcher.deletedDocs":5,
[junit4] 2> "INDEX.sizeInBytes":1,
[junit4] 2> "node_name":"127.0.0.1:10016_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "SEARCHER.searcher.numDocs":2},
[junit4] 2> "core_node2":{
[junit4] 2> "core":"testMergeIntegration_collection_shard1_replica_n2",
[junit4] 2> "SEARCHER.searcher.maxDoc":7,
[junit4] 2> "SEARCHER.searcher.deletedDocs":5,
[junit4] 2> "INDEX.sizeInBytes":1,
[junit4] 2> "node_name":"127.0.0.1:10017_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "SEARCHER.searcher.numDocs":2}},
[junit4] 2> "range":"80000000-ffffffff",
[junit4] 2> "state":"active"}}}
[junit4] 2> ## Max replicas per node: 2
[junit4] 2> ## Min replicas per node: 2
[junit4] 2> ## Total replicas: 4
[junit4] 2> ## * testMergeIntegration_collection 4
[junit4] 2> ## - active 4
[junit4] 2> ######### Solr op counts ##########
[junit4] 2> ## - CREATE 1
[junit4] 2> ## - autoscaling 4
[junit4] 2> ## - update 17
[junit4] 2> ######### Autoscaling event counts ###########
[junit4] 2>
[junit4] 2> 2943730 DEBUG (TEST-IndexSizeTriggerTest.testMergeIntegration-seed#[DC94820ADC48E4C3]) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 6
[junit4] 2> 2943730 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 6
[junit4] 2> 2943731 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 2943731 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2943731 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6, lastZnodeVersion 6
[junit4] 2> 2943841 INFO (TEST-IndexSizeTriggerTest.testMergeIntegration-seed#[DC94820ADC48E4C3]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMergeIntegration
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=IndexSizeTriggerTest -Dtests.method=testMergeIntegration -Dtests.seed=DC94820ADC48E4C3 -Dtests.slow=true -Dtests.locale=fi -Dtests.timezone=America/Los_Angeles -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 2.11s J1 | IndexSizeTriggerTest.testMergeIntegration <<<
[junit4] > Throwable #1: java.lang.AssertionError: did not finish processing in time
[junit4] > at __randomizedtesting.SeedInfo.seed([DC94820ADC48E4C3:8F2DC0BA3E597139]:0)
[junit4] > at org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testMergeIntegration(IndexSizeTriggerTest.java:425)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 2943868 INFO (TEST-IndexSizeTriggerTest.testTrigger-seed#[DC94820ADC48E4C3]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testTrigger
[junit4] 2> 2943926 DEBUG (simCloudManagerPool-9150-thread-34) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testTrigger_collection / shard2: {"core_node3":{
[junit4] 2> "core":"testTrigger_collection_shard2_replica_n3",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":1,
[junit4] 2> "node_name":"127.0.0.1:10016_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "SEARCHER.searcher.numDocs":0,
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testTrigger_collection"}}
[junit4] 2> 2943927 DEBUG (simCloudManagerPool-9150-thread-35) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testTrigger_collection / shard1: {"core_node1":{
[junit4] 2> "core":"testTrigger_collection_shard1_replica_n1",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":1,
[junit4] 2> "node_name":"127.0.0.1:10016_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "SEARCHER.searcher.numDocs":0,
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testTrigger_collection"}}
[junit4] 2> 2944100 DEBUG (ScheduledTrigger-9142-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 2944125 INFO (TEST-IndexSizeTriggerTest.testTrigger-seed#[DC94820ADC48E4C3]) [ ] o.a.s.c.a.IndexSizeTriggerTest #######################################
[junit4] 2> ############ CLUSTER STATE ############
[junit4] 2> #######################################
[junit4] 2> ## Live nodes: 2
[junit4] 2> ## Empty nodes: 0
[junit4] 2> ## Dead nodes: 0
[junit4] 2> ## Collections: [testTrigger_collection]
[junit4] 2> DocCollection(testTrigger_collection//clusterstate.json/49)={
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"2",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "autoCreated":"true",
[junit4] 2> "shards":{
[junit4] 2> "shard2":{
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testTrigger_collection_shard2_replica_n3",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":11,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":1,
[junit4] 2> "node_name":"127.0.0.1:10016_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "SEARCHER.searcher.numDocs":11},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testTrigger_collection_shard2_replica_n4",
[junit4] 2> "SEARCHER.searcher.maxDoc":11,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":1,
[junit4] 2> "node_name":"127.0.0.1:10017_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "SEARCHER.searcher.numDocs":11}},
[junit4] 2> "range":"0-7fffffff",
[junit4] 2> "state":"active"},
[junit4] 2> "shard1":{
[junit4] 2> "replicas":{
[junit4] 2> "core_node1":{
[junit4] 2> "core":"testTrigger_collection_shard1_replica_n1",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":14,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":1,
[junit4] 2> "node_name":"127.0.0.1:10016_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "SEARCHER.searcher.numDocs":14},
[junit4] 2> "core_node2":{
[junit4] 2> "core":"testTrigger_collection_shard1_replica_n2",
[junit4] 2> "SEARCHER.searcher.maxDoc":14,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":1,
[junit4] 2> "node_name":"127.0.0.1:10017_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "SEARCHER.searcher.numDocs":14}},
[junit4] 2> "range":"80000000-ffffffff",
[junit4] 2> "state":"active"}}}
[junit4] 2> ## Max replicas per node: 2
[junit4] 2> ## Min replicas per node: 2
[junit4] 2> ## Total replicas: 4
[junit4] 2> ## * testTrigger_collection 4
[junit4] 2> ## - active 4
[junit4] 2> ######### Solr op counts ##########
[junit4] 2> ## - CREATE 1
[junit4] 2> ## - update 26
[junit4] 2> ######### Autoscaling event counts ###########
[junit4] 2>
[junit4] 2> 2944125 DEBUG (TEST-IndexSizeTriggerTest.testTrigger-seed#[DC94820ADC48E4C3]) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 7
[junit4] 2> 2944125 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 7
[junit4] 2> 2944125 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 2944125 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2944125 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 7, lastZnodeVersion 7
[junit4] 2> 2944235 INFO (TEST-IndexSizeTriggerTest.testTrigger-seed#[DC94820ADC48E4C3]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testTrigger
[junit4] 2> 2944270 INFO (TEST-IndexSizeTriggerTest.testSplitIntegration-seed#[DC94820ADC48E4C3]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSplitIntegration
[junit4] 2> 2944314 DEBUG (simCloudManagerPool-9150-thread-68) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testSplitIntegration_collection / shard1: {"core_node1":{
[junit4] 2> "core":"testSplitIntegration_collection_shard1_replica_n1",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":1,
[junit4] 2> "node_name":"127.0.0.1:10017_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "SEARCHER.searcher.numDocs":0,
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testSplitIntegration_collection"}}
[junit4] 2> 2944345 DEBUG (simCloudManagerPool-9150-thread-69) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2944345 DEBUG (simCloudManagerPool-9150-thread-69) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 8
[junit4] 2> 2944345 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 8
[junit4] 2> 2944345 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 2944345 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2944345 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 8, lastZnodeVersion 8
[junit4] 2> 2944346 DEBUG (simCloudManagerPool-9150-thread-70) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2944346 DEBUG (simCloudManagerPool-9150-thread-70) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 9
[junit4] 2> 2944346 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 9
[junit4] 2> 2944346 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 2944346 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2944346 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 9, lastZnodeVersion 9
[junit4] 2> 2944346 DEBUG (simCloudManagerPool-9150-thread-71) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2944346 DEBUG (simCloudManagerPool-9150-thread-71) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 10
[junit4] 2> 2944347 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 10
[junit4] 2> 2944347 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 2944347 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2944347 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 10, lastZnodeVersion 10
[junit4] 2> 2944371 DEBUG (simCloudManagerPool-9150-thread-123) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2944372 DEBUG (simCloudManagerPool-9150-thread-123) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 11
[junit4] 2> 2944372 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 11
[junit4] 2> 2944372 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 2944372 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2944372 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 11, lastZnodeVersion 11
[junit4] 2> 2944536 DEBUG (ScheduledTrigger-9151-thread-4) [ ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
[junit4] 2> "id":"1a4075e9025f4Te9hqzkyrnadu7q4rt37y8bian",
[junit4] 2> "source":"index_size_trigger2",
[junit4] 2> "eventTime":461826534942196,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "aboveSize":{"testSplitIntegration_collection":["{\"core_node1\":{\n \"core\":\"testSplitIntegration_collection_shard1_replica_n1\",\n \"leader\":\"true\",\n \"SEARCHER.searcher.maxDoc\":25,\n \"SEARCHER.searcher.deletedDocs\":0,\n \"INDEX.sizeInBytes\":1,\n \"__docs__\":25,\n \"violationType\":\"aboveDocs\",\n \"node_name\":\"127.0.0.1:10017_solr\",\n \"state\":\"active\",\n \"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":25,\n \"__bytes__\":1,\n \"shard\":\"shard1\",\n \"collection\":\"testSplitIntegration_collection\"}}"]},
[junit4] 2> "belowSize":{},
[junit4] 2> "requestedOps":["Op{action=SPLITSHARD, hints={COLL_SHARD=[{\n \"first\":\"testSplitIntegration_collection\",\n \"second\":\"shard1\"}]}}"]}}
[junit4] 2> 2944537 DEBUG (ScheduledTrigger-9151-thread-4) [ ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: [index_size_trigger2]
[junit4] 2> 2944538 INFO (ScheduledTrigger-9151-thread-4) [ ] o.a.s.c.a.IndexSizeTriggerTest =======> CapturedEvent{timestamp=461834816757146, stage=STARTED, actionName='null', event={
[junit4] 2> "id":"1a4075e9025f4Te9hqzkyrnadu7q4rt37y8bian",
[junit4] 2> "source":"index_size_trigger2",
[junit4] 2> "eventTime":461826534942196,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "aboveSize":{"testSplitIntegration_collection":["{\"core_node1\":{\n \"core\":\"testSplitIntegration_collection_shard1_replica_n1\",\n \"leader\":\"true\",\n \"SEARCHER.searcher.maxDoc\":25,\n \"SEARCHER.searcher.deletedDocs\":0,\n \"INDEX.sizeInBytes\":1,\n \"__docs__\":25,\n \"violationType\":\"aboveDocs\",\n \"node_name\":\"127.0.0.1:10017_solr\",\n \"state\":\"active\",\n \"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":25,\n \"__bytes__\":1,\n \"shard\":\"shard1\",\n \"collection\":\"testSplitIntegration_collection\"}}"]},
[junit4] 2> "belowSize":{},
[junit4] 2> "_enqueue_time_":461834785142346,
[junit4] 2> "requestedOps":["Op{action=SPLITSHARD, hints={COLL_SHARD=[{\n \"first\":\"testSplitIntegration_collection\",\n \"second\":\"shard1\"}]}}"]}}, context={}, config={
[junit4] 2> "trigger":"index_size_trigger2",
[junit4] 2> "stage":[
[junit4] 2> "STARTED",
[junit4] 2> "ABORTED",
[junit4] 2> "SUCCEEDED",
[junit4] 2> "FAILED"],
[junit4] 2> "afterAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2> "class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener",
[junit4] 2> "beforeAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"]}, message='null'}
[junit4] 2> 2944539 DEBUG (AutoscalingActionExecutor-9152-thread-1) [ ] o.a.s.c.a.ScheduledTriggers -- processing actions for {
[junit4] 2> "id":"1a4075e9025f4Te9hqzkyrnadu7q4rt37y8bian",
[junit4] 2> "source":"index_size_trigger2",
[junit4] 2> "eventTime":461826534942196,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "aboveSize":{"testSplitIntegration_collection":["{\"core_node1\":{\n \"core\":\"testSplitIntegration_collection_shard1_replica_n1\",\n \"leader\":\"true\",\n \"SEARCHER.searcher.maxDoc\":25,\n \"SEARCHER.searcher.deletedDocs\":0,\n \"INDEX.sizeInBytes\":1,\n \"__docs__\":25,\n \"violationType\":\"aboveDocs\",\n \"node_name\":\"127.0.0.1:10017_solr\",\n \"state\":\"active\",\n \"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":25,\n \"__bytes__\":1,\n \"shard\":\"shard1\",\n \"collection\":\"testSplitIntegration_collection\"}}"]},
[junit4] 2> "belowSize":{},
[junit4] 2> "_enqueue_time_":461834785142346,
[junit4] 2> "requestedOps":["Op{action=SPLITSHARD, hints={COLL_SHARD=[{\n \"first\":\"testSplitIntegration_collection\",\n \"second\":\"shard1\"}]}}"]}}
[junit4] 2> 2944540 INFO (AutoscalingActionExecutor-9152-thread-1) [ ] o.a.s.c.a.IndexSizeTriggerTest =======> CapturedEvent{timestamp=461834907374146, stage=BEFORE_ACTION, actionName='compute_plan', event={
[junit4] 2> "id":"1a4075e9025f4Te9hqzkyrnadu7q4rt37y8bian",
[junit4] 2> "source":"index_size_trigger2",
[junit4] 2> "eventTime":461826534942196,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "aboveSize":{"testSplitIntegration_collection":["{\"core_node1\":{\n \"core\":\"testSplitIntegration_collection_shard1_replica_n1\",\n \"leader\":\"true\",\n \"SEARCHER.searcher.maxDoc\":25,\n \"SEARCHER.searcher.deletedDocs\":0,\n \"INDEX.sizeInBytes\":1,\n \"__docs__\":25,\n \"violationType\":\"aboveDocs\",\n \"node_name\":\"127.0.0.1:10017_solr\",\n \"state\":\"active\",\n \"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":25,\n \"__bytes__\":1,\n \"shard\":\"shard1\",\n \"collection\":\"testSplitIntegration_collection\"}}"]},
[junit4] 2> "belowSize":{},
[junit4] 2> "_enqueue_time_":461834785142346,
[junit4] 2> "requestedOps":["Op{action=SPLITSHARD, hints={COLL_SHARD=[{\n \"first\":\"testSplitIntegration_collection\",\n \"second\":\"shard1\"}]}}"]}}, context={properties.BEFORE_ACTION=[compute_plan], source=index_size_trigger2}, config={
[junit4] 2> "trigger":"index_size_trigger2",
[junit4] 2> "stage":[
[junit4] 2> "STARTED",
[junit4] 2> "ABORTED",
[junit4] 2> "SUCCEEDED",
[junit4] 2> "FAILED"],
[junit4] 2> "afterAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2> "class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener",
[junit4] 2> "beforeAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"]}, message='null'}
[junit4] 2> 2944540 DEBUG (AutoscalingActionExecutor-9152-thread-1) [ ] o.a.s.c.a.ComputePlanAction -- processing event: {
[junit4] 2> "id":"1a4075e9025f4Te9hqzkyrnadu7q4rt37y8bian",
[junit4] 2> "source":"index_size_trigger2",
[junit4] 2> "eventTime":461826534942196,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "aboveSize":{"testSplitIntegration_collection":["{\"core_node1\":{\n \"core\":\"testSplitIntegration_collection_shard1_replica_n1\",\n \"leader\":\"true\",\n \"SEARCHER.searcher.maxDoc\":25,\n \"SEARCHER.searcher.deletedDocs\":0,\n \"INDEX.sizeInBytes\":1,\n \"__docs__\":25,\n \"violationType\":\"aboveDocs\",\n \"node_name\":\"127.0.0.1:10017_solr\",\n \"state\":\"active\",\n \"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":25,\n \"__bytes__\":1,\n \"shard\":\"shard1\",\n \"collection\":\"testSplitIntegration_collection\"}}"]},
[junit4] 2> "belowSize":{},
[junit4] 2> "_enqueue_time_":461834785142346,
[junit4] 2> "requestedOps":["Op{action=SPLITSHARD, hints={COLL_SHARD=[{\n \"first\":\"testSplitIntegration_collection\",\n \"second\":\"shard1\"}]}}"]}} with context properties: {BEFORE_ACTION=[compute_plan]}
[junit4] 2> 2944540 INFO (AutoscalingActionExecutor-9152-thread-1) [ ] o.a.s.c.a.ComputePlanAction Computed Plan: action=SPLITSHARD&collection=testSplitIntegration_collection&shard=shard1
[junit4] 2> 2944540 INFO (AutoscalingActionExecutor-9152-thread-1) [ ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 461834946 sessionWrapper.createTime 461834929456896, this.sessionWrapper.createTime 461834929456896
[junit4] 2> 2944540 INFO (AutoscalingActionExecutor-9152-thread-1) [ ] o.a.s.c.a.IndexSizeTriggerTest =======> CapturedEvent{timestamp=461834948109096, stage=AFTER_ACTION, actionName='compute_plan', event={
[junit4] 2> "id":"1a4075e9025f4Te9hqzkyrnadu7q4rt37y8bian",
[junit4] 2> "source":"index_size_trigger2",
[junit4] 2> "eventTime":461826534942196,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "__start__":1,
[junit4] 2> "aboveSize":{"testSplitIntegration_collection":["{\"core_node1\":{\n \"core\":\"testSplitIntegration_collection_shard1_replica_n1\",\n \"leader\":\"true\",\n \"SEARCHER.searcher.maxDoc\":25,\n \"SEARCHER.searcher.deletedDocs\":0,\n \"INDEX.sizeInBytes\":1,\n \"__docs__\":25,\n \"violationType\":\"aboveDocs\",\n \"node_name\":\"127.0.0.1:10017_solr\",\n \"state\":\"active\",\n \"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":25,\n \"__bytes__\":1,\n \"shard\":\"shard1\",\n \"collection\":\"testSplitIntegration_collection\"}}"]},
[junit4] 2> "belowSize":{},
[junit4] 2> "_enqueue_time_":461834785142346,
[junit4] 2> "requestedOps":["Op{action=SPLITSHARD, hints={COLL_SHARD=[{\n \"first\":\"testSplitIntegration_collection\",\n \"second\":\"shard1\"}]}}"]}}, context={properties.operations=[{class=org.apache.solr.client.solrj.request.CollectionAdminRequest$SplitShard, method=GET, params.action=SPLITSHARD, params.collection=testSplitIntegration_collection, params.shard=shard1}], properties.BEFORE_ACTION=[compute_plan], source=index_size_trigger2, properties.AFTER_ACTION=[compute_plan]}, config={
[junit4] 2> "trigger":"index_size_trigger2",
[junit4] 2> "stage":[
[junit4] 2> "STARTED",
[junit4] 2> "ABORTED",
[junit4] 2> "SUCCEEDED",
[junit4] 2> "FAILED"],
[junit4] 2> "afterAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2> "class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener",
[junit4] 2> "beforeAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"]}, message='null'}
[junit4] 2> 2944541 INFO (AutoscalingActionExecutor-9152-thread-1) [ ] o.a.s.c.a.IndexSizeTriggerTest =======> CapturedEvent{timestamp=461834960863996, stage=BEFORE_ACTION, actionName='execute_plan', event={
[junit4] 2> "id":"1a4075e9025f4Te9hqzkyrnadu7q4rt37y8bian",
[junit4] 2> "source":"index_size_trigger2",
[junit4] 2> "eventTime":461826534942196,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "__start__":1,
[junit4] 2> "aboveSize":{"testSplitIntegration_collection":["{\"core_node1\":{\n \"core\":\"testSplitIntegration_collection_shard1_replica_n1\",\n \"leader\":\"true\",\n \"SEARCHER.searcher.maxDoc\":25,\n \"SEARCHER.searcher.deletedDocs\":0,\n \"INDEX.sizeInBytes\":1,\n \"__docs__\":25,\n \"violationType\":\"aboveDocs\",\n \"node_name\":\"127.0.0.1:10017_solr\",\n \"state\":\"active\",\n \"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":25,\n \"__bytes__\":1,\n \"shard\":\"shard1\",\n \"collection\":\"testSplitIntegration_collection\"}}"]},
[junit4] 2> "belowSize":{},
[junit4] 2> "_enqueue_time_":461834785142346,
[junit4] 2> "requestedOps":["Op{action=SPLITSHARD, hints={COLL_SHARD=[{\n \"first\":\"testSplitIntegration_collection\",\n \"second\":\"shard1\"}]}}"]}}, context={properties.operations=[{class=org.apache.solr.client.solrj.request.CollectionAdminRequest$SplitShard, method=GET, params.action=SPLITSHARD, params.collection=testSplitIntegration_collection, params.shard=shard1}], properties.BEFORE_ACTION=[compute_plan, execute_plan], source=index_size_trigger2, properties.AFTER_ACTION=[compute_plan]}, config={
[junit4] 2> "trigger":"index_size_trigger2",
[junit4] 2> "stage":[
[junit4] 2> "STARTED",
[junit4] 2> "ABORTED",
[junit4] 2> "SUCCEEDED",
[junit4] 2> "FAILED"],
[junit4] 2> "afterAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2> "class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener",
[junit4] 2> "beforeAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"]}, message='null'}
[junit4] 2> 2944541 DEBUG (AutoscalingActionExecutor-9152-thread-1) [ ] o.a.s.c.a.ExecutePlanAction -- processing event: {
[junit4] 2> "id":"1a4075e9025f4Te9hqzkyrnadu7q4rt37y8bian",
[junit4] 2> "source":"index_size_trigger2",
[junit4] 2> "eventTime":461826534942196,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "__start__":1,
[junit4] 2> "aboveSize":{"testSplitIntegration_collection":["{\"core_node1\":{\n \"core\":\"testSplitIntegration_collection_shard1_replica_n1\",\n \"leader\":\"true\",\n \"SEARCHER.searcher.maxDoc\":25,\n \"SEARCHER.searcher.deletedDocs\":0,\n \"INDEX.sizeInBytes\":1,\n \"__docs__\":25,\n \"violationType\":\"aboveDocs\",\n \"node_name\":\"127.0.0.1:10017_solr\",\n \"state\":\"active\",\n \"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":25,\n \"__bytes__\":1,\n \"shard\":\"shard1\",\n \"collection\":\"testSplitIntegration_collection\"}}"]},
[junit4] 2> "belowSize":{},
[junit4] 2> "_enqueue_time_":461834785142346,
[junit4] 2> "requestedOps":["Op{action=SPLITSHARD, hints={COLL_SHARD=[{\n \"first\":\"testSplitIntegration_collection\",\n \"second\":\"shard1\"}]}}"]}} with context properties: {operations=[org.apache.solr.client.solrj.request.CollectionAdminRequest$SplitShard@1b71b918], AFTER_ACTION=[compute_plan], BEFORE_ACTION=[compute_plan, execute_plan]}
[junit4] 2> 2944541 DEBUG (AutoscalingActionExecutor-9152-thread-1) [ ] o.a.s.c.a.ExecutePlanAction Executing operation: action=SPLITSHARD&collection=testSplitIntegration_collection&shard=shard1
[junit4] 2> 2944698 DEBUG (simCloudManagerPool-9150-thread-126) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testSplitIntegration_collection / shard2: {"core_node3":{
[junit4] 2> "core":"testSplitIntegration_collection_shard2_replica_n3",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":25,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":1,
[junit4] 2> "node_name":"127.0.0.1:10017_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "SEARCHER.searcher.numDocs":25,
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testSplitIntegration_collection"}}
[junit4] 2> 2944700 DEBUG (simCloudManagerPool-9150-thread-128) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testSplitIntegration_collection / shard1_1: {"core_node10":{
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":12,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":1,
[junit4] 2> "node_name":"127.0.0.1:10016_solr",
[junit4] 2> "base_url":"http://127.0.0.1:10016/solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "SEARCHER.searcher.numDocs":12,
[junit4] 2> "core":"testSplitIntegration_collection_shard1_1_replica1",
[junit4] 2> "shard":"shard1_1",
[junit4] 2> "collection":"testSplitIntegration_collection"}}
[junit4] 2> 2944700 DEBUG (simCloudManagerPool-9150-thread-129) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testSplitIntegration_collection / shard1_0: {"core_node7":{
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":13,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":1,
[junit4] 2> "node_name":"127.0.0.1:10017_solr",
[junit4] 2> "base_url":"http://127.0.0.1:10017/solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "SEARCHER.searcher.numDocs":13,
[junit4] 2> "core":"testSplitIntegration_collection_shard1_0_replica0",
[junit4] 2> "shard":"shard1_0",
[junit4] 2> "collection":"testSplitIntegration_collection"}}
[junit4] 2> 2944701 INFO (AutoscalingActionExecutor-9152-thread-1) [ ] o.a.s.c.a.IndexSizeTriggerTest =======> CapturedEvent{timestamp=461842968439646, stage=AFTER_ACTION, actionName='execute_plan', event={
[junit4] 2> "id":"1a4075e9025f4Te9hqzkyrnadu7q4rt37y8bian",
[junit4] 2> "source":"index_size_trigger2",
[junit4] 2> "eventTime":461826534942196,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "__start__":1,
[junit4] 2> "aboveSize":{"testSplitIntegration_collection":["{\"core_node1\":{\n \"core\":\"testSplitIntegration_collection_shard1_replica_n1\",\n \"leader\":\"true\",\n \"SEARCHER.searcher.maxDoc\":25,\n \"SEARCHER.searcher.deletedDocs\":0,\n \"INDEX.sizeInBytes\":1,\n \"__docs__\":25,\n \"violationType\":\"aboveDocs\",\n \"node_name\":\"127.0.0.1:10017_solr\",\n \"state\":\"active\",\n \"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":25,\n \"__bytes__\":1,\n \"shard\":\"shard1\",\n \"collection\":\"testSplitIntegration_collection\"}}"]},
[junit4] 2> "belowSize":{},
[junit4] 2> "_enqueue_time_":461834785142346,
[junit4] 2> "requestedOps":["Op{action=SPLITSHARD, hints={COLL_SHARD=[{\n \"first\":\"testSplitIntegration_collection\",\n \"second\":\"shard1\"}]}}"]}}, context={properties.operations=[{class=org.apache.solr.client.solrj.request.CollectionAdminRequest$SplitShard, method=GET, params.action=SPLITSHARD, params.async=index_size_trigger2/1a4075e9025f4Te9hqzkyrnadu7q4rt37y8bian/0, params.waitForFinalState=true, params.collection=testSplitIntegration_collection, params.shard=shard1}], properties.BEFORE_ACTION=[compute_plan, execute_plan], properties.responses=[{status={state=completed,msg=found [index_size_trigger2/1a4075e9025f4Te9hqzkyrnadu7q4rt37y8bian/0] in completed tasks},success=}], source=index_size_trigger2, properties.AFTER_ACTION=[compute_plan, execute_plan]}, config={
[junit4] 2> "trigger":"index_size_trigger2",
[junit4] 2> "stage":[
[junit4] 2> "STARTED",
[junit4] 2> "ABORTED",
[junit4] 2> "SUCCEEDED",
[junit4] 2> "FAILED"],
[junit4] 2> "afterAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2> "class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener",
[junit4] 2> "beforeAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"]}, message='null'}
[junit4] 2> 2944701 INFO (AutoscalingActionExecutor-9152-thread-1) [ ] o.a.s.c.a.IndexSizeTriggerTest =======> CapturedEvent{timestamp=461842984381296, stage=SUCCEEDED, actionName='null', event={
[junit4] 2> "id":"1a4075e9025f4Te9hqzkyrnadu7q4rt37y8bian",
[junit4] 2> "source":"index_size_trigger2",
[junit4] 2> "eventTime":461826534942196,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "__start__":1,
[junit4] 2> "aboveSize":{"testSplitIntegration_collection":["{\"core_node1\":{\n \"core\":\"testSplitIntegration_collection_shard1_replica_n1\",\n \"leader\":\"true\",\n \"SEARCHER.searcher.maxDoc\":25,\n \"SEARCHER.searcher.deletedDocs\":0,\n \"INDEX.sizeInBytes\":1,\n \"__docs__\":25,\n \"violationType\":\"aboveDocs\",\n \"node_name\":\"127.0.0.1:10017_solr\",\n \"state\":\"active\",\n \"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":25,\n \"__bytes__\":1,\n \"shard\":\"shard1\",\n \"collection\":\"testSplitIntegration_collection\"}}"]},
[junit4] 2> "belowSize":{},
[junit4] 2> "_enqueue_time_":461834785142346,
[junit4] 2> "requestedOps":["Op{action=SPLITSHARD, hints={COLL_SHARD=[{\n \"first\":\"testSplitIntegration_collection\",\n \"second\":\"shard1\"}]}}"]}}, context={}, config={
[junit4] 2> "trigger":"index_size_trigger2",
[junit4] 2> "stage":[
[junit4] 2> "STARTED",
[junit4] 2> "ABORTED",
[junit4] 2> "SUCCEEDED",
[junit4] 2> "FAILED"],
[junit4] 2> "afterAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2> "class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener",
[junit4] 2> "beforeAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"]}, message='null'}
[junit4] 2> 2944702 DEBUG (AutoscalingActionExecutor-9152-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Resuming trigger: index_size_trigger2 after 100ms
[junit4] 2> 2944702 DEBUG (AutoscalingActionExecutor-9152-thread-1) [ ] o.a.s.c.a.ScheduledTriggers -- processing took 8149 ms for event id=1a4075e9025f4Te9hqzkyrnadu7q4rt37y8bian
[junit4] 2> 2944969 DEBUG (ScheduledTrigger-9151-thread-2) [ ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
[junit4] 2> "id":"1a40c667ca972Te9hqzkyrnadu7q4rt37y8biaq",
[junit4] 2> "source":"index_size_trigger2",
[junit4] 2> "eventTime"
[...truncated too long message...]
ters.SolrJmxReporter@1bdaf142: rootName = solr_64745, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@4ff549a8
[junit4] 2> 29179 INFO (jetty-closer-43-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 29179 INFO (jetty-closer-43-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5561c369: rootName = solr_64744, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@e60a436
[junit4] 2> 29184 INFO (jetty-closer-43-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 29184 INFO (jetty-closer-43-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7209f414: rootName = solr_64744, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@4ff549a8
[junit4] 2> 29185 INFO (jetty-closer-43-thread-2) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:64745_solr
[junit4] 2> 29187 INFO (jetty-closer-43-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 29191 INFO (zkCallback-69-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 29191 INFO (zkCallback-60-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 29191 INFO (zkCallback-58-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 29192 INFO (jetty-closer-43-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:64744_solr
[junit4] 2> 29193 INFO (zkCallback-74-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 29200 INFO (jetty-closer-43-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 29200 INFO (jetty-closer-43-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 29200 INFO (jetty-closer-43-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72080792483725318-127.0.0.1:64744_solr-n_0000000000) closing
[junit4] 2> 29202 WARN (OverseerAutoScalingTriggerThread-72080792483725318-127.0.0.1:64744_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 29203 DEBUG (jetty-closer-43-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 29204 DEBUG (jetty-closer-43-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 29204 DEBUG (jetty-closer-43-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 29204 DEBUG (jetty-closer-43-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 29204 DEBUG (jetty-closer-43-thread-1) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 29204 DEBUG (jetty-closer-43-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 29205 INFO (OverseerStateUpdate-72080792483725318-127.0.0.1:64744_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:64744_solr
[junit4] 2> 29205 INFO (zkCallback-60-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 29207 INFO (jetty-closer-43-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7080ea5{/solr,null,UNAVAILABLE}
[junit4] 2> 29207 INFO (jetty-closer-43-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 29208 INFO (zkCallback-69-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 29211 INFO (jetty-closer-43-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4c35816c{/solr,null,UNAVAILABLE}
[junit4] 2> 29211 INFO (jetty-closer-43-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 29213 ERROR (SUITE-IndexSizeTriggerTest-seed#[DC94820ADC48E4C3]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 29213 INFO (SUITE-IndexSizeTriggerTest-seed#[DC94820ADC48E4C3]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:64742 64742
[junit4] 2> 29228 INFO (Thread-21) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:64742 64742
[junit4] 2> 29509 WARN (Thread-21) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/aliases.json
[junit4] 2> 5 /solr/clusterprops.json
[junit4] 2> 2 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/live_nodes
[junit4] 2> 5 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.IndexSizeTriggerTest_DC94820ADC48E4C3-002
[junit4] 2> May 24, 2018 3:50:34 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=1536, maxMBSortInHeap=7.63771572001866, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@60fe2da2), locale=fi, timezone=America/Los_Angeles
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_172 (64-bit)/cpus=3,threads=1,free=21204512,total=54853632
[junit4] 2> NOTE: All tests run in this JVM: [IndexSizeTriggerTest, IndexSizeTriggerTest]
[junit4] Completed [3/5 (3!)] on J1 in 4.95s, 4 tests, 1 failure <<< FAILURES!
[junit4]
[junit4] Suite: org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest
[junit4] OK 0.13s J0 | IndexSizeTriggerTest.testMixedBounds
[junit4] OK 0.31s J0 | IndexSizeTriggerTest.testMergeIntegration
[junit4] OK 0.50s J0 | IndexSizeTriggerTest.testTrigger
[junit4] OK 1.41s J0 | IndexSizeTriggerTest.testSplitIntegration
[junit4] Completed [4/5 (3!)] on J0 in 5.18s, 4 tests
[junit4]
[junit4] Suite: org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest
[junit4] OK 0.22s J1 | IndexSizeTriggerTest.testMixedBounds
[junit4] OK 0.48s J1 | IndexSizeTriggerTest.testMergeIntegration
[junit4] OK 0.50s J1 | IndexSizeTriggerTest.testTrigger
[junit4] OK 0.91s J1 | IndexSizeTriggerTest.testSplitIntegration
[junit4] Completed [5/5 (3!)] on J1 in 6.77s, 4 tests
[junit4]
[junit4]
[junit4] Tests with failures [seed: DC94820ADC48E4C3]:
[junit4] - org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testTrigger
[junit4] - org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testTrigger
[junit4] - org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testTrigger
[junit4]
[junit4]
[junit4] JVM J0: 0.96 .. 32.56 = 31.60s
[junit4] JVM J1: 0.96 .. 37.75 = 36.80s
[junit4] Execution time total: 37 seconds
[junit4] Tests summary: 5 suites, 20 tests, 3 failures
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/common-build.xml:1568: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/common-build.xml:1092: There were test failures: 5 suites, 20 tests, 3 failures [seed: DC94820ADC48E4C3]
Total time: 40 seconds
[repro] Setting last failure code to 256
[repro] Failures:
[repro] 3/5 failed: org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest
[repro] Exiting with code 256
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all warnings.
<Git Blamer> GIT_COMMIT=71ed5bafac92f3dd0e8ca4388f49f2c039a8db5b, workspace=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX
[WARNINGS] Computing warning deltas based on reference build #4654
Recording test results
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2