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 2020/10/10 20:45:43 UTC
[JENKINS] Lucene-Solr-8.x-Windows (64bit/jdk-12.0.2) - Build # 1714
- Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/1714/
Java: 64bit/jdk-12.0.2 -XX:+UseCompressedOops -XX:+UseSerialGC
5 tests failed.
FAILED: org.apache.solr.cloud.MultiThreadedOCPTest.test
Error Message:
acoll: 1602362720384 bcoll: 1602362721094
Stack Trace:
java.lang.AssertionError: acoll: 1602362720384 bcoll: 1602362721094
at __randomizedtesting.SeedInfo.seed([4079A8F671CD27E3:C82D972CDF314A1B]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.cloud.MultiThreadedOCPTest.testFillWorkQueue(MultiThreadedOCPTest.java:113)
at org.apache.solr.cloud.MultiThreadedOCPTest.test(MultiThreadedOCPTest.java:68)
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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1090)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1061)
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)
FAILED: org.apache.solr.cloud.MultiThreadedOCPTest.test
Error Message:
acoll: 1602362720606 bcoll: 1602362720834
Stack Trace:
java.lang.AssertionError: acoll: 1602362720606 bcoll: 1602362720834
at __randomizedtesting.SeedInfo.seed([4079A8F671CD27E3:C82D972CDF314A1B]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.cloud.MultiThreadedOCPTest.testFillWorkQueue(MultiThreadedOCPTest.java:113)
at org.apache.solr.cloud.MultiThreadedOCPTest.test(MultiThreadedOCPTest.java:68)
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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1090)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1061)
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)
FAILED: org.apache.solr.cloud.MultiThreadedOCPTest.test
Error Message:
acoll: 1602362722834 bcoll: 1602362723302
Stack Trace:
java.lang.AssertionError: acoll: 1602362722834 bcoll: 1602362723302
at __randomizedtesting.SeedInfo.seed([4079A8F671CD27E3:C82D972CDF314A1B]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.cloud.MultiThreadedOCPTest.testFillWorkQueue(MultiThreadedOCPTest.java:113)
at org.apache.solr.cloud.MultiThreadedOCPTest.test(MultiThreadedOCPTest.java:68)
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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1090)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1061)
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)
FAILED: org.apache.solr.cloud.MultiThreadedOCPTest.test
Error Message:
acoll: 1602362720397 bcoll: 1602362720708
Stack Trace:
java.lang.AssertionError: acoll: 1602362720397 bcoll: 1602362720708
at __randomizedtesting.SeedInfo.seed([4079A8F671CD27E3:C82D972CDF314A1B]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.cloud.MultiThreadedOCPTest.testFillWorkQueue(MultiThreadedOCPTest.java:113)
at org.apache.solr.cloud.MultiThreadedOCPTest.test(MultiThreadedOCPTest.java:68)
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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1090)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1061)
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)
FAILED: org.apache.solr.cloud.MultiThreadedOCPTest.test
Error Message:
acoll: 1602359642127 bcoll: 1602359642347
Stack Trace:
java.lang.AssertionError: acoll: 1602359642127 bcoll: 1602359642347
at __randomizedtesting.SeedInfo.seed([4079A8F671CD27E3:C82D972CDF314A1B]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.cloud.MultiThreadedOCPTest.testFillWorkQueue(MultiThreadedOCPTest.java:113)
at org.apache.solr.cloud.MultiThreadedOCPTest.test(MultiThreadedOCPTest.java:68)
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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1090)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1061)
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 14433 lines...]
[junit4] Suite: org.apache.solr.cloud.MultiThreadedOCPTest
[junit4] 2> 782928 INFO (SUITE-MultiThreadedOCPTest-seed#[4079A8F671CD27E3]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of 'C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\server\solr\configsets\_default\conf'
[junit4] 2> 782929 INFO (SUITE-MultiThreadedOCPTest-seed#[4079A8F671CD27E3]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 782929 INFO (SUITE-MultiThreadedOCPTest-seed#[4079A8F671CD27E3]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_4079A8F671CD27E3-001\data-dir-48-001
[junit4] 2> 782929 WARN (SUITE-MultiThreadedOCPTest-seed#[4079A8F671CD27E3]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 782929 INFO (SUITE-MultiThreadedOCPTest-seed#[4079A8F671CD27E3]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 782934 INFO (SUITE-MultiThreadedOCPTest-seed#[4079A8F671CD27E3]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 782934 INFO (SUITE-MultiThreadedOCPTest-seed#[4079A8F671CD27E3]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 782938 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 782938 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 782938 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 782942 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 783043 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.ZkTestServer start zk server on port: 63296
[junit4] 2> 783043 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:63296
[junit4] 2> 783043 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:63296
[junit4] 2> 783043 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 63296
[junit4] 2> 783051 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 783115 INFO (zkConnectionManagerCallback-4758-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 783115 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 783139 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 783185 INFO (zkConnectionManagerCallback-4760-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 783185 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 783206 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 783222 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4] 2> 783262 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 783291 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 783312 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 783330 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
[junit4] 2> 783365 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 783435 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 783465 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 783495 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 783533 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 783570 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise
[junit4] 2> 788483 WARN (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 788483 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 788483 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 788483 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 12.0.2+10
[junit4] 2> 788484 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 788484 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 788484 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 788484 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3392c63b{/,null,AVAILABLE}
[junit4] 2> 788500 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6e6e55cb{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:63340}
[junit4] 2> 788500 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.e.j.s.Server Started @788525ms
[junit4] 2> 788500 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_4079A8F671CD27E3-001\tempDir-001/control/data, hostPort=63340, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_4079A8F671CD27E3-001\control-001\cores, replicaType=NRT}
[junit4] 2> 788500 ERROR (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 788500 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 788500 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.7.0
[junit4] 2> 788500 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 788500 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 788501 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-10-10T19:53:11.119730400Z
[junit4] 2> 788506 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 788514 INFO (zkConnectionManagerCallback-4762-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 788514 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 788646 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 788646 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_4079A8F671CD27E3-001\control-001\solr.xml
[junit4] 2> 788654 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 788654 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 788654 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@412f0ffc, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 788786 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 788795 WARN (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@542f71d1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 788795 WARN (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@542f71d1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 788806 WARN (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5d29cc1b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 788806 WARN (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5d29cc1b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 788806 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63296/solr
[junit4] 2> 788810 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 788838 INFO (zkConnectionManagerCallback-4772-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 788838 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 788946 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [n:127.0.0.1:63340_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 788971 INFO (zkConnectionManagerCallback-4774-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 788971 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [n:127.0.0.1:63340_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 791770 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [n:127.0.0.1:63340_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:63340_
[junit4] 2> 791803 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [n:127.0.0.1:63340_ ] o.a.s.c.Overseer Overseer (id=72099195669315588-127.0.0.1:63340_-n_0000000000) starting
[junit4] 2> 791946 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [n:127.0.0.1:63340_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63340_
[junit4] 2> 791948 INFO (OverseerStateUpdate-72099195669315588-127.0.0.1:63340_-n_0000000000) [n:127.0.0.1:63340_ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:63340_
[junit4] 2> 791964 INFO (OverseerStateUpdate-72099195669315588-127.0.0.1:63340_-n_0000000000) [n:127.0.0.1:63340_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 791988 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [n:127.0.0.1:63340_ ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 791988 WARN (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [n:127.0.0.1:63340_ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 792026 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [n:127.0.0.1:63340_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 792089 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [n:127.0.0.1:63340_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@412f0ffc
[junit4] 2> 792102 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [n:127.0.0.1:63340_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@412f0ffc
[junit4] 2> 792102 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [n:127.0.0.1:63340_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@412f0ffc
[junit4] 2> 792102 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [n:127.0.0.1:63340_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_4079A8F671CD27E3-001\control-001\cores
[junit4] 2> 792222 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 792243 INFO (zkConnectionManagerCallback-4791-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 792243 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 792244 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 792244 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:63296/solr ready
[junit4] 2> 792258 INFO (qtp1332782489-6375) [n:127.0.0.1:63340_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:63340_&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 792289 INFO (OverseerThreadFactory-4781-thread-1-processing-n:127.0.0.1:63340_) [n:127.0.0.1:63340_ ] o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 792534 INFO (qtp1332782489-6378) [n:127.0.0.1:63340_ x:control_collection_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 792546 INFO (qtp1332782489-6378) [n:127.0.0.1:63340_ x:control_collection_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 794980 INFO (qtp1332782489-6378) [n:127.0.0.1:63340_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
[junit4] 2> 795032 INFO (qtp1332782489-6378) [n:127.0.0.1:63340_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 796100 INFO (qtp1332782489-6378) [n:127.0.0.1:63340_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 796146 INFO (qtp1332782489-6378) [n:127.0.0.1:63340_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1' using configuration from configset conf1, trusted=true
[junit4] 2> 796148 INFO (qtp1332782489-6378) [n:127.0.0.1:63340_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.control_collection.shard1.replica_n1' (registry 'solr.core.control_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@412f0ffc
[junit4] 2> 796148 INFO (qtp1332782489-6378) [n:127.0.0.1:63340_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_4079A8F671CD27E3-001\control-001\cores\control_collection_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_4079A8F671CD27E3-001\control-001\cores\control_collection_shard1_replica_n1\data\]
[junit4] 2> 796150 INFO (qtp1332782489-6378) [n:127.0.0.1:63340_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=78.9892578125, floorSegmentMB=1.3828125, forceMergeDeletesPctAllowed=25.801978641542032, segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.35007486517664455, deletesPctAllowed=39.31134438333878
[junit4] 2> 796153 WARN (qtp1332782489-6378) [n:127.0.0.1:63340_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 796346 INFO (qtp1332782489-6378) [n:127.0.0.1:63340_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 796346 INFO (qtp1332782489-6378) [n:127.0.0.1:63340_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 796352 INFO (qtp1332782489-6378) [n:127.0.0.1:63340_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 796352 INFO (qtp1332782489-6378) [n:127.0.0.1:63340_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 796352 INFO (qtp1332782489-6378) [n:127.0.0.1:63340_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=21, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 796370 INFO (qtp1332782489-6378) [n:127.0.0.1:63340_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 796370 INFO (qtp1332782489-6378) [n:127.0.0.1:63340_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 796370 INFO (qtp1332782489-6378) [n:127.0.0.1:63340_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 796370 INFO (qtp1332782489-6378) [n:127.0.0.1:63340_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1680195818868441088
[junit4] 2> 796381 INFO (searcherExecutor-4793-thread-1-processing-n:127.0.0.1:63340_ x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:63340_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 796430 INFO (qtp1332782489-6378) [n:127.0.0.1:63340_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/control_collection/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 796430 INFO (qtp1332782489-6378) [n:127.0.0.1:63340_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/control_collection/leaders/shard1
[junit4] 2> 796496 INFO (qtp1332782489-6378) [n:127.0.0.1:63340_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 796496 INFO (qtp1332782489-6378) [n:127.0.0.1:63340_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 796496 INFO (qtp1332782489-6378) [n:127.0.0.1:63340_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:63340/control_collection_shard1_replica_n1/
[junit4] 2> 796497 INFO (qtp1332782489-6378) [n:127.0.0.1:63340_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 796498 INFO (qtp1332782489-6378) [n:127.0.0.1:63340_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:63340/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 796498 INFO (qtp1332782489-6378) [n:127.0.0.1:63340_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/72099195669315588-core_node2-n_0000000000
[junit4] 2> 796527 INFO (qtp1332782489-6378) [n:127.0.0.1:63340_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:63340/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 796570 INFO (zkCallback-4773-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 796585 INFO (qtp1332782489-6378) [n:127.0.0.1:63340_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 796620 INFO (qtp1332782489-6378) [n:127.0.0.1:63340_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=4078
[junit4] 2> 796646 INFO (qtp1332782489-6375) [n:127.0.0.1:63340_ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 796730 INFO (zkCallback-4773-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 796730 INFO (zkCallback-4773-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 796737 INFO (qtp1332782489-6375) [n:127.0.0.1:63340_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:63340_&wt=javabin&version=2} status=0 QTime=4476
[junit4] 2> 796738 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Waiting to see 1 active replicas in collection: control_collection
[junit4] 2> 796858 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 796873 INFO (zkConnectionManagerCallback-4802-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 796873 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 796882 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 796882 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:63296/solr ready
[junit4] 2> 796882 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 796894 INFO (qtp1332782489-6378) [n:127.0.0.1:63340_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 796924 INFO (OverseerThreadFactory-4781-thread-2-processing-n:127.0.0.1:63340_) [n:127.0.0.1:63340_ ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 796930 INFO (OverseerCollectionConfigSetProcessor-72099195669315588-127.0.0.1:63340_-n_0000000000) [n:127.0.0.1:63340_ ] 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> 797173 WARN (OverseerThreadFactory-4781-thread-2-processing-n:127.0.0.1:63340_) [n:127.0.0.1:63340_ ] o.a.s.c.a.c.CreateCollectionCmd It is unusual to create a collection (collection1) without cores.
[junit4] 2> 797206 INFO (qtp1332782489-6378) [n:127.0.0.1:63340_ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 797207 INFO (qtp1332782489-6378) [n:127.0.0.1:63340_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=1&wt=javabin&version=2} status=0 QTime=313
[junit4] 2> 797210 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected: 2
[junit4] 2> 797210 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 797210 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected: 2
[junit4] 2> 797210 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 797210 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected: 2
[junit4] 2> 797210 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 797210 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating jetty instances pullReplicaCount=0 numOtherReplicas=3
[junit4] 2> 799001 INFO (OverseerCollectionConfigSetProcessor-72099195669315588-127.0.0.1:63340_-n_0000000000) [n:127.0.0.1:63340_ ] 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> 800229 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_4079A8F671CD27E3-001\shard-1-001 of type NRT for shard2
[junit4] 2> 800229 WARN (closeThreadPool-4803-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 800229 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 800229 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 800229 INFO (closeThreadPool-4803-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 12.0.2+10
[junit4] 2> 800229 INFO (closeThreadPool-4803-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 800229 INFO (closeThreadPool-4803-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 800229 INFO (closeThreadPool-4803-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 800229 INFO (closeThreadPool-4803-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@47a48559{/,null,AVAILABLE}
[junit4] 2> 800229 INFO (closeThreadPool-4803-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@10d0b68b{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:63480}
[junit4] 2> 800229 INFO (closeThreadPool-4803-thread-1) [ ] o.e.j.s.Server Started @800261ms
[junit4] 2> 800229 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solrconfig=solrconfig.xml, solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_4079A8F671CD27E3-001\tempDir-001/jetty1, hostPort=63480, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_4079A8F671CD27E3-001\shard-1-001\cores, replicaType=NRT}
[junit4] 2> 800229 ERROR (closeThreadPool-4803-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 800229 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 800229 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.7.0
[junit4] 2> 800229 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 800229 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 800229 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-10-10T19:53:22.847985800Z
[junit4] 2> 800237 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 800250 INFO (zkConnectionManagerCallback-4805-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 800250 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 800309 WARN (closeThreadPool-4803-thread-1-SendThread(127.0.0.1:63296)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10025d622760007.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10025d622760007, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x10025d622760007, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 800410 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 800410 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_4079A8F671CD27E3-001\shard-1-001\solr.xml
[junit4] 2> 800424 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 800424 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 800430 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@412f0ffc, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 800745 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 800760 WARN (closeThreadPool-4803-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@24fce507[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 800760 WARN (closeThreadPool-4803-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@24fce507[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 800774 WARN (closeThreadPool-4803-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@51b10aee[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 800774 WARN (closeThreadPool-4803-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@51b10aee[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 800774 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63296/solr
[junit4] 2> 800777 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 800799 INFO (zkConnectionManagerCallback-4815-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 800799 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 800826 WARN (closeThreadPool-4803-thread-1-SendThread(127.0.0.1:63296)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10025d622760008.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10025d622760008, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x10025d622760008, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 800930 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63480_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 800953 INFO (zkConnectionManagerCallback-4817-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 800953 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63480_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 800975 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63480_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 801003 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63480_ ] o.a.s.c.ZkController Publish node=127.0.0.1:63480_ as DOWN
[junit4] 2> 801018 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63480_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 801018 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63480_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63480_
[junit4] 2> 801033 INFO (zkCallback-4801-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 801033 INFO (zkCallback-4773-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 801033 INFO (zkCallback-4816-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 801038 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63480_ ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 801038 WARN (closeThreadPool-4803-thread-1) [n:127.0.0.1:63480_ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 801064 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63480_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 801089 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63480_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@412f0ffc
[junit4] 2> 801100 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63480_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@412f0ffc
[junit4] 2> 801100 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63480_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@412f0ffc
[junit4] 2> 801103 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63480_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_4079A8F671CD27E3-001\shard-1-001\cores
[junit4] 2> 801165 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:63480_
[junit4] 2> 802822 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_4079A8F671CD27E3-001\shard-2-001 of type NRT for shard1
[junit4] 2> 802822 WARN (closeThreadPool-4803-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 802822 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 802822 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 802822 INFO (closeThreadPool-4803-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 12.0.2+10
[junit4] 2> 802822 INFO (closeThreadPool-4803-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 802822 INFO (closeThreadPool-4803-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 802822 INFO (closeThreadPool-4803-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 802822 INFO (closeThreadPool-4803-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@79319b06{/,null,AVAILABLE}
[junit4] 2> 802853 INFO (closeThreadPool-4803-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@57ef6323{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:63510}
[junit4] 2> 802855 INFO (closeThreadPool-4803-thread-1) [ ] o.e.j.s.Server Started @802880ms
[junit4] 2> 802855 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solrconfig=solrconfig.xml, solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_4079A8F671CD27E3-001\tempDir-001/jetty2, hostPort=63510, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_4079A8F671CD27E3-001\shard-2-001\cores}
[junit4] 2> 802855 ERROR (closeThreadPool-4803-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 802855 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 802855 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.7.0
[junit4] 2> 802855 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 802855 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 802855 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-10-10T19:53:25.473887200Z
[junit4] 2> 802855 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 802878 INFO (zkConnectionManagerCallback-4827-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 802878 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 803003 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 803003 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_4079A8F671CD27E3-001\shard-2-001\solr.xml
[junit4] 2> 803010 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 803010 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 803019 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@412f0ffc, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 803146 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 803152 WARN (closeThreadPool-4803-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6e951367[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 803152 WARN (closeThreadPool-4803-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6e951367[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 803169 WARN (closeThreadPool-4803-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@37b86bda[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 803169 WARN (closeThreadPool-4803-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@37b86bda[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 803169 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63296/solr
[junit4] 2> 803178 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 803229 INFO (zkConnectionManagerCallback-4837-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 803229 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 803361 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63510_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 803405 INFO (zkConnectionManagerCallback-4839-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 803405 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63510_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 803429 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63510_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 803461 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63510_ ] o.a.s.c.ZkController Publish node=127.0.0.1:63510_ as DOWN
[junit4] 2> 803468 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63510_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 803468 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63510_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63510_
[junit4] 2> 803485 INFO (zkCallback-4816-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 803485 INFO (zkCallback-4801-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 803485 INFO (zkCallback-4773-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 803485 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63510_ ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 803485 INFO (zkCallback-4838-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 803485 WARN (closeThreadPool-4803-thread-1) [n:127.0.0.1:63510_ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 803522 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63510_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 803551 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63510_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@412f0ffc
[junit4] 2> 803561 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63510_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@412f0ffc
[junit4] 2> 803561 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63510_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@412f0ffc
[junit4] 2> 803563 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63510_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_4079A8F671CD27E3-001\shard-2-001\cores
[junit4] 2> 803613 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:63510_
[junit4] 2> 805474 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_4079A8F671CD27E3-001\shard-3-001 of type NRT for shard2
[junit4] 2> 805475 WARN (closeThreadPool-4803-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 805475 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 805475 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 805475 INFO (closeThreadPool-4803-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 12.0.2+10
[junit4] 2> 805476 INFO (closeThreadPool-4803-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 805476 INFO (closeThreadPool-4803-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 805476 INFO (closeThreadPool-4803-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 805476 INFO (closeThreadPool-4803-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@725e22cd{/,null,AVAILABLE}
[junit4] 2> 805479 INFO (closeThreadPool-4803-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6d309b8e{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:63586}
[junit4] 2> 805479 INFO (closeThreadPool-4803-thread-1) [ ] o.e.j.s.Server Started @805504ms
[junit4] 2> 805479 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solrconfig=solrconfig.xml, solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_4079A8F671CD27E3-001\tempDir-001/jetty3, hostPort=63586, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_4079A8F671CD27E3-001\shard-3-001\cores, replicaType=NRT}
[junit4] 2> 805480 ERROR (closeThreadPool-4803-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 805480 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 805480 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.7.0
[junit4] 2> 805480 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 805480 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 805480 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-10-10T19:53:28.098333600Z
[junit4] 2> 805482 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 805489 INFO (zkConnectionManagerCallback-4849-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 805489 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 805495 WARN (closeThreadPool-4803-thread-1-SendThread(127.0.0.1:63296)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10025d62276000d.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10025d62276000d, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x10025d62276000d, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 805597 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 805597 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_4079A8F671CD27E3-001\shard-3-001\solr.xml
[junit4] 2> 805602 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 805602 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 805602 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@412f0ffc, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 806027 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 806030 WARN (closeThreadPool-4803-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5e35ab5a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 806030 WARN (closeThreadPool-4803-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5e35ab5a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 806040 WARN (closeThreadPool-4803-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1c1d4ee7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 806040 WARN (closeThreadPool-4803-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1c1d4ee7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 806041 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63296/solr
[junit4] 2> 806044 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 806058 INFO (zkConnectionManagerCallback-4859-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 806058 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 806163 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63586_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 806180 INFO (zkConnectionManagerCallback-4861-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 806181 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63586_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 806232 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63586_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 806284 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63586_ ] o.a.s.c.ZkController Publish node=127.0.0.1:63586_ as DOWN
[junit4] 2> 806294 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63586_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 806294 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63586_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63586_
[junit4] 2> 806304 INFO (zkCallback-4838-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 806304 INFO (zkCallback-4801-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 806304 INFO (zkCallback-4816-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 806304 INFO (zkCallback-4773-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 806305 INFO (zkCallback-4860-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 806310 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63586_ ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 806311 WARN (closeThreadPool-4803-thread-1) [n:127.0.0.1:63586_ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 806384 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63586_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 806433 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63586_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@412f0ffc
[junit4] 2> 806445 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63586_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@412f0ffc
[junit4] 2> 806445 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63586_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@412f0ffc
[junit4] 2> 806451 INFO (closeThreadPool-4803-thread-1) [n:127.0.0.1:63586_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_4079A8F671CD27E3-001\shard-3-001\cores
[junit4] 2> 806536 INFO (closeThreadPool-4803-thread-1) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:63586_
[junit4] 2> 806547 INFO (qtp1332782489-6375) [n:127.0.0.1:63340_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:63480_&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 806547 INFO (qtp1332782489-6376) [n:127.0.0.1:63340_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:63510_&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 806550 INFO (qtp1332782489-6377) [n:127.0.0.1:63340_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:63586_&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 806568 INFO (OverseerThreadFactory-4781-thread-4-processing-n:127.0.0.1:63340_) [n:127.0.0.1:63340_ c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:63510_ for creating new replica of shard shard1 for collection collection1
[junit4] 2> 806568 INFO (OverseerThreadFactory-4781-thread-3-processing-n:127.0.0.1:63340_) [n:127.0.0.1:63340_ c:collection1 s:shard2 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:63480_ for creating new replica of shard shard2 for collection collection1
[junit4] 2> 806582 INFO (OverseerThreadFactory-4781-thread-4-processing-n:127.0.0.1:63340_) [n:127.0.0.1:63340_ c:collection1 s:shard1
[...truncated too long message...]
mit:
[junit4] 2>
[junit4] 2> 16 /solr/collections/collection1/state.json
[junit4] 2> 7 /solr/collections/collection1/terms/shard2
[junit4] 2> 6 /solr/aliases.json
[junit4] 2> 4 /solr/packages.json
[junit4] 2> 4 /solr/security.json
[junit4] 2> 4 /solr/configs/conf1
[junit4] 2> 3 /solr/collections/collection1/collectionprops.json
[junit4] 2> 3 /solr/collections/ocptest_shardsplit2/terms/shard2_0
[junit4] 2> 3 /solr/collections/ocptest_shardsplit2/terms/shard1_1
[junit4] 2> 3 /solr/collections/ocptest_shardsplit2/terms/shard1_0
[junit4] 2> 3 /solr/collections/control_collection/terms/shard1
[junit4] 2> 3 /solr/collections/ocptest_shardsplit2/terms/shard2_1
[junit4] 2> 3 /solr/collections/collection1/terms/shard1
[junit4] 2> 3 /solr/collections/ocptest_shardsplit2/collectionprops.json
[junit4] 2> 2 /solr/collections/ocptest_shardsplit2/terms/shard2
[junit4] 2> 2 /solr/collections/ocptest_shardsplit2/terms/shard3
[junit4] 2> 2 /solr/collections/ocptest_shardsplit2/terms/shard1
[junit4] 2> 2 /solr/collections/ocptest1/terms/shard1
[junit4] 2> 2 /solr/collections/ocptest2/terms/shard1
[junit4] 2> 2 /solr/collections/ocptest2/terms/shard2
[junit4] 2> 2 /solr/collections/ocptest3/terms/shard2
[junit4] 2> 2 /solr/collections/ocptest3/terms/shard3
[junit4] 2> 2 /solr/collections/ocptest3/terms/shard1
[junit4] 2> 2 /solr/collections/collection1/terms/shard1_0
[junit4] 2> 2 /solr/collections/ocptest_shardsplit/terms/shard4
[junit4] 2> 2 /solr/collections/ocptest2/terms/shard3
[junit4] 2> 2 /solr/collections/ocptest_shardsplit/terms/shard3
[junit4] 2> 2 /solr/collections/ocptest_shardsplit/terms/shard2
[junit4] 2> 2 /solr/collections/ocptest1/terms/shard3
[junit4] 2> 2 /solr/collections/ocptest_shardsplit/terms/shard1
[junit4] 2> 2 /solr/collections/ocptest1/terms/shard2
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 132 /solr/collections/ocptest_shardsplit2/state.json
[junit4] 2> 67 /solr/clusterstate.json
[junit4] 2> 42 /solr/collections/ocptest_shardsplit/state.json
[junit4] 2> 33 /solr/collections/ocptest3/state.json
[junit4] 2> 33 /solr/collections/ocptest2/state.json
[junit4] 2> 30 /solr/collections/ocptest1/state.json
[junit4] 2> 12 /solr/collections/control_collection/state.json
[junit4] 2> 6 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 103 /solr/overseer/queue
[junit4] 2> 55 /solr/overseer/collection-queue-work
[junit4] 2> 31 /solr/collections
[junit4] 2> 25 /solr/live_nodes
[junit4] 2>
[junit4] 2> 80131 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:57439
[junit4] 2> 80131 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:57439
[junit4] 2> 80131 INFO (TEST-MultiThreadedOCPTest.test-seed#[4079A8F671CD27E3]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 57439
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=MultiThreadedOCPTest -Dtests.method=test -Dtests.seed=4079A8F671CD27E3 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=sk -Dtests.timezone=Etc/GMT-2 -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] FAILURE 68.7s J1 | MultiThreadedOCPTest.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: acoll: 1602362722834 bcoll: 1602362723302
[junit4] > at __randomizedtesting.SeedInfo.seed([4079A8F671CD27E3:C82D972CDF314A1B]:0)
[junit4] > at org.apache.solr.cloud.MultiThreadedOCPTest.testFillWorkQueue(MultiThreadedOCPTest.java:113)
[junit4] > at org.apache.solr.cloud.MultiThreadedOCPTest.test(MultiThreadedOCPTest.java:68)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:567)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1090)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1061)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:835)
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_4079A8F671CD27E3-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene87): {multiDefault=FST50, _root_=Lucene84, id=Lucene84}, docValues:{range_facet_l_dv=DocValuesFormat(name=Asserting), _version_=DocValuesFormat(name=Lucene80), range_facet_i_dv=DocValuesFormat(name=Lucene80), intDvoDefault=DocValuesFormat(name=Lucene80), timestamp=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=1307, maxMBSortInHeap=6.174658372161959, sim=Asserting(RandomSimilarity(queryNorm=true): {}), locale=sk, timezone=Etc/GMT-2
[junit4] 2> NOTE: Windows 10 10.0 amd64/AdoptOpenJDK 12.0.2 (64-bit)/cpus=6,threads=1,free=88560792,total=263610368
[junit4] 2> NOTE: All tests run in this JVM: [MultiThreadedOCPTest]
[junit4] Completed [5/5 (4!)] on J1 in 80.71s, 1 test, 1 failure <<< FAILURES!
[...truncated 16 lines...]
BUILD FAILED
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\common-build.xml:1599: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\common-build.xml:1126: There were test failures: 5 suites, 5 tests, 4 failures [seed: 4079A8F671CD27E3]
Total time: 1 minute 25 seconds
[repro] Setting last failure code to 1
[repro] Failures w/original seeds:
[repro] 4/5 failed: org.apache.solr.cloud.MultiThreadedOCPTest
[repro] Exiting with code 1
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows>IF EXIST lucene\build move /y lucene\build lucene\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows>IF EXIST solr\build move /y solr\build solr\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows>IF EXIST lucene\build.orig move /y lucene\build.orig lucene\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows>IF EXIST solr\build.orig move /y solr\build.orig solr\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows>exit 0
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Archiving artifacts
[Java] Sleeping for 5 seconds due to JENKINS-32191...
[Java] Parsing console log (workspace: 'C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows')
[Java] Successfully parsed console log
[Java] -> found 112 issues (skipped 103 duplicates)
[Java] Post processing issues on 'Windows VBOX' with source code encoding 'UTF-8'
[Java] Creating SCM blamer to obtain author and commit information for affected files
[Java] Creating SCM miner to obtain statistics for affected repository files
[Java] Resolving file names for all issues in source directory 'C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows'
[Java] -> resolved paths in source directory (53 found, 0 not found)
[Java] Resolving module names from module definitions (build.xml, pom.xml, or Manifest.mf files)
[Java] -> resolved module names for 112 issues
[Java] Resolving package names (or namespaces) by parsing the affected files
[Java] -> resolved package names of 53 affected files
[Java] No filter has been set, publishing all 112 issues
[Java] Creating fingerprints for all affected code blocks to track issues over different builds
[Java] -> created fingerprints for 112 issues (skipped 0 issues)
[Java] Copying affected files to Jenkins' build folder '/home/jenkins/jobs/Lucene-Solr-8.x-Windows/builds/1714/files-with-issues'
[Java] -> 53 copied, 0 not in workspace, 0 not-found, 0 with I/O error
[Java] Using reference build 'Lucene-Solr-8.x-Windows #1713' to compute new, fixed, and outstanding issues
[Java] Issues delta (vs. reference build): outstanding: 112, new: 0, fixed: 0
[Java] No quality gates have been set - skipping
[Java] Health report is disabled - skipping
[Java] Created analysis result for 112 issues (found 0 new issues, fixed 0 issues)
[Java] Attaching ResultAction with ID 'java' to run 'Lucene-Solr-8.x-Windows #1714'.
[Checks API] No suitable checks publisher found.
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Recording test results
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
[Checks API] No suitable checks publisher found.
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
[JENKINS] Lucene-Solr-8.x-Windows (64bit/jdk-15) - Build # 1715 -
Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/1715/
Java: 64bit/jdk-15 -XX:+UseCompressedOops -XX:+UseSerialGC
2 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.sim.TestSnapshotCloudManager.testComplexSnapshot
Error Message:
committed = 143196160 should be < max = 143130624
Stack Trace:
java.lang.IllegalArgumentException: committed = 143196160 should be < max = 143130624
at __randomizedtesting.SeedInfo.seed([329BA15C860BD983:66BFA1E7AD437E38]:0)
at java.management/java.lang.management.MemoryUsage.<init>(MemoryUsage.java:166)
at java.management/sun.management.MemoryPoolImpl.getCollectionUsage0(Native Method)
at java.management/sun.management.MemoryPoolImpl.getCollectionUsage(MemoryPoolImpl.java:264)
at com.codahale.metrics.jvm.MemoryUsageGaugeSet.getMetrics(MemoryUsageGaugeSet.java:96)
at org.apache.solr.metrics.SolrMetricManager.registerAll(SolrMetricManager.java:557)
at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.<init>(SimCloudManager.java:207)
at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.createCluster(SimCloudManager.java:313)
at org.apache.solr.cloud.autoscaling.sim.TestSnapshotCloudManager.testComplexSnapshot(TestSnapshotCloudManager.java:148)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:64)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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:832)
FAILED: org.apache.solr.cloud.autoscaling.sim.TestSnapshotCloudManager.testSimulatorFromSnapshot
Error Message:
committed = 143196160 should be < max = 143130624
Stack Trace:
java.lang.IllegalArgumentException: committed = 143196160 should be < max = 143130624
at __randomizedtesting.SeedInfo.seed([329BA15C860BD983:5CD0AC87E8C9E36F]:0)
at java.management/java.lang.management.MemoryUsage.<init>(MemoryUsage.java:166)
at java.management/sun.management.MemoryPoolImpl.getCollectionUsage0(Native Method)
at java.management/sun.management.MemoryPoolImpl.getCollectionUsage(MemoryPoolImpl.java:264)
at com.codahale.metrics.jvm.MemoryUsageGaugeSet.getMetrics(MemoryUsageGaugeSet.java:96)
at org.apache.solr.metrics.SolrMetricManager.registerAll(SolrMetricManager.java:557)
at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.<init>(SimCloudManager.java:207)
at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.createCluster(SimCloudManager.java:313)
at org.apache.solr.cloud.autoscaling.sim.TestSnapshotCloudManager.testSimulatorFromSnapshot(TestSnapshotCloudManager.java:165)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:64)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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:832)
Build Log:
[...truncated 15483 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.sim.TestSnapshotCloudManager
[junit4] 2> 1557008 INFO (SUITE-TestSnapshotCloudManager-seed#[329BA15C860BD983]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of 'C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\server\solr\configsets\_default\conf'
[junit4] 2> 1557009 INFO (SUITE-TestSnapshotCloudManager-seed#[329BA15C860BD983]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.sim.TestSnapshotCloudManager_329BA15C860BD983-001\data-dir-140-001
[junit4] 2> 1557009 INFO (SUITE-TestSnapshotCloudManager-seed#[329BA15C860BD983]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1557010 INFO (SUITE-TestSnapshotCloudManager-seed#[329BA15C860BD983]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 1557010 INFO (SUITE-TestSnapshotCloudManager-seed#[329BA15C860BD983]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1557011 INFO (SUITE-TestSnapshotCloudManager-seed#[329BA15C860BD983]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 3 servers in C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.sim.TestSnapshotCloudManager_329BA15C860BD983-001\tempDir-001
[junit4] 2> 1557011 INFO (SUITE-TestSnapshotCloudManager-seed#[329BA15C860BD983]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1557012 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 1557012 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1557015 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 1557116 INFO (SUITE-TestSnapshotCloudManager-seed#[329BA15C860BD983]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 52319
[junit4] 2> 1557116 INFO (SUITE-TestSnapshotCloudManager-seed#[329BA15C860BD983]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:52319
[junit4] 2> 1557116 INFO (SUITE-TestSnapshotCloudManager-seed#[329BA15C860BD983]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:52319
[junit4] 2> 1557116 INFO (SUITE-TestSnapshotCloudManager-seed#[329BA15C860BD983]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 52319
[junit4] 2> 1557118 INFO (SUITE-TestSnapshotCloudManager-seed#[329BA15C860BD983]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1557120 INFO (zkConnectionManagerCallback-12984-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1557120 INFO (SUITE-TestSnapshotCloudManager-seed#[329BA15C860BD983]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1557128 INFO (SUITE-TestSnapshotCloudManager-seed#[329BA15C860BD983]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1557133 INFO (zkConnectionManagerCallback-12986-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1557133 INFO (SUITE-TestSnapshotCloudManager-seed#[329BA15C860BD983]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1557134 INFO (SUITE-TestSnapshotCloudManager-seed#[329BA15C860BD983]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1557139 INFO (zkConnectionManagerCallback-12988-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1557139 INFO (SUITE-TestSnapshotCloudManager-seed#[329BA15C860BD983]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1557247 WARN (jetty-launcher-12989-thread-3) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1557247 WARN (jetty-launcher-12989-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1557247 WARN (jetty-launcher-12989-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1557248 INFO (jetty-launcher-12989-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1557248 INFO (jetty-launcher-12989-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1557248 INFO (jetty-launcher-12989-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1557248 INFO (jetty-launcher-12989-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1557248 INFO (jetty-launcher-12989-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1557248 INFO (jetty-launcher-12989-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1557248 INFO (jetty-launcher-12989-thread-3) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 15+36-1562
[junit4] 2> 1557248 INFO (jetty-launcher-12989-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 15+36-1562
[junit4] 2> 1557248 INFO (jetty-launcher-12989-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 15+36-1562
[junit4] 2> 1557249 INFO (jetty-launcher-12989-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1557249 INFO (jetty-launcher-12989-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1557249 INFO (jetty-launcher-12989-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1557249 INFO (jetty-launcher-12989-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1557249 INFO (jetty-launcher-12989-thread-3) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1557249 INFO (jetty-launcher-12989-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1557249 INFO (jetty-launcher-12989-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1557249 INFO (jetty-launcher-12989-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1557249 INFO (jetty-launcher-12989-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1557249 INFO (jetty-launcher-12989-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@419b712d{/solr,null,AVAILABLE}
[junit4] 2> 1557249 INFO (jetty-launcher-12989-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5df7cca3{/solr,null,AVAILABLE}
[junit4] 2> 1557249 INFO (jetty-launcher-12989-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5f3b264{/solr,null,AVAILABLE}
[junit4] 2> 1557251 INFO (jetty-launcher-12989-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@78f5af45{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:52332}
[junit4] 2> 1557251 INFO (jetty-launcher-12989-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@433a19a3{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:52333}
[junit4] 2> 1557251 INFO (jetty-launcher-12989-thread-2) [ ] o.e.j.s.Server Started @1557177ms
[junit4] 2> 1557251 INFO (jetty-launcher-12989-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7100bd2e{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:52334}
[junit4] 2> 1557251 INFO (jetty-launcher-12989-thread-3) [ ] o.e.j.s.Server Started @1557177ms
[junit4] 2> 1557251 INFO (jetty-launcher-12989-thread-1) [ ] o.e.j.s.Server Started @1557177ms
[junit4] 2> 1557251 INFO (jetty-launcher-12989-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=52332}
[junit4] 2> 1557251 INFO (jetty-launcher-12989-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=52333}
[junit4] 2> 1557251 INFO (jetty-launcher-12989-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=52334}
[junit4] 2> 1557252 ERROR (jetty-launcher-12989-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1557252 ERROR (jetty-launcher-12989-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1557252 INFO (jetty-launcher-12989-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1557252 INFO (jetty-launcher-12989-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1557252 ERROR (jetty-launcher-12989-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1557252 INFO (jetty-launcher-12989-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1557252 INFO (jetty-launcher-12989-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.7.0
[junit4] 2> 1557252 INFO (jetty-launcher-12989-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.7.0
[junit4] 2> 1557252 INFO (jetty-launcher-12989-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.7.0
[junit4] 2> 1557252 INFO (jetty-launcher-12989-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1557252 INFO (jetty-launcher-12989-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1557252 INFO (jetty-launcher-12989-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 1557252 INFO (jetty-launcher-12989-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1557252 INFO (jetty-launcher-12989-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 1557252 INFO (jetty-launcher-12989-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 1557252 INFO (jetty-launcher-12989-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-10-11T03:06:09.337511500Z
[junit4] 2> 1557252 INFO (jetty-launcher-12989-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-10-11T03:06:09.337511500Z
[junit4] 2> 1557252 INFO (jetty-launcher-12989-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-10-11T03:06:09.337511500Z
[junit4] 2> 1557253 INFO (jetty-launcher-12989-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1557253 INFO (jetty-launcher-12989-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1557253 INFO (jetty-launcher-12989-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1557257 INFO (zkConnectionManagerCallback-12995-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1557258 INFO (zkConnectionManagerCallback-12993-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1557258 INFO (zkConnectionManagerCallback-12991-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1557258 INFO (jetty-launcher-12989-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1557258 INFO (jetty-launcher-12989-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1557258 INFO (jetty-launcher-12989-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1557258 INFO (jetty-launcher-12989-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1557258 INFO (jetty-launcher-12989-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1557258 INFO (jetty-launcher-12989-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1557696 INFO (jetty-launcher-12989-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1557700 WARN (jetty-launcher-12989-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@43ba3581[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1557700 WARN (jetty-launcher-12989-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@43ba3581[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1557700 WARN (jetty-launcher-12989-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6bf54c2c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1557700 WARN (jetty-launcher-12989-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6bf54c2c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1557704 INFO (jetty-launcher-12989-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52319/solr
[junit4] 2> 1557704 INFO (jetty-launcher-12989-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1557712 INFO (zkConnectionManagerCallback-13011-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1557712 INFO (jetty-launcher-12989-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1557732 INFO (jetty-launcher-12989-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1557733 WARN (jetty-launcher-12989-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7988e3d9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1557733 WARN (jetty-launcher-12989-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7988e3d9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1557735 WARN (jetty-launcher-12989-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@12612ba8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1557735 WARN (jetty-launcher-12989-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@12612ba8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1557736 INFO (jetty-launcher-12989-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52319/solr
[junit4] 2> 1557737 INFO (jetty-launcher-12989-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1557742 INFO (zkConnectionManagerCallback-13018-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1557742 INFO (jetty-launcher-12989-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1557785 INFO (jetty-launcher-12989-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1557787 WARN (jetty-launcher-12989-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@789ebffa[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1557787 WARN (jetty-launcher-12989-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@789ebffa[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1557791 WARN (jetty-launcher-12989-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3bc89c00[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1557791 WARN (jetty-launcher-12989-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3bc89c00[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1557792 INFO (jetty-launcher-12989-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52319/solr
[junit4] 2> 1557793 INFO (jetty-launcher-12989-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1557798 INFO (zkConnectionManagerCallback-13025-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1557798 INFO (jetty-launcher-12989-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1557818 INFO (jetty-launcher-12989-thread-3) [n:127.0.0.1:52333_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1557824 INFO (zkConnectionManagerCallback-13027-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1557824 INFO (jetty-launcher-12989-thread-3) [n:127.0.0.1:52333_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1557920 INFO (jetty-launcher-12989-thread-2) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1557923 INFO (zkConnectionManagerCallback-13031-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1557923 INFO (jetty-launcher-12989-thread-2) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1557963 INFO (jetty-launcher-12989-thread-2) [n:127.0.0.1:52332_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:52332_solr
[junit4] 2> 1557967 INFO (jetty-launcher-12989-thread-2) [n:127.0.0.1:52332_solr ] o.a.s.c.Overseer Overseer (id=72100898457059338-127.0.0.1:52332_solr-n_0000000000) starting
[junit4] 2> 1557995 INFO (jetty-launcher-12989-thread-2) [n:127.0.0.1:52332_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52332_solr
[junit4] 2> 1557995 INFO (OverseerStateUpdate-72100898457059338-127.0.0.1:52332_solr-n_0000000000) [n:127.0.0.1:52332_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:52332_solr
[junit4] 2> 1557998 INFO (OverseerStateUpdate-72100898457059338-127.0.0.1:52332_solr-n_0000000000) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1558005 INFO (jetty-launcher-12989-thread-2) [n:127.0.0.1:52332_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1558005 WARN (jetty-launcher-12989-thread-2) [n:127.0.0.1:52332_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1558020 INFO (jetty-launcher-12989-thread-1) [n:127.0.0.1:52334_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1558025 INFO (zkConnectionManagerCallback-13041-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1558025 INFO (jetty-launcher-12989-thread-1) [n:127.0.0.1:52334_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1558025 INFO (jetty-launcher-12989-thread-2) [n:127.0.0.1:52332_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1558042 INFO (jetty-launcher-12989-thread-1) [n:127.0.0.1:52334_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1558057 INFO (jetty-launcher-12989-thread-2) [n:127.0.0.1:52332_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52332.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a992896
[junit4] 2> 1558064 INFO (jetty-launcher-12989-thread-2) [n:127.0.0.1:52332_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52332.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a992896
[junit4] 2> 1558064 INFO (jetty-launcher-12989-thread-2) [n:127.0.0.1:52332_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52332.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a992896
[junit4] 2> 1558065 INFO (jetty-launcher-12989-thread-1) [n:127.0.0.1:52334_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:52334_solr as DOWN
[junit4] 2> 1558067 INFO (jetty-launcher-12989-thread-2) [n:127.0.0.1:52332_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.sim.TestSnapshotCloudManager_329BA15C860BD983-001\tempDir-001\node2
[junit4] 2> 1558070 INFO (jetty-launcher-12989-thread-1) [n:127.0.0.1:52334_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1558070 INFO (jetty-launcher-12989-thread-1) [n:127.0.0.1:52334_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52334_solr
[junit4] 2> 1558077 INFO (zkCallback-13030-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1558077 INFO (zkCallback-13040-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1558084 INFO (jetty-launcher-12989-thread-1) [n:127.0.0.1:52334_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1558084 WARN (jetty-launcher-12989-thread-1) [n:127.0.0.1:52334_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1558103 INFO (jetty-launcher-12989-thread-1) [n:127.0.0.1:52334_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1558121 INFO (jetty-launcher-12989-thread-1) [n:127.0.0.1:52334_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52334.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a992896
[junit4] 2> 1558128 INFO (jetty-launcher-12989-thread-1) [n:127.0.0.1:52334_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52334.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a992896
[junit4] 2> 1558128 INFO (jetty-launcher-12989-thread-1) [n:127.0.0.1:52334_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52334.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a992896
[junit4] 2> 1558129 INFO (jetty-launcher-12989-thread-1) [n:127.0.0.1:52334_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.sim.TestSnapshotCloudManager_329BA15C860BD983-001\tempDir-001\node1
[junit4] 2> 1558238 INFO (jetty-launcher-12989-thread-3) [n:127.0.0.1:52333_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1558243 INFO (jetty-launcher-12989-thread-3) [n:127.0.0.1:52333_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:52333_solr as DOWN
[junit4] 2> 1558245 INFO (jetty-launcher-12989-thread-3) [n:127.0.0.1:52333_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1558245 INFO (jetty-launcher-12989-thread-3) [n:127.0.0.1:52333_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52333_solr
[junit4] 2> 1558247 INFO (zkCallback-13030-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1558247 INFO (zkCallback-13040-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1558248 INFO (zkCallback-13026-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1558248 INFO (jetty-launcher-12989-thread-3) [n:127.0.0.1:52333_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1558249 WARN (jetty-launcher-12989-thread-3) [n:127.0.0.1:52333_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1558262 INFO (jetty-launcher-12989-thread-3) [n:127.0.0.1:52333_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1558276 INFO (jetty-launcher-12989-thread-3) [n:127.0.0.1:52333_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52333.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a992896
[junit4] 2> 1558283 INFO (jetty-launcher-12989-thread-3) [n:127.0.0.1:52333_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52333.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a992896
[junit4] 2> 1558283 INFO (jetty-launcher-12989-thread-3) [n:127.0.0.1:52333_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52333.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a992896
[junit4] 2> 1558284 INFO (jetty-launcher-12989-thread-3) [n:127.0.0.1:52333_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.sim.TestSnapshotCloudManager_329BA15C860BD983-001\tempDir-001\node3
[junit4] 2> 1558295 INFO (SUITE-TestSnapshotCloudManager-seed#[329BA15C860BD983]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=3
[junit4] 2> 1558296 INFO (SUITE-TestSnapshotCloudManager-seed#[329BA15C860BD983]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1558300 INFO (zkConnectionManagerCallback-13064-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1558300 INFO (SUITE-TestSnapshotCloudManager-seed#[329BA15C860BD983]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1558301 INFO (SUITE-TestSnapshotCloudManager-seed#[329BA15C860BD983]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1558302 INFO (SUITE-TestSnapshotCloudManager-seed#[329BA15C860BD983]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:52319/solr ready
[junit4] 2> 1558315 INFO (qtp2143767718-19955) [n:127.0.0.1:52334_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=1&name=.system&nrtReplicas=2&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1558334 INFO (OverseerThreadFactory-13038-thread-1-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection .system
[junit4] 2> 1558449 INFO (OverseerStateUpdate-72100898457059338-127.0.0.1:52332_solr-n_0000000000) [n:127.0.0.1:52332_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":".system",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:52334/solr",
[junit4] 2> "node_name":"127.0.0.1:52334_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1558455 INFO (OverseerStateUpdate-72100898457059338-127.0.0.1:52332_solr-n_0000000000) [n:127.0.0.1:52332_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":".system",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":".system_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:52333/solr",
[junit4] 2> "node_name":"127.0.0.1:52333_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1558461 INFO (OverseerStateUpdate-72100898457059338-127.0.0.1:52332_solr-n_0000000000) [n:127.0.0.1:52332_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":".system",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":".system_shard1_replica_p4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:52332/solr",
[junit4] 2> "node_name":"127.0.0.1:52332_solr",
[junit4] 2> "type":"PULL",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1558665 INFO (qtp2143767718-19952) [n:127.0.0.1:52334_solr x:.system_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n1&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1558665 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr x:.system_shard1_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n2&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1558665 INFO (qtp1983012851-19957) [n:127.0.0.1:52332_solr x:.system_shard1_replica_p4 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node6&collection.configName=.system&newCollection=true&name=.system_shard1_replica_p4&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=PULL
[junit4] 2> 1558665 INFO (qtp1983012851-19957) [n:127.0.0.1:52332_solr x:.system_shard1_replica_p4 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1558678 INFO (qtp2143767718-19952) [n:127.0.0.1:52334_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
[junit4] 2> 1558678 INFO (qtp1983012851-19957) [n:127.0.0.1:52332_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
[junit4] 2> 1558678 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
[junit4] 2> 1558696 INFO (qtp1983012851-19957) [n:127.0.0.1:52332_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 1558696 INFO (qtp2143767718-19952) [n:127.0.0.1:52334_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 1558696 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 1558698 INFO (qtp1983012851-19957) [n:127.0.0.1:52332_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.s.IndexSchema Schema name=_system collection or core
[junit4] 2> 1558698 INFO (qtp2143767718-19952) [n:127.0.0.1:52334_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=_system collection or core
[junit4] 2> 1558698 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema name=_system collection or core
[junit4] 2> 1558701 INFO (qtp1983012851-19957) [n:127.0.0.1:52332_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id
[junit4] 2> 1558701 INFO (qtp2143767718-19952) [n:127.0.0.1:52334_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id
[junit4] 2> 1558701 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id
[junit4] 2> 1558707 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore '.system_shard1_replica_n2' using configuration from configset .system, trusted=true
[junit4] 2> 1558707 INFO (qtp1983012851-19957) [n:127.0.0.1:52332_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.c.CoreContainer Creating SolrCore '.system_shard1_replica_p4' using configuration from configset .system, trusted=true
[junit4] 2> 1558707 INFO (qtp1983012851-19957) [n:127.0.0.1:52332_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52332.solr.core..system.shard1.replica_p4' (registry 'solr.core..system.shard1.replica_p4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a992896
[junit4] 2> 1558707 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52333.solr.core..system.shard1.replica_n2' (registry 'solr.core..system.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a992896
[junit4] 2> 1558708 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.SolrCore [[.system_shard1_replica_n2] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.sim.TestSnapshotCloudManager_329BA15C860BD983-001\tempDir-001\node3\.system_shard1_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.sim.TestSnapshotCloudManager_329BA15C860BD983-001\tempDir-001\node3\.system_shard1_replica_n2\data\]
[junit4] 2> 1558708 INFO (qtp1983012851-19957) [n:127.0.0.1:52332_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.c.SolrCore [[.system_shard1_replica_p4] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.sim.TestSnapshotCloudManager_329BA15C860BD983-001\tempDir-001\node2\.system_shard1_replica_p4], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.sim.TestSnapshotCloudManager_329BA15C860BD983-001\tempDir-001\node2\.system_shard1_replica_p4\data\]
[junit4] 2> 1558709 INFO (qtp2143767718-19952) [n:127.0.0.1:52334_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Created and persisted managed schema znode at /configs/.system/managed-schema
[junit4] 2> 1558721 INFO (qtp2143767718-19952) [n:127.0.0.1:52334_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in ZooKeeper, renamed the non-managed schema /configs/.system/schema.xml to /configs/.system/schema.xml.bak
[junit4] 2> 1558724 INFO (qtp2143767718-19952) [n:127.0.0.1:52334_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore '.system_shard1_replica_n1' using configuration from configset .system, trusted=true
[junit4] 2> 1558724 INFO (qtp2143767718-19952) [n:127.0.0.1:52334_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52334.solr.core..system.shard1.replica_n1' (registry 'solr.core..system.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a992896
[junit4] 2> 1558724 INFO (qtp2143767718-19952) [n:127.0.0.1:52334_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [[.system_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.sim.TestSnapshotCloudManager_329BA15C860BD983-001\tempDir-001\node1\.system_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.sim.TestSnapshotCloudManager_329BA15C860BD983-001\tempDir-001\node1\.system_shard1_replica_n1\data\]
[junit4] 2> 1558791 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1558791 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1558794 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: if 1 uncommitted docs;
[junit4] 2> 1558794 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1558796 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/.system
[junit4] 2> 1558796 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/.system
[junit4] 2> 1558796 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/.system/managed-schema
[junit4] 2> 1558797 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 0 from Zookeeper
[junit4] 2> 1558799 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema name=_system collection or core
[junit4] 2> 1558799 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id
[junit4] 2> 1558799 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 4 ms
[junit4] 2> 1558799 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1558799 INFO (qtp1983012851-19957) [n:127.0.0.1:52332_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.u.CommitTracker Hard AutoCommit: if 1 uncommitted docs;
[junit4] 2> 1558799 INFO (qtp1983012851-19957) [n:127.0.0.1:52332_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1558799 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1680223052375261184
[junit4] 2> 1558804 INFO (qtp1983012851-19957) [n:127.0.0.1:52332_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/.system
[junit4] 2> 1558804 INFO (searcherExecutor-13067-thread-1-processing-n:127.0.0.1:52333_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node5) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.SolrCore [.system_shard1_replica_n2] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1558805 INFO (qtp1983012851-19957) [n:127.0.0.1:52332_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/.system
[junit4] 2> 1558805 INFO (qtp1983012851-19957) [n:127.0.0.1:52332_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/.system/managed-schema
[junit4] 2> 1558806 INFO (qtp2143767718-19952) [n:127.0.0.1:52334_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1558806 INFO (qtp2143767718-19952) [n:127.0.0.1:52334_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1558809 INFO (qtp1983012851-19957) [n:127.0.0.1:52332_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 0 from Zookeeper
[junit4] 2> 1558809 INFO (qtp2143767718-19952) [n:127.0.0.1:52334_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: if 1 uncommitted docs;
[junit4] 2> 1558809 INFO (qtp2143767718-19952) [n:127.0.0.1:52334_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1558811 INFO (qtp1983012851-19957) [n:127.0.0.1:52332_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.s.IndexSchema Schema name=_system collection or core
[junit4] 2> 1558813 INFO (qtp2143767718-19952) [n:127.0.0.1:52334_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/.system
[junit4] 2> 1558813 INFO (qtp1983012851-19957) [n:127.0.0.1:52332_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id
[junit4] 2> 1558813 INFO (qtp1983012851-19957) [n:127.0.0.1:52332_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 4 ms
[junit4] 2> 1558813 INFO (qtp2143767718-19952) [n:127.0.0.1:52334_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/.system
[junit4] 2> 1558813 INFO (qtp2143767718-19952) [n:127.0.0.1:52334_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/.system/managed-schema
[junit4] 2> 1558814 INFO (qtp1983012851-19957) [n:127.0.0.1:52332_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1558816 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/.system/terms/shard1 to Terms{values={core_node5=0}, version=0}
[junit4] 2> 1558816 INFO (qtp2143767718-19952) [n:127.0.0.1:52334_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 1558816 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/.system/leaders/shard1
[junit4] 2> 1558816 INFO (qtp2143767718-19952) [n:127.0.0.1:52334_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1558816 INFO (qtp2143767718-19952) [n:127.0.0.1:52334_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1680223052393086976
[junit4] 2> 1558817 INFO (searcherExecutor-13068-thread-1-processing-n:127.0.0.1:52332_solr x:.system_shard1_replica_p4 c:.system s:shard1 r:core_node6) [n:127.0.0.1:52332_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.c.SolrCore [.system_shard1_replica_p4] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1558820 INFO (searcherExecutor-13070-thread-1-processing-n:127.0.0.1:52334_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:52334_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [.system_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1558830 INFO (qtp1983012851-19957) [n:127.0.0.1:52332_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.c.ZkController .system_shard1_replica_p4 starting background replication from leader
[junit4] 2> 1558830 INFO (qtp1983012851-19957) [n:127.0.0.1:52332_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.c.ReplicateFromLeader Will start replication from leader with poll interval: 00:00:01
[junit4] 2> 1558831 INFO (qtp1983012851-19957) [n:127.0.0.1:52332_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 1558831 INFO (qtp1983012851-19957) [n:127.0.0.1:52332_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1558833 INFO (qtp2143767718-19952) [n:127.0.0.1:52334_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/.system/terms/shard1 to Terms{values={core_node3=0, core_node5=0}, version=1}
[junit4] 2> 1558833 INFO (qtp2143767718-19952) [n:127.0.0.1:52334_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/.system/leaders/shard1
[junit4] 2> 1558834 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=2 found=1 timeoute in=9999ms
[junit4] 2> 1558936 INFO (zkCallback-13030-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [3])
[junit4] 2> 1558936 INFO (zkCallback-13030-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [3])
[junit4] 2> 1559335 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1559335 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1559335 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:52333/solr/.system_shard1_replica_n2/
[junit4] 2> 1559336 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.u.PeerSync PeerSync: core=.system_shard1_replica_n2 url=http://127.0.0.1:52333/solr START replicas=[http://127.0.0.1:52334/solr/.system_shard1_replica_n1/] nUpdates=100
[junit4] 2> 1559336 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.u.PeerSync PeerSync: core=.system_shard1_replica_n2 url=http://127.0.0.1:52333/solr DONE. We have no versions. sync failed.
[junit4] 2> 1559337 INFO (qtp2143767718-19958) [n:127.0.0.1:52334_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1559338 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 1559338 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 1559338 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/.system/leaders/shard1/leader after winning as /collections/.system/leader_elect/shard1/election/72100898457059337-core_node5-n_0000000000
[junit4] 2> 1559343 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:52333/solr/.system_shard1_replica_n2/ shard1
[junit4] 2> 1559449 INFO (zkCallback-13026-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [3])
[junit4] 2> 1559449 INFO (zkCallback-13030-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [3])
[junit4] 2> 1559449 INFO (zkCallback-13030-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [3])
[junit4] 2> 1559451 INFO (indexFetcher-13083-thread-1) [ ] o.a.s.h.IndexFetcher Replica core_node5 is leader but it's state is down, skipping replication
[junit4] 2> 1559451 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1559457 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node5&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n2&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=792
[junit4] 2> 1559559 INFO (zkCallback-13026-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [3])
[junit4] 2> 1559559 INFO (zkCallback-13026-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [3])
[junit4] 2> 1559833 INFO (indexFetcher-13083-thread-1) [ ] o.a.s.h.IndexFetcher Last replication failed, so I'll force replication
[junit4] 2> 1559833 INFO (indexFetcher-13083-thread-1) [ ] o.a.s.h.IndexFetcher Updated leaderUrl to http://127.0.0.1:52333/solr/.system_shard1_replica_n2/
[junit4] 2> 1559835 INFO (qtp1308491372-19950) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request [.system_shard1_replica_n2] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 1559835 INFO (indexFetcher-13083-thread-1) [ ] o.a.s.h.IndexFetcher Leader's generation: 1
[junit4] 2> 1559835 INFO (indexFetcher-13083-thread-1) [ ] o.a.s.h.IndexFetcher Leader's version: 0
[junit4] 2> 1559835 INFO (indexFetcher-13083-thread-1) [ ] o.a.s.h.IndexFetcher Follower's generation: 1
[junit4] 2> 1559835 INFO (indexFetcher-13083-thread-1) [ ] o.a.s.h.IndexFetcher Follower's version: 0
[junit4] 2> 1559835 INFO (indexFetcher-13083-thread-1) [ ] o.a.s.h.IndexFetcher New index in Leader. Deleting mine...
[junit4] 2> 1559837 INFO (searcherExecutor-13068-thread-1) [ ] o.a.s.c.SolrCore [.system_shard1_replica_p4] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1559841 INFO (qtp1983012851-19957) [n:127.0.0.1:52332_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node6&collection.configName=.system&newCollection=true&name=.system_shard1_replica_p4&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=PULL} status=0 QTime=1176
[junit4] 2> 1559845 INFO (qtp2143767718-19952) [n:127.0.0.1:52334_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n1&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1180
[junit4] 2> 1559851 INFO (qtp2143767718-19955) [n:127.0.0.1:52334_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> 1559947 INFO (zkCallback-13026-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [3])
[junit4] 2> 1559947 INFO (zkCallback-13040-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [3])
[junit4] 2> 1559947 INFO (zkCallback-13026-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [3])
[junit4] 2> 1559947 INFO (zkCallback-13030-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [3])
[junit4] 2> 1559947 INFO (zkCallback-13030-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [3])
[junit4] 2> 1559947 INFO (zkCallback-13040-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [3])
[junit4] 2> 1559949 INFO (qtp2143767718-19955) [n:127.0.0.1:52334_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={pullReplicas=1&name=.system&nrtReplicas=2&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2} status=0 QTime=1634
[junit4] 2> 1559949 INFO (qtp2143767718-19961) [n:127.0.0.1:52334_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params name=coll1&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1559957 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection coll1
[junit4] 2> 1559959 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default to /configs/coll1.AUTOCREATED
[junit4] 2> 1559959 INFO (OverseerCollectionConfigSetProcessor-72100898457059338-127.0.0.1:52332_solr-n_0000000000) [n:127.0.0.1:52332_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> 1559964 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/coll1.AUTOCREATED/managed-schema
[junit4] 2> 1559967 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/coll1.AUTOCREATED/protwords.txt
[junit4] 2> 1559976 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/coll1.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 1559984 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/coll1.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 1559992 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/coll1.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 1559999 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/coll1.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 1560007 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/coll1.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 1560015 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/coll1.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 1560023 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_et.txt to /configs/coll1.AUTOCREATED/lang/stopwords_et.txt
[junit4] 2> 1560032 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/coll1.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 1560041 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/coll1.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 1560048 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/coll1.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 1560054 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/coll1.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 1560062 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/coll1.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 1560069 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/coll1.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 1560076 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/coll1.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 1560083 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/coll1.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 1560089 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/coll1.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 1560096 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/coll1.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 1560103 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/coll1.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 1560111 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/coll1.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 1560119 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/coll1.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 1560128 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/coll1.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 1560136 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/coll1.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 1560145 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/coll1.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 1560152 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/coll1.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 1560159 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/coll1.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 1560166 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/coll1.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 1560172 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/coll1.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 1560176 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/coll1.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 1560186 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/coll1.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 1560192 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/coll1.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 1560199 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/coll1.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 1560205 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/coll1.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 1560212 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/coll1.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 1560218 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/coll1.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 1560226 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/coll1.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 1560235 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/coll1.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 1560245 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/coll1.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 1560255 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/coll1.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 1560263 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/coll1.AUTOCREATED/lang/stopwords_gl.txt
[junit4] 2> 1560270 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/coll1.AUTOCREATED/solrconfig.xml
[junit4] 2> 1560275 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/coll1.AUTOCREATED/synonyms.txt
[junit4] 2> 1560281 INFO (OverseerThreadFactory-13038-thread-2-processing-n:127.0.0.1:52332_solr) [n:127.0.0.1:52332_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/coll1.AUTOCREATED/stopwords.txt
[junit4] 2> 1560505 INFO (OverseerStateUpdate-72100898457059338-127.0.0.1:52332_solr-n_0000000000) [n:127.0.0.1:52332_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"coll1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"coll1_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:52333/solr",
[junit4] 2> "node_name":"127.0.0.1:52333_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1560707 INFO (qtp1308491372-19953) [n:127.0.0.1:52333_solr x:coll1_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=coll1.AUTOCREATED&newCollection=true&name=coll1_shard1_replica_n1&action=CREATE&numShards=1&collection=coll1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1560720 INFO (qtp1308491372-19953) [n:127.0.0.1:52333_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
[junit4] 2> 1560736 INFO (qtp1308491372-19953) [n:127.0.0.1:52333_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1560845 INFO (qtp1308491372-19956) [n:127.0.0.1:52333_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request [.system_shard1_replica_n2] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 1560846 INFO (indexFetcher-13083-thread-1) [ ] o.a.s.h.IndexFetcher Leader's generation: 1
[junit4] 2> 1560846 INFO (indexFetcher-13083-thread-1) [ ] o.a.s.h.IndexFetcher Leader's version: 0
[junit4] 2> 1560846 INFO (indexFetcher-13083-thread-1) [ ] o.a.s.h.IndexFetcher Follower's generation: 2
[junit4] 2> 1560846 INFO (indexFetcher-13083-thread-1) [ ] o.a.s.h.IndexFetcher Follower's version: 1602385571920
[junit4] 2> 1560846 INFO (indexFetcher-13083-thread-1) [ ] o.a.s.h.IndexFetcher New index in Leader. Deleting mine...
[junit4] 2> 1560848 INFO (searcherExecutor-13068-thread-1) [ ] o.a.s.c.SolrCore [.system_shard1_replica_p4] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1560889 INFO (qtp1308491372-19953) [n:127.0.0.1:52333_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 1560926 INFO (qtp1308491372-19953) [n:127.0.0.1:52333_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'coll1_shard1_replica_n1' using configuration from configset coll1.AUTOCREATED, trusted=true
[junit4] 2> 1560926 INFO (qtp1308491372-19953) [n:127.0.0.1:52333_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52333.solr.core.coll1.shard1.replica_n1' (registry 'solr.core.coll1.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a992896
[junit4] 2> 1560926 INFO (qtp1308491372-19953) [n:127.0.0.1:52333_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.c.SolrCore [[coll1_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.sim.TestSnapshotCloudManager_329BA15C860BD983-001\tempDir-001\node3\coll1_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.sim.TestSnapshotCloudManager_329BA15C860BD983-001\tempDir-001\node3\coll1_shard1_replica_n1\data\]
[junit4] 2> 1560996 INFO (qtp1308491372-19953) [n:127.0.0.1:52333_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1560996 INFO (qtp1308491372-19953) [n:127.0.0.1:52333_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1560998 INFO (qtp1308491372-19953) [n:127.0.0.1:52333_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 1560998 INFO (qtp1308491372-19953) [n:127.0.0.1:52333_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1561000 INFO (qtp1308491372-19953) [n:127.0.0.1:52333_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/coll1.AUTOCREATED
[junit4] 2> 1561000 INFO (qtp1308491372-19953) [n:127.0.0.1:52333_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/coll1.AUTOCREATED
[junit4] 2> 1561000 INFO (qtp1308491372-19953) [n:127.0.0.1:52333_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/coll1.AUTOCREATED/managed-schema
[junit4] 2> 1561001 INFO (qtp1308491372-19953) [n:127.0.0.1:52333_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 1561001 INFO (qtp1308491372-19953) [n:127.0.0.1:52333_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1561001 INFO (qtp1308491372-19953) [n:127.0.0.1:52333_solr c:coll1 s:shard1 r:core_node2 x:coll1_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> 1561002 INFO (qtp1308491372-19953) [n:127.0.0.1:52333_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1561002 INFO (qtp1308491372-19953) [n:127.0.0.1:52333_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1680223054685274112
[junit4] 2> 1561004 INFO (searcherExecutor-13085-thread-1-processing-n:127.0.0.1:52333_solr x:coll1_shard1_replica_n1 c:c
[...truncated too long message...]
c86d8ab
[junit4] 2> 1564284 INFO (jetty-closer-13098-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 1564284 INFO (jetty-closer-13098-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1d86dfb8: rootName = solr_52334, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@5953993e
[junit4] 2> 1564284 INFO (jetty-closer-13098-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 1564285 INFO (jetty-closer-13098-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 1564285 INFO (jetty-closer-13098-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@238ea283: rootName = solr_52333, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@1c86d8ab
[junit4] 2> 1564288 INFO (jetty-closer-13098-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 1564288 INFO (jetty-closer-13098-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@75d2e8c: rootName = solr_52333, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@5953993e
[junit4] 2> 1564288 INFO (jetty-closer-13098-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 1564380 INFO (jetty-closer-13098-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72100898457059338-127.0.0.1:52332_solr-n_0000000000) closing
[junit4] 2> 1564380 INFO (jetty-closer-13098-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@78f5af45{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1564380 INFO (jetty-closer-13098-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5df7cca3{/solr,null,UNAVAILABLE}
[junit4] 2> 1564380 INFO (jetty-closer-13098-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1564481 INFO (jetty-closer-13098-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@433a19a3{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1564481 INFO (jetty-closer-13098-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@419b712d{/solr,null,UNAVAILABLE}
[junit4] 2> 1564481 INFO (jetty-closer-13098-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1564581 INFO (jetty-closer-13098-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@7100bd2e{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1564581 INFO (jetty-closer-13098-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5f3b264{/solr,null,UNAVAILABLE}
[junit4] 2> 1564581 INFO (jetty-closer-13098-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1564584 INFO (SUITE-TestSnapshotCloudManager-seed#[329BA15C860BD983]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1564791 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 7 /solr/collections/.system/terms/shard1
[junit4] 2> 4 /solr/clusterprops.json
[junit4] 2> 4 /solr/aliases.json
[junit4] 2> 3 /solr/configs/.system
[junit4] 2> 3 /solr/packages.json
[junit4] 2> 3 /solr/security.json
[junit4] 2> 3 /solr/configs/.system/managed-schema
[junit4] 2> 2 /solr/collections/coll1/terms/shard1
[junit4] 2> 2 /solr/collections/coll10/terms/shard1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 26 /solr/collections/.system/state.json
[junit4] 2> 11 /solr/collections/coll1/state.json
[junit4] 2> 9 /solr/collections/coll10/state.json
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2> 2 /solr/autoscaling.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 42 /solr/overseer/queue
[junit4] 2> 16 /solr/collections
[junit4] 2> 12 /solr/live_nodes
[junit4] 2> 11 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 1564798 INFO (SUITE-TestSnapshotCloudManager-seed#[329BA15C860BD983]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:52319
[junit4] 2> 1564799 INFO (SUITE-TestSnapshotCloudManager-seed#[329BA15C860BD983]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:52319
[junit4] 2> 1564799 INFO (SUITE-TestSnapshotCloudManager-seed#[329BA15C860BD983]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 52319
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.sim.TestSnapshotCloudManager_329BA15C860BD983-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene87): {}, docValues:{}, maxPointsInLeafNode=1896, maxMBSortInHeap=7.894573320140256, sim=Asserting(RandomSimilarity(queryNorm=true): {}), locale=so-DJ, timezone=Pacific/Port_Moresby
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 15 (64-bit)/cpus=6,threads=1,free=250508136,total=517644288
[junit4] 2> NOTE: All tests run in this JVM: [PreAnalyzedFieldManagedSchemaCloudTest, TestCollectionsAPIViaSolrCloudCluster, TestDistribIDF, HighlighterWithoutStoredIdTest, HttpPartitionTest, CdcrVersionReplicationTest, ResourceLoaderTest, HLLSerializationTest, TestInitQParser, SpatialHeatmapFacetsTest, TestSizeLimitedDistributedMap, ManagedSchemaRoundRobinCloudTest, TestIBSimilarityFactory, BigEndianAscendingWordDeserializerTest, SchemaApiFailureTest, TestConfigSetProperties, AddBlockUpdateTest, TestBadConfig, TestRecovery, SolrRrdBackendFactoryTest, LargeFieldTest, HdfsDirectoryFactoryTest, DistributedMLTComponentTest, TestGroupingSearch, SaslZkACLProviderTest, TestNamedUpdateProcessors, TestDistributedTracing, CoreAdminOperationTest, TestComplexPhraseLeadingWildcard, SolrLogAuditLoggerPluginTest, SolrJmxReporterCloudTest, TestStressThreadBackup, TestSerializedLuceneMatchVersion, TestIndexingPerformance, ReindexCollectionTest, CircularListTest, TestPullReplica, TestBlobHandler, SignatureUpdateProcessorFactoryTest, BadCopyFieldTest, TestEmbeddedSolrServerConstructors, ZookeeperReadAPITest, TestJsonFacetRefinement, TestTolerantUpdateProcessorCloud, PathHierarchyTokenizerFactoryTest, WordBreakSolrSpellCheckerTest, ConfigureRecoveryStrategyTest, TestChildDocTransformerHierarchy, TestSearcherReuse, TestCustomStream, XmlInterpolationTest, AnalyticsMergeStrategyTest, BasicZkTest, TestSort, PhrasesIdentificationComponentTest, SystemCollectionCompatTest, TestLeaderElectionZkExpiry, TestInfoStreamLogging, TestDynamicFieldCollectionResource, TestObjectReleaseTracker, TestCloudPhrasesIdentificationComponent, MetricsHandlerTest, TestSimScenario, MetricTriggerIntegrationTest, SortByFunctionTest, TestCloudSearcherWarming, TestSimGenericDistributedQueue, RankQParserPluginTest, XsltUpdateRequestHandlerTest, TestNRTOpen, TestHttpShardHandlerFactory, TestPerFieldSimilarity, TestStressUserVersions, ShowFileRequestHandlerTest, TestStreamBody, TestReversedWildcardFilterFactory, AuditLoggerPluginTest, ZkCLITest, NodeLostTriggerTest, DocValuesTest, TestCursorMarkWithoutUniqueKey, TestManagedResource, TestFieldTypeResource, OutputWriterTest, TestRawResponseWriter, NodeMutatorTest, DocValuesNotIndexedTest, CollectionsAPIAsyncDistributedZkTest, JWTAuthPluginTest, ConnectionManagerTest, CreateRoutedAliasTest, DeleteShardTest, DistribDocExpirationUpdateProcessorTest, MissingSegmentRecoveryTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerTest, ReplaceNodeNoTargetTest, ReplaceNodeTest, ShardRoutingTest, TestConfigSetsAPIZkFailure, TestDeleteCollectionOnDownNodes, TestDistributedMap, TestDynamicFieldNamesIndexCorrectly, TestRequestForwarding, TestSolrCloudWithKerberosAlt, TestStressInPlaceUpdates, ZkNodePropsTest, ZkShardTermsTest, AssignTest, ConcurrentDeleteAndCreateCollectionTest, CustomCollectionTest, TestLocalFSCloudBackupRestore, AutoAddReplicasIntegrationTest, ScheduledTriggerIntegrationTest, TestPolicyCloud, TestSimClusterStateProvider, TestSimComputePlanAction, TestSimUtils, TestSnapshotCloudManager]
[junit4] Completed [592/920 (1!)] on J3 in 10.07s, 5 tests, 2 errors <<< FAILURES!
[...truncated 46104 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/1715/consoleText
[repro] Revision: f1fccfbf7e84169330fc8df2fe2f56243cb8641a
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC"
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr\core
[repro] TestSnapshotCloudManager
[repro] ant compile-test
[...truncated 3476 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestSnapshotCloudManager" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=329BA15C860BD983 -Dtests.slow=true -Dtests.locale=so-DJ -Dtests.timezone=Pacific/Port_Moresby -Dtests.asserts=true -Dtests.file.encoding=Cp1252
[...truncated 102 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.cloud.autoscaling.sim.TestSnapshotCloudManager
[repro] Exiting with code 0
[...truncated 98 lines...]