You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2019/03/09 09:18:16 UTC
[JENKINS] Lucene-Solr-8.x-Windows (64bit/jdk-9.0.4) - Build # 74 -
Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/74/
Java: 64bit/jdk-9.0.4 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.sim.TestSimComputePlanAction.testNodeAdded
Error Message:
The operations computed by ComputePlanAction should not be null{AFTER_ACTION=[compute_plan, null], BEFORE_ACTION=[compute_plan, null]}
Stack Trace:
java.lang.AssertionError: The operations computed by ComputePlanAction should not be null{AFTER_ACTION=[compute_plan, null], BEFORE_ACTION=[compute_plan, null]}
at __randomizedtesting.SeedInfo.seed([71B25240C0EC196F:14710437624FB16C]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.junit.Assert.assertNotNull(Assert.java:712)
at org.apache.solr.cloud.autoscaling.sim.TestSimComputePlanAction.testNodeAdded(TestSimComputePlanAction.java:325)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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:844)
Build Log:
[...truncated 2087 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\core\test\temp\junit4-J0-20190309_071720_43612654965414246719632.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\core\test\temp\junit4-J1-20190309_071720_4351954316595190880659.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 322 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\test-framework\test\temp\junit4-J0-20190309_072603_95814843052636016760044.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\test-framework\test\temp\junit4-J1-20190309_072603_95817702375887219406344.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 1081 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\analysis\common\test\temp\junit4-J0-20190309_072738_84013542660710086551845.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\analysis\common\test\temp\junit4-J1-20190309_072738_8415587292493644617836.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 257 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\analysis\icu\test\temp\junit4-J1-20190309_073142_61314651535334658404388.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\analysis\icu\test\temp\junit4-J0-20190309_073142_61214399821420825857130.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 254 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\analysis\kuromoji\test\temp\junit4-J1-20190309_073208_9424847136798697215993.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\analysis\kuromoji\test\temp\junit4-J0-20190309_073208_9392454241282485550222.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 163 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\analysis\morfologik\test\temp\junit4-J0-20190309_073256_7982846201768192606049.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\analysis\morfologik\test\temp\junit4-J1-20190309_073256_80213251731667754993504.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 205 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\analysis\nori\test\temp\junit4-J1-20190309_073308_9228194363323959388814.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 6 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\analysis\nori\test\temp\junit4-J0-20190309_073308_9225140487402284967739.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 169 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\analysis\opennlp\test\temp\junit4-J0-20190309_073329_4908576472179682941573.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\analysis\opennlp\test\temp\junit4-J1-20190309_073329_49015940172998375974990.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 177 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\analysis\phonetic\test\temp\junit4-J1-20190309_073338_34212096096421656390667.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\analysis\phonetic\test\temp\junit4-J0-20190309_073338_342783781824447485397.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 160 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\analysis\smartcn\test\temp\junit4-J0-20190309_073358_07517830948005820109327.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\analysis\smartcn\test\temp\junit4-J1-20190309_073358_07512811706506983054435.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 165 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\analysis\stempel\test\temp\junit4-J0-20190309_073410_24012218429331730692272.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\analysis\stempel\test\temp\junit4-J1-20190309_073410_24012836218174855169364.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 194 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\backward-codecs\test\temp\junit4-J1-20190309_073416_84315828334167871468723.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 9 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\backward-codecs\test\temp\junit4-J0-20190309_073416_84313861733868596736274.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 1553 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\benchmark\test\temp\junit4-J0-20190309_073552_77313694402237949867775.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\benchmark\test\temp\junit4-J1-20190309_073552_7737203089264907495225.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 255 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\classification\test\temp\junit4-J0-20190309_073607_4258657762782202879822.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\classification\test\temp\junit4-J1-20190309_073607_42514922284696049140283.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 258 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\codecs\test\temp\junit4-J0-20190309_073628_8984208818461214277868.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 7 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\codecs\test\temp\junit4-J1-20190309_073628_8987839100666056760752.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 235 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\demo\test\temp\junit4-J1-20190309_073851_1038128084442877396922.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\demo\test\temp\junit4-J0-20190309_073851_10316604992029095125786.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 171 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\expressions\test\temp\junit4-J0-20190309_073859_6617912218711913180411.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 6 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\expressions\test\temp\junit4-J1-20190309_073859_66614541551450281964286.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 235 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\facet\test\temp\junit4-J0-20190309_073915_3326953248021807929021.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\facet\test\temp\junit4-J1-20190309_073915_33214927382729955595365.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 186 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\grouping\test\temp\junit4-J1-20190309_074003_34315606240800122337207.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\grouping\test\temp\junit4-J0-20190309_074003_3424990832131719265903.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 256 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\highlighter\test\temp\junit4-J0-20190309_074020_38413021510631850011352.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\highlighter\test\temp\junit4-J1-20190309_074020_3843854488404560288941.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 167 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\join\test\temp\junit4-J0-20190309_074046_2906614847646457207651.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\join\test\temp\junit4-J1-20190309_074046_29015549792999032417114.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 156 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\memory\test\temp\junit4-J0-20190309_074059_43314773837527247489104.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\memory\test\temp\junit4-J1-20190309_074059_436275265623138478966.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 199 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\misc\test\temp\junit4-J1-20190309_074115_3672544741830018802452.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\misc\test\temp\junit4-J0-20190309_074115_3672680561383196737558.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 307 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\queries\test\temp\junit4-J1-20190309_074149_2899327194884255454896.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\queries\test\temp\junit4-J0-20190309_074149_2892127181791069431702.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 232 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\queryparser\test\temp\junit4-J0-20190309_074208_2111690074496038341599.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\queryparser\test\temp\junit4-J1-20190309_074208_2112327755423254499575.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 197 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\replicator\test\temp\junit4-J0-20190309_074227_2891802192837071971781.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 23 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\replicator\test\temp\junit4-J1-20190309_074227_28910017385988997109577.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 240 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\sandbox\test\temp\junit4-J0-20190309_074251_30511664494966110399548.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 5 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\sandbox\test\temp\junit4-J1-20190309_074251_3059412794128189217429.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 294 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\spatial-extras\test\temp\junit4-J1-20190309_074340_19314788076306164776740.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 9 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\spatial-extras\test\temp\junit4-J0-20190309_074340_1933928670731725026856.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 193 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\spatial3d\test\temp\junit4-J0-20190309_074428_97714538474316797684637.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 5 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\spatial3d\test\temp\junit4-J1-20190309_074428_97715696866641405556572.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 152 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\spatial\test\temp\junit4-J0-20190309_074503_5911863076731945507912.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 256 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\suggest\test\temp\junit4-J0-20190309_074508_08113464853588639724883.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\suggest\test\temp\junit4-J1-20190309_074508_0816786987856209154512.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 2082 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.sim.TestSimComputePlanAction
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.sim.TestSimComputePlanAction_71B25240C0EC196F-001\init-core-data-001
[junit4] 2> 440575 WARN (SUITE-TestSimComputePlanAction-seed#[71B25240C0EC196F]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=237 numCloses=237
[junit4] 2> 440575 INFO (SUITE-TestSimComputePlanAction-seed#[71B25240C0EC196F]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 440578 INFO (SUITE-TestSimComputePlanAction-seed#[71B25240C0EC196F]-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> 440578 INFO (SUITE-TestSimComputePlanAction-seed#[71B25240C0EC196F]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 440579 INFO (TEST-TestSimComputePlanAction.testNodeAdded-seed#[71B25240C0EC196F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testNodeAdded
[junit4] 2> 440605 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 440605 DEBUG (TEST-TestSimComputePlanAction.testNodeAdded-seed#[71B25240C0EC196F]) [ ] o.a.s.c.a.s.SimClusterStateProvider --- new Overseer leader: 127.0.0.1:10000_solr
[junit4] 2> 440605 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 440606 DEBUG (TEST-TestSimComputePlanAction.testNodeAdded-seed#[71B25240C0EC196F]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=0
[junit4] 2> 440606 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 440606 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 440606 DEBUG (TEST-TestSimComputePlanAction.testNodeAdded-seed#[71B25240C0EC196F]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 0
[junit4] 2> 440606 INFO (TEST-TestSimComputePlanAction.testNodeAdded-seed#[71B25240C0EC196F]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 440608 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:10000_solr]
[junit4] 2> 440608 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- cleaning old nodeLost / nodeAdded markers
[junit4] 2> 440608 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 440608 DEBUG (ScheduledTrigger-2731-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 440619 DEBUG (simCloudManagerPool-2730-thread-1) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 440621 DEBUG (simCloudManagerPool-2730-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 440622 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 440622 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- cleaning old nodeLost / nodeAdded markers
[junit4] 2> 440622 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 440624 DEBUG (simCloudManagerPool-2730-thread-2) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 440624 DEBUG (simCloudManagerPool-2730-thread-2) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3
[junit4] 2> 440624 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 440624 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- cleaning old nodeLost / nodeAdded markers
[junit4] 2> 440624 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 440632 DEBUG (ScheduledTrigger-2731-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 440653 DEBUG (ScheduledTrigger-2731-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 440674 DEBUG (ScheduledTrigger-2731-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 440695 DEBUG (ScheduledTrigger-2731-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 440717 DEBUG (ScheduledTrigger-2731-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 440735 DEBUG (simCloudManagerPool-2730-thread-3) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 440738 DEBUG (simCloudManagerPool-2730-thread-3) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 4
[junit4] 2> 440739 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 4
[junit4] 2> 440739 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger - Initial livenodes: [127.0.0.1:10000_solr]
[junit4] 2> 440739 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=1, actions=[{name=compute_plan, class=solr.ComputePlanAction}, {name=test, class=org.apache.solr.cloud.autoscaling.sim.TestSimComputePlanAction$AssertingTriggerAction}], enabled=true}
[junit4] 2> 440739 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- cleaning old nodeLost / nodeAdded markers
[junit4] 2> 440739 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVersion 4
[junit4] 2> 440746 DEBUG (ScheduledTrigger-2731-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 440746 DEBUG (ScheduledTrigger-2731-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 440747 DEBUG (ScheduledTrigger-2731-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 440748 DEBUG (simCloudManagerPool-2730-thread-4) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 440748 DEBUG (simCloudManagerPool-2730-thread-4) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 5
[junit4] 2> 440748 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 5
[junit4] 2> 440748 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- cleaning old nodeLost / nodeAdded markers
[junit4] 2> 440748 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5, lastZnodeVersion 5
[junit4] 2> 440752 DEBUG (simCloudManagerPool-2730-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider -- simCreateCollection testNodeAdded, currentVersion=1
[junit4] 2> 440753 DEBUG (simCloudManagerPool-2730-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=1
[junit4] 2> 440753 DEBUG (simCloudManagerPool-2730-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 1
[junit4] 2> 440753 DEBUG (simCloudManagerPool-2730-thread-5) [ ] o.a.s.c.o.ClusterStateMutator building a new cName: testNodeAdded
[junit4] 2> 440764 DEBUG (simCloudManagerPool-2730-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=2
[junit4] 2> 440765 DEBUG (simCloudManagerPool-2730-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 2
[junit4] 2> 440766 WARN (simCloudManagerPool-2730-thread-5) [ ] o.a.s.c.a.c.CreateCollectionCmd Specified number of replicas of 4 on collection testNodeAdded is higher than the number of Solr instances currently live or live and part of your createNodeSet(1). It's unusual to run two replica of the same slice on the same Solr-instance.
[junit4] 2> 440779 DEBUG (ScheduledTrigger-2731-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 440779 DEBUG (ScheduledTrigger-2731-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 440779 DEBUG (ScheduledTrigger-2731-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 440784 DEBUG (simCloudManagerPool-2730-thread-5) [ ] o.a.s.c.s.c.a.PolicyHelper Creating a new session
[junit4] 2> 440784 DEBUG (simCloudManagerPool-2730-thread-5) [ ] o.a.s.c.s.c.a.PolicyHelper New session created
[junit4] 2> 440793 DEBUG (simCloudManagerPool-2730-thread-5) [ ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 1064944803 sessionWrapper.createTime 1064944385745300, this.sessionWrapper.createTime 1064944385745300
[junit4] 2> 440793 DEBUG (simCloudManagerPool-2730-thread-5) [ ] o.a.s.c.s.c.a.PolicyHelper session set to NULL
[junit4] 2> 440797 DEBUG (simCloudManagerPool-2730-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider -- finished createCollection testNodeAdded, currentVersion=3
[junit4] 2> 440797 DEBUG (TEST-TestSimComputePlanAction.testNodeAdded-seed#[71B25240C0EC196F]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=3
[junit4] 2> 440798 DEBUG (TEST-TestSimComputePlanAction.testNodeAdded-seed#[71B25240C0EC196F]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 3
[junit4] 2> 440799 DEBUG (simCloudManagerPool-2730-thread-10) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 440799 DEBUG (simCloudManagerPool-2730-thread-10) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 6
[junit4] 2> 440802 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 6
[junit4] 2> 440802 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- cleaning old nodeLost / nodeAdded markers
[junit4] 2> 440802 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6, lastZnodeVersion 6
[junit4] 2> 440802 DEBUG (TEST-TestSimComputePlanAction.testNodeAdded-seed#[71B25240C0EC196F]) [ ] o.a.s.c.a.s.SimClusterStateProvider -- creating marker: /autoscaling/nodeAdded/127.0.0.1:10001_solr
[junit4] 2> 440802 DEBUG (ScheduledTrigger-2731-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 1
[junit4] 2> 440803 DEBUG (ScheduledTrigger-2731-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 440803 DEBUG (ScheduledTrigger-2731-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 440803 DEBUG (ScheduledTrigger-2731-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:10001_solr at time 1064945177280300
[junit4] 2> 440823 DEBUG (ScheduledTrigger-2731-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 440823 DEBUG (ScheduledTrigger-2731-thread-1) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 440823 DEBUG (ScheduledTrigger-2731-thread-1) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 440823 DEBUG (ScheduledTrigger-2731-thread-1) [ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger firing registered processor for nodes: [127.0.0.1:10001_solr] added at times [1064945177280300], now=1064946208525300
[junit4] 2> 440823 DEBUG (ScheduledTrigger-2731-thread-1) [ ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
[junit4] 2> "id":"3c88fdeff3f2cT2zhth4cx7cwkjc2v0qgzqdha9",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":1064945177280300,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[1064945177280300],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "nodeNames":["127.0.0.1:10001_solr"]}}
[junit4] 2> 440823 DEBUG (ScheduledTrigger-2731-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: [.auto_add_replicas, .scheduled_maintenance, node_added_trigger]
[junit4] 2> 440824 DEBUG (ScheduledTrigger-2731-thread-1) [ ] o.a.s.c.a.SystemLogListener Collection .system missing, skip sending event {
[junit4] 2> "id":"3c88fdeff3f2cT2zhth4cx7cwkjc2v0qgzqdha9",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":1064945177280300,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[1064945177280300],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":1064946225770300,
[junit4] 2> "nodeNames":["127.0.0.1:10001_solr"]}}
[junit4] 2> 440824 DEBUG (ScheduledTrigger-2731-thread-1) [ ] o.a.s.c.a.NodeAddedTrigger Removing new node from tracking: 127.0.0.1:10001_solr
[junit4] 2> 440904 DEBUG (simCloudManagerPool-2730-thread-6) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=4
[junit4] 2> 440904 DEBUG (simCloudManagerPool-2730-thread-6) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 4
[junit4] 2> 440913 DEBUG (AutoscalingActionExecutor-2732-thread-1) [ ] o.a.s.c.a.ScheduledTriggers -- processing actions for {
[junit4] 2> "id":"3c88fdeff3f2cT2zhth4cx7cwkjc2v0qgzqdha9",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":1064945177280300,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[1064945177280300],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":1064946225770300,
[junit4] 2> "nodeNames":["127.0.0.1:10001_solr"]}}
[junit4] 2> 440913 DEBUG (AutoscalingActionExecutor-2732-thread-1) [ ] o.a.s.c.a.ComputePlanAction -- processing event: {
[junit4] 2> "id":"3c88fdeff3f2cT2zhth4cx7cwkjc2v0qgzqdha9",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":1064945177280300,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[1064945177280300],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":1064946225770300,
[junit4] 2> "nodeNames":["127.0.0.1:10001_solr"]}} with context properties: {BEFORE_ACTION=[compute_plan]}
[junit4] 2> 440913 DEBUG (AutoscalingActionExecutor-2732-thread-1) [ ] o.a.s.c.s.c.a.PolicyHelper Creating a new session
[junit4] 2> 440919 DEBUG (simCloudManagerPool-2730-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=5
[junit4] 2> 440919 DEBUG (AutoscalingActionExecutor-2732-thread-1) [ ] o.a.s.c.s.c.a.PolicyHelper New session created
[junit4] 2> 440920 DEBUG (simCloudManagerPool-2730-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 5
[junit4] 2> 440931 DEBUG (simCloudManagerPool-2730-thread-8) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=6
[junit4] 2> 440939 DEBUG (simCloudManagerPool-2730-thread-8) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 6
[junit4] 2> 441000 DEBUG (simCloudManagerPool-2730-thread-9) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=7
[junit4] 2> 441027 DEBUG (simCloudManagerPool-2730-thread-9) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 7
[junit4] 2> 441028 DEBUG (simCloudManagerPool-2730-thread-13) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testNodeAdded / shard1 (currentVersion=8): {"core_node1":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "node_name":"127.0.0.1:10000_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 441028 DEBUG (AutoscalingActionExecutor-2732-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=8
[junit4] 2> 441032 DEBUG (AutoscalingActionExecutor-2732-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 8
[junit4] 2> 441040 DEBUG (AutoscalingActionExecutor-2732-thread-1) [ ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 1064957069 sessionWrapper.createTime 1064951033730300, this.sessionWrapper.createTime 1064951033730300
[junit4] 2> 441040 DEBUG (AutoscalingActionExecutor-2732-thread-1) [ ] o.a.s.c.s.c.a.PolicyHelper session set to NULL
[junit4] 2> 441040 DEBUG (AutoscalingActionExecutor-2732-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=9
[junit4] 2> 441040 DEBUG (AutoscalingActionExecutor-2732-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 9
[junit4] 2> 441040 DEBUG (AutoscalingActionExecutor-2732-thread-1) [ ] o.a.s.c.a.SystemLogListener Collection .system missing, skip sending event {
[junit4] 2> "id":"3c88fdeff3f2cT2zhth4cx7cwkjc2v0qgzqdha9",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":1064945177280300,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[1064945177280300],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":1064946225770300,
[junit4] 2> "nodeNames":["127.0.0.1:10001_solr"]}}
[junit4] 2> 441040 DEBUG (AutoscalingActionExecutor-2732-thread-1) [ ] o.a.s.c.a.SystemLogListener Collection .system missing, skip sending event {
[junit4] 2> "id":"3c88fdeff3f2cT2zhth4cx7cwkjc2v0qgzqdha9",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":1064945177280300,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[1064945177280300],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":1064946225770300,
[junit4] 2> "nodeNames":["127.0.0.1:10001_solr"]}}
[junit4] 2> 441040 DEBUG (AutoscalingActionExecutor-2732-thread-1) [ ] o.a.s.c.a.SystemLogListener Collection .system missing, skip sending event {
[junit4] 2> "id":"3c88fdeff3f2cT2zhth4cx7cwkjc2v0qgzqdha9",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":1064945177280300,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[1064945177280300],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":1064946225770300,
[junit4] 2> "nodeNames":["127.0.0.1:10001_solr"]}}
[junit4] 2> 441040 DEBUG (AutoscalingActionExecutor-2732-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger after 100ms
[junit4] 2> 441040 DEBUG (AutoscalingActionExecutor-2732-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Resuming trigger: .scheduled_maintenance after 100ms
[junit4] 2> 441040 DEBUG (AutoscalingActionExecutor-2732-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Resuming trigger: .auto_add_replicas after 100ms
[junit4] 2> 441040 DEBUG (AutoscalingActionExecutor-2732-thread-1) [ ] o.a.s.c.a.ScheduledTriggers -- processing took 6432 ms for event id=3c88fdeff3f2cT2zhth4cx7cwkjc2v0qgzqdha9
[junit4] 2> 441044 INFO (TEST-TestSimComputePlanAction.testNodeAdded-seed#[71B25240C0EC196F]) [ ] o.a.s.c.a.s.TestSimComputePlanAction Node values: {
[junit4] 2> "127.0.0.1:10001_solr":{
[junit4] 2> "sysprop.java.vendor":"Oracle Corporation",
[junit4] 2> "cores":0,
[junit4] 2> "host":"127.0.0.1",
[junit4] 2> "freedisk":1024,
[junit4] 2> "sysprop.java.version":"9.0.4",
[junit4] 2> "ip_1":"127",
[junit4] 2> "ip_3":"0",
[junit4] 2> "ip_2":"0",
[junit4] 2> "ip_4":"1",
[junit4] 2> "node":"127.0.0.1:10001_solr",
[junit4] 2> "metrics:solr.jvm:buffers.direct.Count":0,
[junit4] 2> "port":10001,
[junit4] 2> "heapUsage":123450000,
[junit4] 2> "sysLoadAvg":1.0,
[junit4] 2> "metrics:solr.node:ADMIN./admin/authorization.clientErrors:count":0,
[junit4] 2> "totaldisk":10240},
[junit4] 2> "127.0.0.1:10000_solr":{
[junit4] 2> "sysprop.java.vendor":"Oracle Corporation",
[junit4] 2> "cores":4,
[junit4] 2> "host":"127.0.0.1",
[junit4] 2> "freedisk":1020,
[junit4] 2> "sysprop.java.version":"9.0.4",
[junit4] 2> "ip_1":"127",
[junit4] 2> "ip_3":"0",
[junit4] 2> "ip_2":"0",
[junit4] 2> "ip_4":"1",
[junit4] 2> "node":"127.0.0.1:10000_solr",
[junit4] 2> "metrics:solr.jvm:buffers.direct.Count":0,
[junit4] 2> "port":10000,
[junit4] 2> "heapUsage":123450000,
[junit4] 2> "sysLoadAvg":1.0,
[junit4] 2> "metrics:solr.node:ADMIN./admin/authorization.clientErrors:count":0,
[junit4] 2> "totaldisk":10240}}
[junit4] 2> 441044 INFO (TEST-TestSimComputePlanAction.testNodeAdded-seed#[71B25240C0EC196F]) [ ] o.a.s.c.a.s.TestSimComputePlanAction Live nodes: [127.0.0.1:10001_solr, 127.0.0.1:10000_solr], collection state: DocCollection(testNodeAdded//clusterstate.json/9)={
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"-1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"4",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "autoCreated":"true",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "replicas":{
[junit4] 2> "core_node1":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "node_name":"127.0.0.1:10000_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0},
[junit4] 2> "core_node2":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "node_name":"127.0.0.1:10000_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0},
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n3",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "node_name":"127.0.0.1:10000_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n4",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "node_name":"127.0.0.1:10000_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}},
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active"}}}
[junit4] 2> 441044 INFO (TEST-TestSimComputePlanAction.testNodeAdded-seed#[71B25240C0EC196F]) [ ] o.a.s.c.a.s.TestSimComputePlanAction -------------_ FINAL STATE --------------
[junit4] 2> 441044 INFO (TEST-TestSimComputePlanAction.testNodeAdded-seed#[71B25240C0EC196F]) [ ] o.a.s.c.a.s.TestSimComputePlanAction * Node values: {
[junit4] 2> "127.0.0.1:10001_solr":{
[junit4] 2> "sysprop.java.vendor":"Oracle Corporation",
[junit4] 2> "cores":0,
[junit4] 2> "host":"127.0.0.1",
[junit4] 2> "freedisk":1024,
[junit4] 2> "sysprop.java.version":"9.0.4",
[junit4] 2> "ip_1":"127",
[junit4] 2> "ip_3":"0",
[junit4] 2> "ip_2":"0",
[junit4] 2> "ip_4":"1",
[junit4] 2> "node":"127.0.0.1:10001_solr",
[junit4] 2> "metrics:solr.jvm:buffers.direct.Count":0,
[junit4] 2> "port":10001,
[junit4] 2> "heapUsage":123450000,
[junit4] 2> "sysLoadAvg":1.0,
[junit4] 2> "metrics:solr.node:ADMIN./admin/authorization.clientErrors:count":0,
[junit4] 2> "totaldisk":10240},
[junit4] 2> "127.0.0.1:10000_solr":{
[junit4] 2> "sysprop.java.vendor":"Oracle Corporation",
[junit4] 2> "cores":4,
[junit4] 2> "host":"127.0.0.1",
[junit4] 2> "freedisk":1020,
[junit4] 2> "sysprop.java.version":"9.0.4",
[junit4] 2> "ip_1":"127",
[junit4] 2> "ip_3":"0",
[junit4] 2> "ip_2":"0",
[junit4] 2> "ip_4":"1",
[junit4] 2> "node":"127.0.0.1:10000_solr",
[junit4] 2> "metrics:solr.jvm:buffers.direct.Count":0,
[junit4] 2> "port":10000,
[junit4] 2> "heapUsage":123450000,
[junit4] 2> "sysLoadAvg":1.0,
[junit4] 2> "metrics:solr.node:ADMIN./admin/authorization.clientErrors:count":0,
[junit4] 2> "totaldisk":10240}}
[junit4] 2> 441044 INFO (TEST-TestSimComputePlanAction.testNodeAdded-seed#[71B25240C0EC196F]) [ ] o.a.s.c.a.s.TestSimComputePlanAction * Live nodes: [127.0.0.1:10001_solr, 127.0.0.1:10000_solr]
[junit4] 2> 441044 INFO (TEST-TestSimComputePlanAction.testNodeAdded-seed#[71B25240C0EC196F]) [ ] o.a.s.c.a.s.TestSimComputePlanAction * Collection testNodeAdded state: DocCollection(testNodeAdded//clusterstate.json/9)={
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"-1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"4",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "autoCreated":"true",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "replicas":{
[junit4] 2> "core_node1":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "node_name":"127.0.0.1:10000_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0},
[junit4] 2> "core_node2":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "node_name":"127.0.0.1:10000_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0},
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n3",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "node_name":"127.0.0.1:10000_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n4",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "node_name":"127.0.0.1:10000_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}},
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active"}}}
[junit4] 2> 441044 DEBUG (TEST-TestSimComputePlanAction.testNodeAdded-seed#[71B25240C0EC196F]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 441044 DEBUG (TEST-TestSimComputePlanAction.testNodeAdded-seed#[71B25240C0EC196F]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 441044 DEBUG (TEST-TestSimComputePlanAction.testNodeAdded-seed#[71B25240C0EC196F]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 441044 INFO (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 441044 DEBUG (TEST-TestSimComputePlanAction.testNodeAdded-seed#[71B25240C0EC196F]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSimComputePlanAction -Dtests.method=testNodeAdded -Dtests.seed=71B25240C0EC196F -Dtests.slow=true -Dtests.locale=en-DM -Dtests.timezone=America/Guadeloupe -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] FAILURE 0.47s J0 | TestSimComputePlanAction.testNodeAdded <<<
[junit4] > Throwable #1: java.lang.AssertionError: The operations computed by ComputePlanAction should not be null{AFTER_ACTION=[compute_plan, null], BEFORE_ACTION=[compute_plan, null]}
[junit4] > at __randomizedtesting.SeedInfo.seed([71B25240C0EC196F:14710437624FB16C]:0)
[junit4] > at org.apache.solr.cloud.autoscaling.sim.TestSimComputePlanAction.testNodeAdded(TestSimComputePlanAction.java:325)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] IGNOR/A 0.00s J0 | TestSimComputePlanAction.testNodeLost
[junit4] > Assumption #1: 'awaitsfix' test group is disabled (@AwaitsFix(bugUrl="https://issues.apache.org/jira/browse/SOLR-12028"))
[junit4] 2> 441044 DEBUG (TEST-TestSimComputePlanAction.testNodeAdded-seed#[71B25240C0EC196F]) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 441044 DEBUG (TEST-TestSimComputePlanAction.testNodeAdded-seed#[71B25240C0EC196F]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 441044 INFO (TEST-TestSimComputePlanAction.testNodeAdded-seed#[71B25240C0EC196F]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testNodeAdded
[junit4] 2> 441049 INFO (TEST-TestSimComputePlanAction.testNodeWithMultipleReplicasLost-seed#[71B25240C0EC196F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testNodeWithMultipleReplicasLost
[junit4] 2> 441054 DEBUG (TEST-TestSimComputePlanAction.testNodeWithMultipleReplicasLost-seed#[71B25240C0EC196F]) [ ] o.a.s.c.a.s.SimClusterStateProvider --- new Overseer leader: 127.0.0.1:10002_solr
[junit4] 2> 441054 DEBUG (TEST-TestSimComputePlanAction.testNodeWithMultipleReplicasLost-seed#[71B25240C0EC196F]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=0
[junit4] 2> 441054 DEBUG (TEST-TestSimComputePlanAction.testNodeWithMultipleReplicasLost-seed#[71B25240C0EC196F]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 0
[junit4] 2> 441054 INFO (TEST-TestSimComputePlanAction.testNodeWithMultipleReplicasLost-seed#[71B25240C0EC196F]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 441058 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 441058 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 441058 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 441058 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 441059 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:10002_solr]
[junit4] 2> 441059 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- cleaning old nodeLost / nodeAdded markers
[junit4] 2> 441059 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 441062 DEBUG (simCloudManagerPool-2736-thread-1) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 441064 DEBUG (simCloudManagerPool-2736-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 441064 DEBUG (ScheduledTrigger-2737-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 441067 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 441067 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- cleaning old nodeLost / nodeAdded markers
[junit4] 2> 441067 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 441069 DEBUG (simCloudManagerPool-2736-thread-2) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 441069 DEBUG (simCloudManagerPool-2736-thread-2) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3
[junit4] 2> 441070 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 441070 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- cleaning old nodeLost / nodeAdded markers
[junit4] 2> 441070 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 441086 DEBUG (ScheduledTrigger-2737-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 441107 DEBUG (ScheduledTrigger-2737-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 441129 DEBUG (ScheduledTrigger-2737-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 441150 DEBUG (ScheduledTrigger-2737-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 441171 DEBUG (ScheduledTrigger-2737-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 441173 DEBUG (simCloudManagerPool-2736-thread-3) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 441173 DEBUG (simCloudManagerPool-2736-thread-3) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 4
[junit4] 2> 441173 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 4
[junit4] 2> 441173 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger node_lost_trigger - Initial livenodes: [127.0.0.1:10004_solr, 127.0.0.1:10005_solr, 127.0.0.1:10002_solr, 127.0.0.1:10003_solr]
[junit4] 2> 441173 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- cleaning old nodeLost / nodeAdded markers
[junit4] 2> 441173 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVersion 4
[junit4] 2> 441174 DEBUG (ScheduledTrigger-2737-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 4 and last live nodes: 4
[junit4] 2> 441174 DEBUG (simCloudManagerPool-2736-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider -- simCreateCollection testNodeWithMultipleReplicasLost, currentVersion=1
[junit4] 2> 441174 DEBUG (simCloudManagerPool-2736-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=1
[junit4] 2> 441175 DEBUG (simCloudManagerPool-2736-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 1
[junit4] 2> 441175 DEBUG (simCloudManagerPool-2736-thread-4) [ ] o.a.s.c.o.ClusterStateMutator building a new cName: testNodeWithMultipleReplicasLost
[junit4] 2> 441185 DEBUG (simCloudManagerPool-2736-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=2
[junit4] 2> 441185 DEBUG (simCloudManagerPool-2736-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 2
[junit4] 2> 441185 DEBUG (simCloudManagerPool-2736-thread-4) [ ] o.a.s.c.s.c.a.PolicyHelper Creating a new session
[junit4] 2> 441186 DEBUG (simCloudManagerPool-2736-thread-4) [ ] o.a.s.c.s.c.a.PolicyHelper New session created
[junit4] 2> 441193 DEBUG (ScheduledTrigger-2737-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 and last live nodes: 1
[junit4] 2> 441193 DEBUG (simCloudManagerPool-2736-thread-4) [ ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 1064964757 sessionWrapper.createTime 1064964324905300, this.sessionWrapper.createTime 1064964324905300
[junit4] 2> 441195 DEBUG (simCloudManagerPool-2736-thread-4) [ ] o.a.s.c.s.c.a.PolicyHelper session set to NULL
[junit4] 2> 441195 DEBUG (simCloudManagerPool-2736-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider -- finished createCollection testNodeWithMultipleReplicasLost, currentVersion=3
[junit4] 2> 441196 DEBUG (ScheduledTrigger-2737-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 4 and last live nodes: 4
[junit4] 2> 441206 DEBUG (simCloudManagerPool-2736-thread-10) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=3
[junit4] 2> 441206 DEBUG (simCloudManagerPool-2736-thread-10) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 3
[junit4] 2> 441214 DEBUG (ScheduledTrigger-2737-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 and last live nodes: 4
[junit4] 2> 441216 DEBUG (ScheduledTrigger-2737-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 4 and last live nodes: 4
[junit4] 2> 441216 DEBUG (simCloudManagerPool-2736-thread-9) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=4
[junit4] 2> 441217 DEBUG (simCloudManagerPool-2736-thread-9) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 4
[junit4] 2> 441228 DEBUG (simCloudManagerPool-2736-thread-8) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=5
[junit4] 2> 441228 DEBUG (simCloudManagerPool-2736-thread-8) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 5
[junit4] 2> 441234 DEBUG (ScheduledTrigger-2737-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 and last live nodes: 4
[junit4] 2> 441237 DEBUG (ScheduledTrigger-2737-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 4 and last live nodes: 4
[junit4] 2> 441238 DEBUG (simCloudManagerPool-2736-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=6
[junit4] 2> 441239 DEBUG (simCloudManagerPool-2736-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 6
[junit4] 2> 441249 DEBUG (simCloudManagerPool-2736-thread-6) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=7
[junit4] 2> 441249 DEBUG (simCloudManagerPool-2736-thread-6) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 7
[junit4] 2> 441254 DEBUG (ScheduledTrigger-2737-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 and last live nodes: 4
[junit4] 2> 441258 DEBUG (ScheduledTrigger-2737-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 4 and last live nodes: 4
[junit4] 2> 441260 DEBUG (simCloudManagerPool-2736-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=8
[junit4] 2> 441260 DEBUG (simCloudManagerPool-2736-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 8
[junit4] 2> 441260 DEBUG (TEST-TestSimComputePlanAction.testNodeWithMultipleReplicasLost-seed#[71B25240C0EC196F]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=9
[junit4] 2> 441262 DEBUG (TEST-TestSimComputePlanAction.testNodeWithMultipleReplicasLost-seed#[71B25240C0EC196F]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 9
[junit4] 2> 441262 INFO (TEST-TestSimComputePlanAction.testNodeWithMultipleReplicasLost-seed#[71B25240C0EC196F]) [ ] o.a.s.c.CloudTestUtils -- shards without leaders: [shard2, shard1]
[junit4] 2> 441262 DEBUG (simCloudManagerPool-2736-thread-11) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testNodeWithMultipleReplicasLost / shard2 (currentVersion=10): {"core_node6":{
[junit4] 2> "core":"testNodeWithMultipleReplicasLost_shard2_replica_n6",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testNodeWithMultipleReplicasLost",
[junit4] 2> "node_name":"127.0.0.1:10002_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 441263 DEBUG (simCloudManagerPool-2736-thread-15) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testNodeWithMultipleReplicasLost / shard1 (currentVersion=10): {"core_node3":{
[junit4] 2> "core":"testNodeWithMultipleReplicasLost_shard1_replica_n3",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeWithMultipleReplicasLost",
[junit4] 2> "node_name":"127.0.0.1:10005_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 441264 DEBUG (TEST-TestSimComputePlanAction.testNodeWithMultipleReplicasLost-seed#[71B25240C0EC196F]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=10
[junit4] 2> 441264 DEBUG (TEST-TestSimComputePlanAction.testNodeWithMultipleReplicasLost-seed#[71B25240C0EC196F]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 10
[junit4] 2> 441264 DEBUG (TEST-TestSimComputePlanAction.testNodeWithMultipleReplicasLost-seed#[71B25240C0EC196F]) [ ] o.a.s.c.a.s.TestSimComputePlanAction -- cluster state: znodeVersion: 11
[junit4] 2> live nodes:[127.0.0.1:10004_solr, 127.0.0.1:10002_solr, 127.0.0.1:10003_solr, 127.0.0.1:10005_solr]
[junit4] 2> collections:{testNodeWithMultipleReplicasLost=DocCollection(testNodeWithMultipleReplicasLost//clusterstate.json/10)={
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"2",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"3",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "autoCreated":"true",
[junit4] 2> "shards":{
[junit4] 2> "shard2":{
[junit4] 2> "replicas":{
[junit4] 2> "core_node6":{
[junit4] 2> "core":"testNodeWithMultipleReplicasLost_shard2_replica_n6",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "node_name":"127.0.0.1:10002_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeWithMultipleReplicasLost_shard2_replica_n4",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "node_name":"127.0.0.1:10004_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0},
[junit4] 2> "core_node5":{
[junit4] 2> "core":"testNodeWithMultipleReplicasLost_shard2_replica_n5",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "node_name":"127.0.0.1:10005_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}},
[junit4] 2> "range":"0-7fffffff",
[junit4] 2> "state":"active"},
[junit4] 2> "shard1":{
[junit4] 2> "replicas":{
[junit4] 2> "core_node1":{
[junit4] 2> "core":"testNodeWithMultipleReplicasLost_shard1_replica_n1",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "node_name":"127.0.0.1:10003_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0},
[junit4] 2> "core_node2":{
[junit4] 2> "core":"testNodeWithMultipleReplicasLost_shard1_replica_n2",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "node_name":"127.0.0.1:10002_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0},
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testNodeWithMultipleReplicasLost_shard1_replica_n3",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "node_name":"127.0.0.1:10005_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}},
[junit4] 2> "range":"80000000-ffffffff",
[junit4] 2> "state":"active"}}}}
[junit4] 2> 441265 DEBUG (TEST-TestSimComputePlanAction.testNodeWithMultipleReplicasLost-seed#[71B25240C0EC196F]) [ ] o.a.s.c.a.s.SimClusterStateProvider -- created marker: /autoscaling/nodeLost/127.0.0.1:10005_solr
[junit4] 2> 441265 DEBUG (TEST-TestSimComputePlanAction.testNodeWithMultipleReplicasLost-seed#[71B25240C0EC196F]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=11
[junit4] 2> 441265 DEBUG (TEST-TestSimComputePlanAction.testNodeWithMultipleReplicasLost-seed#[71B25240C0EC196F]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 11
[junit4] 2> 441265 INFO (simCloudManagerPool-2736-thread-20) [ ] o.a.s.c.ActionThrottle Throttling leader attempts - waiting for 4839ms
[junit4] 2> 441275 DEBUG (ScheduledTrigger-2737-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 3 and last live nodes: 4
[junit4] 2> 441275 DEBUG (ScheduledTrigger-2737-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Tracking lost node: 127.0.0.1:10005_solr
[junit4] 2> 441279 DEBUG (ScheduledTrigger-2737-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 3 and last live nodes: 4
[junit4] 2> 441279 DEBUG (ScheduledTrigger-2737-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Tracking lost node: 127.0.0.1:10005_solr
[junit4] 2> 441297 DEBUG (ScheduledTrigger-2737-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 3 and last live nodes: 3
[junit4] 2> 441300 DEBUG (ScheduledTrigger-2737-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 3 and last live nodes: 3
[junit4] 2> 441300 DEBUG (ScheduledTrigger-2737-thread-4) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger firing registered processor for lost nodes: [127.0.0.1:10005_solr]
[junit4] 2> 441300 DEBUG (ScheduledTrigger-2737-thread-4) [ ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
[junit4] 2> "id":"3c8956ad2f854T2zhth4cx7cwkjc2v0qgzqdhab",
[junit4] 2> "source":"node_lost_trigger",
[junit4] 2> "eventTime":1064968998025300,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[1064968998025300],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "nodeNames":["127.0.0.1:10005_solr"]}}
[junit4] 2> 441300 DEBUG (ScheduledTrigger-2737-thread-4) [ ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: [.auto_add_replicas, .scheduled_maintenance, node_lost_trigger]
[junit4] 2> 441300 DEBUG (ScheduledTrigg
[...truncated too long message...]
Lucene-Solr-8.x-Windows\solr\build\contrib\solr-analytics\test\temp\junit4-J0-20190309_085916_6996575483858021960947.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\contrib\solr-analytics\test\temp\junit4-J1-20190309_085916_6997848692990431287168.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 572 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\contrib\solr-clustering\test\temp\junit4-J0-20190309_090226_86814360261712987131952.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\contrib\solr-clustering\test\temp\junit4-J1-20190309_090226_86817128655132988106248.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 1301 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\contrib\solr-dataimporthandler-extras\test\temp\junit4-J0-20190309_090334_18915282714779791377883.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\contrib\solr-dataimporthandler-extras\test\temp\junit4-J1-20190309_090334_1892738352158433042284.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 667 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\contrib\solr-dataimporthandler\test\temp\junit4-J1-20190309_090411_7598360265203091611899.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\contrib\solr-dataimporthandler\test\temp\junit4-J0-20190309_090411_7595218638372842687342.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 574 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\contrib\solr-cell\test\temp\junit4-J0-20190309_090521_03983401498146525555.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\contrib\solr-cell\test\temp\junit4-J1-20190309_090521_0404251208116988765826.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 582 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\contrib\solr-langid\test\temp\junit4-J0-20190309_090602_39613835065362435426863.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\contrib\solr-langid\test\temp\junit4-J1-20190309_090602_39613370397460155235344.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 681 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\contrib\solr-ltr\test\temp\junit4-J1-20190309_090636_7454795839840348821960.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\contrib\solr-ltr\test\temp\junit4-J0-20190309_090636_7458404514295422688617.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 582 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\contrib\solr-prometheus-exporter\test\temp\junit4-J1-20190309_090754_71314872950083743149860.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\contrib\solr-prometheus-exporter\test\temp\junit4-J0-20190309_090754_71314550429521133521148.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 570 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\contrib\solr-velocity\test\temp\junit4-J0-20190309_090850_0443886859216239269968.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 33558 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/74/consoleText
[repro] Revision: 83ab355772d46b755cab52e73051f395f938fd72
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseConcMarkSweepGC"
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr\core
[repro] TestSimComputePlanAction
[repro] ant compile-test
[...truncated 3602 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestSimComputePlanAction" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseConcMarkSweepGC" -Dtests.seed=71B25240C0EC196F -Dtests.slow=true -Dtests.locale=en-DM -Dtests.timezone=America/Guadeloupe -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[...truncated 60 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\temp\junit4-J1-20190309_091645_310299455074071127947.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 7 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\temp\junit4-J0-20190309_091645_31413185311909426860232.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 21 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.cloud.autoscaling.sim.TestSimComputePlanAction
[repro] Exiting with code 0
[...truncated 78 lines...]
[JENKINS] Lucene-Solr-8.x-Windows (64bit/jdk-9.0.4) - Build # 75 -
Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/75/
Java: 64bit/jdk-9.0.4 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
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([A9746563D833E63B:32CF0B3B956BD465]: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:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
Build Log:
[...truncated 14296 lines...]
[junit4] Suite: org.apache.solr.cloud.DocValuesNotIndexedTest
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DocValuesNotIndexedTest_A9746563D833E63B-001\init-core-data-001
[junit4] 2> 2095645 WARN (SUITE-DocValuesNotIndexedTest-seed#[A9746563D833E63B]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=105 numCloses=105
[junit4] 2> 2095645 INFO (SUITE-DocValuesNotIndexedTest-seed#[A9746563D833E63B]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 2095648 INFO (SUITE-DocValuesNotIndexedTest-seed#[A9746563D833E63B]-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> 2095648 INFO (SUITE-DocValuesNotIndexedTest-seed#[A9746563D833E63B]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 2095698 INFO (TEST-DocValuesNotIndexedTest.testGroupingDVOnly-seed#[A9746563D833E63B]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testGroupingDVOnly
[junit4] 2> 2095698 INFO (TEST-DocValuesNotIndexedTest.testGroupingDVOnly-seed#[A9746563D833E63B]) [ ] 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\J0\temp\solr.cloud.DocValuesNotIndexedTest_A9746563D833E63B-001\tempDir-001
[junit4] 2> 2095698 INFO (TEST-DocValuesNotIndexedTest.testGroupingDVOnly-seed#[A9746563D833E63B]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2095698 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2095698 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2095799 INFO (TEST-DocValuesNotIndexedTest.testGroupingDVOnly-seed#[A9746563D833E63B]) [ ] o.a.s.c.ZkTestServer start zk server on port:63843
[junit4] 2> 2095799 INFO (TEST-DocValuesNotIndexedTest.testGroupingDVOnly-seed#[A9746563D833E63B]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:63843
[junit4] 2> 2095799 INFO (TEST-DocValuesNotIndexedTest.testGroupingDVOnly-seed#[A9746563D833E63B]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 63843
[junit4] 2> 2095806 INFO (zkConnectionManagerCallback-10151-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2095812 INFO (zkConnectionManagerCallback-10153-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2095816 INFO (zkConnectionManagerCallback-10155-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2095818 WARN (jetty-launcher-10156-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2095818 INFO (jetty-launcher-10156-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 2095818 INFO (jetty-launcher-10156-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2095818 INFO (jetty-launcher-10156-thread-1) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 9.0.4+11
[junit4] 2> 2095818 INFO (jetty-launcher-10156-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2095818 INFO (jetty-launcher-10156-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2095818 INFO (jetty-launcher-10156-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2095818 INFO (jetty-launcher-10156-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@46524361{/solr,null,AVAILABLE}
[junit4] 2> 2095818 WARN (jetty-launcher-10156-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2095818 INFO (jetty-launcher-10156-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 2095818 INFO (jetty-launcher-10156-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2095824 INFO (jetty-launcher-10156-thread-2) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 9.0.4+11
[junit4] 2> 2095835 INFO (jetty-launcher-10156-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2095835 INFO (jetty-launcher-10156-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2095835 INFO (jetty-launcher-10156-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2095835 INFO (jetty-launcher-10156-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@97fae96{/solr,null,AVAILABLE}
[junit4] 2> 2095867 INFO (jetty-launcher-10156-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@60835308{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:63854}
[junit4] 2> 2095867 INFO (jetty-launcher-10156-thread-1) [ ] o.e.j.s.Server Started @2095913ms
[junit4] 2> 2095867 INFO (jetty-launcher-10156-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=63854}
[junit4] 2> 2095868 ERROR (jetty-launcher-10156-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2095868 INFO (jetty-launcher-10156-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2095868 INFO (jetty-launcher-10156-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.1.0
[junit4] 2> 2095868 INFO (jetty-launcher-10156-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2095868 INFO (jetty-launcher-10156-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2095868 INFO (jetty-launcher-10156-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-03-09T14:01:18.021800500Z
[junit4] 2> 2095875 INFO (jetty-launcher-10156-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6dbb1737{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:63857}
[junit4] 2> 2095875 INFO (jetty-launcher-10156-thread-2) [ ] o.e.j.s.Server Started @2095921ms
[junit4] 2> 2095875 INFO (jetty-launcher-10156-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=63857}
[junit4] 2> 2095875 ERROR (jetty-launcher-10156-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2095875 INFO (jetty-launcher-10156-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2095875 INFO (jetty-launcher-10156-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.1.0
[junit4] 2> 2095875 INFO (jetty-launcher-10156-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2095875 INFO (jetty-launcher-10156-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2095875 INFO (jetty-launcher-10156-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-03-09T14:01:18.028991700Z
[junit4] 2> 2095905 INFO (zkConnectionManagerCallback-10158-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2095906 INFO (jetty-launcher-10156-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2095966 INFO (zkConnectionManagerCallback-10160-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2095967 INFO (jetty-launcher-10156-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2096470 INFO (jetty-launcher-10156-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 2096470 WARN (jetty-launcher-10156-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@19dcb502[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2096476 WARN (jetty-launcher-10156-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@2d72063b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2096476 INFO (jetty-launcher-10156-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63843/solr
[junit4] 2> 2096491 INFO (zkConnectionManagerCallback-10166-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2096506 INFO (zkConnectionManagerCallback-10168-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2096780 INFO (jetty-launcher-10156-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 2096782 WARN (jetty-launcher-10156-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@47fa819b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2096787 WARN (jetty-launcher-10156-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@34f91a09[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2096788 INFO (jetty-launcher-10156-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63843/solr
[junit4] 2> 2096791 INFO (zkConnectionManagerCallback-10176-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2096792 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 0x10040c8dc890007, likely client has closed socket
[junit4] 2> 2096795 INFO (zkConnectionManagerCallback-10178-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2096810 INFO (jetty-launcher-10156-thread-2) [n:127.0.0.1:63857_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:63857_solr
[junit4] 2> 2096810 INFO (jetty-launcher-10156-thread-2) [n:127.0.0.1:63857_solr ] o.a.s.c.Overseer Overseer (id=72128825475530760-127.0.0.1:63857_solr-n_0000000000) starting
[junit4] 2> 2096818 INFO (zkConnectionManagerCallback-10185-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2096821 INFO (jetty-launcher-10156-thread-2) [n:127.0.0.1:63857_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:63843/solr ready
[junit4] 2> 2096821 INFO (jetty-launcher-10156-thread-2) [n:127.0.0.1:63857_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63857_solr
[junit4] 2> 2096821 INFO (OverseerStateUpdate-72128825475530760-127.0.0.1:63857_solr-n_0000000000) [n:127.0.0.1:63857_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:63857_solr
[junit4] 2> 2096823 INFO (OverseerStateUpdate-72128825475530760-127.0.0.1:63857_solr-n_0000000000) [n:127.0.0.1:63857_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2096825 INFO (zkCallback-10184-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2096836 INFO (jetty-launcher-10156-thread-1) [n:127.0.0.1:63854_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2096839 INFO (jetty-launcher-10156-thread-1) [n:127.0.0.1:63854_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:63854_solr as DOWN
[junit4] 2> 2096839 INFO (jetty-launcher-10156-thread-1) [n:127.0.0.1:63854_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2096839 INFO (jetty-launcher-10156-thread-1) [n:127.0.0.1:63854_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63854_solr
[junit4] 2> 2096844 INFO (zkCallback-10184-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2096844 INFO (zkCallback-10177-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2096866 INFO (zkCallback-10167-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2096870 INFO (jetty-launcher-10156-thread-2) [n:127.0.0.1:63857_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2096882 INFO (zkConnectionManagerCallback-10191-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2096883 INFO (jetty-launcher-10156-thread-1) [n:127.0.0.1:63854_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2096884 INFO (jetty-launcher-10156-thread-1) [n:127.0.0.1:63854_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:63843/solr ready
[junit4] 2> 2096884 INFO (jetty-launcher-10156-thread-1) [n:127.0.0.1:63854_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2096903 INFO (jetty-launcher-10156-thread-2) [n:127.0.0.1:63857_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63857.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@73734bae
[junit4] 2> 2096908 INFO (jetty-launcher-10156-thread-1) [n:127.0.0.1:63854_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63854.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@73734bae
[junit4] 2> 2096914 INFO (jetty-launcher-10156-thread-2) [n:127.0.0.1:63857_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63857.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@73734bae
[junit4] 2> 2096914 INFO (jetty-launcher-10156-thread-2) [n:127.0.0.1:63857_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63857.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@73734bae
[junit4] 2> 2096915 INFO (jetty-launcher-10156-thread-2) [n:127.0.0.1:63857_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\J0\temp\solr.cloud.DocValuesNotIndexedTest_A9746563D833E63B-001\tempDir-001\node2\.
[junit4] 2> 2096919 INFO (jetty-launcher-10156-thread-1) [n:127.0.0.1:63854_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63854.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@73734bae
[junit4] 2> 2096919 INFO (jetty-launcher-10156-thread-1) [n:127.0.0.1:63854_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63854.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@73734bae
[junit4] 2> 2096919 INFO (jetty-launcher-10156-thread-1) [n:127.0.0.1:63854_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\J0\temp\solr.cloud.DocValuesNotIndexedTest_A9746563D833E63B-001\tempDir-001\node1\.
[junit4] 2> 2096975 INFO (zkConnectionManagerCallback-10197-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2096977 INFO (TEST-DocValuesNotIndexedTest.testGroupingDVOnly-seed#[A9746563D833E63B]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2096978 INFO (TEST-DocValuesNotIndexedTest.testGroupingDVOnly-seed#[A9746563D833E63B]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:63843/solr ready
[junit4] 2> 2096985 INFO (qtp1547594248-30694) [n:127.0.0.1:63854_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> 2096989 INFO (OverseerThreadFactory-10429-thread-1-processing-n:127.0.0.1:63857_solr) [n:127.0.0.1:63857_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection dv_coll
[junit4] 2> 2097097 INFO (OverseerStateUpdate-72128825475530760-127.0.0.1:63857_solr-n_0000000000) [n:127.0.0.1:63857_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:63854/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2097099 INFO (OverseerStateUpdate-72128825475530760-127.0.0.1:63857_solr-n_0000000000) [n:127.0.0.1:63857_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:63857/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2097101 INFO (OverseerStateUpdate-72128825475530760-127.0.0.1:63857_solr-n_0000000000) [n:127.0.0.1:63857_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:63854/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2097102 INFO (OverseerStateUpdate-72128825475530760-127.0.0.1:63857_solr-n_0000000000) [n:127.0.0.1:63857_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:63857/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2097306 INFO (qtp1547594248-30688) [n:127.0.0.1:63854_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> 2097306 INFO (qtp1604655689-30701) [n:127.0.0.1:63857_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> 2097306 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_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> 2097306 INFO (qtp1604655689-30699) [n:127.0.0.1:63857_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> 2097306 INFO (qtp1604655689-30699) [n:127.0.0.1:63857_solr x:dv_coll_shard2_replica_n2] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2098324 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_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> 2098324 INFO (qtp1604655689-30699) [n:127.0.0.1:63857_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> 2098326 INFO (qtp1604655689-30701) [n:127.0.0.1:63857_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> 2098326 INFO (qtp1547594248-30688) [n:127.0.0.1:63854_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> 2098334 INFO (qtp1604655689-30699) [n:127.0.0.1:63857_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> 2098335 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_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> 2098337 INFO (qtp1547594248-30688) [n:127.0.0.1:63854_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> 2098337 INFO (qtp1604655689-30699) [n:127.0.0.1:63857_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> 2098337 INFO (qtp1604655689-30699) [n:127.0.0.1:63857_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> 2098337 INFO (qtp1604655689-30699) [n:127.0.0.1:63857_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_63857.solr.core.dv_coll.shard2.replica_n2' (registry 'solr.core.dv_coll.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@73734bae
[junit4] 2> 2098337 INFO (qtp1604655689-30699) [n:127.0.0.1:63857_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\J0\temp\solr.cloud.DocValuesNotIndexedTest_A9746563D833E63B-001\tempDir-001\node2\dv_coll_shard2_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DocValuesNotIndexedTest_A9746563D833E63B-001\tempDir-001\node2\.\dv_coll_shard2_replica_n2\data\]
[junit4] 2> 2098338 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_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> 2098338 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_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> 2098338 INFO (qtp1604655689-30701) [n:127.0.0.1:63857_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> 2098338 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_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_63854.solr.core.dv_coll.shard3.replica_n4' (registry 'solr.core.dv_coll.shard3.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@73734bae
[junit4] 2> 2098339 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_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\J0\temp\solr.cloud.DocValuesNotIndexedTest_A9746563D833E63B-001\tempDir-001\node1\dv_coll_shard3_replica_n4], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DocValuesNotIndexedTest_A9746563D833E63B-001\tempDir-001\node1\.\dv_coll_shard3_replica_n4\data\]
[junit4] 2> 2098340 INFO (qtp1547594248-30688) [n:127.0.0.1:63854_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> 2098340 INFO (qtp1547594248-30688) [n:127.0.0.1:63854_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> 2098341 INFO (qtp1547594248-30688) [n:127.0.0.1:63854_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_63854.solr.core.dv_coll.shard1.replica_n1' (registry 'solr.core.dv_coll.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@73734bae
[junit4] 2> 2098341 INFO (qtp1547594248-30688) [n:127.0.0.1:63854_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\J0\temp\solr.cloud.DocValuesNotIndexedTest_A9746563D833E63B-001\tempDir-001\node1\dv_coll_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DocValuesNotIndexedTest_A9746563D833E63B-001\tempDir-001\node1\.\dv_coll_shard1_replica_n1\data\]
[junit4] 2> 2098341 INFO (qtp1604655689-30701) [n:127.0.0.1:63857_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> 2098341 INFO (qtp1604655689-30701) [n:127.0.0.1:63857_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> 2098341 INFO (qtp1604655689-30701) [n:127.0.0.1:63857_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_63857.solr.core.dv_coll.shard4.replica_n6' (registry 'solr.core.dv_coll.shard4.replica_n6') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@73734bae
[junit4] 2> 2098342 INFO (qtp1604655689-30701) [n:127.0.0.1:63857_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\J0\temp\solr.cloud.DocValuesNotIndexedTest_A9746563D833E63B-001\tempDir-001\node2\dv_coll_shard4_replica_n6], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DocValuesNotIndexedTest_A9746563D833E63B-001\tempDir-001\node2\.\dv_coll_shard4_replica_n6\data\]
[junit4] 2> 2098478 INFO (qtp1604655689-30699) [n:127.0.0.1:63857_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> 2098478 INFO (qtp1604655689-30699) [n:127.0.0.1:63857_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> 2098484 INFO (qtp1604655689-30699) [n:127.0.0.1:63857_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> 2098484 INFO (qtp1604655689-30699) [n:127.0.0.1:63857_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> 2098486 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_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> 2098486 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_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> 2098489 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_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> 2098489 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_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> 2098492 INFO (qtp1604655689-30699) [n:127.0.0.1:63857_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@5c422070[dv_coll_shard2_replica_n2] main]
[junit4] 2> 2098494 INFO (qtp1604655689-30699) [n:127.0.0.1:63857_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> 2098495 INFO (qtp1604655689-30699) [n:127.0.0.1:63857_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> 2098495 INFO (qtp1604655689-30699) [n:127.0.0.1:63857_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> 2098496 INFO (qtp1604655689-30699) [n:127.0.0.1:63857_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> 2098496 INFO (qtp1604655689-30699) [n:127.0.0.1:63857_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> 2098496 INFO (qtp1604655689-30699) [n:127.0.0.1:63857_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 1627536837206605824
[junit4] 2> 2098499 INFO (searcherExecutor-10438-thread-1-processing-n:127.0.0.1:63857_solr x:dv_coll_shard2_replica_n2 c:dv_coll s:shard2 r:core_node5) [n:127.0.0.1:63857_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@5c422070[dv_coll_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2098505 INFO (qtp1604655689-30699) [n:127.0.0.1:63857_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> 2098506 INFO (qtp1604655689-30699) [n:127.0.0.1:63857_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> 2098507 INFO (qtp1547594248-30688) [n:127.0.0.1:63854_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> 2098507 INFO (qtp1547594248-30688) [n:127.0.0.1:63854_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> 2098508 INFO (qtp1604655689-30699) [n:127.0.0.1:63857_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> 2098508 INFO (qtp1604655689-30699) [n:127.0.0.1:63857_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> 2098508 INFO (qtp1604655689-30699) [n:127.0.0.1:63857_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:63857/solr/dv_coll_shard2_replica_n2/
[junit4] 2> 2098509 INFO (qtp1604655689-30699) [n:127.0.0.1:63857_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> 2098509 INFO (qtp1604655689-30699) [n:127.0.0.1:63857_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:63857/solr/dv_coll_shard2_replica_n2/ has no replicas
[junit4] 2> 2098509 INFO (qtp1604655689-30699) [n:127.0.0.1:63857_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/72128825475530760-core_node5-n_0000000000
[junit4] 2> 2098511 INFO (qtp1604655689-30701) [n:127.0.0.1:63857_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> 2098511 INFO (qtp1604655689-30701) [n:127.0.0.1:63857_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> 2098511 INFO (qtp1604655689-30699) [n:127.0.0.1:63857_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:63857/solr/dv_coll_shard2_replica_n2/ shard2
[junit4] 2> 2098511 INFO (qtp1547594248-30688) [n:127.0.0.1:63854_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> 2098511 INFO (qtp1547594248-30688) [n:127.0.0.1:63854_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> 2098511 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.SolrIndexSearcher Opening [Searcher@7ee378a1[dv_coll_shard3_replica_n4] main]
[junit4] 2> 2098514 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_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> 2098514 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_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> 2098514 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_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> 2098514 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_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> 2098514 INFO (qtp1547594248-30688) [n:127.0.0.1:63854_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2e10d0e3[dv_coll_shard1_replica_n1] main]
[junit4] 2> 2098514 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_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> 2098515 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_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 1627536837226528768
[junit4] 2> 2098515 INFO (qtp1604655689-30701) [n:127.0.0.1:63857_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> 2098515 INFO (qtp1604655689-30701) [n:127.0.0.1:63857_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> 2098515 INFO (qtp1547594248-30688) [n:127.0.0.1:63854_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> 2098515 INFO (qtp1547594248-30688) [n:127.0.0.1:63854_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> 2098515 INFO (qtp1547594248-30688) [n:127.0.0.1:63854_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> 2098517 INFO (qtp1604655689-30701) [n:127.0.0.1:63857_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.s.SolrIndexSearcher Opening [Searcher@8d4dafe[dv_coll_shard4_replica_n6] main]
[junit4] 2> 2098517 INFO (qtp1547594248-30688) [n:127.0.0.1:63854_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> 2098517 INFO (qtp1547594248-30688) [n:127.0.0.1:63854_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> 2098517 INFO (qtp1547594248-30688) [n:127.0.0.1:63854_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 1627536837228625920
[junit4] 2> 2098518 INFO (searcherExecutor-10439-thread-1-processing-n:127.0.0.1:63854_solr x:dv_coll_shard3_replica_n4 c:dv_coll s:shard3 r:core_node7) [n:127.0.0.1:63854_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@7ee378a1[dv_coll_shard3_replica_n4] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2098519 INFO (qtp1604655689-30701) [n:127.0.0.1:63857_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> 2098520 INFO (qtp1604655689-30701) [n:127.0.0.1:63857_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> 2098520 INFO (qtp1604655689-30701) [n:127.0.0.1:63857_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> 2098521 INFO (qtp1604655689-30701) [n:127.0.0.1:63857_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> 2098521 INFO (searcherExecutor-10440-thread-1-processing-n:127.0.0.1:63854_solr x:dv_coll_shard1_replica_n1 c:dv_coll s:shard1 r:core_node3) [n:127.0.0.1:63854_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@2e10d0e3[dv_coll_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2098521 INFO (qtp1604655689-30701) [n:127.0.0.1:63857_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> 2098521 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_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> 2098521 INFO (qtp1604655689-30701) [n:127.0.0.1:63857_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 1627536837232820224
[junit4] 2> 2098521 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_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> 2098524 INFO (searcherExecutor-10441-thread-1-processing-n:127.0.0.1:63857_solr x:dv_coll_shard4_replica_n6 c:dv_coll s:shard4 r:core_node8) [n:127.0.0.1:63857_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@8d4dafe[dv_coll_shard4_replica_n6] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2098524 INFO (qtp1547594248-30688) [n:127.0.0.1:63854_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> 2098524 INFO (qtp1547594248-30688) [n:127.0.0.1:63854_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> 2098526 INFO (qtp1604655689-30701) [n:127.0.0.1:63857_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> 2098526 INFO (qtp1604655689-30701) [n:127.0.0.1:63857_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> 2098527 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_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> 2098527 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_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> 2098527 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_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:63854/solr/dv_coll_shard3_replica_n4/
[junit4] 2> 2098527 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_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> 2098527 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_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:63854/solr/dv_coll_shard3_replica_n4/ has no replicas
[junit4] 2> 2098527 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_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/72128825475530758-core_node7-n_0000000000
[junit4] 2> 2098529 INFO (qtp1547594248-30688) [n:127.0.0.1:63854_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> 2098529 INFO (qtp1547594248-30688) [n:127.0.0.1:63854_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> 2098529 INFO (qtp1547594248-30688) [n:127.0.0.1:63854_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:63854/solr/dv_coll_shard1_replica_n1/
[junit4] 2> 2098530 INFO (qtp1547594248-30688) [n:127.0.0.1:63854_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> 2098530 INFO (qtp1604655689-30701) [n:127.0.0.1:63857_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> 2098530 INFO (qtp1604655689-30701) [n:127.0.0.1:63857_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> 2098530 INFO (qtp1604655689-30701) [n:127.0.0.1:63857_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:63857/solr/dv_coll_shard4_replica_n6/
[junit4] 2> 2098530 INFO (qtp1604655689-30701) [n:127.0.0.1:63857_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> 2098530 INFO (qtp1604655689-30701) [n:127.0.0.1:63857_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:63857/solr/dv_coll_shard4_replica_n6/ has no replicas
[junit4] 2> 2098530 INFO (qtp1604655689-30701) [n:127.0.0.1:63857_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/72128825475530760-core_node8-n_0000000000
[junit4] 2> 2098530 INFO (qtp1547594248-30688) [n:127.0.0.1:63854_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:63854/solr/dv_coll_shard1_replica_n1/ has no replicas
[junit4] 2> 2098530 INFO (qtp1547594248-30688) [n:127.0.0.1:63854_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/72128825475530758-core_node3-n_0000000000
[junit4] 2> 2098530 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_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:63854/solr/dv_coll_shard3_replica_n4/ shard3
[junit4] 2> 2098532 INFO (qtp1604655689-30701) [n:127.0.0.1:63857_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:63857/solr/dv_coll_shard4_replica_n6/ shard4
[junit4] 2> 2098532 INFO (qtp1547594248-30688) [n:127.0.0.1:63854_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:63854/solr/dv_coll_shard1_replica_n1/ shard1
[junit4] 2> 2098634 INFO (zkCallback-10167-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> 2098634 INFO (zkCallback-10177-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> 2098634 INFO (zkCallback-10177-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> 2098637 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_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> 2098637 INFO (qtp1547594248-30688) [n:127.0.0.1:63854_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> 2098638 INFO (qtp1604655689-30701) [n:127.0.0.1:63857_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> 2098638 INFO (qtp1604655689-30699) [n:127.0.0.1:63857_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> 2098641 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_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=1337
[junit4] 2> 2098641 INFO (qtp1604655689-30701) [n:127.0.0.1:63857_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=1337
[junit4] 2> 2098641 INFO (qtp1547594248-30688) [n:127.0.0.1:63854_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=1338
[junit4] 2> 2098641 INFO (qtp1604655689-30699) [n:127.0.0.1:63857_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=1338
[junit4] 2> 2098647 INFO (qtp1547594248-30694) [n:127.0.0.1:63854_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> 2098746 INFO (zkCallback-10177-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> 2098746 INFO (zkCallback-10177-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> 2098746 INFO (zkCallback-10167-thread-3) [ ] 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> 2098746 INFO (zkCallback-10177-thread-3) [ ] 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> 2098746 INFO (zkCallback-10167-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> 2098746 INFO (qtp1547594248-30694) [n:127.0.0.1:63854_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&maxShardsPerNode=2&name=dv_coll&nrtReplicas=1&action=CREATE&numShards=4&wt=javabin&version=2} status=0 QTime=1761
[junit4] 2> 2098751 INFO (qtp1547594248-30690) [n:127.0.0.1:63854_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.SchemaManager managed schema loaded . version : 0
[junit4] 2> 2098755 INFO (qtp1547594248-30690) [n:127.0.0.1:63854_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> 2098757 INFO (qtp1547594248-30690) [n:127.0.0.1:63854_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> 2098806 INFO (qtp1547594248-30690) [n:127.0.0.1:63854_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.ZkController Persisted config data to node /configs/conf1/managed-schema
[junit4] 2> 2098806 INFO (zkCallback-10167-thread-2) [ ] o.a.s.s.ZkIndexSchemaReader A schema change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/configs/conf1/managed-schema, has occurred - updating schema from ZooKeeper ...
[junit4] 2> 2098806 INFO (zkCallback-10167-thread-3) [ ] o.a.s.s.ZkIndexSchemaReader A schema change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/configs/conf1/managed-schema, has occurred - updating schema from ZooKeeper ...
[junit4] 2> 2098806 INFO (zkCallback-10177-thread-4) [ ] o.a.s.s.ZkIndexSchemaReader A schema change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/configs/conf1/managed-schema, has occurred - updating schema from ZooKeeper ...
[junit4] 2> 2098806 INFO (zkCallback-10177-thread-3) [ ] o.a.s.s.ZkIndexSchemaReader A schema change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/configs/conf1/managed-schema, has occurred - updating schema from ZooKeeper ...
[junit4] 2> 2098810 INFO (zkCallback-10167-thread-3) [ ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 1 from ZooKeeper
[junit4] 2> 2098810 INFO (zkCallback-10177-thread-4) [ ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 1 from ZooKeeper
[junit4] 2> 2098815 INFO (zkCallback-10177-thread-4) [ ] o.a.s.s.IndexSchema [dv_coll_shard2_replica_n2] Schema name=minimal
[junit4] 2> 2098822 INFO (zkCallback-10177-thread-4) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2098822 INFO (zkCallback-10177-thread-4) [ ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 9 ms
[junit4] 2> 2098822 INFO (zkCallback-10177-thread-3) [ ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 1 from ZooKeeper
[junit4] 2> 2098824 INFO (zkCallback-10177-thread-3) [ ] o.a.s.s.IndexSchema [dv_coll_shard4_replica_n6] Schema name=minimal
[junit4] 2> 2098832 INFO (zkCallback-10177-thread-3) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2098832 INFO (zkCallback-10177-thread-3) [ ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 9 ms
[junit4] 2> 2098834 INFO (zkCallback-10167-thread-3) [ ] o.a.s.s.IndexSchema [dv_coll_shard1_replica_n1] Schema name=minimal
[junit4] 2> 2098845 INFO (zkCallback-10167-thread-3) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2098845 INFO (zkCallback-10167-thread-3) [ ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 35 ms
[junit4] 2> 2098906 INFO (qtp1547594248-30690) [n:127.0.0.1:63854_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> 2098916 INFO (qtp1547594248-30690) [n:127.0.0.1:63854_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> 2098925 INFO (qtp1547594248-30690) [n:127.0.0.1:63854_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> 2098925 INFO (qtp1547594248-30690) [n:127.0.0.1:63854_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.CoreContainer Reloading SolrCore 'dv_coll_shard3_replica_n4' using configuration from collection dv_coll
[junit4] 2> 2099075 INFO (qtp1547594248-30690) [n:127.0.0.1:63854_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_63854.solr.core.dv_coll.shard3.replica_n4' (registry 'solr.core.dv_coll.shard3.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@73734bae
[junit4] 2> 2099075 INFO (qtp1547594248-30690) [n:127.0.0.1:63854_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\J0\temp\solr.cloud.DocValuesNotIndexedTest_A9746563D833E63B-001\tempDir-001\node1\dv_coll_shard3_replica_n4], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DocValuesNotIndexedTest_A9746563D833E63B-001\tempDir-001\node1\.\dv_coll_shard3_replica_n4\data\]
[junit4] 2> 2099079 INFO (Thread-4845) [ ] o.a.s.c.SolrCore config update listener called for core dv_coll_shard4_replica_n6
[junit4] 2> 2099080 INFO (Thread-4844) [ ] o.a.s.c.SolrCore config update listener called for core dv_coll_shard3_replica_n4
[junit4] 2> 2099115 INFO (OverseerCollectionConfigSetProcessor-72128825475530760-127.0.0.1:63857_solr-n_0000000000) [n:127.0.0.1:63857_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> 2099116 INFO (Thread-4845) [ ] o.a.s.c.SolrCore config update listener called for core dv_coll_shard2_replica_n2
[junit4] 2> 2099117 INFO (Thread-4844) [ ] o.a.s.c.SolrCore core reload dv_coll_shard3_replica_n4
[junit4] 2> 2099381 INFO (qtp1547594248-30690) [n:127.0.0.1:63854_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> 2099381 INFO (qtp1547594248-30690) [n:127.0.0.1:63854_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> 2099424 INFO (qtp1547594248-30690) [n:127.0.0.1:63854_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.SolrIndexSearcher Opening [Searcher@38f86da7[dv_coll_shard3_replica_n4] main]
[junit4] 2> 2099466 INFO (qtp1547594248-30690) [n:127.0.0.1:63854_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> 2099466 INFO (qtp1547594248-30690) [n:127.0.0.1:63854_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> 2099466 INFO (qtp1547594248-30690) [n:127.0.0.1:63854_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> 2099468 INFO (qtp1547594248-30690) [n:127.0.0.1:63854_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.ZkIndexSchemaReader Current schema version 1 is already the latest
[junit4] 2> 2099469 INFO (qtp1547594248-30690) [n:127.0.0.1:63854_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> 2099471 INFO (qtp1547594248-30690) [n:127.0.0.1:63854_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 1627536838228967424
[junit4] 2> 2099474 INFO (searcherExecutor-10458-thread-1-processing-n:127.0.0.1:63854_solr x:dv_coll_shard3_replica_n4 c:dv_coll s:shard3 r:core_node7) [n:127.0.0.1:63854_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@38f86da7[dv_coll_shard3_replica_n4] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2099475 INFO (qtp1547594248-30690) [n:127.0.0.1:63854_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 2099475 INFO (qtp1547594248-30690) [n:127.0.0.1:63854_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.SolrIndexSearcher Opening [Searcher@6f2b48fa[dv_coll_shard3_replica_n4] main]
[junit4] 2> 2099476 INFO (qtp1547594248-30690) [n:127.0.0.1:63854_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.ManagedIndexSchema Waiting up to 599 secs for 3 replicas to apply schema update version 1 for collection dv_coll
[junit4] 2> 2099478 INFO (zkCallback-10167-thread-2) [ ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 1 from ZooKeeper
[junit4] 2> 2099478 INFO (searcherExecutor-10458-thread-1-processing-n:127.0.0.1:63854_solr x:dv_coll_shard3_replica_n4 c:dv_coll s:shard3 r:core_node7) [n:127.0.0.1:63854_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@6f2b48fa[dv_coll_shard3_replica_n4] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2099480 INFO (zkCallback-10167-thread-2) [ ] o.a.s.s.IndexSchema [dv_coll_shard3_replica_n4] Schema name=minimal
[junit4] 2> 2099485 INFO (zkCallback-10167-thread-2) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2099485 INFO (zkCallback-10167-thread-2) [ ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 6 ms
[junit4] 2> 2099490 INFO (Thread-4844) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 2099496 INFO (Thread-4844) [ ] o.a.s.s.IndexSchema [dv_coll_shard3_replica_n4] Schema name=minimal
[junit4] 2> 2099500 INFO (Thread-4844) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2099500 INFO (Thread-4844) [ ] o.a.s.c.CoreContainer Reloading SolrCore 'dv_coll_shard3_replica_n4' using configuration from collection dv_coll
[junit4] 2> 2099516 INFO (Thread-4844) [n:127.0.0.1:63854_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_63854.solr.core.dv_coll.shard3.replica_n4' (registry 'solr.core.dv_coll.shard3.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@73734bae
[junit4] 2> 2099516 INFO (Thread-4844) [n:127.0.0.1:63854_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\J0\temp\solr.cloud.DocValuesNotIndexedTest_A9746563D833E63B-001\tempDir-001\node1\dv_coll_shard3_replica_n4], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DocValuesNotIndexedTest_A9746563D833E63B-001\tempDir-001\node1\.\dv_coll_shard3_replica_n4\data\]
[junit4] 2> 2099616 INFO (qtp1604655689-30696) [n:127.0.0.1:63857_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.c.S.Request [dv_coll_shard2_replica_n2] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2099653 INFO (qtp1604655689-30701) [n:127.0.0.1:63857_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.c.S.Request [dv_coll_shard4_replica_n6] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2099656 INFO (qtp1547594248-30687) [n:127.0.0.1:63854_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.c.S.Request [dv_coll_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2099656 INFO (qtp1547594248-30690) [n:127.0.0.1:63854_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.ManagedIndexSchema Took 181.0ms for 3 replicas to apply schema update version 1 for collection dv_coll
[junit4] 2> 2099656 INFO (qtp1547594248-30690) [n:127.0.0.1:63854_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.S.Request [dv_coll_shard3_replica_n4] webapp=/solr path=/schema params={wt=javabin&version=2} status=0 QTime=906
[junit4] 2> 2099656 INFO (qtp1547594248-30690) [n:127.0.0.1:63854_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] CLOSING SolrCore org.apache.solr.core.SolrCore@56fc5a86
[junit4] 2> 2099656 INFO (qtp1547594248-30690) [n:127.0.0.1:63854_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.dv_coll.shard3.replica_n4, tag=56fc5a86
[junit4] 2> 2099656 INFO (qtp1547594248-30690) [n:127.0.0.1:63854_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@246638f0: rootName = solr_63854, domain = solr.core.dv_coll.shard3.replica_n4, service url = null, agent id = null] for registry solr.core.dv_coll.shard3.replica_n4 / com.codahale.metrics.MetricRegistry@2f0a856f
[junit4] 2> 2099666 INFO (Thread-4844) [n:127.0.0.1:63854_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> 2099666 INFO (Thread-4844) [n:127.0.0.1:63854_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> 2099666 INFO (qtp1547594248-30690) [n:127.0.0.1:63854_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.dv_coll.shard3.leader, tag=56fc5a86
[junit4] 2> 2099671 INFO (Thread-4844) [n:127.0.0.1:63854_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.SolrIndexSearcher Opening [Searcher@44a43d96[dv_coll_shard3_replica_n4] main]
[junit4] 2> 2099674 INFO (Thread-4844) [n:127.0.0.1:63854_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> 2099675 INFO (Thread-4844) [n:127.0.0.1:63854_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> 2099675 INFO (Thread-4844) [n:127.0.0.1:63854_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> 2099675 INFO (Thread-4844) [n:127.0.0.1:63854_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.ZkIndexSchemaReader Current schema version 1 is already the latest
[junit4] 2> 2099675 INFO (Thread-4844) [n:127.0.0.1:63854_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> 2099675 INFO (Thread-4844) [n:127.0.0.1:63854_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 1627536838442876928
[junit4] 2> 2099680 INFO (Thread-4844) [n:127.0.0.1:63854_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 2099685 INFO (searcherExecutor-10463-thread-1-processing-n:127.0.0.1:63854_solr x:dv_coll_shard3_replica_n4 c:dv_coll s:shard3 r:core_node7) [n:127.0.0.1:63854_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@44a43d96[dv_coll_shard3_replica_n4] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2099689 INFO (qtp1604655689-30699) [n:127.0.0.1:63857_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=1}, version=1}
[junit4] 2> 2099689 INFO (qtp1604655689-30699) [n:127.0.0.1:63857_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard4_replica_n6] webapp=/solr path=/update params={_stateVer_=dv_coll:4&wt=javabin&version=2}{add=[10000 (1627536838453362688), 5 (1627536838454411264), 17 (1627536838454411265), 19 (1627536838454411266), 21 (1627536838454411267), 23 (1627536838454411268), 25 (1627536838454411269), 29 (1627536838456508416), 10030 (1627536838456508417), 33 (1627536838456508418), ... (13 adds)]} 0 4
[junit4] 2> 2099690 INFO (qtp1604655689-30698) [n:127.0.0.1:63857_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=1}, version=1}
[junit4] 2> 2099691 INFO (qtp1604655689-30698) [n:127.0.0.1:63857_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard2_replica_n2] webapp=/solr path=/update params={_stateVer_=dv_coll:4&wt=javabin&version=2}{add=[0 (1627536838448119808), 4 (1627536838454411264), 10005 (1627536838454411265), 12 (1627536838454411266), 15 (1627536838454411267), 16 (1627536838454411268), 20 (1627536838454411269), 22 (1627536838454411270), 34 (1627536838456508416), 35 (1627536838456508417), ... (16 adds)]} 0 8
[junit4] 2> 2099693 INFO (qtp1547594248-30692) [n:127.0.0.1:63854_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=1}, version=1}
[junit4] 2> 2099694 INFO (qtp1547594248-30692) [n:127.0.0.1:63854_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=dv_coll:4&wt=javabin&version=2}{add=[1 (1627536838453362688), 8 (1627536838458605568), 10 (1627536838458605569), 11 (1627536838458605570), 13 (1627536838458605571), 14 (1627536838458605572), 10015 (1627536838459654144), 10020 (1627536838459654145), 24 (1627536838459654146), 27 (1627536838459654147), ... (16 adds)]} 0 8
[junit4] 2> 2099749 INFO (qtp1547594248-30688) [n:127.0.0.1:63854_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=1}, version=1}
[junit4] 2> 2099750 INFO (qtp1547594248-30688) [n:127.0.0.1:63854_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard3_replica_n4] webapp=/solr path=/update params={_stateVer_=dv_coll:4&wt=javabin&version=2}{add=[2 (1627536838448119808), 3 (1627536838454411264), 6 (1627536838462799872), 7 (1627536838517325824), 9 (1627536838517325825), 10010 (1627536838517325826), 18 (1627536838517325827), 10025 (1627536838517325828), 26 (1627536838517325829), 30 (1627536838517325830), ... (14 adds)]} 0 68
[junit4] 2> 2099900 INFO (qtp1604655689-30696) [n:127.0.0.1:63857_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1627536838678806528,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2099900 INFO (qtp1604655689-30696) [n:127.0.0.1:63857_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@11e58808 commitCommandVersion:1627536838678806528
[junit4] 2> 2099900 INFO (qtp1604655689-30700) [n:127.0.0.1:63857_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1627536838678806528,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2099900 INFO (qtp1604655689-30700) [n:127.0.0.1:63857_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5977e91a commitCommandVersion:1627536838678806528
[junit4] 2> 2099903 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1627536838681952256,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2099903 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@9530a4 commitCommandVersion:1627536838681952256
[junit4] 2> 2100055 INFO (Thread-4844) [n:127.0.0.1:63854_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.SolrIndexSearcher Opening [Searcher@4cc106ac[dv_coll_shard3_replica_n4] main]
[junit4] 2> 2100055 INFO (Thread-4844) [n:127.0.0.1:63854_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.c.SolrCore config update listener called for core dv_coll_shard1_replica_n1
[junit4] 2> 2100058 INFO (searcherExecutor-10463-thread-1-processing-n:127.0.0.1:63854_solr x:dv_coll_shard3_replica_n4 c:dv_coll s:shard3 r:core_node7) [n:127.0.0.1:63854_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@4cc106ac[dv_coll_shard3_replica_n4] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.1.0):C1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=8.1.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1552140082189}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 2100067 INFO (qtp1604655689-30700) [n:127.0.0.1:63857_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.s.SolrIndexSearcher Opening [Searcher@2b3f50a7[dv_coll_shard4_replica_n6] main]
[junit4] 2> 2100070 INFO (searcherExecutor-10441-thread-1-processing-n:127.0.0.1:63857_solr x:dv_coll_shard4_replica_n6 c:dv_coll s:shard4 r:core_node8) [n:127.0.0.1:63857_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@2b3f50a7[dv_coll_shard4_replica_n6] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.1.0):C13:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=8.1.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1552140082194}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 2100070 INFO (qtp1604655689-30700) [n:127.0.0.1:63857_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2100070 INFO (qtp1604655689-30700) [n:127.0.0.1:63857_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6] o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard4_replica_n6] webapp=/solr path=/update params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:63854/solr/dv_coll_shard1_replica_n1/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 170
[junit4] 2> 2100071 INFO (qtp1604655689-30696) [n:127.0.0.1:63857_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@38e2791c[dv_coll_shard2_replica_n2] main]
[junit4] 2> 2100073 INFO (searcherExecutor-10438-thread-1-processing-n:127.0.0.1:63857_solr x:dv_coll_shard2_replica_n2 c:dv_coll s:shard2 r:core_node5) [n:127.0.0.1:63857_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@38e2791c[dv_coll_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.1.0):C16:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=8.1.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1552140082197}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 2100073 INFO (qtp1604655689-30696) [n:127.0.0.1:63857_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2100075 INFO (qtp1604655689-30696) [n:127.0.0.1:63857_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:63854/solr/dv_coll_shard1_replica_n1/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 174
[junit4] 2> 2100076 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.s.SolrIndexSearcher Opening [Searcher@4c40cd38[dv_coll_shard3_replica_n4] main]
[junit4] 2> 2100079 INFO (searcherExecutor-10458-thread-1-processing-n:127.0.0.1:63854_solr x:dv_coll_shard3_replica_n4 c:dv_coll s:shard3 r:core_node7) [n:127.0.0.1:63854_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@4c40cd38[dv_coll_shard3_replica_n4] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.1.0):C1:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=8.1.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1552140082189}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_1(8.1.0):C13:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11, lucene.version=8.1.0, os.arch=amd64, java.runtime.version=9.0.4+11, source=flush, os.version=10.0, timestamp=1552140082217}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 2100080 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2100080 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard3_replica_n4] webapp=/solr path=/update params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:63854/solr/dv_coll_shard1_replica_n1/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 177
[junit4] 2> 2100080 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_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] CLOSING SolrCore org.apache.solr.core.SolrCore@4e9f48bf
[junit4] 2> 2100080 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.dv_coll.shard3.replica_n4, tag=4e9f48bf
[junit4] 2> 2100080 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3adc2642: rootName = solr_63854, domain = solr.core.dv_coll.shard3.replica_n4, service url = null, agent id = null] for registry solr.core.dv_coll.shard3.replica_n4 / com.codahale.metrics.MetricRegistry@2f0a856f
[junit4] 2> 2100089 INFO (qtp1547594248-30691) [n:127.0.0.1:63854_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.dv_coll.shard3.leader, tag=4e9f48bf
[junit4] 2> 2100090 INFO (qtp1547594248-30687) [n:127.0.0.1:63854_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1627536838878035968,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2100090 INFO (qtp1547594248-30687) [n:127.0.0.1:63854_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4ec656ce commitCommandVersion:1627536838878035968
[junit4] 2> 2100094 INFO (qtp1547594248-30687) [n:127.0.0.1:63854_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@30642914[dv_coll_shard1_replica_n1] main]
[junit4] 2> 2100094 INFO (qtp1547594248-30687) [n:127.0.0.1:63854_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2100096 INFO (searcherExecutor-10440-thread-1-processing-n:127.0.0.1:63854_solr x:dv_coll_shard1_replica_n1 c:dv_coll s:shard1 r:core_node3) [n:127.0.0.1:63854_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@30642914[dv_coll_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.1.0):C16:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=9.0.4, j
[...truncated too long message...]
INFO (jetty-closer-10400-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@351e161f{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 2116132 INFO (jetty-closer-10400-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@8bf8234{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 2116132 INFO (jetty-closer-10400-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3abba49e{/solr,null,UNAVAILABLE}
[junit4] 2> 2116133 INFO (jetty-closer-10400-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2116133 INFO (jetty-closer-10400-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@33cc327a{/solr,null,UNAVAILABLE}
[junit4] 2> 2116133 INFO (jetty-closer-10400-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2116135 INFO (TEST-DocValuesNotIndexedTest.testGroupingDocAbsent-seed#[A9746563D833E63B]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 2116136 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> 6 /solr/configs/conf1/managed-schema
[junit4] 2> 5 /solr/aliases.json
[junit4] 2> 5 /solr/clusterprops.json
[junit4] 2> 4 /solr/configs/conf1
[junit4] 2> 3 /solr/collections/dv_coll/terms/shard3
[junit4] 2> 3 /solr/collections/dv_coll/terms/shard2
[junit4] 2> 3 /solr/collections/dv_coll/terms/shard1
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/collections/dv_coll/terms/shard4
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 17 /solr/collections/dv_coll/state.json
[junit4] 2> 5 /solr/clusterstate.json
[junit4] 2> 2 /solr/configs/conf1/managed-schema
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/live_nodes
[junit4] 2> 5 /solr/collections
[junit4] 2>
[junit4] 2> 2116140 INFO (TEST-DocValuesNotIndexedTest.testGroupingDocAbsent-seed#[A9746563D833E63B]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:64250
[junit4] 2> 2116140 INFO (TEST-DocValuesNotIndexedTest.testGroupingDocAbsent-seed#[A9746563D833E63B]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 64250
[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.cloud.DocValuesNotIndexedTest_A9746563D833E63B-001
[junit4] 2> 2117144 INFO (TEST-DocValuesNotIndexedTest.testGroupingDocAbsent-seed#[A9746563D833E63B]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testGroupingDocAbsent
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@458e046d), locale=kw-GB, timezone=Africa/Malabo
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 9.0.4 (64-bit)/cpus=3,threads=1,free=250253864,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [ShardSplitTest, TestMissingGroups, UnloadDistributedZkTest, HdfsRecoveryZkTest, TestCorePropertiesReload, StatsReloadRaceTest, TestSubQueryTransformer, TestDynamicFieldCollectionResource, TestOrdValues, TestReload, TestCloudPseudoReturnFields, TestAuthorizationFramework, DateRangeFieldTest, TestXIncludeConfig, BitVectorTest, AutoscalingHistoryHandlerTest, TestSuggestSpellingConverter, DistanceFunctionTest, CustomHighlightComponentTest, TestObjectReleaseTracker, TestSweetSpotSimilarityFactory, TestHighFrequencyDictionaryFactory, TestStressInPlaceUpdates, DistributedQueryComponentCustomSortTest, PeerSyncReplicationTest, TestCustomDocTransformer, URLClassifyProcessorTest, HttpTriggerListenerTest, ClusterStateTest, TestCloudDeleteByQuery, TestTrieFacet, CdcrBidirectionalTest, NodeAddedTriggerIntegrationTest, TestJoin, TestCustomSort, ChaosMonkeyNothingIsSafeWithPullReplicasTest, TestSolr4Spatial, CollectionTooManyReplicasTest, TestGroupingSearch, TestDownShardTolerantSearch, CdcrWithNodesRestartsTest, TestJsonRequest, TestInitQParser, EnumFieldTest, HdfsChaosMonkeyNothingIsSafeTest, TestFreeTextSuggestions, TestStressLucene, UpdateParamsTest, TestTlogReplica, TestRandomCollapseQParserPlugin, DistribJoinFromCollectionTest, TestDistributedStatsComponentCardinality, SearchRateTriggerTest, CacheHeaderTest, DataDrivenBlockJoinTest, DistributedFacetSimpleRefinementLongTailTest, CloneFieldUpdateProcessorFactoryTest, SolrCoreMetricManagerTest, TestManagedSchemaAPI, ConnectionReuseTest, LeaderElectionIntegrationTest, SolrGangliaReporterTest, TestUnifiedSolrHighlighter, TestPayloadCheckQParserPlugin, TestFaceting, ProtectedTermFilterFactoryTest, SpatialHeatmapFacetsTest, TestTrie, DistributedSuggestComponentTest, TestRuleBasedAuthorizationPlugin, InfoHandlerTest, TestPolicyCloud, TestBlobHandler, TestClusterProperties, TestConfigSetsAPIExclusivity, TestSearchPerf, MoveReplicaHDFSFailoverTest, TriggerSetPropertiesIntegrationTest, SolrIndexConfigTest, SpellCheckCollatorTest, TestEmbeddedSolrServerSchemaAPI, TestHalfAndHalfDocValues, ScheduledTriggerTest, TestSerializedLuceneMatchVersion, SaslZkACLProviderTest, SolrJmxReporterCloudTest, TestSolrCloudWithHadoopAuthPlugin, TestDFRSimilarityFactory, TestLRUStatsCache, HdfsBasicDistributedZk2Test, IgnoreLargeDocumentProcessorFactoryTest, TestCollationField, TlogReplayBufferedWhileIndexingTest, AnalysisErrorHandlingTest, TestFastLRUCache, TestSimTriggerIntegration, TransactionLogTest, ForceLeaderTest, AnalysisAfterCoreReloadTest, UniqFieldsUpdateProcessorFactoryTest, ExecutePlanActionTest, TestSystemCollAutoCreate, ConvertedLegacyTest, AutoAddReplicasIntegrationTest, ConjunctionSolrSpellCheckerTest, CleanupOldIndexTest, TestFoldingMultitermQuery, TestCollapseQParserPlugin, TestNumericTerms32, TestNamedUpdateProcessors, TestSolrCloudWithDelegationTokens, TestDynamicURP, MultiThreadedOCPTest, TestDistribDocBasedVersion, TestClassicSimilarityFactory, TestInPlaceUpdatesDistrib, OutputWriterTest, TestSortableTextField, TestSolrDeletionPolicy1, XsltUpdateRequestHandlerTest, SuggesterFSTTest, TestLegacyFieldReuse, TestSolrIndexConfig, TestLeaderElectionZkExpiry, IndexSchemaRuntimeFieldTest, CheckHdfsIndexTest, RequiredFieldsTest, TestLegacyBM25SimilarityFactory, TestFieldCacheSortRandom, JWTAuthPluginTest, TestRandomRequestDistribution, OverseerRolesTest, BinaryUpdateRequestHandlerTest, FullHLLTest, TestSimpleQParserPlugin, TestUseDocValuesAsStored2, DeleteInactiveReplicaTest, AddSchemaFieldsUpdateProcessorFactoryTest, HttpSolrCallGetCoreTest, TestFieldCollectionResource, LeaderFailoverAfterPartitionTest, ManagedSchemaRoundRobinCloudTest, TestInfoStreamLogging, TestMiniSolrCloudClusterSSL, OrderedExecutorTest, BadIndexSchemaTest, TestMergePolicyConfig, DocValuesMultiTest, CollectionPropsTest, PropertiesRequestHandlerTest, HighlighterTest, TestQuerySenderListener, ZkFailoverTest, TermVectorComponentTest, PreAnalyzedUpdateProcessorTest, ScheduledMaintenanceTriggerTest, RAMDirectoryFactoryTest, HighlighterConfigTest, DistributedFacetPivotWhiteBoxTest, TestHdfsBackupRestoreCore, TestManagedSynonymGraphFilterFactory, TestConfigSetProperties, TestExactSharedStatsCache, TestUtils, MetricsHandlerTest, TestDefaultStatsCache, StatsComponentTest, ScriptEngineTest, TestManagedStopFilterFactory, UninvertDocValuesMergePolicyTest, SolrInfoBeanTest, TestElisionMultitermQuery, UtilsToolTest, TestCodecSupport, TestStressVersions, TestDelegationWithHadoopAuth, SplitShardTest, TestTolerantUpdateProcessorRandomCloud, LukeRequestHandlerTest, BasicZkTest, TestHttpShardHandlerFactory, JvmMetricsTest, TestGraphMLResponseWriter, RandomizedTaggerTest, DeleteNodeTest, TriggerEventQueueTest, TestFastOutputStream, TestDistributedGrouping, CoreSorterTest, TestWithCollection, TestCloudRecovery2, HLLUtilTest, TestFieldTypeCollectionResource, SubstringBytesRefFilterTest, TestJsonFacetsWithNestedObjects, TestRawResponseWriter, PreAnalyzedFieldTest, TestEmbeddedSolrServerAdminHandler, RegexBytesRefFilterTest, AnalyticsMergeStrategyTest, FullSolrCloudDistribCmdsTest, TestSystemIdResolver, DistribCursorPagingTest, TestBlendedInfixSuggestions, QueryEqualityTest, BasicDistributedZk2Test, ConfigSetsAPITest, TestNumericTokenStream, JsonLoaderTest, NumericFieldsTest, TestSegmentSorting, TestRandomFaceting, TestSolrCoreProperties, TestTolerantSearch, PathHierarchyTokenizerFactoryTest, TestDeprecatedFilters, TestLuceneMatchVersion, TestReversedWildcardFilterFactory, TokenizerChainTest, ActionThrottleTest, AddReplicaTest, AssignBackwardCompatibilityTest, ChaosMonkeySafeLeaderWithPullReplicasTest, CreateCollectionCleanupTest, DeleteLastCustomShardedReplicaTest, DeleteShardTest, DeleteStatusTest, DistributedVersionInfoTest, DocValuesNotIndexedTest]
[junit4] Completed [454/845 (1!)] on J0 in 21.59s, 4 tests, 1 failure <<< FAILURES!
[...truncated 43864 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/75/consoleText
[repro] Revision: 83ab355772d46b755cab52e73051f395f938fd72
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC"
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr\core
[repro] DocValuesNotIndexedTest
[repro] ant compile-test
[...truncated 3602 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.DocValuesNotIndexedTest" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC" -Dtests.seed=A9746563D833E63B -Dtests.slow=true -Dtests.locale=kw-GB -Dtests.timezone=Africa/Malabo -Dtests.asserts=true -Dtests.file.encoding=Cp1252
[...truncated 88 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.cloud.DocValuesNotIndexedTest
[repro] Exiting with code 0
[...truncated 78 lines...]