Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/207/
Java: 64bit/jdk-12 -XX:-UseCompressedOops -XX:+UseSerialGC

8 tests failed.
FAILED:  org.apache.solr.cloud.DocValuesNotIndexedTest.testGroupingSorting

Error Message:
Should have exactly 4 documents returned expected:<4> but was:<3>

Stack Trace:
java.lang.AssertionError: Should have exactly 4 documents returned expected:<4> 
but was:<3>
        at 
__randomizedtesting.SeedInfo.seed([2E415D15E151514F:3079551D9DFAEBCF]:0)
        at org.junit.Assert.fail(Assert.java:88)
        at org.junit.Assert.failNotEquals(Assert.java:834)
        at org.junit.Assert.assertEquals(Assert.java:645)
        at 
org.apache.solr.cloud.DocValuesNotIndexedTest.checkSortOrder(DocValuesNotIndexedTest.java:262)
        at 
org.apache.solr.cloud.DocValuesNotIndexedTest.testGroupingSorting(DocValuesNotIndexedTest.java:247)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:567)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.base/java.lang.Thread.run(Thread.java:835)


FAILED:  org.apache.solr.cloud.DocValuesNotIndexedTest.testGroupingDVOnly

Error Message:
Should be exactly one group with 1 entry of 10 for null for field intGSF 
expected:<1> but was:<0>

Stack Trace:
java.lang.AssertionError: Should be exactly one group with 1 entry of 10 for 
null for field intGSF expected:<1> but was:<0>
        at 
__randomizedtesting.SeedInfo.seed([2E415D15E151514F:B5FA334DAC096311]:0)
        at org.junit.Assert.fail(Assert.java:88)
        at org.junit.Assert.failNotEquals(Assert.java:834)
        at org.junit.Assert.assertEquals(Assert.java:645)
        at 
org.apache.solr.cloud.DocValuesNotIndexedTest.doGroupingDvOnly(DocValuesNotIndexedTest.java:395)
        at 
org.apache.solr.cloud.DocValuesNotIndexedTest.testGroupingDVOnly(DocValuesNotIndexedTest.java:321)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:567)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.base/java.lang.Thread.run(Thread.java:835)


FAILED:  org.apache.solr.security.AuditLoggerIntegrationTest.testAsyncQueueDrain

Error Message:
Failed waiting for 3 callbacks after 5 seconds

Stack Trace:
java.lang.AssertionError: Failed waiting for 3 callbacks after 5 seconds
        at 
__randomizedtesting.SeedInfo.seed([2E415D15E151514F:9F5EA2315192C290]:0)
        at org.junit.Assert.fail(Assert.java:88)
        at 
org.apache.solr.security.AuditLoggerIntegrationTest.waitForAuditEventCallbacks(AuditLoggerIntegrationTest.java:229)
        at 
org.apache.solr.security.AuditLoggerIntegrationTest.waitForAuditEventCallbacks(AuditLoggerIntegrationTest.java:221)
        at 
org.apache.solr.security.AuditLoggerIntegrationTest.assertThreeAdminEvents(AuditLoggerIntegrationTest.java:252)
        at 
org.apache.solr.security.AuditLoggerIntegrationTest.testAsyncQueueDrain(AuditLoggerIntegrationTest.java:132)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:567)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.base/java.lang.Thread.run(Thread.java:835)


FAILED:  org.apache.solr.security.AuditLoggerIntegrationTest.testAsyncQueueDrain

Error Message:
Failed waiting for 3 callbacks after 5 seconds

Stack Trace:
java.lang.AssertionError: Failed waiting for 3 callbacks after 5 seconds
        at 
__randomizedtesting.SeedInfo.seed([2E415D15E151514F:9F5EA2315192C290]:0)
        at org.junit.Assert.fail(Assert.java:88)
        at 
org.apache.solr.security.AuditLoggerIntegrationTest.waitForAuditEventCallbacks(AuditLoggerIntegrationTest.java:229)
        at 
org.apache.solr.security.AuditLoggerIntegrationTest.waitForAuditEventCallbacks(AuditLoggerIntegrationTest.java:221)
        at 
org.apache.solr.security.AuditLoggerIntegrationTest.assertThreeAdminEvents(AuditLoggerIntegrationTest.java:252)
        at 
org.apache.solr.security.AuditLoggerIntegrationTest.testAsyncQueueDrain(AuditLoggerIntegrationTest.java:132)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:567)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.base/java.lang.Thread.run(Thread.java:835)


FAILED:  org.apache.solr.security.AuditLoggerIntegrationTest.testAsyncQueueDrain

Error Message:
Failed waiting for 3 callbacks after 5 seconds

Stack Trace:
java.lang.AssertionError: Failed waiting for 3 callbacks after 5 seconds
        at 
__randomizedtesting.SeedInfo.seed([2E415D15E151514F:9F5EA2315192C290]:0)
        at org.junit.Assert.fail(Assert.java:88)
        at 
org.apache.solr.security.AuditLoggerIntegrationTest.waitForAuditEventCallbacks(AuditLoggerIntegrationTest.java:229)
        at 
org.apache.solr.security.AuditLoggerIntegrationTest.waitForAuditEventCallbacks(AuditLoggerIntegrationTest.java:221)
        at 
org.apache.solr.security.AuditLoggerIntegrationTest.assertThreeAdminEvents(AuditLoggerIntegrationTest.java:252)
        at 
org.apache.solr.security.AuditLoggerIntegrationTest.testAsyncQueueDrain(AuditLoggerIntegrationTest.java:132)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:567)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.base/java.lang.Thread.run(Thread.java:835)


FAILED:  org.apache.solr.security.AuditLoggerIntegrationTest.testAsyncQueueDrain

Error Message:
Failed waiting for 3 callbacks after 5 seconds

Stack Trace:
java.lang.AssertionError: Failed waiting for 3 callbacks after 5 seconds
        at 
__randomizedtesting.SeedInfo.seed([2E415D15E151514F:9F5EA2315192C290]:0)
        at org.junit.Assert.fail(Assert.java:88)
        at 
org.apache.solr.security.AuditLoggerIntegrationTest.waitForAuditEventCallbacks(AuditLoggerIntegrationTest.java:229)
        at 
org.apache.solr.security.AuditLoggerIntegrationTest.waitForAuditEventCallbacks(AuditLoggerIntegrationTest.java:221)
        at 
org.apache.solr.security.AuditLoggerIntegrationTest.assertThreeAdminEvents(AuditLoggerIntegrationTest.java:252)
        at 
org.apache.solr.security.AuditLoggerIntegrationTest.testAsyncQueueDrain(AuditLoggerIntegrationTest.java:132)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:567)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.base/java.lang.Thread.run(Thread.java:835)


FAILED:  org.apache.solr.security.AuditLoggerIntegrationTest.testAsyncQueueDrain

Error Message:
Failed waiting for 3 callbacks after 5 seconds

Stack Trace:
java.lang.AssertionError: Failed waiting for 3 callbacks after 5 seconds
        at 
__randomizedtesting.SeedInfo.seed([2E415D15E151514F:9F5EA2315192C290]:0)
        at org.junit.Assert.fail(Assert.java:88)
        at 
org.apache.solr.security.AuditLoggerIntegrationTest.waitForAuditEventCallbacks(AuditLoggerIntegrationTest.java:229)
        at 
org.apache.solr.security.AuditLoggerIntegrationTest.waitForAuditEventCallbacks(AuditLoggerIntegrationTest.java:221)
        at 
org.apache.solr.security.AuditLoggerIntegrationTest.assertThreeAdminEvents(AuditLoggerIntegrationTest.java:252)
        at 
org.apache.solr.security.AuditLoggerIntegrationTest.testAsyncQueueDrain(AuditLoggerIntegrationTest.java:132)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:567)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.base/java.lang.Thread.run(Thread.java:835)


FAILED:  org.apache.solr.security.AuditLoggerIntegrationTest.testAsyncQueueDrain

Error Message:
Failed waiting for 3 callbacks after 5 seconds

Stack Trace:
java.lang.AssertionError: Failed waiting for 3 callbacks after 5 seconds
        at 
__randomizedtesting.SeedInfo.seed([2E415D15E151514F:9F5EA2315192C290]:0)
        at org.junit.Assert.fail(Assert.java:88)
        at 
org.apache.solr.security.AuditLoggerIntegrationTest.waitForAuditEventCallbacks(AuditLoggerIntegrationTest.java:229)
        at 
org.apache.solr.security.AuditLoggerIntegrationTest.waitForAuditEventCallbacks(AuditLoggerIntegrationTest.java:221)
        at 
org.apache.solr.security.AuditLoggerIntegrationTest.assertThreeAdminEvents(AuditLoggerIntegrationTest.java:252)
        at 
org.apache.solr.security.AuditLoggerIntegrationTest.testAsyncQueueDrain(AuditLoggerIntegrationTest.java:132)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:567)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.base/java.lang.Thread.run(Thread.java:835)




Build Log:
[...truncated 13053 lines...]
   [junit4] Suite: org.apache.solr.cloud.DocValuesNotIndexedTest
   [junit4]   2> 199517 INFO  
(SUITE-DocValuesNotIndexedTest-seed#[2E415D15E151514F]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> Creating dataDir: 
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.DocValuesNotIndexedTest_2E415D15E151514F-001\init-core-data-001
   [junit4]   2> 199517 INFO  
(SUITE-DocValuesNotIndexedTest-seed#[2E415D15E151514F]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 199519 INFO  
(SUITE-DocValuesNotIndexedTest-seed#[2E415D15E151514F]-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> 199522 INFO  
(TEST-DocValuesNotIndexedTest.testGroupingSorting-seed#[2E415D15E151514F]) [    
] o.a.s.SolrTestCaseJ4 ###Starting testGroupingSorting
   [junit4]   2> 199523 INFO  
(TEST-DocValuesNotIndexedTest.testGroupingSorting-seed#[2E415D15E151514F]) [    
] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in 
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.DocValuesNotIndexedTest_2E415D15E151514F-001\tempDir-001
   [junit4]   2> 199524 INFO  
(TEST-DocValuesNotIndexedTest.testGroupingSorting-seed#[2E415D15E151514F]) [    
] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 199525 INFO  (ZkTestServer Run Thread) [    ] 
o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 199525 INFO  (ZkTestServer Run Thread) [    ] 
o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 199627 INFO  
(TEST-DocValuesNotIndexedTest.testGroupingSorting-seed#[2E415D15E151514F]) [    
] o.a.s.c.ZkTestServer start zk server on port:54838
   [junit4]   2> 199627 INFO  
(TEST-DocValuesNotIndexedTest.testGroupingSorting-seed#[2E415D15E151514F]) [    
] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:54838
   [junit4]   2> 199627 INFO  
(TEST-DocValuesNotIndexedTest.testGroupingSorting-seed#[2E415D15E151514F]) [    
] o.a.s.c.ZkTestServer connecting to 127.0.0.1 54838
   [junit4]   2> 199633 INFO  (zkConnectionManagerCallback-1075-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 199637 INFO  (zkConnectionManagerCallback-1077-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 199640 INFO  (zkConnectionManagerCallback-1079-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 199642 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 
0x1001919cb0e0002, likely client has closed socket
   [junit4]   2> 199643 WARN  (jetty-launcher-1080-thread-1) [    ] 
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 199643 WARN  (jetty-launcher-1080-thread-2) [    ] 
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 199644 INFO  (jetty-launcher-1080-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
   [junit4]   2> 199644 INFO  (jetty-launcher-1080-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
   [junit4]   2> 199644 INFO  (jetty-launcher-1080-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 199644 INFO  (jetty-launcher-1080-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 199644 INFO  (jetty-launcher-1080-thread-2) [    ] 
o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: 
c4550056e785fb5665914545889f21dc136ad9e6; jvm 12+33
   [junit4]   2> 199644 INFO  (jetty-launcher-1080-thread-1) [    ] 
o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: 
c4550056e785fb5665914545889f21dc136ad9e6; jvm 12+33
   [junit4]   2> 199644 INFO  (jetty-launcher-1080-thread-2) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 199644 INFO  (jetty-launcher-1080-thread-2) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 199644 INFO  (jetty-launcher-1080-thread-2) [    ] 
o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 199645 INFO  (jetty-launcher-1080-thread-1) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 199645 INFO  (jetty-launcher-1080-thread-1) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 199645 INFO  (jetty-launcher-1080-thread-1) [    ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 199645 INFO  (jetty-launcher-1080-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@36deae27{/solr,null,AVAILABLE}
   [junit4]   2> 199645 INFO  (jetty-launcher-1080-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@d196930{/solr,null,AVAILABLE}
   [junit4]   2> 199648 INFO  (jetty-launcher-1080-thread-1) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@63f416e8{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:?????}
   [junit4]   2> 199648 INFO  (jetty-launcher-1080-thread-1) [    ] 
o.e.j.s.Server Started @??????ms
   [junit4]   2> 199648 INFO  (jetty-launcher-1080-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=54850}
   [junit4]   2> 199649 ERROR (jetty-launcher-1080-thread-1) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 199649 INFO  (jetty-launcher-1080-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 199649 INFO  (jetty-launcher-1080-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.1.0
   [junit4]   2> 199649 INFO  (jetty-launcher-1080-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 199649 INFO  (jetty-launcher-1080-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 199649 INFO  (jetty-launcher-1080-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2019-04-18T14:43:38.831501200Z
   [junit4]   2> 199651 INFO  (jetty-launcher-1080-thread-2) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@3f9b58f{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:?????}
   [junit4]   2> 199651 INFO  (jetty-launcher-1080-thread-2) [    ] 
o.e.j.s.Server Started @??????ms
   [junit4]   2> 199651 INFO  (jetty-launcher-1080-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=54849}
   [junit4]   2> 199651 ERROR (jetty-launcher-1080-thread-2) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 199651 INFO  (jetty-launcher-1080-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 199651 INFO  (jetty-launcher-1080-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.1.0
   [junit4]   2> 199651 INFO  (jetty-launcher-1080-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 199651 INFO  (jetty-launcher-1080-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 199651 INFO  (jetty-launcher-1080-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2019-04-18T14:43:38.833865900Z
   [junit4]   2> 199657 INFO  (zkConnectionManagerCallback-1082-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 199658 INFO  (jetty-launcher-1080-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 199659 INFO  (zkConnectionManagerCallback-1084-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 199662 INFO  (jetty-launcher-1080-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 199664 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 
0x1001919cb0e0003, likely client has closed socket
   [junit4]   2> 199712 INFO  (jetty-launcher-1080-thread-1) [    ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 199732 WARN  (jetty-launcher-1080-thread-1) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@4a613fc6[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 199738 WARN  (jetty-launcher-1080-thread-1) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@66702844[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 199738 INFO  (jetty-launcher-1080-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54838/solr
   [junit4]   2> 199745 INFO  (zkConnectionManagerCallback-1092-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 199745 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 
0x1001919cb0e0005, likely client has closed socket
   [junit4]   2> 199749 INFO  (zkConnectionManagerCallback-1094-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 199901 INFO  (jetty-launcher-1080-thread-1) 
[n:127.0.0.1:54850_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:54850_solr
   [junit4]   2> 199902 INFO  (jetty-launcher-1080-thread-1) 
[n:127.0.0.1:54850_solr    ] o.a.s.c.Overseer Overseer 
(id=72085192609497094-127.0.0.1:54850_solr-n_0000000000) starting
   [junit4]   2> 199949 INFO  (zkConnectionManagerCallback-1101-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 199951 INFO  (jetty-launcher-1080-thread-1) 
[n:127.0.0.1:54850_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:54838/solr ready
   [junit4]   2> 199952 INFO  (jetty-launcher-1080-thread-1) 
[n:127.0.0.1:54850_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:54850_solr
   [junit4]   2> 199988 INFO  (jetty-launcher-1080-thread-1) 
[n:127.0.0.1:54850_solr    ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 200024 INFO  (jetty-launcher-1080-thread-1) 
[n:127.0.0.1:54850_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_54850.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@51f47300
   [junit4]   2> 200034 INFO  (jetty-launcher-1080-thread-1) 
[n:127.0.0.1:54850_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_54850.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@51f47300
   [junit4]   2> 200034 INFO  (jetty-launcher-1080-thread-1) 
[n:127.0.0.1:54850_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_54850.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@51f47300
   [junit4]   2> 200037 INFO  (jetty-launcher-1080-thread-1) 
[n:127.0.0.1:54850_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\J1\temp\solr.cloud.DocValuesNotIndexedTest_2E415D15E151514F-001\tempDir-001\node1\.
   [junit4]   2> 200117 INFO  
(OverseerStateUpdate-72085192609497094-127.0.0.1:54850_solr-n_0000000000) 
[n:127.0.0.1:54850_solr    ] o.a.s.c.Overseer Starting to work on the main 
queue : 127.0.0.1:54850_solr
   [junit4]   2> 200118 INFO  
(OverseerStateUpdate-72085192609497094-127.0.0.1:54850_solr-n_0000000000) 
[n:127.0.0.1:54850_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 200120 INFO  (zkCallback-1100-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 200259 INFO  (jetty-launcher-1080-thread-2) [    ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 200261 WARN  (jetty-launcher-1080-thread-2) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@6c67372c[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 200266 WARN  (jetty-launcher-1080-thread-2) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@1c432a35[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 200266 INFO  (jetty-launcher-1080-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54838/solr
   [junit4]   2> 200270 INFO  (zkConnectionManagerCallback-1108-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 200271 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 
0x1001919cb0e0008, likely client has closed socket
   [junit4]   2> 200275 INFO  (zkConnectionManagerCallback-1110-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 200280 INFO  (jetty-launcher-1080-thread-2) 
[n:127.0.0.1:54849_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 200285 INFO  (jetty-launcher-1080-thread-2) 
[n:127.0.0.1:54849_solr    ] o.a.s.c.ZkController Publish 
node=127.0.0.1:54849_solr as DOWN
   [junit4]   2> 200285 INFO  (jetty-launcher-1080-thread-2) 
[n:127.0.0.1:54849_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 200285 INFO  (jetty-launcher-1080-thread-2) 
[n:127.0.0.1:54849_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:54849_solr
   [junit4]   2> 200287 INFO  (zkCallback-1100-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 200287 INFO  (zkCallback-1093-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 200288 INFO  (zkCallback-1109-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 200322 INFO  (zkConnectionManagerCallback-1117-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 200324 INFO  (jetty-launcher-1080-thread-2) 
[n:127.0.0.1:54849_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 200325 INFO  (jetty-launcher-1080-thread-2) 
[n:127.0.0.1:54849_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:54838/solr ready
   [junit4]   2> 200325 INFO  (jetty-launcher-1080-thread-2) 
[n:127.0.0.1:54849_solr    ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 200347 INFO  (jetty-launcher-1080-thread-2) 
[n:127.0.0.1:54849_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_54849.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@51f47300
   [junit4]   2> 200354 INFO  (jetty-launcher-1080-thread-2) 
[n:127.0.0.1:54849_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_54849.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@51f47300
   [junit4]   2> 200356 INFO  (jetty-launcher-1080-thread-2) 
[n:127.0.0.1:54849_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_54849.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@51f47300
   [junit4]   2> 200357 INFO  (jetty-launcher-1080-thread-2) 
[n:127.0.0.1:54849_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\J1\temp\solr.cloud.DocValuesNotIndexedTest_2E415D15E151514F-001\tempDir-001\node2\.
   [junit4]   2> 200385 INFO  (zkConnectionManagerCallback-1123-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 200387 INFO  
(TEST-DocValuesNotIndexedTest.testGroupingSorting-seed#[2E415D15E151514F]) [    
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 200388 INFO  
(TEST-DocValuesNotIndexedTest.testGroupingSorting-seed#[2E415D15E151514F]) [    
] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:54838/solr ready
   [junit4]   2> 200396 INFO  (qtp1289615069-2804) [n:127.0.0.1:54849_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
collection.configName=conf1&maxShardsPerNode=2&name=dv_coll&nrtReplicas=1&action=CREATE&numShards=4&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 200400 INFO  
(OverseerThreadFactory-745-thread-1-processing-n:127.0.0.1:54850_solr) 
[n:127.0.0.1:54850_solr    ] o.a.s.c.a.c.CreateCollectionCmd Create collection 
dv_coll
   [junit4]   2> 200510 INFO  
(OverseerStateUpdate-72085192609497094-127.0.0.1:54850_solr-n_0000000000) 
[n:127.0.0.1:54850_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"dv_coll",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"dv_coll_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:54849/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 200514 INFO  
(OverseerStateUpdate-72085192609497094-127.0.0.1:54850_solr-n_0000000000) 
[n:127.0.0.1:54850_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"dv_coll",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"dv_coll_shard2_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:54850/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 200518 INFO  
(OverseerStateUpdate-72085192609497094-127.0.0.1:54850_solr-n_0000000000) 
[n:127.0.0.1:54850_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"dv_coll",
   [junit4]   2>   "shard":"shard3",
   [junit4]   2>   "core":"dv_coll_shard3_replica_n4",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:54849/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 200522 INFO  
(OverseerStateUpdate-72085192609497094-127.0.0.1:54850_solr-n_0000000000) 
[n:127.0.0.1:54850_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"dv_coll",
   [junit4]   2>   "shard":"shard4",
   [junit4]   2>   "core":"dv_coll_shard4_replica_n6",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:54850/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 200729 INFO  (qtp1289267298-2817) [n:127.0.0.1:54850_solr    
x:dv_coll_shard2_replica_n2] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf1&newCollection=true&name=dv_coll_shard2_replica_n2&action=CREATE&numShards=4&collection=dv_coll&shard=shard2&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 200733 INFO  (qtp1289267298-2817) [n:127.0.0.1:54850_solr    
x:dv_coll_shard2_replica_n2] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 200736 INFO  (qtp1289615069-2810) [n:127.0.0.1:54849_solr    
x:dv_coll_shard3_replica_n4] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf1&newCollection=true&name=dv_coll_shard3_replica_n4&action=CREATE&numShards=4&collection=dv_coll&shard=shard3&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 200738 INFO  (qtp1289615069-2809) [n:127.0.0.1:54849_solr    
x:dv_coll_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf1&newCollection=true&name=dv_coll_shard1_replica_n1&action=CREATE&numShards=4&collection=dv_coll&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 200738 INFO  (qtp1289267298-2814) [n:127.0.0.1:54850_solr    
x:dv_coll_shard4_replica_n6] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf1&newCollection=true&name=dv_coll_shard4_replica_n6&action=CREATE&numShards=4&collection=dv_coll&shard=shard4&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 201768 INFO  (qtp1289615069-2810) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 8.1.0
   [junit4]   2> 201772 INFO  (qtp1289267298-2817) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 8.1.0
   [junit4]   2> 201786 INFO  (qtp1289615069-2809) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 8.1.0
   [junit4]   2> 201795 INFO  (qtp1289267298-2814) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 8.1.0
   [junit4]   2> 201806 INFO  (qtp1289267298-2814) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] 
o.a.s.s.IndexSchema [dv_coll_shard4_replica_n6] Schema name=minimal
   [junit4]   2> 201806 INFO  (qtp1289267298-2817) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] 
o.a.s.s.IndexSchema [dv_coll_shard2_replica_n2] Schema name=minimal
   [junit4]   2> 201818 INFO  (qtp1289615069-2810) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] 
o.a.s.s.IndexSchema [dv_coll_shard3_replica_n4] Schema name=minimal
   [junit4]   2> 201823 INFO  (qtp1289267298-2817) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 201823 INFO  (qtp1289267298-2817) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] 
o.a.s.c.CoreContainer Creating SolrCore 'dv_coll_shard2_replica_n2' using 
configuration from collection dv_coll, trusted=true
   [junit4]   2> 201823 INFO  (qtp1289267298-2817) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_54850.solr.core.dv_coll.shard2.replica_n2' (registry 
'solr.core.dv_coll.shard2.replica_n2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@51f47300
   [junit4]   2> 201824 INFO  (qtp1289267298-2817) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.SolrCore 
[[dv_coll_shard2_replica_n2] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.DocValuesNotIndexedTest_2E415D15E151514F-001\tempDir-001\node1\dv_coll_shard2_replica_n2],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.DocValuesNotIndexedTest_2E415D15E151514F-001\tempDir-001\node1\.\dv_coll_shard2_replica_n2\data\]
   [junit4]   2> 201824 INFO  (qtp1289615069-2809) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] 
o.a.s.s.IndexSchema [dv_coll_shard1_replica_n1] Schema name=minimal
   [junit4]   2> 201828 INFO  (qtp1289267298-2814) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 201828 INFO  (qtp1289267298-2814) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] 
o.a.s.c.CoreContainer Creating SolrCore 'dv_coll_shard4_replica_n6' using 
configuration from collection dv_coll, trusted=true
   [junit4]   2> 201828 INFO  (qtp1289267298-2814) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_54850.solr.core.dv_coll.shard4.replica_n6' (registry 
'solr.core.dv_coll.shard4.replica_n6') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@51f47300
   [junit4]   2> 201828 INFO  (qtp1289267298-2814) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.SolrCore 
[[dv_coll_shard4_replica_n6] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.DocValuesNotIndexedTest_2E415D15E151514F-001\tempDir-001\node1\dv_coll_shard4_replica_n6],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.DocValuesNotIndexedTest_2E415D15E151514F-001\tempDir-001\node1\.\dv_coll_shard4_replica_n6\data\]
   [junit4]   2> 201834 INFO  (qtp1289615069-2810) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 201834 INFO  (qtp1289615069-2810) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] 
o.a.s.c.CoreContainer Creating SolrCore 'dv_coll_shard3_replica_n4' using 
configuration from collection dv_coll, trusted=true
   [junit4]   2> 201834 INFO  (qtp1289615069-2810) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_54849.solr.core.dv_coll.shard3.replica_n4' (registry 
'solr.core.dv_coll.shard3.replica_n4') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@51f47300
   [junit4]   2> 201834 INFO  (qtp1289615069-2810) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.SolrCore 
[[dv_coll_shard3_replica_n4] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.DocValuesNotIndexedTest_2E415D15E151514F-001\tempDir-001\node2\dv_coll_shard3_replica_n4],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.DocValuesNotIndexedTest_2E415D15E151514F-001\tempDir-001\node2\.\dv_coll_shard3_replica_n4\data\]
   [junit4]   2> 201840 INFO  (qtp1289615069-2809) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 201840 INFO  (qtp1289615069-2809) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] 
o.a.s.c.CoreContainer Creating SolrCore 'dv_coll_shard1_replica_n1' using 
configuration from collection dv_coll, trusted=true
   [junit4]   2> 201840 INFO  (qtp1289615069-2809) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_54849.solr.core.dv_coll.shard1.replica_n1' (registry 
'solr.core.dv_coll.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@51f47300
   [junit4]   2> 201843 INFO  (qtp1289615069-2809) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.SolrCore 
[[dv_coll_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.DocValuesNotIndexedTest_2E415D15E151514F-001\tempDir-001\node2\dv_coll_shard1_replica_n1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.DocValuesNotIndexedTest_2E415D15E151514F-001\tempDir-001\node2\.\dv_coll_shard1_replica_n1\data\]
   [junit4]   2> 201925 INFO  (qtp1289267298-2814) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 201925 INFO  (qtp1289267298-2814) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 201929 INFO  (qtp1289267298-2814) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 201929 INFO  (qtp1289267298-2814) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 201947 INFO  (qtp1289615069-2809) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 201947 INFO  (qtp1289615069-2809) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 201952 INFO  (qtp1289615069-2809) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 201952 INFO  (qtp1289615069-2809) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 202001 INFO  (qtp1289615069-2809) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@4ac18b60[dv_coll_shard1_replica_n1] 
main]
   [junit4]   2> 202002 INFO  (qtp1289267298-2814) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@730797b7[dv_coll_shard4_replica_n6] 
main]
   [junit4]   2> 202082 INFO  (qtp1289615069-2810) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 202082 INFO  (qtp1289615069-2810) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 202086 INFO  (qtp1289615069-2810) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 202086 INFO  (qtp1289615069-2810) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 202088 INFO  (qtp1289267298-2814) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 202088 INFO  (qtp1289615069-2809) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 202088 INFO  (qtp1289267298-2814) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 202093 INFO  (qtp1289267298-2814) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] 
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at 
/configs/conf1/managed-schema
   [junit4]   2> 202094 INFO  (qtp1289615069-2809) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 202094 INFO  (qtp1289615069-2809) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] 
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at 
/configs/conf1/managed-schema
   [junit4]   2> 202095 INFO  (qtp1289267298-2814) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 202095 INFO  (qtp1289267298-2814) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 202095 INFO  (qtp1289267298-2814) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1631163379904151552
   [junit4]   2> 202097 INFO  (qtp1289615069-2809) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 202098 INFO  (qtp1289615069-2809) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 202098 INFO  (qtp1289615069-2809) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1631163379907297280
   [junit4]   2> 202100 INFO  
(searcherExecutor-754-thread-1-processing-n:127.0.0.1:54850_solr 
x:dv_coll_shard4_replica_n6 c:dv_coll s:shard4 r:core_node8) 
[n:127.0.0.1:54850_solr c:dv_coll s:shard4 r:core_node8 
x:dv_coll_shard4_replica_n6] o.a.s.c.SolrCore [dv_coll_shard4_replica_n6] 
Registered new searcher Searcher@730797b7[dv_coll_shard4_replica_n6] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 202100 INFO  
(searcherExecutor-756-thread-1-processing-n:127.0.0.1:54849_solr 
x:dv_coll_shard1_replica_n1 c:dv_coll s:shard1 r:core_node3) 
[n:127.0.0.1:54849_solr c:dv_coll s:shard1 r:core_node3 
x:dv_coll_shard1_replica_n1] o.a.s.c.SolrCore [dv_coll_shard1_replica_n1] 
Registered new searcher Searcher@4ac18b60[dv_coll_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 202113 INFO  (qtp1289267298-2814) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/dv_coll/terms/shard4 to Terms{values={core_node8=0}, version=0}
   [junit4]   2> 202113 INFO  (qtp1289267298-2814) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] 
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created 
/collections/dv_coll/leaders/shard4
   [junit4]   2> 202114 INFO  (qtp1289615069-2809) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/dv_coll/terms/shard1 to Terms{values={core_node3=0}, version=0}
   [junit4]   2> 202114 INFO  (qtp1289615069-2809) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created 
/collections/dv_coll/leaders/shard1
   [junit4]   2> 202118 INFO  (qtp1289267298-2814) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 202118 INFO  (qtp1289267298-2814) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 202118 INFO  (qtp1289267298-2814) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:54850/solr/dv_coll_shard4_replica_n6/
   [junit4]   2> 202118 INFO  (qtp1289615069-2809) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 202118 INFO  (qtp1289615069-2809) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 202118 INFO  (qtp1289615069-2809) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:54849/solr/dv_coll_shard1_replica_n1/
   [junit4]   2> 202118 INFO  (qtp1289267298-2814) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 202118 INFO  (qtp1289615069-2809) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 202118 INFO  (qtp1289267298-2814) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] 
o.a.s.c.SyncStrategy http://127.0.0.1:54850/solr/dv_coll_shard4_replica_n6/ has 
no replicas
   [junit4]   2> 202118 INFO  (qtp1289267298-2814) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] 
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node 
/collections/dv_coll/leaders/shard4/leader after winning as 
/collections/dv_coll/leader_elect/shard4/election/72085192609497094-core_node8-n_0000000000
   [junit4]   2> 202123 INFO  (qtp1289615069-2809) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] 
o.a.s.c.SyncStrategy http://127.0.0.1:54849/solr/dv_coll_shard1_replica_n1/ has 
no replicas
   [junit4]   2> 202123 INFO  (qtp1289615069-2809) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node 
/collections/dv_coll/leaders/shard1/leader after winning as 
/collections/dv_coll/leader_elect/shard1/election/72085192609497097-core_node3-n_0000000000
   [junit4]   2> 202124 INFO  (qtp1289267298-2814) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:54850/solr/dv_coll_shard4_replica_n6/ shard4
   [junit4]   2> 202124 INFO  (qtp1289615069-2809) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:54849/solr/dv_coll_shard1_replica_n1/ shard1
   [junit4]   2> 202130 INFO  (qtp1289615069-2810) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@548dcdf8[dv_coll_shard3_replica_n4] 
main]
   [junit4]   2> 202132 INFO  (qtp1289615069-2810) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 202132 INFO  (qtp1289615069-2810) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 202132 INFO  (qtp1289615069-2810) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] 
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at 
/configs/conf1/managed-schema
   [junit4]   2> 202132 INFO  (qtp1289615069-2810) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 202133 INFO  (qtp1289615069-2810) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 202133 INFO  (qtp1289615069-2810) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1631163379943997440
   [junit4]   2> 202135 INFO  
(searcherExecutor-755-thread-1-processing-n:127.0.0.1:54849_solr 
x:dv_coll_shard3_replica_n4 c:dv_coll s:shard3 r:core_node7) 
[n:127.0.0.1:54849_solr c:dv_coll s:shard3 r:core_node7 
x:dv_coll_shard3_replica_n4] o.a.s.c.SolrCore [dv_coll_shard3_replica_n4] 
Registered new searcher Searcher@548dcdf8[dv_coll_shard3_replica_n4] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 202138 INFO  (qtp1289615069-2810) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/dv_coll/terms/shard3 to Terms{values={core_node7=0}, version=0}
   [junit4]   2> 202138 INFO  (qtp1289615069-2810) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] 
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created 
/collections/dv_coll/leaders/shard3
   [junit4]   2> 202138 INFO  (qtp1289267298-2817) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 202138 INFO  (qtp1289267298-2817) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 202138 INFO  (qtp1289615069-2810) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 202138 INFO  (qtp1289615069-2810) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 202138 INFO  (qtp1289615069-2810) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:54849/solr/dv_coll_shard3_replica_n4/
   [junit4]   2> 202138 INFO  (qtp1289615069-2810) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 202138 INFO  (qtp1289615069-2810) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] 
o.a.s.c.SyncStrategy http://127.0.0.1:54849/solr/dv_coll_shard3_replica_n4/ has 
no replicas
   [junit4]   2> 202138 INFO  (qtp1289615069-2810) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] 
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node 
/collections/dv_coll/leaders/shard3/leader after winning as 
/collections/dv_coll/leader_elect/shard3/election/72085192609497097-core_node7-n_0000000000
   [junit4]   2> 202144 INFO  (qtp1289267298-2817) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 202144 INFO  (qtp1289267298-2817) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 202144 INFO  (qtp1289615069-2810) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:54849/solr/dv_coll_shard3_replica_n4/ shard3
   [junit4]   2> 202146 INFO  (qtp1289267298-2817) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1b94a968[dv_coll_shard2_replica_n2] 
main]
   [junit4]   2> 202147 INFO  (qtp1289267298-2817) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 202147 INFO  (qtp1289267298-2817) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 202148 INFO  (qtp1289267298-2817) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] 
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at 
/configs/conf1/managed-schema
   [junit4]   2> 202148 INFO  (qtp1289267298-2817) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 202148 INFO  (qtp1289267298-2817) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 202148 INFO  (qtp1289267298-2817) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1631163379959726080
   [junit4]   2> 202148 INFO  
(searcherExecutor-753-thread-1-processing-n:127.0.0.1:54850_solr 
x:dv_coll_shard2_replica_n2 c:dv_coll s:shard2 r:core_node5) 
[n:127.0.0.1:54850_solr c:dv_coll s:shard2 r:core_node5 
x:dv_coll_shard2_replica_n2] o.a.s.c.SolrCore [dv_coll_shard2_replica_n2] 
Registered new searcher Searcher@1b94a968[dv_coll_shard2_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 202153 INFO  (qtp1289267298-2817) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/dv_coll/terms/shard2 to Terms{values={core_node5=0}, version=0}
   [junit4]   2> 202153 INFO  (qtp1289267298-2817) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] 
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created 
/collections/dv_coll/leaders/shard2
   [junit4]   2> 202156 INFO  (qtp1289267298-2817) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 202156 INFO  (qtp1289267298-2817) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 202156 INFO  (qtp1289267298-2817) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:54850/solr/dv_coll_shard2_replica_n2/
   [junit4]   2> 202156 INFO  (qtp1289267298-2817) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 202156 INFO  (qtp1289267298-2817) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] 
o.a.s.c.SyncStrategy http://127.0.0.1:54850/solr/dv_coll_shard2_replica_n2/ has 
no replicas
   [junit4]   2> 202156 INFO  (qtp1289267298-2817) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] 
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node 
/collections/dv_coll/leaders/shard2/leader after winning as 
/collections/dv_coll/leader_elect/shard2/election/72085192609497094-core_node5-n_0000000000
   [junit4]   2> 202157 INFO  (qtp1289267298-2817) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:54850/solr/dv_coll_shard2_replica_n2/ shard2
   [junit4]   2> 202259 INFO  (zkCallback-1093-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] 
for collection [dv_coll] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 202259 INFO  (zkCallback-1093-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] 
for collection [dv_coll] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 202259 INFO  (zkCallback-1109-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] 
for collection [dv_coll] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 202259 INFO  (zkCallback-1109-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] 
for collection [dv_coll] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 202261 INFO  (qtp1289615069-2809) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 202261 INFO  (qtp1289267298-2817) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 202262 INFO  (qtp1289615069-2810) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 202262 INFO  (qtp1289267298-2814) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 202273 INFO  (qtp1289615069-2809) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf1&newCollection=true&name=dv_coll_shard1_replica_n1&action=CREATE&numShards=4&collection=dv_coll&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=1534
   [junit4]   2> 202273 INFO  (qtp1289615069-2810) [n:127.0.0.1:54849_solr 
c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf1&newCollection=true&name=dv_coll_shard3_replica_n4&action=CREATE&numShards=4&collection=dv_coll&shard=shard3&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=1536
   [junit4]   2> 202275 INFO  (qtp1289267298-2817) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf1&newCollection=true&name=dv_coll_shard2_replica_n2&action=CREATE&numShards=4&collection=dv_coll&shard=shard2&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=1543
   [junit4]   2> 202275 INFO  (qtp1289267298-2814) [n:127.0.0.1:54850_solr 
c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf1&newCollection=true&name=dv_coll_shard4_replica_n6&action=CREATE&numShards=4&collection=dv_coll&shard=shard4&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=1537
   [junit4]   2> 202286 INFO  (qtp1289615069-2804) [n:127.0.0.1:54849_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> 202377 INFO  (zkCallback-1093-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] 
for collection [dv_coll] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 202377 INFO  (zkCallback-1109-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] 
for collection [dv_coll] has occurred - updating... (l

[...truncated too long message...]

54844_solr-n_0000000000) [n:127.0.0.1:54844_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> 182204 INFO  (qtp1724274477-3232) [n:127.0.0.1:54844_solr    ] 
o.a.s.s.CallbackAuditLoggerPlugin Sent audit callback 
{"requestUrl":"http://127.0.0.1:54844/solr/admin/collections","nodeName":"n:127.0.0.1:54844_solr","message":"Completed","level":"INFO","date":1555603866148,"clientIp":"127.0.0.1","collections":[],"headers":{"User-Agent":"Solr[org.apache.solr.client.solrj.impl.HttpSolrClient]
 
1.0","Connection":"keep-alive","Host":"127.0.0.1:54844"},"solrParams":{"action":["OVERSEERSTATUS"],"wt":["javabin"],"version":["2"]},"solrHost":"127.0.0.1","solrPort":54844,"solrIp":"127.0.0.1","resource":"/admin/collections","httpMethod":"GET","httpQueryString":"action=OVERSEERSTATUS&wt=javabin&version=2","eventType":"COMPLETED","requestType":"ADMIN","status":200,"qtime":36.0}
 to localhost:54817
   [junit4]   2> 182212 INFO  (auditTestCallback-771-thread-1) [    ] 
o.a.s.s.AuditLoggerIntegrationTest Received audit event for path 
/admin/collections
   [junit4]   2> 182304 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 
0x1001969d6b60007, likely client has closed socket
   [junit4]   2> 182308 INFO  (jetty-closer-1322-thread-1) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=832038278
   [junit4]   2> 182308 INFO  (jetty-closer-1322-thread-1) [    ] 
o.a.s.c.ZkController Remove node as live in 
ZooKeeper:/live_nodes/127.0.0.1:54844_solr
   [junit4]   2> 182310 INFO  (jetty-closer-1322-thread-1) [    ] 
o.a.s.c.ZkController Publish this node as DOWN...
   [junit4]   2> 182310 INFO  (jetty-closer-1322-thread-1) [    ] 
o.a.s.c.ZkController Publish node=127.0.0.1:54844_solr as DOWN
   [junit4]   2> 182312 INFO  (jetty-closer-1322-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 182312 INFO  (jetty-closer-1322-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 182312 INFO  (jetty-closer-1322-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@7048c10: rootName = 
solr_54844, domain = solr.node, service url = null, agent id = null] for 
registry solr.node / com.codahale.metrics.MetricRegistry@689e31fe
   [junit4]   2> 182318 INFO  (jetty-closer-1322-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 182318 INFO  (jetty-closer-1322-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@1a0d7d03: rootName = 
solr_54844, domain = solr.jvm, service url = null, agent id = null] for 
registry solr.jvm / com.codahale.metrics.MetricRegistry@7c8dbf5e
   [junit4]   2> 182320 INFO  (jetty-closer-1322-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 182320 INFO  (jetty-closer-1322-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@fcda43e: rootName = 
solr_54844, domain = solr.jetty, service url = null, agent id = null] for 
registry solr.jetty / com.codahale.metrics.MetricRegistry@35c1dc69
   [junit4]   2> 182323 INFO  (jetty-closer-1322-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, 
tag=null
   [junit4]   2> 182326 INFO  (jetty-closer-1322-thread-1) [    ] 
o.a.s.c.Overseer Overseer 
(id=72085536402440197-127.0.0.1:54844_solr-n_0000000000) closing
   [junit4]   2> 182326 INFO  
(OverseerStateUpdate-72085536402440197-127.0.0.1:54844_solr-n_0000000000) 
[n:127.0.0.1:54844_solr    ] o.a.s.c.Overseer Overseer Loop exiting : 
127.0.0.1:54844_solr
   [junit4]   2> 182329 INFO  
(OverseerAutoScalingTriggerThread-72085536402440197-127.0.0.1:54844_solr-n_0000000000)
 [    ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we 
are closed, exiting.
   [junit4]   2> 182329 INFO  (zkCallback-1314-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
   [junit4]   2> 182334 INFO  (closeThreadPool-1326-thread-2) [    ] 
o.a.s.c.Overseer Overseer 
(id=72085536402440197-127.0.0.1:54844_solr-n_0000000000) closing
   [junit4]   2> 182335 INFO  (closeThreadPool-1326-thread-1) [    ] 
o.a.s.c.Overseer Overseer 
(id=72085536402440197-127.0.0.1:54844_solr-n_0000000000) closing
   [junit4]   2> 182337 INFO  (jetty-closer-1322-thread-1) [    ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@49c49a7f{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:0}
   [junit4]   2> 182337 INFO  (jetty-closer-1322-thread-1) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@75db790b{/solr,null,UNAVAILABLE}
   [junit4]   2> 182337 INFO  (jetty-closer-1322-thread-1) [    ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 182676 INFO  
(TEST-AuditLoggerIntegrationTest.testSynchronous-seed#[2E415D15E151514F]) [    
] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
   [junit4]   2> 182677 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>        3       /solr/aliases.json
   [junit4]   2>        3       /solr/clusterprops.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        3       /solr/clusterstate.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        3       /solr/live_nodes
   [junit4]   2>        3       /solr/collections
   [junit4]   2> 
   [junit4]   2> 182680 INFO  
(TEST-AuditLoggerIntegrationTest.testSynchronous-seed#[2E415D15E151514F]) [    
] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:54821
   [junit4]   2> 182680 INFO  
(TEST-AuditLoggerIntegrationTest.testSynchronous-seed#[2E415D15E151514F]) [    
] o.a.s.c.ZkTestServer connecting to 127.0.0.1 54821
   [junit4]   2> 183689 INFO  
(TEST-AuditLoggerIntegrationTest.testSynchronous-seed#[2E415D15E151514F]) [    
] o.a.s.SolrTestCaseJ4 ###Ending testSynchronous
   [junit4] OK      3.09s J0 | AuditLoggerIntegrationTest.testSynchronous
   [junit4]   2> NOTE: leaving temporary files on disk at: 
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.security.AuditLoggerIntegrationTest_2E415D15E151514F-003
   [junit4]   2> NOTE: test params are: codec=Lucene80, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@6ba2131d),
 locale=en-TT, timezone=Europe/Podgorica
   [junit4]   2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 12 
(64-bit)/cpus=3,threads=1,free=100911040,total=518979584
   [junit4]   2> NOTE: All tests run in this JVM: [DocValuesNotIndexedTest, 
DocValuesNotIndexedTest, AuditLoggerIntegrationTest, 
AuditLoggerIntegrationTest, AuditLoggerIntegrationTest]
   [junit4] Completed [10/10 (7!)] on J0 in 31.80s, 7 tests, 1 failure <<< 
FAILURES!

[...truncated 16 lines...]
BUILD FAILED
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\common-build.xml:1573:
 The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\common-build.xml:1100:
 There were test failures: 10 suites, 55 tests, 7 failures [seed: 
2E415D15E151514F]

Total time: 3 minutes 7 seconds

[repro] Setting last failure code to 1

[repro] Failures:
[repro]   2/5 failed: org.apache.solr.cloud.DocValuesNotIndexedTest
[repro]   5/5 failed: org.apache.solr.security.AuditLoggerIntegrationTest
[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 
Archiving artifacts
Setting 
ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting 
ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
No credentials specified
Setting 
ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all 
warnings.
<Git Blamer> GIT_COMMIT=57eb7797a874a62476dff3ed0c24d5fd2827649f, 
workspace=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows
[WARNINGS] Computing warning deltas based on reference build #206
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
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
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to