You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2019/09/27 16:22:34 UTC
[JENKINS] Lucene-Solr-master-MacOSX (64bit/jdk-13) - Build # 5365 -
Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/5365/
Java: 64bit/jdk-13 -XX:-UseCompressedOops -XX:+UseSerialGC
12 tests failed.
FAILED: org.apache.lucene.search.TestIndexSearcher.testRejectedExecution
Error Message:
Test abandoned because suite timeout was reached.
Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
at __randomizedtesting.SeedInfo.seed([669FB48211F6CD27]:0)
FAILED: junit.framework.TestSuite.org.apache.lucene.search.TestIndexSearcher
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([669FB48211F6CD27]:0)
FAILED: org.apache.lucene.search.TestIndexSearcher.testRejectedExecution
Error Message:
Test abandoned because suite timeout was reached.
Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
at __randomizedtesting.SeedInfo.seed([669FB48211F6CD27]:0)
FAILED: junit.framework.TestSuite.org.apache.lucene.search.TestIndexSearcher
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([669FB48211F6CD27]:0)
FAILED: org.apache.lucene.search.TestIndexSearcher.testRejectedExecution
Error Message:
Test abandoned because suite timeout was reached.
Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
at __randomizedtesting.SeedInfo.seed([669FB48211F6CD27]:0)
FAILED: junit.framework.TestSuite.org.apache.lucene.search.TestIndexSearcher
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([669FB48211F6CD27]:0)
FAILED: org.apache.lucene.search.TestIndexSearcher.testRejectedExecution
Error Message:
Test abandoned because suite timeout was reached.
Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
at __randomizedtesting.SeedInfo.seed([669FB48211F6CD27]:0)
FAILED: junit.framework.TestSuite.org.apache.lucene.search.TestIndexSearcher
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([669FB48211F6CD27]:0)
FAILED: org.apache.lucene.search.TestIndexSearcher.testRejectedExecution
Error Message:
Test abandoned because suite timeout was reached.
Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
at __randomizedtesting.SeedInfo.seed([669FB48211F6CD27]:0)
FAILED: junit.framework.TestSuite.org.apache.lucene.search.TestIndexSearcher
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([669FB48211F6CD27]:0)
FAILED: org.apache.solr.cloud.OverseerRolesTest.testOverseerRole
Error Message:
The old leader should have rejoined election
Stack Trace:
java.lang.AssertionError: The old leader should have rejoined election
at __randomizedtesting.SeedInfo.seed([D5906C10284C2193:345B918413FF1742]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.cloud.OverseerRolesTest.testOverseerRole(OverseerRolesTest.java:167)
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:567)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
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:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
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 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:830)
FAILED: org.apache.solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest.testCatTime
Error Message:
took over 10 seconds after collection creation to update aliases
Stack Trace:
java.lang.AssertionError: took over 10 seconds after collection creation to update aliases
at __randomizedtesting.SeedInfo.seed([D5906C10284C2193:D29B231317BF707B]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.apache.solr.update.processor.RoutedAliasUpdateProcessorTest.waitColAndAlias(RoutedAliasUpdateProcessorTest.java:77)
at org.apache.solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest.testCatTime(DimensionalRoutedAliasUpdateProcessorTest.java:480)
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:567)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
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:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
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 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:830)
Build Log:
[...truncated 1955 lines...]
[junit4] Suite: org.apache.lucene.search.TestIndexSearcher
[junit4] 2> sept. 27, 2019 12:03:58 P.?M. com.carrotsearch.randomizedtesting.ThreadLeakControl$2 evaluate
[junit4] 2> WARNING: Suite execution timed out: org.apache.lucene.search.TestIndexSearcher
[junit4] 2> 1) Thread[id=1, name=main, state=WAITING, group=main]
[junit4] 2> at java.base@13/java.lang.Object.wait(Native Method)
[junit4] 2> at java.base@13/java.lang.Thread.join(Thread.java:1303)
[junit4] 2> at java.base@13/java.lang.Thread.join(Thread.java:1371)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner.runSuite(RandomizedRunner.java:639)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner.run(RandomizedRunner.java:496)
[junit4] 2> at app//com.carrotsearch.ant.tasks.junit4.slave.SlaveMain.execute(SlaveMain.java:269)
[junit4] 2> at app//com.carrotsearch.ant.tasks.junit4.slave.SlaveMain.main(SlaveMain.java:394)
[junit4] 2> at app//com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe.main(SlaveMainSafe.java:13)
[junit4] 2> 2) Thread[id=10, name=JUnit4-serializer-daemon, state=TIMED_WAITING, group=main]
[junit4] 2> at java.base@13/java.lang.Thread.sleep(Native Method)
[junit4] 2> at app//com.carrotsearch.ant.tasks.junit4.events.Serializer$1.run(Serializer.java:50)
[junit4] 2> 3) Thread[id=472, name=SUITE-TestIndexSearcher-seed#[669FB48211F6CD27], state=RUNNABLE, group=TGRP-TestIndexSearcher]
[junit4] 2> at java.base/java.lang.Thread.getStackTrace(Thread.java:1598)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$4.run(ThreadLeakControl.java:696)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$4.run(ThreadLeakControl.java:693)
[junit4] 2> at java.base/java.security.AccessController.doPrivileged(AccessController.java:312)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.getStackTrace(ThreadLeakControl.java:693)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.getThreadsWithTraces(ThreadLeakControl.java:709)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.formatThreadStacksFull(ThreadLeakControl.java:689)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.access$1000(ThreadLeakControl.java:65)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$2.evaluate(ThreadLeakControl.java:415)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSuite(RandomizedRunner.java:708)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.access$200(RandomizedRunner.java:138)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$2.run(RandomizedRunner.java:629)
[junit4] 2> 4) Thread[id=473, name=TEST-TestIndexSearcher.testRejectedExecution-seed#[669FB48211F6CD27], state=WAITING, group=TGRP-TestIndexSearcher]
[junit4] 2> at java.base@13/jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> at java.base@13/java.util.concurrent.locks.LockSupport.park(LockSupport.java:194)
[junit4] 2> at java.base@13/java.util.concurrent.FutureTask.awaitDone(FutureTask.java:447)
[junit4] 2> at java.base@13/java.util.concurrent.FutureTask.get(FutureTask.java:190)
[junit4] 2> at app//org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:688)
[junit4] 2> at app//org.apache.lucene.search.IndexSearcher.searchAfter(IndexSearcher.java:492)
[junit4] 2> at app//org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:503)
[junit4] 2> at app//org.apache.lucene.search.TestIndexSearcher.testRejectedExecution(TestIndexSearcher.java:293)
[junit4] 2> at java.base@13/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at java.base@13/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at java.base@13/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.base@13/java.lang.reflect.Method.invoke(Method.java:567)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
[junit4] 2> at app//org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
[junit4] 2> at app//org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at app//org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4] 2> at app//org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at app//org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
[junit4] 2> at app//org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at app//org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at app//org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
[junit4] 2> at app//org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at app//org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at app//org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
[junit4] 2> at java.base@13/java.lang.Thread.run(Thread.java:830)
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestIndexSearcher -Dtests.method=testRejectedExecution -Dtests.seed=669FB48211F6CD27 -Dtests.slow=true -Dtests.locale=es-ES -Dtests.timezone=Asia/Muscat -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 7200s J1 | TestIndexSearcher.testRejectedExecution <<<
[junit4] > Throwable #1: java.lang.Exception: Test abandoned because suite timeout was reached.
[junit4] > at __randomizedtesting.SeedInfo.seed([669FB48211F6CD27]:0)
[junit4] 2> sept. 27, 2019 12:03:58 P.?M. com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> sept. 27, 2019 12:04:18 P.?M. com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> SEVERE: 1 thread leaked from SUITE scope at org.apache.lucene.search.TestIndexSearcher:
[junit4] 2> 1) Thread[id=473, name=TEST-TestIndexSearcher.testRejectedExecution-seed#[669FB48211F6CD27], state=WAITING, group=TGRP-TestIndexSearcher]
[junit4] 2> at java.base@13/jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> at java.base@13/java.util.concurrent.locks.LockSupport.park(LockSupport.java:194)
[junit4] 2> at java.base@13/java.util.concurrent.FutureTask.awaitDone(FutureTask.java:447)
[junit4] 2> at java.base@13/java.util.concurrent.FutureTask.get(FutureTask.java:190)
[junit4] 2> at app//org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:688)
[junit4] 2> at app//org.apache.lucene.search.IndexSearcher.searchAfter(IndexSearcher.java:492)
[junit4] 2> at app//org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:503)
[junit4] 2> at app//org.apache.lucene.search.TestIndexSearcher.testRejectedExecution(TestIndexSearcher.java:293)
[junit4] 2> at java.base@13/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at java.base@13/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at java.base@13/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.base@13/java.lang.reflect.Method.invoke(Method.java:567)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
[junit4] 2> at app//org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
[junit4] 2> at app//org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at app//org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4] 2> at app//org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at app//org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
[junit4] 2> at app//org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at app//org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at app//org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
[junit4] 2> at app//org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at app//org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at app//org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
[junit4] 2> at java.base@13/java.lang.Thread.run(Thread.java:830)
[junit4] 2> sept. 27, 2019 12:04:18 P.?M. com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=473, name=TEST-TestIndexSearcher.testRejectedExecution-seed#[669FB48211F6CD27], state=WAITING, group=TGRP-TestIndexSearcher]
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/build/core/test/J1/temp/lucene.search.TestIndexSearcher_669FB48211F6CD27-001
[junit4] 2> Sep 27, 2019 8:04:18 AM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: All leaked threads terminated.
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene80): {field=Lucene50(blocksize=128), field2=Lucene50(blocksize=128)}, docValues:{field2=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=1361, maxMBSortInHeap=7.1505255055043495, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@38c5a189), locale=es-ES, timezone=Asia/Muscat
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 13 (64-bit)/cpus=3,threads=1,free=60371312,total=236998656
[junit4] 2> NOTE: All tests run in this JVM: [TestMultiCollector, TestLMJelinekMercerSimilarity, TestPhrasePrefixQuery, TestFilterCodecReader, TestPerSegmentDeletes, TestSegmentReader, TestFloatRange, TestFilterIterator, TestPolygon, TestLazyProxSkipping, TestFstDirect, TestTimSorterWorstCase, TestSparseFixedBitSet, TestSmallFloat, TestPayloads, TestSimpleFSLockFactory, TestTransactionRollback, TestPackedInts, TestAxiomaticSimilarity, TestFileSwitchDirectory, TestTotalHitCountCollector, TestRangeFieldsDocValuesQuery, TestBagOfPostings, TestAllFilesHaveChecksumFooter, TestStringMSBRadixSorter, TestSpanExplanations, TestCharacterUtils, TestPointValues, TestIndexWriterNRTIsCurrent, TestDirectoryReaderReopen, TestLucene60PointsFormat, TestNeverDelete, TestBinaryTerms, TestSimilarityBase, TestMultiTermConstantScore, TestSpanCollection, TestSimpleExplanationsWithFillerDocs, Test2BPositions, TestDocumentWriter, TestOneMergeWrappingMergePolicy, TestBufferedIndexInput, TestFrozenBufferedUpdates, TestParallelCompositeReader, TestBooleanRewrites, TestLongRangeFieldQueries, TestPositiveScoresOnlyCollector, TestSloppyPhraseQuery, TestBasicModelIn, TestNearSpansOrdered, TestUsageTrackingFilterCachingPolicy, TestLongBitSet, TestOfflineSorter, TestMergeRateLimiter, TestCompiledAutomaton, TestLockFactory, TestMatchAllDocsQuery, TestIndexWriterCommit, TestIndexSearcher]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestIndexSearcher -Dtests.seed=669FB48211F6CD27 -Dtests.slow=true -Dtests.locale=es-ES -Dtests.timezone=Asia/Muscat -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J1 | TestIndexSearcher (suite) <<<
[junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
[junit4] > at __randomizedtesting.SeedInfo.seed([669FB48211F6CD27]:0)
[junit4] Completed [452/524 (1!)] on J1 in 7220.63s, 4 tests, 2 errors <<< FAILURES!
[...truncated 12327 lines...]
[junit4] Suite: org.apache.solr.cloud.OverseerRolesTest
[junit4] 2> 2148566 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] 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.OverseerRolesTest_D5906C10284C2193-001/tempDir-001
[junit4] 2> 2148567 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2148567 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2148567 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2148676 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.c.ZkTestServer start zk server on port:64496
[junit4] 2> 2148676 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:64496
[junit4] 2> 2148676 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:64496
[junit4] 2> 2148676 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 64496
[junit4] 2> 2148678 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2148689 INFO (zkConnectionManagerCallback-12004-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2148689 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2148692 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2148693 INFO (zkConnectionManagerCallback-12006-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2148693 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2148698 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2148700 INFO (zkConnectionManagerCallback-12008-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2148700 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2148815 WARN (jetty-launcher-12009-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2148815 WARN (jetty-launcher-12009-thread-3) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2148815 WARN (jetty-launcher-12009-thread-4) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2148815 INFO (jetty-launcher-12009-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 2148815 INFO (jetty-launcher-12009-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 2148815 INFO (jetty-launcher-12009-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2148815 INFO (jetty-launcher-12009-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2148815 INFO (jetty-launcher-12009-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 2148815 INFO (jetty-launcher-12009-thread-1) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 13+33
[junit4] 2> 2148815 INFO (jetty-launcher-12009-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2148815 INFO (jetty-launcher-12009-thread-4) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 13+33
[junit4] 2> 2148815 INFO (jetty-launcher-12009-thread-3) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 13+33
[junit4] 2> 2148816 WARN (jetty-launcher-12009-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2148816 INFO (jetty-launcher-12009-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 2148816 INFO (jetty-launcher-12009-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2148816 INFO (jetty-launcher-12009-thread-2) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 13+33
[junit4] 2> 2148820 INFO (jetty-launcher-12009-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2148820 INFO (jetty-launcher-12009-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2148820 INFO (jetty-launcher-12009-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2148821 INFO (jetty-launcher-12009-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2148821 INFO (jetty-launcher-12009-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2148821 INFO (jetty-launcher-12009-thread-4) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2148821 INFO (jetty-launcher-12009-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2148821 INFO (jetty-launcher-12009-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2148821 INFO (jetty-launcher-12009-thread-3) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2148821 INFO (jetty-launcher-12009-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2148821 INFO (jetty-launcher-12009-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2148821 INFO (jetty-launcher-12009-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2148822 INFO (jetty-launcher-12009-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1c955162{/solr,null,AVAILABLE}
[junit4] 2> 2148822 INFO (jetty-launcher-12009-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@15b626fd{/solr,null,AVAILABLE}
[junit4] 2> 2148822 INFO (jetty-launcher-12009-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@523c6ba8{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:64501}
[junit4] 2> 2148822 INFO (jetty-launcher-12009-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2b39951b{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:64502}
[junit4] 2> 2148822 INFO (jetty-launcher-12009-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@c36473b{/solr,null,AVAILABLE}
[junit4] 2> 2148822 INFO (jetty-launcher-12009-thread-4) [ ] o.e.j.s.Server Started @2148895ms
[junit4] 2> 2148822 INFO (jetty-launcher-12009-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=64502}
[junit4] 2> 2148822 INFO (jetty-launcher-12009-thread-1) [ ] o.e.j.s.Server Started @2148896ms
[junit4] 2> 2148822 INFO (jetty-launcher-12009-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=64501}
[junit4] 2> 2148822 INFO (jetty-launcher-12009-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@50bd737d{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:64503}
[junit4] 2> 2148822 INFO (jetty-launcher-12009-thread-3) [ ] o.e.j.s.Server Started @2148896ms
[junit4] 2> 2148822 INFO (jetty-launcher-12009-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5f4f32af{/solr,null,AVAILABLE}
[junit4] 2> 2148822 INFO (jetty-launcher-12009-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=64503}
[junit4] 2> 2148823 INFO (jetty-launcher-12009-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@bb00804{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:64504}
[junit4] 2> 2148823 INFO (jetty-launcher-12009-thread-2) [ ] o.e.j.s.Server Started @2148896ms
[junit4] 2> 2148823 INFO (jetty-launcher-12009-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=64504}
[junit4] 2> 2148823 ERROR (jetty-launcher-12009-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2148823 ERROR (jetty-launcher-12009-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2148823 INFO (jetty-launcher-12009-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2148823 ERROR (jetty-launcher-12009-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2148823 INFO (jetty-launcher-12009-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2148823 ERROR (jetty-launcher-12009-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2148823 INFO (jetty-launcher-12009-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2148823 INFO (jetty-launcher-12009-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 2148823 INFO (jetty-launcher-12009-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2148823 INFO (jetty-launcher-12009-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2148823 INFO (jetty-launcher-12009-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-09-27T09:04:08.452291Z
[junit4] 2> 2148823 INFO (jetty-launcher-12009-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2148823 INFO (jetty-launcher-12009-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 2148823 INFO (jetty-launcher-12009-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2148823 INFO (jetty-launcher-12009-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2148823 INFO (jetty-launcher-12009-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-09-27T09:04:08.452479Z
[junit4] 2> 2148823 INFO (jetty-launcher-12009-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 2148823 INFO (jetty-launcher-12009-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2148823 INFO (jetty-launcher-12009-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2148823 INFO (jetty-launcher-12009-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-09-27T09:04:08.452536Z
[junit4] 2> 2148823 INFO (jetty-launcher-12009-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 2148823 INFO (jetty-launcher-12009-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2148823 INFO (jetty-launcher-12009-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2148823 INFO (jetty-launcher-12009-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-09-27T09:04:08.452650Z
[junit4] 2> 2148827 INFO (jetty-launcher-12009-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2148828 INFO (jetty-launcher-12009-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2148828 INFO (jetty-launcher-12009-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2148828 INFO (jetty-launcher-12009-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2148833 INFO (zkConnectionManagerCallback-12015-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2148833 INFO (jetty-launcher-12009-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2148833 INFO (zkConnectionManagerCallback-12013-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2148833 INFO (jetty-launcher-12009-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2148833 INFO (zkConnectionManagerCallback-12011-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2148833 INFO (jetty-launcher-12009-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2148833 INFO (zkConnectionManagerCallback-12017-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2148834 INFO (jetty-launcher-12009-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2148834 INFO (jetty-launcher-12009-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2148834 INFO (jetty-launcher-12009-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2148834 INFO (jetty-launcher-12009-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2148835 INFO (jetty-launcher-12009-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2149065 INFO (jetty-launcher-12009-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 2149065 WARN (jetty-launcher-12009-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7de7f11c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2149065 WARN (jetty-launcher-12009-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7de7f11c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2149068 WARN (jetty-launcher-12009-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2e07778c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2149068 WARN (jetty-launcher-12009-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2e07778c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2149068 INFO (jetty-launcher-12009-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64496/solr
[junit4] 2> 2149069 INFO (jetty-launcher-12009-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 2149069 INFO (jetty-launcher-12009-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2149070 WARN (jetty-launcher-12009-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3b8741c5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2149070 WARN (jetty-launcher-12009-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3b8741c5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2149071 INFO (zkConnectionManagerCallback-12025-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2149071 INFO (jetty-launcher-12009-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2149072 WARN (jetty-launcher-12009-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2103e985[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2149072 WARN (jetty-launcher-12009-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2103e985[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2149073 INFO (jetty-launcher-12009-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64496/solr
[junit4] 2> 2149074 INFO (jetty-launcher-12009-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2149076 INFO (zkConnectionManagerCallback-12031-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2149076 INFO (jetty-launcher-12009-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2149156 INFO (jetty-launcher-12009-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 2149157 WARN (jetty-launcher-12009-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3151f8f5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2149157 WARN (jetty-launcher-12009-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3151f8f5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2149159 WARN (jetty-launcher-12009-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5f5e9ad8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2149159 WARN (jetty-launcher-12009-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5f5e9ad8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2149160 INFO (jetty-launcher-12009-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64496/solr
[junit4] 2> 2149161 INFO (jetty-launcher-12009-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2149164 INFO (zkConnectionManagerCallback-12038-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2149164 INFO (jetty-launcher-12009-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2149326 INFO (jetty-launcher-12009-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 2149327 WARN (jetty-launcher-12009-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4e3b9193[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2149327 WARN (jetty-launcher-12009-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4e3b9193[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2149329 INFO (jetty-launcher-12009-thread-3) [n:127.0.0.1:64503_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2149329 INFO (jetty-launcher-12009-thread-1) [n:127.0.0.1:64501_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2149330 WARN (jetty-launcher-12009-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7ef03e27[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2149330 WARN (jetty-launcher-12009-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7ef03e27[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2149331 INFO (jetty-launcher-12009-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64496/solr
[junit4] 2> 2149332 INFO (jetty-launcher-12009-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2149332 INFO (zkConnectionManagerCallback-12045-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2149333 INFO (jetty-launcher-12009-thread-1) [n:127.0.0.1:64501_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2149333 INFO (zkConnectionManagerCallback-12042-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2149333 INFO (jetty-launcher-12009-thread-3) [n:127.0.0.1:64503_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2149335 INFO (zkConnectionManagerCallback-12049-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2149335 INFO (jetty-launcher-12009-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2149438 INFO (jetty-launcher-12009-thread-2) [n:127.0.0.1:64504_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2149440 INFO (zkConnectionManagerCallback-12051-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2149440 INFO (jetty-launcher-12009-thread-2) [n:127.0.0.1:64504_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2149537 INFO (jetty-launcher-12009-thread-4) [n:127.0.0.1:64502_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2149540 INFO (zkConnectionManagerCallback-12059-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2149540 INFO (jetty-launcher-12009-thread-4) [n:127.0.0.1:64502_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2149571 INFO (jetty-launcher-12009-thread-4) [n:127.0.0.1:64502_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:64502_solr
[junit4] 2> 2149573 INFO (jetty-launcher-12009-thread-4) [n:127.0.0.1:64502_solr ] o.a.s.c.Overseer Overseer (id=72112781309706254-127.0.0.1:64502_solr-n_0000000000) starting
[junit4] 2> 2149589 INFO (jetty-launcher-12009-thread-4) [n:127.0.0.1:64502_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2149591 INFO (zkConnectionManagerCallback-12066-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2149591 INFO (jetty-launcher-12009-thread-4) [n:127.0.0.1:64502_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2149598 INFO (jetty-launcher-12009-thread-4) [n:127.0.0.1:64502_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:64496/solr ready
[junit4] 2> 2149600 INFO (OverseerStateUpdate-72112781309706254-127.0.0.1:64502_solr-n_0000000000) [n:127.0.0.1:64502_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:64502_solr
[junit4] 2> 2149601 INFO (jetty-launcher-12009-thread-4) [n:127.0.0.1:64502_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64502_solr
[junit4] 2> 2149605 INFO (OverseerStateUpdate-72112781309706254-127.0.0.1:64502_solr-n_0000000000) [n:127.0.0.1:64502_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2149605 INFO (zkCallback-12065-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2149612 INFO (jetty-launcher-12009-thread-4) [n:127.0.0.1:64502_solr ] o.a.s.c.PackageManager clusterprops.json changed , version -1
[junit4] 2> 2149642 INFO (jetty-launcher-12009-thread-4) [n:127.0.0.1:64502_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2149664 INFO (jetty-launcher-12009-thread-1) [n:127.0.0.1:64501_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2149667 INFO (jetty-launcher-12009-thread-4) [n:127.0.0.1:64502_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64502.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@42ed7bf9
[junit4] 2> 2149681 INFO (jetty-launcher-12009-thread-1) [n:127.0.0.1:64501_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:64501_solr as DOWN
[junit4] 2> 2149686 INFO (jetty-launcher-12009-thread-4) [n:127.0.0.1:64502_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64502.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@42ed7bf9
[junit4] 2> 2149686 INFO (jetty-launcher-12009-thread-4) [n:127.0.0.1:64502_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64502.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@42ed7bf9
[junit4] 2> 2149688 INFO (jetty-launcher-12009-thread-4) [n:127.0.0.1:64502_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.OverseerRolesTest_D5906C10284C2193-001/tempDir-001/node4/.
[junit4] 2> 2149693 INFO (jetty-launcher-12009-thread-1) [n:127.0.0.1:64501_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2149693 INFO (jetty-launcher-12009-thread-1) [n:127.0.0.1:64501_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64501_solr
[junit4] 2> 2149712 INFO (zkCallback-12065-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2149712 INFO (zkCallback-12058-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2149713 INFO (zkCallback-12044-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2149716 INFO (jetty-launcher-12009-thread-1) [n:127.0.0.1:64501_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2149719 INFO (zkConnectionManagerCallback-12072-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2149719 INFO (jetty-launcher-12009-thread-1) [n:127.0.0.1:64501_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2149724 INFO (jetty-launcher-12009-thread-1) [n:127.0.0.1:64501_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2149728 INFO (jetty-launcher-12009-thread-1) [n:127.0.0.1:64501_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:64496/solr ready
[junit4] 2> 2149728 INFO (jetty-launcher-12009-thread-1) [n:127.0.0.1:64501_solr ] o.a.s.c.PackageManager clusterprops.json changed , version -1
[junit4] 2> 2149757 INFO (jetty-launcher-12009-thread-1) [n:127.0.0.1:64501_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2149770 INFO (jetty-launcher-12009-thread-2) [n:127.0.0.1:64504_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2149782 INFO (jetty-launcher-12009-thread-2) [n:127.0.0.1:64504_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:64504_solr as DOWN
[junit4] 2> 2149783 INFO (jetty-launcher-12009-thread-1) [n:127.0.0.1:64501_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64501.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@42ed7bf9
[junit4] 2> 2149784 INFO (jetty-launcher-12009-thread-2) [n:127.0.0.1:64504_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2149784 INFO (jetty-launcher-12009-thread-2) [n:127.0.0.1:64504_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64504_solr
[junit4] 2> 2149787 INFO (zkCallback-12071-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2149788 INFO (zkCallback-12044-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2149788 INFO (zkCallback-12058-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2149788 INFO (zkCallback-12065-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2149788 INFO (zkCallback-12050-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2149790 INFO (jetty-launcher-12009-thread-2) [n:127.0.0.1:64504_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2149793 INFO (zkConnectionManagerCallback-12078-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2149793 INFO (jetty-launcher-12009-thread-2) [n:127.0.0.1:64504_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2149796 INFO (jetty-launcher-12009-thread-2) [n:127.0.0.1:64504_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 2149799 INFO (jetty-launcher-12009-thread-2) [n:127.0.0.1:64504_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:64496/solr ready
[junit4] 2> 2149799 INFO (jetty-launcher-12009-thread-2) [n:127.0.0.1:64504_solr ] o.a.s.c.PackageManager clusterprops.json changed , version -1
[junit4] 2> 2149813 INFO (jetty-launcher-12009-thread-3) [n:127.0.0.1:64503_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 2149830 INFO (jetty-launcher-12009-thread-2) [n:127.0.0.1:64504_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2149846 INFO (jetty-launcher-12009-thread-1) [n:127.0.0.1:64501_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64501.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@42ed7bf9
[junit4] 2> 2149847 INFO (jetty-launcher-12009-thread-1) [n:127.0.0.1:64501_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64501.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@42ed7bf9
[junit4] 2> 2149848 INFO (jetty-launcher-12009-thread-1) [n:127.0.0.1:64501_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.OverseerRolesTest_D5906C10284C2193-001/tempDir-001/node1/.
[junit4] 2> 2149852 INFO (jetty-launcher-12009-thread-3) [n:127.0.0.1:64503_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:64503_solr as DOWN
[junit4] 2> 2149859 INFO (jetty-launcher-12009-thread-3) [n:127.0.0.1:64503_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2149860 INFO (jetty-launcher-12009-thread-3) [n:127.0.0.1:64503_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64503_solr
[junit4] 2> 2149866 INFO (jetty-launcher-12009-thread-2) [n:127.0.0.1:64504_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64504.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@42ed7bf9
[junit4] 2> 2149869 INFO (zkCallback-12050-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2149869 INFO (zkCallback-12071-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2149870 INFO (zkCallback-12077-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2149870 INFO (zkCallback-12065-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2149871 INFO (zkCallback-12044-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2149871 INFO (zkCallback-12058-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2149871 INFO (zkCallback-12041-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2149873 INFO (jetty-launcher-12009-thread-3) [n:127.0.0.1:64503_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2149875 INFO (zkConnectionManagerCallback-12084-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2149875 INFO (jetty-launcher-12009-thread-3) [n:127.0.0.1:64503_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2149876 INFO (jetty-launcher-12009-thread-2) [n:127.0.0.1:64504_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64504.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@42ed7bf9
[junit4] 2> 2149876 INFO (jetty-launcher-12009-thread-2) [n:127.0.0.1:64504_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64504.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@42ed7bf9
[junit4] 2> 2149877 INFO (jetty-launcher-12009-thread-2) [n:127.0.0.1:64504_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.OverseerRolesTest_D5906C10284C2193-001/tempDir-001/node2/.
[junit4] 2> 2149878 INFO (jetty-launcher-12009-thread-3) [n:127.0.0.1:64503_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 2149880 INFO (jetty-launcher-12009-thread-3) [n:127.0.0.1:64503_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:64496/solr ready
[junit4] 2> 2149880 INFO (jetty-launcher-12009-thread-3) [n:127.0.0.1:64503_solr ] o.a.s.c.PackageManager clusterprops.json changed , version -1
[junit4] 2> 2149914 INFO (jetty-launcher-12009-thread-3) [n:127.0.0.1:64503_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2149946 INFO (jetty-launcher-12009-thread-3) [n:127.0.0.1:64503_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64503.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@42ed7bf9
[junit4] 2> 2149957 INFO (jetty-launcher-12009-thread-3) [n:127.0.0.1:64503_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64503.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@42ed7bf9
[junit4] 2> 2149957 INFO (jetty-launcher-12009-thread-3) [n:127.0.0.1:64503_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64503.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@42ed7bf9
[junit4] 2> 2149958 INFO (jetty-launcher-12009-thread-3) [n:127.0.0.1:64503_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.OverseerRolesTest_D5906C10284C2193-001/tempDir-001/node3/.
[junit4] 2> 2150008 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=4
[junit4] 2> 2150009 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2150011 INFO (zkConnectionManagerCallback-12090-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2150011 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2150014 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 2150016 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:64496/solr ready
[junit4] 2> 2150020 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.c.OverseerRolesTest Overseer: 127.0.0.1:64502_solr
[junit4] 2> 2150021 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.c.OverseerRolesTest Election queue: [72112781309706254-127.0.0.1:64502_solr-n_0000000000, 72112781309706250-127.0.0.1:64501_solr-n_0000000001, 72112781309706253-127.0.0.1:64504_solr-n_0000000002, 72112781309706251-127.0.0.1:64503_solr-n_0000000003]
[junit4] 2> 2150027 INFO (qtp1585088194-32279) [n:127.0.0.1:64503_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :removerole with params node=127.0.0.1:64502_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2150035 INFO (OverseerThreadFactory-9942-thread-1-processing-n:127.0.0.1:64502_solr) [n:127.0.0.1:64502_solr ] o.a.s.c.a.c.OverseerRoleCmd Overseer role removed from 127.0.0.1:64502_solr
[junit4] 2> 2150042 INFO (qtp1585088194-32279) [n:127.0.0.1:64503_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:64502_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2} status=0 QTime=15
[junit4] 2> 2150043 INFO (qtp1585088194-32283) [n:127.0.0.1:64503_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :removerole with params node=127.0.0.1:64501_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2150050 INFO (OverseerCollectionConfigSetProcessor-72112781309706254-127.0.0.1:64502_solr-n_0000000000) [n:127.0.0.1:64502_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 2150050 INFO (OverseerThreadFactory-9942-thread-2-processing-n:127.0.0.1:64502_solr) [n:127.0.0.1:64502_solr ] o.a.s.c.a.c.OverseerRoleCmd Overseer role removed from 127.0.0.1:64501_solr
[junit4] 2> 2150055 INFO (qtp1585088194-32283) [n:127.0.0.1:64503_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:64501_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2} status=0 QTime=11
[junit4] 2> 2150055 INFO (qtp1585088194-32287) [n:127.0.0.1:64503_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :removerole with params node=127.0.0.1:64504_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2150061 INFO (OverseerCollectionConfigSetProcessor-72112781309706254-127.0.0.1:64502_solr-n_0000000000) [n:127.0.0.1:64502_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 2150061 INFO (OverseerThreadFactory-9942-thread-3-processing-n:127.0.0.1:64502_solr) [n:127.0.0.1:64502_solr ] o.a.s.c.a.c.OverseerRoleCmd Overseer role removed from 127.0.0.1:64504_solr
[junit4] 2> 2150065 INFO (qtp1585088194-32287) [n:127.0.0.1:64503_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:64504_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2} status=0 QTime=9
[junit4] 2> 2150066 INFO (qtp1585088194-32291) [n:127.0.0.1:64503_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :removerole with params node=127.0.0.1:64503_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2150071 INFO (OverseerCollectionConfigSetProcessor-72112781309706254-127.0.0.1:64502_solr-n_0000000000) [n:127.0.0.1:64502_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000004 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 2150072 INFO (OverseerThreadFactory-9942-thread-4-processing-n:127.0.0.1:64502_solr) [n:127.0.0.1:64502_solr ] o.a.s.c.a.c.OverseerRoleCmd Overseer role removed from 127.0.0.1:64503_solr
[junit4] 2> 2150076 INFO (qtp1585088194-32291) [n:127.0.0.1:64503_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:64503_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2} status=0 QTime=9
[junit4] 2> 2150076 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.c.OverseerRolesTest ### Setting overseer designate 127.0.0.1:64504_solr
[junit4] 2> 2150079 INFO (qtp1585088194-32295) [n:127.0.0.1:64503_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addrole with params node=127.0.0.1:64504_solr&role=overseer&action=ADDROLE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2150084 INFO (OverseerCollectionConfigSetProcessor-72112781309706254-127.0.0.1:64502_solr-n_0000000000) [n:127.0.0.1:64502_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000006 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 2150084 INFO (OverseerThreadFactory-9942-thread-5-processing-n:127.0.0.1:64502_solr) [n:127.0.0.1:64502_solr ] o.a.s.c.a.c.OverseerRoleCmd Overseer role added to 127.0.0.1:64504_solr
[junit4] 2> 2150087 INFO (qtp1585088194-32295) [n:127.0.0.1:64503_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:64504_solr&role=overseer&action=ADDROLE&wt=javabin&version=2} status=0 QTime=8
[junit4] 2> 2150088 INFO (Thread-4281) [ ] o.a.s.c.OverseerNodePrioritizer prioritizing overseer nodes at 72112781309706254-127.0.0.1:64502_solr-n_0000000000 overseer designates are [127.0.0.1:64504_solr]
[junit4] 2> 2150088 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.c.OverseerRolesTest Expecting node: 127.0.0.1:64504_solr
[junit4] 2> 2150088 INFO (Thread-4281) [ ] o.a.s.c.OverseerNodePrioritizer sorted nodes [72112781309706254-127.0.0.1:64502_solr-n_0000000000, 72112781309706250-127.0.0.1:64501_solr-n_0000000001, 72112781309706253-127.0.0.1:64504_solr-n_0000000002, 72112781309706251-127.0.0.1:64503_solr-n_0000000003]
[junit4] 2> 2150088 INFO (Thread-4281) [ ] o.a.s.c.OverseerNodePrioritizer asking node 72112781309706253-127.0.0.1:64504_solr-n_0000000002 to come join election at head
[junit4] 2> 2150095 INFO (qtp91748058-32281) [n:127.0.0.1:64504_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={op=rejoinAtHead&qt=/admin/cores&action=OVERSEEROP&electionNode=72112781309706253-127.0.0.1:64504_solr-n_0000000002&wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 2150096 INFO (Thread-4281) [ ] o.a.s.c.OverseerNodePrioritizer asking the old first in line 72112781309706250-127.0.0.1:64501_solr-n_0000000001 to rejoin election
[junit4] 2> 2150102 INFO (qtp1559989831-32276) [n:127.0.0.1:64501_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={op=rejoin&qt=/admin/cores&action=OVERSEEROP&electionNode=72112781309706250-127.0.0.1:64501_solr-n_0000000001&wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 2150106 INFO (OverseerStateUpdate-72112781309706254-127.0.0.1:64502_solr-n_0000000000) [n:127.0.0.1:64502_solr ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"72112781309706254-127.0.0.1:64502_solr-n_0000000000"} 127.0.0.1:64502_solr
[junit4] 2> 2150107 INFO (OverseerStateUpdate-72112781309706254-127.0.0.1:64502_solr-n_0000000000) [n:127.0.0.1:64502_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:64502_solr
[junit4] 2> 2150111 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer I (id=72112781309706254-127.0.0.1:64502_solr-n_0000000000) am exiting, but I'm still the leader
[junit4] 2> 2150113 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer Overseer (id=72112781309706254-127.0.0.1:64502_solr-n_0000000000) closing
[junit4] 2> 2150113 INFO (OverseerAutoScalingTriggerThread-72112781309706254-127.0.0.1:64502_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 2150115 INFO (zkCallback-12050-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:64504_solr
[junit4] 2> 2150118 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer Overseer (id=72112781309706254-127.0.0.1:64502_solr-n_0000000000) closing
[junit4] 2> 2150118 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer Overseer (id=72112781309706254-127.0.0.1:64502_solr-n_0000000000) closing
[junit4] 2> 2150119 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer Overseer (id=72112781309706254-127.0.0.1:64502_solr-n_0000000000) closing
[junit4] 2> 2150119 INFO (zkCallback-12050-thread-1) [n:127.0.0.1:64504_solr ] o.a.s.c.Overseer Overseer (id=72112781309706253-127.0.0.1:64504_solr-n_0000000001) starting
[junit4] 2> 2150132 INFO (OverseerStateUpdate-72112781309706253-127.0.0.1:64504_solr-n_0000000001) [n:127.0.0.1:64504_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:64504_solr
[junit4] 2> 2150191 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.c.OverseerRolesTest ### Adding another overseer designate 127.0.0.1:64501_solr
[junit4] 2> 2150192 INFO (qtp1585088194-32275) [n:127.0.0.1:64503_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addrole with params node=127.0.0.1:64501_solr&role=overseer&action=ADDROLE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2150200 INFO (OverseerThreadFactory-9962-thread-1-processing-n:127.0.0.1:64504_solr) [n:127.0.0.1:64504_solr ] o.a.s.c.a.c.OverseerRoleCmd Overseer role added to 127.0.0.1:64501_solr
[junit4] 2> 2150207 INFO (qtp1585088194-32275) [n:127.0.0.1:64503_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:64501_solr&role=overseer&action=ADDROLE&wt=javabin&version=2} status=0 QTime=15
[junit4] 2> 2150210 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.c.OverseerRolesTest Overseer: 127.0.0.1:64504_solr
[junit4] 2> 2150211 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.c.OverseerRolesTest Election queue: [72112781309706253-127.0.0.1:64504_solr-n_0000000001, 72112781309706251-127.0.0.1:64503_solr-n_0000000003, 72112781309706250-127.0.0.1:64501_solr-n_0000000005, 72112781309706254-127.0.0.1:64502_solr-n_0000000006]
[junit4] 2> 2150211 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1337826669
[junit4] 2> 2150211 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:64504_solr
[junit4] 2> 2150212 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 2150212 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:64504_solr as DOWN
[junit4] 2> 2150219 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2150219 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6a22c856: rootName = solr_64504, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@5a4c9d10
[junit4] 2> 2150228 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2150228 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5407854e: rootName = solr_64504, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@501e27e7
[junit4] 2> 2150233 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2150233 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2ed17d04: rootName = solr_64504, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@5260eceb
[junit4] 2> 2150233 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 2150237 INFO (closeThreadPool-12094-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72112781309706253-127.0.0.1:64504_solr-n_0000000001) closing
[junit4] 2> 2150238 INFO (OverseerStateUpdate-72112781309706253-127.0.0.1:64504_solr-n_0000000001) [n:127.0.0.1:64504_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:64504_solr
[junit4] 2> 2150239 INFO (OverseerAutoScalingTriggerThread-72112781309706253-127.0.0.1:64504_solr-n_0000000001) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 2150253 INFO (zkCallback-12071-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2150254 INFO (zkCallback-12041-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2150254 INFO (zkCallback-12041-thread-2) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:64503_solr
[junit4] 2> 2150263 INFO (closeThreadPool-12094-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72112781309706253-127.0.0.1:64504_solr-n_0000000001) closing
[junit4] 2> 2150353 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.c.Overseer Overseer (id=72112781309706253-127.0.0.1:64504_solr-n_0000000001) closing
[junit4] 2> 2150355 INFO (zkCallback-12044-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2150355 INFO (zkCallback-12089-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2150355 INFO (zkCallback-12077-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2150356 INFO (zkCallback-12058-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2150356 INFO (zkCallback-12083-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2150356 INFO (zkCallback-12065-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2150357 INFO (zkCallback-12041-thread-2) [n:127.0.0.1:64503_solr ] o.a.s.c.Overseer Overseer (id=72112781309706251-127.0.0.1:64503_solr-n_0000000003) starting
[junit4] 2> 2150368 INFO (OverseerStateUpdate-72112781309706251-127.0.0.1:64503_solr-n_0000000003) [n:127.0.0.1:64503_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:64503_solr
[junit4] 2> 2150371 INFO (OverseerCollectionConfigSetProcessor-72112781309706251-127.0.0.1:64503_solr-n_0000000003) [n:127.0.0.1:64503_solr ] o.a.s.c.OverseerNodePrioritizer prioritizing overseer nodes at 72112781309706251-127.0.0.1:64503_solr-n_0000000003 overseer designates are [127.0.0.1:64504_solr, 127.0.0.1:64501_solr]
[junit4] 2> 2150372 INFO (OverseerCollectionConfigSetProcessor-72112781309706251-127.0.0.1:64503_solr-n_0000000003) [n:127.0.0.1:64503_solr ] o.a.s.c.OverseerNodePrioritizer sorted nodes [72112781309706251-127.0.0.1:64503_solr-n_0000000003, 72112781309706250-127.0.0.1:64501_solr-n_0000000005, 72112781309706254-127.0.0.1:64502_solr-n_0000000006]
[junit4] 2> 2150384 INFO (OverseerStateUpdate-72112781309706251-127.0.0.1:64503_solr-n_0000000003) [n:127.0.0.1:64503_solr ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"72112781309706251-127.0.0.1:64503_solr-n_0000000003"} 127.0.0.1:64503_solr
[junit4] 2> 2150385 INFO (OverseerStateUpdate-72112781309706251-127.0.0.1:64503_solr-n_0000000003) [n:127.0.0.1:64503_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:64503_solr
[junit4] 2> 2150388 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer I (id=72112781309706251-127.0.0.1:64503_solr-n_0000000003) am exiting, but I'm still the leader
[junit4] 2> 2150390 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer Overseer (id=72112781309706251-127.0.0.1:64503_solr-n_0000000003) closing
[junit4] 2> 2150390 INFO (OverseerAutoScalingTriggerThread-72112781309706251-127.0.0.1:64503_solr-n_0000000003) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 2150394 INFO (zkCallback-12044-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:64501_solr
[junit4] 2> 2150396 INFO (zkCallback-12044-thread-1) [n:127.0.0.1:64501_solr ] o.a.s.c.Overseer Overseer (id=72112781309706250-127.0.0.1:64501_solr-n_0000000005) starting
[junit4] 2> 2150405 INFO (OverseerStateUpdate-72112781309706250-127.0.0.1:64501_solr-n_0000000005) [n:127.0.0.1:64501_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:64501_solr
[junit4] 2> 2150406 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer Overseer (id=72112781309706251-127.0.0.1:64503_solr-n_0000000003) closing
[junit4] 2> 2150406 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer Overseer (id=72112781309706251-127.0.0.1:64503_solr-n_0000000003) closing
[junit4] 2> 2150407 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer Overseer (id=72112781309706251-127.0.0.1:64503_solr-n_0000000003) closing
[junit4] 2> 2150466 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@bb00804{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 2150467 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5f4f32af{/solr,null,UNAVAILABLE}
[junit4] 2> 2150468 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2150479 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.c.OverseerRolesTest Expecting node: 127.0.0.1:64501_solr
[junit4] 2> 2150479 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.c.OverseerRolesTest ### Adding last overseer designate 127.0.0.1:64503_solr
[junit4] 2> 2150481 INFO (qtp1559989831-32284) [n:127.0.0.1:64501_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addrole with params node=127.0.0.1:64503_solr&role=overseer&action=ADDROLE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2150486 INFO (OverseerThreadFactory-9971-thread-1-processing-n:127.0.0.1:64501_solr) [n:127.0.0.1:64501_solr ] o.a.s.c.a.c.OverseerRoleCmd Overseer role added to 127.0.0.1:64503_solr
[junit4] 2> 2150490 INFO (qtp1559989831-32284) [n:127.0.0.1:64501_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:64503_solr&role=overseer&action=ADDROLE&wt=javabin&version=2} status=0 QTime=9
[junit4] 2> 2150491 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.c.OverseerRolesTest Overseer: 127.0.0.1:64501_solr
[junit4] 2> 2150492 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.c.OverseerRolesTest Election queue: [72112781309706250-127.0.0.1:64501_solr-n_0000000005, 72112781309706254-127.0.0.1:64502_solr-n_0000000006, 72112781309706251-127.0.0.1:64503_solr-n_0000000007]
[junit4] 2> 2150493 INFO (qtp1559989831-32288) [n:127.0.0.1:64501_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :removerole with params node=127.0.0.1:64501_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2150497 INFO (OverseerCollectionConfigSetProcessor-72112781309706250-127.0.0.1:64501_solr-n_0000000005) [n:127.0.0.1:64501_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000012 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 2150497 INFO (OverseerThreadFactory-9971-thread-2-processing-n:127.0.0.1:64501_solr) [n:127.0.0.1:64501_solr ] o.a.s.c.a.c.OverseerRoleCmd Overseer role removed from 127.0.0.1:64501_solr
[junit4] 2> 2150500 INFO (Thread-4283) [ ] o.a.s.c.OverseerNodePrioritizer prioritizing overseer nodes at 72112781309706250-127.0.0.1:64501_solr-n_0000000005 overseer designates are [127.0.0.1:64504_solr, 127.0.0.1:64503_solr]
[junit4] 2> 2150501 INFO (Thread-4283) [ ] o.a.s.c.OverseerNodePrioritizer sorted nodes [72112781309706250-127.0.0.1:64501_solr-n_0000000005, 72112781309706254-127.0.0.1:64502_solr-n_0000000006, 72112781309706251-127.0.0.1:64503_solr-n_0000000007]
[junit4] 2> 2150501 INFO (Thread-4283) [ ] o.a.s.c.OverseerNodePrioritizer asking node 72112781309706251-127.0.0.1:64503_solr-n_0000000007 to come join election at head
[junit4] 2> 2150501 INFO (qtp1559989831-32288) [n:127.0.0.1:64501_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:64501_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2} status=0 QTime=8
[junit4] 2> 2150501 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[D5906C10284C2193]) [ ] o.a.s.c.OverseerRolesTest Expecting node: 127.0.0.
[...truncated too long message...]
zedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> sept. 27, 2019 8:21:11 P.?M. com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> SEVERE: 1 thread leaked from SUITE scope at org.apache.lucene.search.TestIndexSearcher:
[junit4] 2> 1) Thread[id=17, name=TEST-TestIndexSearcher.testRejectedExecution-seed#[669FB48211F6CD27], state=WAITING, group=TGRP-TestIndexSearcher]
[junit4] 2> at java.base@13/jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> at java.base@13/java.util.concurrent.locks.LockSupport.park(LockSupport.java:194)
[junit4] 2> at java.base@13/java.util.concurrent.FutureTask.awaitDone(FutureTask.java:447)
[junit4] 2> at java.base@13/java.util.concurrent.FutureTask.get(FutureTask.java:190)
[junit4] 2> at app//org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:688)
[junit4] 2> at app//org.apache.lucene.search.IndexSearcher.searchAfter(IndexSearcher.java:492)
[junit4] 2> at app//org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:503)
[junit4] 2> at app//org.apache.lucene.search.TestIndexSearcher.testRejectedExecution(TestIndexSearcher.java:293)
[junit4] 2> at java.base@13/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at java.base@13/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at java.base@13/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.base@13/java.lang.reflect.Method.invoke(Method.java:567)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
[junit4] 2> at app//org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
[junit4] 2> at app//org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at app//org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4] 2> at app//org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at app//org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
[junit4] 2> at app//org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at app//org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at app//org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
[junit4] 2> at app//org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at app//org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at app//org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
[junit4] 2> at java.base@13/java.lang.Thread.run(Thread.java:830)
[junit4] 2> sept. 27, 2019 8:21:11 P.?M. com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=17, name=TEST-TestIndexSearcher.testRejectedExecution-seed#[669FB48211F6CD27], state=WAITING, group=TGRP-TestIndexSearcher]
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/build/core/test/J0/temp/lucene.search.TestIndexSearcher_669FB48211F6CD27-003
[junit4] 2> Sep 27, 2019 4:21:12 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: All leaked threads terminated.
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene80): {field=Lucene50(blocksize=128), field2=Lucene50(blocksize=128)}, docValues:{field2=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=1361, maxMBSortInHeap=7.1505255055043495, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@58e66319), locale=es-ES, timezone=Asia/Muscat
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 13 (64-bit)/cpus=3,threads=1,free=41756600,total=54853632
[junit4] 2> NOTE: All tests run in this JVM: [TestIndexSearcher, TestIndexSearcher, TestIndexSearcher]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestIndexSearcher -Dtests.seed=669FB48211F6CD27 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=es-ES -Dtests.timezone=Asia/Muscat -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J0 | TestIndexSearcher (suite) <<<
[junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
[junit4] > at __randomizedtesting.SeedInfo.seed([669FB48211F6CD27]:0)
[junit4] Completed [5/5 (5!)] on J0 in 7220.39s, 4 tests, 2 errors <<< FAILURES!
[...truncated 19 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/common-build.xml:1492: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/common-build.xml:1019: There were test failures: 5 suites, 20 tests, 5 suite-level errors, 5 errors [seed: 669FB48211F6CD27]
Total time: 361 minutes 5 seconds
[repro] Setting last failure code to 256
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.cloud.OverseerRolesTest
[repro] 0/5 failed: org.apache.solr.search.TestIndexSearcher
[repro] 0/5 failed: org.apache.solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest
[repro] 5/5 failed: org.apache.lucene.search.TestIndexSearcher
[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
No credentials specified
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=15db6bfa88952cf0912b3c93d59c0cdc55bf9e2a, workspace=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX
[WARNINGS] Computing warning deltas based on reference build #5364
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
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/jdk-12.0.1) - Build #
5366 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/5366/
Java: 64bit/jdk-12.0.1 -XX:-UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest.testTimeCat
Error Message:
expected:<6> but was:<4>
Stack Trace:
java.lang.AssertionError: expected:<6> but was:<4>
at __randomizedtesting.SeedInfo.seed([8BB738A4992805A5:B24F7917D90949CE]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotEquals(Assert.java:834)
at org.junit.Assert.assertEquals(Assert.java:645)
at org.junit.Assert.assertEquals(Assert.java:631)
at org.apache.solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest.assertCatTimeInvariants(DimensionalRoutedAliasUpdateProcessorTest.java:677)
at org.apache.solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest.testTimeCat(DimensionalRoutedAliasUpdateProcessorTest.java:174)
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:567)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
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:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
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 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:835)
Build Log:
[...truncated 15642 lines...]
[junit4] Suite: org.apache.solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest
[junit4] 2> 4511580 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[8BB738A4992805A5]) [ ] 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.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001
[junit4] 2> 4511580 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[8BB738A4992805A5]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 4511588 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 4511588 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 4511685 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[8BB738A4992805A5]) [ ] o.a.s.c.ZkTestServer start zk server on port:50765
[junit4] 2> 4511685 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[8BB738A4992805A5]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:50765
[junit4] 2> 4511685 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[8BB738A4992805A5]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:50765
[junit4] 2> 4511685 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[8BB738A4992805A5]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 50765
[junit4] 2> 4511688 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[8BB738A4992805A5]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4511708 INFO (zkConnectionManagerCallback-25361-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4511708 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[8BB738A4992805A5]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4511712 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[8BB738A4992805A5]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4511715 INFO (zkConnectionManagerCallback-25363-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4511715 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[8BB738A4992805A5]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4511717 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[8BB738A4992805A5]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4511719 INFO (zkConnectionManagerCallback-25365-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4511719 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[8BB738A4992805A5]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4511838 WARN (jetty-launcher-25366-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 4511839 INFO (jetty-launcher-25366-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 4511839 INFO (jetty-launcher-25366-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 4511839 INFO (jetty-launcher-25366-thread-1) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 12.0.1+12
[junit4] 2> 4511840 WARN (jetty-launcher-25366-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 4511840 INFO (jetty-launcher-25366-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 4511840 INFO (jetty-launcher-25366-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 4511840 INFO (jetty-launcher-25366-thread-2) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 12.0.1+12
[junit4] 2> 4511841 WARN (jetty-launcher-25366-thread-3) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 4511841 INFO (jetty-launcher-25366-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 4511841 INFO (jetty-launcher-25366-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 4511841 INFO (jetty-launcher-25366-thread-3) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 12.0.1+12
[junit4] 2> 4511842 WARN (jetty-launcher-25366-thread-4) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 4511842 INFO (jetty-launcher-25366-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 4511842 INFO (jetty-launcher-25366-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 4511842 INFO (jetty-launcher-25366-thread-4) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 12.0.1+12
[junit4] 2> 4511853 INFO (jetty-launcher-25366-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4511853 INFO (jetty-launcher-25366-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4511853 INFO (jetty-launcher-25366-thread-3) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 4511855 INFO (jetty-launcher-25366-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@526017f8{/solr,null,AVAILABLE}
[junit4] 2> 4511857 INFO (jetty-launcher-25366-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4511857 INFO (jetty-launcher-25366-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4511857 INFO (jetty-launcher-25366-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 4511862 INFO (jetty-launcher-25366-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@17707820{/solr,null,AVAILABLE}
[junit4] 2> 4511862 INFO (jetty-launcher-25366-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4f5e048f{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:50770}
[junit4] 2> 4511862 INFO (jetty-launcher-25366-thread-3) [ ] o.e.j.s.Server Started @4511939ms
[junit4] 2> 4511862 INFO (jetty-launcher-25366-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=50770}
[junit4] 2> 4511862 INFO (jetty-launcher-25366-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2c5be7fd{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:50771}
[junit4] 2> 4511862 INFO (jetty-launcher-25366-thread-2) [ ] o.e.j.s.Server Started @4511939ms
[junit4] 2> 4511862 INFO (jetty-launcher-25366-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=50771}
[junit4] 2> 4511863 ERROR (jetty-launcher-25366-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 4511863 ERROR (jetty-launcher-25366-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 4511863 INFO (jetty-launcher-25366-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4511863 INFO (jetty-launcher-25366-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4511863 INFO (jetty-launcher-25366-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 4511863 INFO (jetty-launcher-25366-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 4511863 INFO (jetty-launcher-25366-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4511863 INFO (jetty-launcher-25366-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4511864 INFO (jetty-launcher-25366-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-09-28T04:12:02.724811Z
[junit4] 2> 4511864 INFO (jetty-launcher-25366-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4511864 INFO (jetty-launcher-25366-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4511864 INFO (jetty-launcher-25366-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-09-28T04:12:02.725238Z
[junit4] 2> 4511880 INFO (jetty-launcher-25366-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4511880 INFO (jetty-launcher-25366-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4511880 INFO (jetty-launcher-25366-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 4511880 INFO (jetty-launcher-25366-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@67166eed{/solr,null,AVAILABLE}
[junit4] 2> 4511881 INFO (jetty-launcher-25366-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@63ac8ff4{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:50772}
[junit4] 2> 4511881 INFO (jetty-launcher-25366-thread-1) [ ] o.e.j.s.Server Started @4511957ms
[junit4] 2> 4511881 INFO (jetty-launcher-25366-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=50772}
[junit4] 2> 4511881 ERROR (jetty-launcher-25366-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 4511881 INFO (jetty-launcher-25366-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4511881 INFO (jetty-launcher-25366-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 4511882 INFO (jetty-launcher-25366-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4511882 INFO (jetty-launcher-25366-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4511882 INFO (jetty-launcher-25366-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-09-28T04:12:02.743020Z
[junit4] 2> 4511883 INFO (jetty-launcher-25366-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4511884 INFO (jetty-launcher-25366-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4511885 INFO (jetty-launcher-25366-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4511885 INFO (jetty-launcher-25366-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4511885 INFO (jetty-launcher-25366-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4511885 INFO (jetty-launcher-25366-thread-4) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 4511886 INFO (jetty-launcher-25366-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@21559917{/solr,null,AVAILABLE}
[junit4] 2> 4511886 INFO (jetty-launcher-25366-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@34bba7f1{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:50773}
[junit4] 2> 4511886 INFO (jetty-launcher-25366-thread-4) [ ] o.e.j.s.Server Started @4511963ms
[junit4] 2> 4511886 INFO (jetty-launcher-25366-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=50773}
[junit4] 2> 4511886 ERROR (jetty-launcher-25366-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 4511886 INFO (jetty-launcher-25366-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4511887 INFO (jetty-launcher-25366-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 4511887 INFO (jetty-launcher-25366-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4511887 INFO (jetty-launcher-25366-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4511887 INFO (jetty-launcher-25366-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-09-28T04:12:02.748680Z
[junit4] 2> 4511889 INFO (jetty-launcher-25366-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4511896 INFO (zkConnectionManagerCallback-25370-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4511896 INFO (jetty-launcher-25366-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4511897 INFO (zkConnectionManagerCallback-25374-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4511897 INFO (jetty-launcher-25366-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4511897 INFO (zkConnectionManagerCallback-25368-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4511898 INFO (jetty-launcher-25366-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4511898 INFO (zkConnectionManagerCallback-25372-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4511898 INFO (jetty-launcher-25366-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4511898 INFO (jetty-launcher-25366-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 4511899 INFO (jetty-launcher-25366-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 4511899 INFO (jetty-launcher-25366-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 4511900 INFO (jetty-launcher-25366-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 4512176 INFO (jetty-launcher-25366-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 4512177 WARN (jetty-launcher-25366-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@771c0133[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4512177 WARN (jetty-launcher-25366-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@771c0133[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4512181 WARN (jetty-launcher-25366-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1c9328d0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4512181 WARN (jetty-launcher-25366-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1c9328d0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4512182 INFO (jetty-launcher-25366-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50765/solr
[junit4] 2> 4512184 INFO (jetty-launcher-25366-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4512235 INFO (zkConnectionManagerCallback-25382-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4512235 INFO (jetty-launcher-25366-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4512378 INFO (jetty-launcher-25366-thread-2) [n:127.0.0.1:50771_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4512381 INFO (zkConnectionManagerCallback-25386-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4512381 INFO (jetty-launcher-25366-thread-2) [n:127.0.0.1:50771_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4512383 INFO (jetty-launcher-25366-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 4512387 WARN (jetty-launcher-25366-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3bcf6087[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4512387 WARN (jetty-launcher-25366-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3bcf6087[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4512398 WARN (jetty-launcher-25366-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5f90fdbd[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4512398 WARN (jetty-launcher-25366-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5f90fdbd[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4512399 INFO (jetty-launcher-25366-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50765/solr
[junit4] 2> 4512400 INFO (jetty-launcher-25366-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4512426 INFO (zkConnectionManagerCallback-25394-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4512426 INFO (jetty-launcher-25366-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4512542 INFO (jetty-launcher-25366-thread-1) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4512550 INFO (zkConnectionManagerCallback-25396-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4512550 INFO (jetty-launcher-25366-thread-1) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4512574 INFO (jetty-launcher-25366-thread-1) [n:127.0.0.1:50772_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:50772_solr
[junit4] 2> 4512575 INFO (jetty-launcher-25366-thread-1) [n:127.0.0.1:50772_solr ] o.a.s.c.Overseer Overseer (id=72117295053799434-127.0.0.1:50772_solr-n_0000000000) starting
[junit4] 2> 4512597 INFO (jetty-launcher-25366-thread-1) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4512604 INFO (zkConnectionManagerCallback-25403-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4512604 INFO (jetty-launcher-25366-thread-1) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4512619 INFO (jetty-launcher-25366-thread-1) [n:127.0.0.1:50772_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50765/solr ready
[junit4] 2> 4512621 INFO (OverseerStateUpdate-72117295053799434-127.0.0.1:50772_solr-n_0000000000) [n:127.0.0.1:50772_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:50772_solr
[junit4] 2> 4512639 INFO (jetty-launcher-25366-thread-1) [n:127.0.0.1:50772_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50772_solr
[junit4] 2> 4512646 INFO (zkCallback-25402-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4512648 INFO (zkCallback-25395-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4512651 INFO (zkCallback-25385-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4512657 INFO (jetty-launcher-25366-thread-1) [n:127.0.0.1:50772_solr ] o.a.s.c.PackageManager clusterprops.json changed , version -1
[junit4] 2> 4512673 INFO (jetty-launcher-25366-thread-2) [n:127.0.0.1:50771_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:50771_solr as DOWN
[junit4] 2> 4512676 INFO (jetty-launcher-25366-thread-2) [n:127.0.0.1:50771_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 4512676 INFO (jetty-launcher-25366-thread-2) [n:127.0.0.1:50771_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50771_solr
[junit4] 2> 4512679 INFO (zkCallback-25402-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 4512679 INFO (zkCallback-25385-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 4512679 INFO (zkCallback-25395-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 4512684 INFO (jetty-launcher-25366-thread-2) [n:127.0.0.1:50771_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4512691 INFO (zkConnectionManagerCallback-25408-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4512691 INFO (jetty-launcher-25366-thread-2) [n:127.0.0.1:50771_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4512695 INFO (jetty-launcher-25366-thread-2) [n:127.0.0.1:50771_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 4512698 INFO (jetty-launcher-25366-thread-1) [n:127.0.0.1:50772_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 4512700 INFO (jetty-launcher-25366-thread-2) [n:127.0.0.1:50771_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50765/solr ready
[junit4] 2> 4512700 INFO (jetty-launcher-25366-thread-2) [n:127.0.0.1:50771_solr ] o.a.s.c.PackageManager clusterprops.json changed , version -1
[junit4] 2> 4512772 INFO (jetty-launcher-25366-thread-2) [n:127.0.0.1:50771_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 4512794 INFO (jetty-launcher-25366-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 4512806 WARN (jetty-launcher-25366-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3d20651f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4512806 WARN (jetty-launcher-25366-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3d20651f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4512809 WARN (jetty-launcher-25366-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3d4b0362[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4512809 WARN (jetty-launcher-25366-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3d4b0362[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4512810 INFO (jetty-launcher-25366-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50765/solr
[junit4] 2> 4512811 INFO (jetty-launcher-25366-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4512815 INFO (zkConnectionManagerCallback-25416-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4512816 INFO (jetty-launcher-25366-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4512831 INFO (jetty-launcher-25366-thread-1) [n:127.0.0.1:50772_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50772.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2ebce062
[junit4] 2> 4512838 INFO (jetty-launcher-25366-thread-2) [n:127.0.0.1:50771_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50771.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2ebce062
[junit4] 2> 4512849 INFO (jetty-launcher-25366-thread-1) [n:127.0.0.1:50772_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50772.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2ebce062
[junit4] 2> 4512849 INFO (jetty-launcher-25366-thread-1) [n:127.0.0.1:50772_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50772.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2ebce062
[junit4] 2> 4512851 INFO (jetty-launcher-25366-thread-1) [n:127.0.0.1:50772_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.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node1/.
[junit4] 2> 4512861 INFO (jetty-launcher-25366-thread-2) [n:127.0.0.1:50771_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50771.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2ebce062
[junit4] 2> 4512861 INFO (jetty-launcher-25366-thread-2) [n:127.0.0.1:50771_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50771.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2ebce062
[junit4] 2> 4512864 INFO (jetty-launcher-25366-thread-2) [n:127.0.0.1:50771_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.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node2/.
[junit4] 2> 4512937 INFO (jetty-launcher-25366-thread-3) [n:127.0.0.1:50770_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4512943 INFO (zkConnectionManagerCallback-25418-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4512943 INFO (jetty-launcher-25366-thread-3) [n:127.0.0.1:50770_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4512955 INFO (jetty-launcher-25366-thread-3) [n:127.0.0.1:50770_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 4512963 INFO (jetty-launcher-25366-thread-3) [n:127.0.0.1:50770_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:50770_solr as DOWN
[junit4] 2> 4512969 INFO (jetty-launcher-25366-thread-3) [n:127.0.0.1:50770_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 4512969 INFO (jetty-launcher-25366-thread-3) [n:127.0.0.1:50770_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50770_solr
[junit4] 2> 4512973 INFO (zkCallback-25395-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4512973 INFO (zkCallback-25402-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4512974 INFO (zkCallback-25385-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4512978 INFO (zkCallback-25407-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4512978 INFO (zkCallback-25417-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4512980 INFO (jetty-launcher-25366-thread-3) [n:127.0.0.1:50770_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4512983 INFO (zkConnectionManagerCallback-25425-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4512983 INFO (jetty-launcher-25366-thread-3) [n:127.0.0.1:50770_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4512986 INFO (jetty-launcher-25366-thread-3) [n:127.0.0.1:50770_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 4512989 INFO (jetty-launcher-25366-thread-3) [n:127.0.0.1:50770_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50765/solr ready
[junit4] 2> 4512989 INFO (jetty-launcher-25366-thread-3) [n:127.0.0.1:50770_solr ] o.a.s.c.PackageManager clusterprops.json changed , version -1
[junit4] 2> 4513023 INFO (jetty-launcher-25366-thread-3) [n:127.0.0.1:50770_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 4513142 INFO (jetty-launcher-25366-thread-3) [n:127.0.0.1:50770_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50770.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2ebce062
[junit4] 2> 4513171 INFO (jetty-launcher-25366-thread-3) [n:127.0.0.1:50770_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50770.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2ebce062
[junit4] 2> 4513171 INFO (jetty-launcher-25366-thread-3) [n:127.0.0.1:50770_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50770.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2ebce062
[junit4] 2> 4513173 INFO (jetty-launcher-25366-thread-3) [n:127.0.0.1:50770_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.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node3/.
[junit4] 2> 4513234 INFO (jetty-launcher-25366-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 4513235 WARN (jetty-launcher-25366-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7f216ce9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4513235 WARN (jetty-launcher-25366-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7f216ce9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4513264 WARN (jetty-launcher-25366-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@fd2309e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4513264 WARN (jetty-launcher-25366-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@fd2309e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4513265 INFO (jetty-launcher-25366-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50765/solr
[junit4] 2> 4513266 INFO (jetty-launcher-25366-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4513284 INFO (zkConnectionManagerCallback-25432-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4513285 INFO (jetty-launcher-25366-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4513389 INFO (jetty-launcher-25366-thread-4) [n:127.0.0.1:50773_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4513398 INFO (zkConnectionManagerCallback-25434-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4513398 INFO (jetty-launcher-25366-thread-4) [n:127.0.0.1:50773_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4513417 INFO (jetty-launcher-25366-thread-4) [n:127.0.0.1:50773_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 4513433 INFO (jetty-launcher-25366-thread-4) [n:127.0.0.1:50773_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:50773_solr as DOWN
[junit4] 2> 4513436 INFO (jetty-launcher-25366-thread-4) [n:127.0.0.1:50773_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 4513436 INFO (jetty-launcher-25366-thread-4) [n:127.0.0.1:50773_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50773_solr
[junit4] 2> 4513440 INFO (zkCallback-25407-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4513440 INFO (zkCallback-25395-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4513440 INFO (zkCallback-25385-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4513441 INFO (zkCallback-25402-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4513441 INFO (zkCallback-25417-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4513441 INFO (zkCallback-25424-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4513441 INFO (zkCallback-25433-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4513448 INFO (jetty-launcher-25366-thread-4) [n:127.0.0.1:50773_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4513450 INFO (zkConnectionManagerCallback-25441-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4513450 INFO (jetty-launcher-25366-thread-4) [n:127.0.0.1:50773_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4513456 INFO (jetty-launcher-25366-thread-4) [n:127.0.0.1:50773_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 4513461 INFO (jetty-launcher-25366-thread-4) [n:127.0.0.1:50773_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50765/solr ready
[junit4] 2> 4513462 INFO (jetty-launcher-25366-thread-4) [n:127.0.0.1:50773_solr ] o.a.s.c.PackageManager clusterprops.json changed , version -1
[junit4] 2> 4513490 INFO (jetty-launcher-25366-thread-4) [n:127.0.0.1:50773_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 4513520 INFO (jetty-launcher-25366-thread-4) [n:127.0.0.1:50773_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50773.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2ebce062
[junit4] 2> 4513532 INFO (jetty-launcher-25366-thread-4) [n:127.0.0.1:50773_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50773.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2ebce062
[junit4] 2> 4513532 INFO (jetty-launcher-25366-thread-4) [n:127.0.0.1:50773_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50773.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2ebce062
[junit4] 2> 4513534 INFO (jetty-launcher-25366-thread-4) [n:127.0.0.1:50773_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.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/.
[junit4] 2> 4513587 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[8BB738A4992805A5]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=4
[junit4] 2> 4513589 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[8BB738A4992805A5]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4513591 INFO (zkConnectionManagerCallback-25447-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4513591 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[8BB738A4992805A5]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4513595 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[8BB738A4992805A5]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 4513598 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[8BB738A4992805A5]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50765/solr ready
[junit4] 2> 4513599 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[8BB738A4992805A5]) [ ] o.a.s.u.p.DimensionalRoutedAliasUpdateProcessorTest SolrClient: org.apache.solr.client.solrj.impl.CloudSolrClient@1348eb6c
[junit4] 2> 4513600 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[8BB738A4992805A5]) [ ] o.a.s.u.p.DimensionalRoutedAliasUpdateProcessorTest ClusterStateProvider 127.0.0.1:50765/solr
[junit4] 2> 4513601 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[8BB738A4992805A5]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4513604 INFO (zkConnectionManagerCallback-25452-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4513604 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[8BB738A4992805A5]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4513609 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[8BB738A4992805A5]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 4513611 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[8BB738A4992805A5]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50765/solr ready
[junit4] 2> 4513616 INFO (qtp1100604108-66097) [n:127.0.0.1:50770_solr ] o.a.s.h.a.ConfigSetsHandler Invoked ConfigSet Action :create with params baseConfigSet=_default&name=testTimeCat&action=CREATE&wt=javabin&version=2
[junit4] 2> 4513625 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.OverseerConfigSetMessageHandler OverseerConfigSetMessageHandler.processMessage : create , {
[junit4] 2> "name":"testTimeCat",
[junit4] 2> "baseConfigSet":"_default",
[junit4] 2> "immutable":"false",
[junit4] 2> "operation":"configsets:create"}
[junit4] 2> 4513630 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testTimeCat/managed-schema
[junit4] 2> 4513636 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testTimeCat/protwords.txt
[junit4] 2> 4513641 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testTimeCat/solrconfig.xml
[junit4] 2> 4513650 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/testTimeCat/synonyms.txt
[junit4] 2> 4513654 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/testTimeCat/stopwords.txt
[junit4] 2> 4513662 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/testTimeCat/lang/contractions_it.txt
[junit4] 2> 4513666 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/testTimeCat/lang/contractions_ca.txt
[junit4] 2> 4513671 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/testTimeCat/lang/stemdict_nl.txt
[junit4] 2> 4513676 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/testTimeCat/lang/stopwords_hy.txt
[junit4] 2> 4513681 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/testTimeCat/lang/stopwords_no.txt
[junit4] 2> 4513685 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/testTimeCat/lang/stopwords_id.txt
[junit4] 2> 4513690 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_et.txt to /configs/testTimeCat/lang/stopwords_et.txt
[junit4] 2> 4513694 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/testTimeCat/lang/stopwords_da.txt
[junit4] 2> 4513698 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/testTimeCat/lang/stopwords_ga.txt
[junit4] 2> 4513702 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/testTimeCat/lang/stopwords_hi.txt
[junit4] 2> 4513707 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/testTimeCat/lang/stopwords_ja.txt
[junit4] 2> 4513711 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/testTimeCat/lang/stopwords_pt.txt
[junit4] 2> 4513715 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/testTimeCat/lang/stopwords_hu.txt
[junit4] 2> 4513720 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/testTimeCat/lang/stopwords_el.txt
[junit4] 2> 4513724 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/testTimeCat/lang/stopwords_ru.txt
[junit4] 2> 4513728 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/testTimeCat/lang/stopwords_tr.txt
[junit4] 2> 4513738 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/testTimeCat/lang/stopwords_ar.txt
[junit4] 2> 4513742 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/testTimeCat/lang/userdict_ja.txt
[junit4] 2> 4513746 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/testTimeCat/lang/stopwords_eu.txt
[junit4] 2> 4513750 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/testTimeCat/lang/stopwords_it.txt
[junit4] 2> 4513755 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/testTimeCat/lang/stopwords_cz.txt
[junit4] 2> 4513759 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/testTimeCat/lang/stoptags_ja.txt
[junit4] 2> 4513763 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/testTimeCat/lang/contractions_fr.txt
[junit4] 2> 4513768 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/testTimeCat/lang/stopwords_de.txt
[junit4] 2> 4513772 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/testTimeCat/lang/stopwords_fa.txt
[junit4] 2> 4513777 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/testTimeCat/lang/contractions_ga.txt
[junit4] 2> 4513781 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/testTimeCat/lang/stopwords_ca.txt
[junit4] 2> 4513785 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/testTimeCat/lang/stopwords_nl.txt
[junit4] 2> 4513790 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/testTimeCat/lang/stopwords_bg.txt
[junit4] 2> 4513793 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/testTimeCat/lang/stopwords_en.txt
[junit4] 2> 4513802 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/testTimeCat/lang/stopwords_es.txt
[junit4] 2> 4513806 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/testTimeCat/lang/stopwords_lv.txt
[junit4] 2> 4513816 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/testTimeCat/lang/stopwords_ro.txt
[junit4] 2> 4513820 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/testTimeCat/lang/stopwords_th.txt
[junit4] 2> 4513825 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/testTimeCat/lang/stopwords_fr.txt
[junit4] 2> 4513828 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/testTimeCat/lang/stopwords_sv.txt
[junit4] 2> 4513832 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/testTimeCat/lang/stopwords_fi.txt
[junit4] 2> 4513836 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/testTimeCat/lang/hyphenations_ga.txt
[junit4] 2> 4513840 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/testTimeCat/lang/stopwords_gl.txt
[junit4] 2> 4513845 INFO (OverseerThreadFactory-21122-thread-1-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/params.json to /configs/testTimeCat/params.json
[junit4] 2> 4513851 INFO (qtp1100604108-66097) [n:127.0.0.1:50770_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/configs params={baseConfigSet=_default&name=testTimeCat&action=CREATE&wt=javabin&version=2} status=0 QTime=235
[junit4] 2> 4513852 INFO (qtp1100604108-66099) [n:127.0.0.1:50770_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=testTimeCat&name=testTimeCat&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 4513859 INFO (OverseerThreadFactory-21122-thread-2-processing-n:127.0.0.1:50772_solr) [n:127.0.0.1:50772_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testTimeCat
[junit4] 2> 4513859 INFO (OverseerCollectionConfigSetProcessor-72117295053799434-127.0.0.1:50772_solr-n_0000000000) [n:127.0.0.1:50772_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 4513987 INFO (OverseerStateUpdate-72117295053799434-127.0.0.1:50772_solr-n_0000000000) [n:127.0.0.1:50772_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testTimeCat",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testTimeCat_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:50773/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 4514198 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr x:testTimeCat_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=testTimeCat&newCollection=true&name=testTimeCat_shard1_replica_n1&action=CREATE&numShards=1&collection=testTimeCat&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 4515232 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 4515232 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 4515233 WARN (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../contrib/extraction/lib
[junit4] 2> 4515233 WARN (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../dist
[junit4] 2> 4515233 WARN (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../contrib/clustering/lib
[junit4] 2> 4515233 WARN (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../dist
[junit4] 2> 4515234 WARN (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../contrib/langid/lib
[junit4] 2> 4515234 WARN (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../dist
[junit4] 2> 4515234 WARN (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../contrib/velocity/lib
[junit4] 2> 4515234 WARN (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../dist
[junit4] 2> 4515234 WARN (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../dist filtered by solr-ltr-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../dist
[junit4] 2> 4515235 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 4515263 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.s.IndexSchema [testTimeCat_shard1_replica_n1] Schema name=default-config
[junit4] 2> 4515575 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 4515575 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'testTimeCat_shard1_replica_n1' using configuration from collection testTimeCat, trusted=true
[junit4] 2> 4515576 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50773.solr.core.testTimeCat.shard1.replica_n1' (registry 'solr.core.testTimeCat.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2ebce062
[junit4] 2> 4515576 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.SolrCore [[testTimeCat_shard1_replica_n1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/./testTimeCat_shard1_replica_n1/data/]
[junit4] 2> 4515580 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
[junit4] 2> 4515672 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 4515672 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 4515674 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 4515674 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 4515684 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@237c57e7[testTimeCat_shard1_replica_n1] main]
[junit4] 2> 4515688 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/testTimeCat
[junit4] 2> 4515689 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/testTimeCat
[junit4] 2> 4515689 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/testTimeCat/managed-schema
[junit4] 2> 4515690 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 4515691 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 4515691 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 2> 4515696 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 4515696 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1645890949933432832
[junit4] 2> 4515699 INFO (searcherExecutor-21145-thread-1-processing-n:127.0.0.1:50773_solr x:testTimeCat_shard1_replica_n1 c:testTimeCat s:shard1 r:core_node2) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@237c57e7[testTimeCat_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4515699 INFO (searcherExecutor-21145-thread-1-processing-n:127.0.0.1:50773_solr x:testTimeCat_shard1_replica_n1 c:testTimeCat s:shard1 r:core_node2) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 4515699 INFO (searcherExecutor-21145-thread-1-processing-n:127.0.0.1:50773_solr x:testTimeCat_shard1_replica_n1 c:testTimeCat s:shard1 r:core_node2) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 4515700 INFO (searcherExecutor-21145-thread-1-processing-n:127.0.0.1:50773_solr x:testTimeCat_shard1_replica_n1 c:testTimeCat s:shard1 r:core_node2) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.SolrCore [testTimeCat_shard1_replica_n1] Registered new searcher Searcher@237c57e7[testTimeCat_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4515706 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testTimeCat/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 4515707 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testTimeCat/leaders/shard1
[junit4] 2> 4515715 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 4515715 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 4515715 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:50773/solr/testTimeCat_shard1_replica_n1/
[junit4] 2> 4515716 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 4515717 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:50773/solr/testTimeCat_shard1_replica_n1/ has no replicas
[junit4] 2> 4515717 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testTimeCat/leaders/shard1/leader after winning as /collections/testTimeCat/leader_elect/shard1/election/72117295053799441-core_node2-n_0000000000
[junit4] 2> 4515724 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:50773/solr/testTimeCat_shard1_replica_n1/ shard1
[junit4] 2> 4515834 INFO (zkCallback-25433-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testTimeCat/state.json] for collection [testTimeCat] has occurred - updating... (live nodes size: [4])
[junit4] 2> 4515835 INFO (zkCallback-25433-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testTimeCat/state.json] for collection [testTimeCat] has occurred - updating... (live nodes size: [4])
[junit4] 2> 4515836 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 4515841 INFO (qtp981225166-66126) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=testTimeCat&newCollection=true&name=testTimeCat_shard1_replica_n1&action=CREATE&numShards=1&collection=testTimeCat&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1642
[junit4] 2> 4515844 INFO (qtp1100604108-66099) [n:127.0.0.1:50770_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 4515867 INFO (OverseerCollectionConfigSetProcessor-72117295053799434-127.0.0.1:50772_solr-n_0000000000) [n:127.0.0.1:50772_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 4515941 INFO (zkCallback-25417-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testTimeCat/state.json] for collection [testTimeCat] has occurred - updating... (live nodes size: [4])
[junit4] 2> 4515941 INFO (zkCallback-25433-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testTimeCat/state.json] for collection [testTimeCat] has occurred - updating... (live nodes size: [4])
[junit4] 2> 4515941 INFO (zkCallback-25433-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testTimeCat/state.json] for collection [testTimeCat] has occurred - updating... (live nodes size: [4])
[junit4] 2> 4515942 INFO (zkCallback-25433-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testTimeCat/state.json] for collection [testTimeCat] has occurred - updating... (live nodes size: [4])
[junit4] 2> 4515944 INFO (qtp1100604108-66099) [n:127.0.0.1:50770_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=testTimeCat&name=testTimeCat&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=2091
[junit4] 2> 4515946 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[8BB738A4992805A5]) [ ] o.a.s.c.SolrCloudTestCase waitForState (testTimeCat): waiting for collections to be created
[junit4] 2> 4516470 INFO (qtp981225166-66128) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler Executed config commands successfully and persisted to ZK [{"set-user-property":{"update.autoCreateFields":false}}, {"add-updateprocessor":{
[junit4] 2> "name":"tolerant",
[junit4] 2> "class":"solr.TolerantUpdateProcessorFactory"}}, {"add-updateprocessor":{
[junit4] 2> "name":"tracking-testSliceRouting",
[junit4] 2> "class":"solr.TrackingUpdateProcessorFactory",
[junit4] 2> "group":"testTimeCat"}}, {"add-updateprocessor":{
[junit4] 2> "name":"inc",
[junit4] 2> "class":"org.apache.solr.update.processor.RoutedAliasUpdateProcessorTest$IncrementURPFactory",
[junit4] 2> "fieldName":"integer_i"}}]
[junit4] 2> 4516471 INFO (qtp981225166-66128) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler Waiting up to 30 secs for 1 replicas to set the property overlay to be of version 0 for collection testTimeCat
[junit4] 2> 4516472 INFO (Thread-8815) [ ] o.a.s.c.SolrCore config update listener called for core testTimeCat_shard1_replica_n1
[junit4] 2> 4516473 INFO (Thread-8815) [ ] o.a.s.c.SolrCore core reload testTimeCat_shard1_replica_n1
[junit4] 2> 4516473 INFO (solrHandlerExecutor-21150-thread-1-processing-n:127.0.0.1:50773_solr x:testTimeCat_shard1_replica_n1 c:testTimeCat s:shard1 r:core_node2) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 4516486 INFO (Thread-8815) [ ] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 4516486 INFO (Thread-8815) [ ] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 4516486 WARN (Thread-8815) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../contrib/extraction/lib
[junit4] 2> 4516486 WARN (Thread-8815) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../dist
[junit4] 2> 4516486 WARN (Thread-8815) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../contrib/clustering/lib
[junit4] 2> 4516487 WARN (Thread-8815) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../dist
[junit4] 2> 4516487 WARN (Thread-8815) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../contrib/langid/lib
[junit4] 2> 4516487 WARN (Thread-8815) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../dist
[junit4] 2> 4516487 WARN (Thread-8815) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../contrib/velocity/lib
[junit4] 2> 4516487 WARN (Thread-8815) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../dist
[junit4] 2> 4516487 WARN (Thread-8815) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../dist filtered by solr-ltr-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A4992805A5-001/tempDir-001/node4/testTimeCat_shard1_replica_n1/../../../../dist
[junit4] 2> 4516488 INFO (Thread-8815) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 4516525 INFO (Thread-8815) [ ] o.a.s.s.IndexSchema [testTimeCat_shard1_replica_n1] Schema name=default-config
[junit4] 2> 4516596 INFO (qtp981225166-66132) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler expecting overlay version 0 but my version is -1
[junit4] 2> 4516596 INFO (qtp981225166-66132) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.S.Request [testTimeCat_shard1_replica_n1] webapp=/solr path=/config/znodeVersion params={overlay=0&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 4516596 INFO (SolrConfigHandler-refreshconf) [ ] o.a.s.h.SolrConfigHandler Another reload is in progress . Not doing anything
[junit4] 2> 4516597 INFO (solrHandlerExecutor-21150-thread-1-processing-n:127.0.0.1:50773_solr x:testTimeCat_shard1_replica_n1 c:testTimeCat s:shard1 r:core_node2) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler Could not get expectedVersion 0 from http://127.0.0.1:50773/solr/testTimeCat_shard1_replica_n1/ for prop overlay after 0 attempts
[junit4] 2> 4516597 INFO (solrHandlerExecutor-21150-thread-1-processing-n:127.0.0.1:50773_solr x:testTimeCat_shard1_replica_n1 c:testTimeCat s:shard1 r:core_node2) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 4516698 INFO (qtp981225166-66125) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler expecting overlay version 0 but my version is -1
[junit4] 2> 4516698 INFO (qtp981225166-66125) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.S.Request [testTimeCat_shard1_replica_n1] webapp=/solr path=/config/znodeVersion params={overlay=0&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 4516698 INFO (SolrConfigHandler-refreshconf) [ ] o.a.s.h.SolrConfigHandler Another reload is in progress . Not doing anything
[junit4] 2> 4516699 INFO (solrHandlerExecutor-21150-thread-1-processing-n:127.0.0.1:50773_solr x:testTimeCat_shard1_replica_n1 c:testTimeCat s:shard1 r:core_node2) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler Could not get expectedVersion 0 from http://127.0.0.1:50773/solr/testTimeCat_shard1_replica_n1/ for prop overlay after 1 attempts
[junit4] 2> 4516699 INFO (solrHandlerExecutor-21150-thread-1-processing-n:127.0.0.1:50773_solr x:testTimeCat_shard1_replica_n1 c:testTimeCat s:shard1 r:core_node2) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 4516746 INFO (Thread-8815) [ ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 4516746 INFO (Thread-8815) [ ] o.a.s.c.CoreContainer Reloading SolrCore 'testTimeCat_shard1_replica_n1' using configuration from collection testTimeCat
[junit4] 2> 4516776 INFO (Thread-8815) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50773.solr.core.testTimeCat.shard1.replica_n1' (registry 'solr.core.testTimeCat.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2ebce062
[junit4] 2> 4516776 INFO (Thread-8815) [n:127.0.0.1:50773_solr c:testTimeCat s:shard1 r:core_node2 x:testTimeCat_shard1_replica_n1 ] o.a.s.c.SolrCore [[testTimeCat_shard1_replica_n1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_8BB738A
[...truncated too long message...]
ockOnStartupTest, TestPullReplica, LoggingHandlerTest, TestJettySolrRunner, TestSuggestSpellingConverter, TestReplicationHandler, MoreLikeThisHandlerTest, TestFreeTextSuggestions, TestReplicaProperties, ChaosMonkeySafeLeaderWithPullReplicasTest, TestFieldCacheWithThreads, SliceStateTest, CacheHeaderTest, TestCloudRecovery, TestHighFrequencyDictionaryFactory, SolrIndexSplitterTest, TestShortCircuitedRequests, TestBinaryField, TestFastLRUCache, TriggerEventQueueTest, TestCollapseQParserPlugin, TestSubQueryTransformerCrossCore, SolrMetricManagerTest, ResourceLoaderTest, TestSchemaResource, BasicAuthStandaloneTest, ConcurrentDeleteAndCreateCollectionTest, CollectionsAPIAsyncDistributedZkTest, TestJsonFacetsWithNestedObjects, TestManagedSynonymFilterFactory, CollectionPropsTest, SpatialFilterTest, BasicAuthIntegrationTest, TestFoldingMultitermQuery, TestGraphMLResponseWriter, OverseerCollectionConfigSetProcessorTest, AutoScalingHandlerTest, TestCoreContainer, ChaosMonkeyNothingIsSafeWithPullReplicasTest, CollectionsAPISolrJTest, SolrInfoBeanTest, TestMultiValuedNumericRangeQuery, TestChildDocTransformer, DeleteInactiveReplicaTest, TestBM25SimilarityFactory, TestSimLargeCluster, TestSolrDeletionPolicy2, TestOmitPositions, ZkFailoverTest, TestIndexingPerformance, SystemCollectionCompatTest, TestExpandComponent, FieldMutatingUpdateProcessorTest, DocumentBuilderTest, ExternalFileFieldSortTest, TestOnReconnectListenerSupport, IndexSchemaTest, SearchRateTriggerTest, HdfsRestartWhileUpdatingTest, TestRetrieveFieldsOptimizer, BitVectorTest, SolrJmxReporterCloudTest, TestCloudPivotFacet, AssignTest, ResponseLogComponentTest, HdfsBasicDistributedZkTest, FullHLLTest, TestSolrXml, TestReload, SpellCheckComponentTest, EchoParamsTest, HdfsUnloadDistributedZkTest, LeaderElectionIntegrationTest, DistributedUpdateProcessorTest, TestSlowCompositeReaderWrapper, TestDFISimilarityFactory, CopyFieldTest, FullSolrCloudDistribCmdsTest, TestStressVersions, TestDocTermOrdsUninvertLimit, TestSolrJ, TestCodecSupport, TestReplicationHandlerDiskOverFlow, TestUniqueKeyFieldResource, LargeFieldTest, TestAnalyzeInfixSuggestions, TestLuceneMatchVersion, InfixSuggestersTest, RegexBoostProcessorTest, TestRecovery, ExecutePlanActionTest, TestTolerantUpdateProcessorCloud, TestQuerySenderNoQuery, TestSimTriggerIntegration, TestNumericRangeQuery64, CleanupOldIndexTest, SubstringBytesRefFilterTest, TestFastOutputStream, ConvertedLegacyTest, NotRequiredUniqueKeyTest, TestPHPSerializedResponseWriter, MetricsHistoryWithAuthIntegrationTest, SuggesterFSTTest, TestManagedSchemaAPI, TestFilteredDocIdSet, DateFieldTest, LegacyCloudClusterPropTest, TestJoin, TestInitParams, TestBlendedInfixSuggestions, HDFSCollectionsAPITest, RemoteQueryErrorTest, PrimitiveFieldTypeTest, TestCloudNestedDocsSort, SchemaVersionSpecificBehaviorTest, BadCopyFieldTest, AuditLoggerIntegrationTest, TestConfigSetProperties, BlockJoinFacetDistribTest, TestDistribIDF, SpellCheckCollatorTest, OutOfBoxZkACLAndCredentialsProvidersTest, SimpleCollectionCreateDeleteTest, TestPayloadCheckQParserPlugin, CoreMergeIndexesAdminHandlerTest, TestSkipOverseerOperations, TestFieldResource, DistributedDebugComponentTest, TestSolrCoreParser, ParsingFieldUpdateProcessorsTest, TestUnInvertedFieldException, TestPivotHelperCode, Tagger2Test, RegexBytesRefFilterTest, OverseerRolesTest, TestConfigSetsAPIZkFailure, SoftAutoCommitTest, BufferStoreTest, TestConfigSetsAPI, TestDocumentBuilder, TestGroupingSearch, TestHighlightDedupGrouping, TestRandomFaceting, TestSolrCoreProperties, TestSolrTestCaseJ4, TestTolerantSearch, ProtectedTermFilterFactoryTest, TokenizerChainTest, TestLuceneIndexBackCompat, AddReplicaTest, AssignBackwardCompatibilityTest, ClusterStateTest, ClusterStateUpdateTest, ConfigSetsAPITest, DeleteNodeTest, DeleteShardTest, DeleteStatusTest, DistribCursorPagingTest, DistribDocExpirationUpdateProcessorTest, DistributedQueueTest, DocValuesNotIndexedTest, ForceLeaderTest, ForceLeaderWithTlogReplicasTest, HttpPartitionOnCommitTest, HttpPartitionWithTlogReplicasTest, MultiSolrCloudTestCaseTest, MultiThreadedOCPTest, NestedShardedAtomicUpdateTest, NodeMutatorTest, PeerSyncReplicationTest, ReplaceNodeTest, ReplicationFactorTest, RestartWhileUpdatingTest, RollingRestartTest, SaslZkACLProviderTest, ShardRoutingCustomTest, TestCloudSearcherWarming, TestRandomFlRTGCloud, TestSolrCloudWithKerberosAlt, TestStressCloudBlindAtomicUpdates, TestStressLiveNodes, TestZkChroot, TlogReplayBufferedWhileIndexingTest, TriLevelCompositeIdRoutingTest, UnloadDistributedZkTest, VMParamsZkACLAndCredentialsProvidersTest, ZkCLITest, CollectionReloadTest, CollectionTooManyReplicasTest, AutoAddReplicasIntegrationTest, MetricTriggerIntegrationTest, MetricTriggerTest, NodeAddedTriggerIntegrationTest, NodeAddedTriggerTest, NodeLostTriggerIntegrationTest, NodeLostTriggerTest, NodeMarkersRegistrationTest, RestoreTriggerStateTest, ScheduledMaintenanceTriggerTest, ScheduledTriggerIntegrationTest, ScheduledTriggerTest, SearchRateTriggerIntegrationTest, TestPolicyCloud, TriggerSetPropertiesIntegrationTest, TestSimClusterStateProvider, TestSimComputePlanAction, TestSimExtremeIndexing, TestSimGenericDistributedQueue, CdcrOpsAndBoundariesTest, CdcrVersionReplicationTest, CdcrWithNodesRestartsTest, HdfsChaosMonkeySafeLeaderTest, HdfsNNFailoverTest, HdfsRecoverLeaseTest, HdfsRecoveryZkTest, ZkCollectionPropsCachingTest, ZkStateReaderTest, ZkStateWriterTest, CachingDirectoryFactoryTest, TestConfigOverlay, TestConfigSetImmutable, TestDynamicLoadingUrl, TestImplicitCoreProperties, TestInfoStreamLogging, TestLazyCores, CSVRequestHandlerTest, DocumentAnalysisRequestHandlerTest, FieldAnalysisRequestHandlerTest, JsonLoaderTest, RequestLoggingTest, SearchHandlerTest, TestBlobHandler, TestContainerReqHandler, TestSolrConfigHandlerConcurrent, TestSystemCollAutoCreate, V2ApiIntegrationTest, V2StandaloneTest, XsltUpdateRequestHandlerTest, AutoscalingHistoryHandlerTest, CustomTermsComponentTest, DistributedFacetPivotLongTailTest, DistributedFacetPivotSmallTest, DistributedMLTComponentTest, DistributedQueryElevationComponentTest, DistributedSpellCheckComponentTest, TestDistributedStatsComponentCardinality, TestExportWriter, JavabinLoaderTest, HighlighterMaxOffsetTest, HighlighterTest, TestLegacyFieldReuse, TestLegacyTerms, TestLogWatcher, MetricsConfigTest, SolrCoreMetricManagerTest, SolrSlf4jReporterTest, SolrShardReporterTest, TestFaceting, TestIntervalFaceting, TestRemoteStreaming, TestMacroExpander, TestMacros, JSONWriterTest, TestCustomDocTransformer, TestGeoJSONResponseWriter, TestRawTransformer, TestSubQueryTransformer, TestManagedResource, TestRestManager, TestBulkSchemaAPI, EnumFieldTest, PreAnalyzedFieldManagedSchemaCloudTest, PreAnalyzedFieldTest, ResolveAnalyzerByNameTest, SchemaApiFailureTest, SynonymTokenizerTest, TestCloudManagedSchema, TestCollationField, TestCollationFieldDocValues, TestHalfAndHalfDocValues, TestManagedSchema, TestSchemaManager, TestSchemalessBufferedUpdates, TestUseDocValuesAsStored2, UUIDFieldTest, AnalyticsMergeStrategyTest, AnalyticsQueryTest, QueryEqualityTest, QueryParsingTest, RankQueryTest, ReturnFieldsTest, TestCustomSort, TestDocSet, TestElisionMultitermQuery, TestExtendedDismaxParser, TestHashQParserPlugin, TestLRUCache, TestLegacyNumericRangeQueryBuilder, TestMaxScoreQueryParser, TestMinHashQParser, TestNoOpRegenerator, TestOverriddenPrefixQueryForCustomFieldType, TestQueryTypes, TestRTGBase, TestRandomCollapseQParserPlugin, TestReRankQParserPlugin, TestRealTimeGet, TestSearchPerf, TestSimpleQParserPlugin, TestSmileRequest, TestStandardQParsers, TestStressLucene, TestStressRecovery, TestStressReorder, TestValueSourceCache, TestXmlQParserPlugin, RangeFacetCloudTest, TestCloudJSONFacetJoinDomain, TestFunctionQuery, TestMinMaxOnMultiValuedField, DistanceFunctionTest, BJQParserTest, BJQFilterAccessibleTest, TestJsonRequest, CloudMLTQParserTest, TestImpersonationWithHadoopAuth, NoCacheHeaderTest, FileBasedSpellCheckerTest, SpellPossibilityIteratorTest, SpellingQueryConverterTest, SuggesterTest, HdfsLockFactoryTest, TestDocTermOrds, TestFieldCacheReopen, TestFieldCacheSort, TestNumericTerms32, TestNumericTerms64, RootFieldTest, TestExceedMaxTermLength, TestHdfsUpdateLog, TestInPlaceUpdateWithRouteField, TestInPlaceUpdatesRequiredField, TestInPlaceUpdatesStandalone, VersionInfoTest, AtomicUpdateProcessorFactoryTest, AtomicUpdateRemovalJavabinTest, ClassificationUpdateProcessorFactoryTest, ClassificationUpdateProcessorIntegrationTest, ClassificationUpdateProcessorTest, CloneFieldUpdateProcessorFactoryTest, DefaultValueUpdateProcessorTest, DimensionalRoutedAliasUpdateProcessorTest]
[junit4] Completed [845/878 (1!)] on J0 in 120.20s, 2 tests, 1 failure <<< FAILURES!
[...truncated 44833 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/5366/consoleText
[repro] Revision: ae72f4f542a648b8e159da258daa23ee9c2306d3
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseParallelGC"
[repro] Repro line: ant test -Dtestcase=DimensionalRoutedAliasUpdateProcessorTest -Dtests.method=testTimeCat -Dtests.seed=8BB738A4992805A5 -Dtests.slow=true -Dtests.locale=ha -Dtests.timezone=Canada/Central -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] DimensionalRoutedAliasUpdateProcessorTest
[repro] ant compile-test
[...truncated 3344 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.DimensionalRoutedAliasUpdateProcessorTest" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseParallelGC" -Dtests.seed=8BB738A4992805A5 -Dtests.slow=true -Dtests.locale=ha -Dtests.timezone=Canada/Central -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 86 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest
[repro] Exiting with code 0
[...truncated 73 lines...]