You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2020/01/11 23:58:30 UTC

[JENKINS] Lucene-Solr-8.x-MacOSX (64bit/jdk-13.0.1) - Build # 507 - Unstable!

Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/507/
Java: 64bit/jdk-13.0.1 -XX:-UseCompressedOops -XX:+UseG1GC

1 tests failed.
FAILED:  org.apache.solr.search.TestRecovery.stressLogReplay

Error Message:
mismatch: '61'!='62' @ response/numFound

Stack Trace:
java.lang.RuntimeException: mismatch: '61'!='62' @ response/numFound
	at __randomizedtesting.SeedInfo.seed([B727845A6BFBB934:728967F3BC6DAE76]:0)
	at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:1108)
	at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:1055)
	at org.apache.solr.search.TestRecovery.stressLogReplay(TestRecovery.java:171)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.base/java.lang.reflect.Method.invoke(Method.java:567)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
	at java.base/java.lang.Thread.run(Thread.java:830)




Build Log:
[...truncated 13844 lines...]
   [junit4] Suite: org.apache.solr.search.TestRecovery
   [junit4]   2> 1087338 INFO  (SUITE-TestRecovery-seed#[B727845A6BFBB934]-worker) [     ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 1087339 INFO  (SUITE-TestRecovery-seed#[B727845A6BFBB934]-worker) [     ] o.a.s.SolrTestCaseJ4 Created dataDir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.search.TestRecovery_B727845A6BFBB934-001/data-dir-103-001
   [junit4]   2> 1087339 WARN  (SUITE-TestRecovery-seed#[B727845A6BFBB934]-worker) [     ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=16 numCloses=16
   [junit4]   2> 1087339 INFO  (SUITE-TestRecovery-seed#[B727845A6BFBB934]-worker) [     ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 1087340 INFO  (SUITE-TestRecovery-seed#[B727845A6BFBB934]-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) w/ MAC_OS_X supressed clientAuth
   [junit4]   2> 1087343 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [     ] o.a.s.SolrTestCaseJ4 ###Starting testReload
   [junit4]   2> 1087343 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [     ] o.a.s.SolrTestCaseJ4 updateLog impl=solr.UpdateLog
   [junit4]   2> 1087343 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [     ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1087344 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [     ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/lib, /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1087479 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [     ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.5.0
   [junit4]   2> 1087504 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [     ] o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 1087572 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [     ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 1087770 WARN  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [     ] o.a.s.f.DistribPackageStore Unable to create [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/filestore] directory in SOLR_HOME [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr].  Features requiring this directory may fail.
   [junit4]   2>           => java.security.AccessControlException: access denied ("java.io.FilePermission" "/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/filestore" "write")
   [junit4]   2> 	at java.base/java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
   [junit4]   2> java.security.AccessControlException: access denied ("java.io.FilePermission" "/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/filestore" "write")
   [junit4]   2> 	at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472) ~[?:?]
   [junit4]   2> 	at java.security.AccessController.checkPermission(AccessController.java:1036) ~[?:?]
   [junit4]   2> 	at java.lang.SecurityManager.checkPermission(SecurityManager.java:408) ~[?:?]
   [junit4]   2> 	at java.lang.SecurityManager.checkWrite(SecurityManager.java:838) ~[?:?]
   [junit4]   2> 	at java.io.File.mkdir(File.java:1323) ~[?:?]
   [junit4]   2> 	at java.io.File.mkdirs(File.java:1355) ~[?:?]
   [junit4]   2> 	at org.apache.solr.filestore.DistribPackageStore.ensurePackageStoreDir(DistribPackageStore.java:520) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.filestore.DistribPackageStore.<init>(DistribPackageStore.java:77) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.filestore.PackageStoreAPI.<init>(PackageStoreAPI.java:79) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.core.CoreContainer.load(CoreContainer.java:620) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.util.TestHarness.<init>(TestHarness.java:180) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.util.TestHarness.<init>(TestHarness.java:143) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.util.TestHarness.<init>(TestHarness.java:149) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.util.TestHarness.<init>(TestHarness.java:112) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:815) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:805) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:666) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:655) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.search.TestRecovery.beforeTest(TestRecovery.java:80) ~[test/:?]
   [junit4]   2> 	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
   [junit4]   2> 	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]
   [junit4]   2> 	at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
   [junit4]   2> 	at java.lang.reflect.Method.invoke(Method.java:567) ~[?:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:972) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) ~[java/:?]
   [junit4]   2> 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) ~[java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) ~[java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) ~[java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) ~[java/:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) ~[java/:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) ~[java/:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) ~[java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) ~[java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) ~[java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) ~[java/:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:830) [?:?]
   [junit4]   2> 1087771 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 1087773 WARN  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4158d710[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1087774 WARN  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4158d710[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1087779 WARN  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@28a96a73[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1087779 WARN  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@28a96a73[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1087780 WARN  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [     ] o.a.s.c.CoreContainer Not all security plugins configured!  authentication=disabled authorization=disabled.  Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external.  See https://s.apache.org/solrsecurity for more info
   [junit4]   2> 1087911 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 1087911 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 1088023 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6abd70b7
   [junit4]   2> 1088032 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6abd70b7
   [junit4]   2> 1088032 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6abd70b7
   [junit4]   2> 1088033 INFO  (coreLoadExecutor-3955-thread-1) [    x:collection1 ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/lib, /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1088057 INFO  (coreLoadExecutor-3955-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.5.0
   [junit4]   2> 1088085 INFO  (coreLoadExecutor-3955-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1088159 INFO  (coreLoadExecutor-3955-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 1088167 INFO  (coreLoadExecutor-3955-thread-1) [    x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1, trusted=true
   [junit4]   2> 1088168 INFO  (coreLoadExecutor-3955-thread-1) [    x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6abd70b7
   [junit4]   2> 1088168 INFO  (coreLoadExecutor-3955-thread-1) [    x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.search.TestRecovery_B727845A6BFBB934-001/data-dir-103-001/]
   [junit4]   2> 1088171 INFO  (coreLoadExecutor-3955-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=40, maxMergeAtOnceExplicit=43, maxMergedSegmentMB=59.4638671875, floorSegmentMB=1.2275390625, forceMergeDeletesPctAllowed=2.3534358879180104, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=26.18428876975151
   [junit4]   2> 1088445 WARN  (coreLoadExecutor-3955-thread-1) [    x:collection1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
   [junit4]   2> 1088501 INFO  (coreLoadExecutor-3955-thread-1) [    x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1088501 INFO  (coreLoadExecutor-3955-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1088524 INFO  (coreLoadExecutor-3955-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1088524 INFO  (coreLoadExecutor-3955-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1088526 INFO  (coreLoadExecutor-3955-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=913704995, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
   [junit4]   2> 1088527 INFO  (coreLoadExecutor-3955-thread-1) [    x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7e8de053[collection1] main]
   [junit4]   2> 1088528 WARN  (coreLoadExecutor-3955-thread-1) [    x:collection1 ] o.a.s.r.ManagedResourceStorage Cannot write to config directory /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
   [junit4]   2> 1088529 INFO  (coreLoadExecutor-3955-thread-1) [    x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1088529 INFO  (coreLoadExecutor-3955-thread-1) [    x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1655471862688251904
   [junit4]   2> 1088532 INFO  (searcherExecutor-3956-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7e8de053[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1088533 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [     ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1088557 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[reload1 (1655471862692446208)]} 0 24
   [junit4]   2> 1088557 ERROR (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [     ] o.a.s.c.CorePropertiesLocator Couldn't load core descriptor from /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/core.properties:java.nio.file.NoSuchFileException: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/core.properties
   [junit4]   2> 1088558 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [     ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/lib, /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1088592 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [     ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.5.0
   [junit4]   2> 1088609 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [     ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1088708 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [     ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 1088813 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [     ] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1
   [junit4]   2> 1088870 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6abd70b7
   [junit4]   2> 1088870 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.search.TestRecovery_B727845A6BFBB934-001/data-dir-103-001/]
   [junit4]   2> 1088875 WARN  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
   [junit4]   2> 1088940 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1088940 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1088959 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@56e46d97[collection1] main]
   [junit4]   2> 1088959 WARN  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.r.ManagedResourceStorage Cannot write to config directory /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
   [junit4]   2> 1088959 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1088966 INFO  (searcherExecutor-3961-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@56e46d97[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.5.0):C1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.1, java.vm.version=13.0.1+9, lucene.version=8.5.0, os.arch=x86_64, java.runtime.version=13.0.1+9, source=flush, os.version=10.11.6, timestamp=1578780997402}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1089120 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=29, maxMergeAtOnceExplicit=35, maxMergedSegmentMB=46.5166015625, floorSegmentMB=2.052734375, forceMergeDeletesPctAllowed=1.2760503245576438, segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=31.221011523327597
   [junit4]   2> 1089122 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1089126 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@38909ee8[collection1] main]
   [junit4]   2> 1089127 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@7441fcb1
   [junit4]   2> 1089127 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=SolrCore@7441fcb1
   [junit4]   2> 1089127 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@452f6e67: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@193cf9e7
   [junit4]   2> 1089130 INFO  (searcherExecutor-3961-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@38909ee8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.5.0):C1:[diagnostics={java.vendor=AdoptOpenJDK, os=Mac OS X, java.version=13.0.1, java.vm.version=13.0.1+9, lucene.version=8.5.0, os.arch=x86_64, java.runtime.version=13.0.1+9, source=flush, os.version=10.11.6, timestamp=1578780997402}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1089151 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[reload1 (1655471863339417600)]} 0 1
   [junit4]   2> 1089152 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1655471863341514752,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1089152 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7d32875b commitCommandVersion:1655471863341514752
   [junit4]   2> 1089303 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@25dcc0cd[collection1] main]
   [junit4]   2> 1089303 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1089306 INFO  (searcherExecutor-3961-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@25dcc0cd[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.5.0):C1:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.1, java.vm.version=13.0.1+9, lucene.version=8.5.0, os.arch=x86_64, java.runtime.version=13.0.1+9, source=flush, os.version=10.11.6, timestamp=1578780997605}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1089308 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 156
   [junit4]   2> 1089311 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[reload1 (1655471863507189760)]} 0 1
   [junit4]   2> 1089331 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@36131e13[collection1] realtime]
   [junit4]   2> 1089332 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{deleteByQuery=foo_t:hownowbrowncow (-1655471863508238336)} 0 21
   [junit4]   2> 1089334 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[reload1 (1655471863531307008)]} 0 1
   [junit4]   2> 1089334 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1089334 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1887364980
   [junit4]   2> 1089335 INFO  (coreCloseExecutor-3965-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@4027b4a3
   [junit4]   2> 1089335 INFO  (coreCloseExecutor-3965-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=SolrCore@4027b4a3
   [junit4]   2> 1089335 INFO  (coreCloseExecutor-3965-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@142db7e7: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@193cf9e7
   [junit4]   2> 1089351 INFO  (coreCloseExecutor-3965-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
   [junit4]   2> 1089352 INFO  (coreCloseExecutor-3965-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7d32875b commitCommandVersion:0
   [junit4]   2> 1089427 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
   [junit4]   2> 1089427 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@28f4f897: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@5c10c7ae
   [junit4]   2> 1089437 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
   [junit4]   2> 1089437 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@22bc2887: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@7fbac0d4
   [junit4]   2> 1089442 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
   [junit4]   2> 1089442 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1f085e52: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@4e13dc6c
   [junit4]   2> 1089444 INFO  (TEST-TestRecovery.testReload-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.SolrTestCaseJ4 ###Ending testReload
   [junit4]   2> 1089448 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.SolrTestCaseJ4 ###Starting stressLogReplay
   [junit4]   2> 1089448 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.SolrTestCaseJ4 updateLog impl=solr.UpdateLog
   [junit4]   2> 1089448 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1089448 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/lib, /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1089465 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.5.0
   [junit4]   2> 1089509 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.SolrTestCaseJ4 Created dataDir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.search.TestRecovery_B727845A6BFBB934-001/data-dir-104-001
   [junit4]   2> 1089512 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 1089608 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 1089744 WARN  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.f.DistribPackageStore Unable to create [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/filestore] directory in SOLR_HOME [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr].  Features requiring this directory may fail.
   [junit4]   2>           => java.security.AccessControlException: access denied ("java.io.FilePermission" "/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/filestore" "write")
   [junit4]   2> 	at java.base/java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
   [junit4]   2> java.security.AccessControlException: access denied ("java.io.FilePermission" "/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/filestore" "write")
   [junit4]   2> 	at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472) ~[?:?]
   [junit4]   2> 	at java.security.AccessController.checkPermission(AccessController.java:1036) ~[?:?]
   [junit4]   2> 	at java.lang.SecurityManager.checkPermission(SecurityManager.java:408) ~[?:?]
   [junit4]   2> 	at java.lang.SecurityManager.checkWrite(SecurityManager.java:838) ~[?:?]
   [junit4]   2> 	at java.io.File.mkdir(File.java:1323) ~[?:?]
   [junit4]   2> 	at java.io.File.mkdirs(File.java:1355) ~[?:?]
   [junit4]   2> 	at org.apache.solr.filestore.DistribPackageStore.ensurePackageStoreDir(DistribPackageStore.java:520) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.filestore.DistribPackageStore.<init>(DistribPackageStore.java:77) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.filestore.PackageStoreAPI.<init>(PackageStoreAPI.java:79) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.core.CoreContainer.load(CoreContainer.java:620) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.util.TestHarness.<init>(TestHarness.java:180) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.util.TestHarness.<init>(TestHarness.java:143) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.util.TestHarness.<init>(TestHarness.java:149) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.util.TestHarness.<init>(TestHarness.java:112) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:815) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:805) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:666) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:655) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.search.TestRecovery.beforeTest(TestRecovery.java:80) ~[test/:?]
   [junit4]   2> 	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
   [junit4]   2> 	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]
   [junit4]   2> 	at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
   [junit4]   2> 	at java.lang.reflect.Method.invoke(Method.java:567) ~[?:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:972) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) ~[java/:?]
   [junit4]   2> 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) ~[java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) ~[java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) ~[java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) ~[java/:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) ~[java/:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) ~[java/:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) ~[java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) ~[java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) ~[java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) ~[java/:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) ~[randomizedtesting-runner-2.7.2.jar:?]
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:830) [?:?]
   [junit4]   2> 1089744 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 1089746 WARN  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7caa9777[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1089746 WARN  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7caa9777[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1089749 WARN  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2a5c0794[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1089749 WARN  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2a5c0794[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1089751 WARN  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.c.CoreContainer Not all security plugins configured!  authentication=disabled authorization=disabled.  Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external.  See https://s.apache.org/solrsecurity for more info
   [junit4]   2> 1089768 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 1089768 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 1089799 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6abd70b7
   [junit4]   2> 1089813 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6abd70b7
   [junit4]   2> 1089813 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6abd70b7
   [junit4]   2> 1089815 INFO  (coreLoadExecutor-3975-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/lib, /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1089838 INFO  (coreLoadExecutor-3975-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.5.0
   [junit4]   2> 1089854 INFO  (coreLoadExecutor-3975-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1089904 INFO  (coreLoadExecutor-3975-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 1089910 INFO  (coreLoadExecutor-3975-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1, trusted=true
   [junit4]   2> 1089910 INFO  (coreLoadExecutor-3975-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6abd70b7
   [junit4]   2> 1089910 INFO  (coreLoadExecutor-3975-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.search.TestRecovery_B727845A6BFBB934-001/data-dir-104-001/]
   [junit4]   2> 1089913 INFO  (coreLoadExecutor-3975-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=40, maxMergeAtOnceExplicit=43, maxMergedSegmentMB=59.4638671875, floorSegmentMB=1.2275390625, forceMergeDeletesPctAllowed=2.3534358879180104, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=26.18428876975151
   [junit4]   2> 1089940 WARN  (coreLoadExecutor-3975-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
   [junit4]   2> 1089994 INFO  (coreLoadExecutor-3975-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1089994 INFO  (coreLoadExecutor-3975-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1089996 INFO  (coreLoadExecutor-3975-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1089996 INFO  (coreLoadExecutor-3975-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1089998 INFO  (coreLoadExecutor-3975-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=913704995, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
   [junit4]   2> 1089998 INFO  (coreLoadExecutor-3975-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4693e406[collection1] main]
   [junit4]   2> 1089998 WARN  (coreLoadExecutor-3975-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.r.ManagedResourceStorage Cannot write to config directory /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
   [junit4]   2> 1089999 INFO  (coreLoadExecutor-3975-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1089999 INFO  (coreLoadExecutor-3975-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1655471864229658624
   [junit4]   2> 1090001 INFO  (searcherExecutor-3976-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4693e406[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1090001 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1090002 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1090003 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3dcb1610[collection1] realtime]
   [junit4]   2> 1090003 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:* (-9223372036854775807)} 0 1
   [junit4]   2> 1090003 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1655471864233852928,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1090004 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@69206c86 commitCommandVersion:1655471864233852928
   [junit4]   2> 1090025 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@53bcba62[collection1] main]
   [junit4]   2> 1090026 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1090028 INFO  (searcherExecutor-3976-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@53bcba62[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1090028 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 24
   [junit4]   2> 1090030 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5159 (1655471864261115904)]} 0 1
   [junit4]   2> 1090030 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[9974 (1655471864262164480)]} 0 0
   [junit4]   2> 1090030 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[4246 (1655471864262164481)]} 0 0
   [junit4]   2> 1090030 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5484 (1655471864262164482)]} 0 0
   [junit4]   2> 1090031 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[8523 (1655471864263213056)]} 0 0
   [junit4]   2> 1090031 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[6188 (1655471864263213057)]} 0 0
   [junit4]   2> 1090031 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[3241 (1655471864263213058)]} 0 0
   [junit4]   2> 1090031 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5038 (1655471864263213059)]} 0 0
   [junit4]   2> 1090031 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[2187 (1655471864263213060)]} 0 0
   [junit4]   2> 1090032 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[674 (1655471864264261632)]} 0 0
   [junit4]   2> 1090043 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5d5e0c65[collection1] realtime]
   [junit4]   2> 1090045 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@9456613[collection1] realtime]
   [junit4]   2> 1090045 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{deleteByQuery=val_i_dvo:617 (-1655471864264261633)} 0 12
   [junit4]   2> 1090047 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5207 (1655471864278941696)]} 0 0
   [junit4]   2> 1090047 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[2973 (1655471864279990272)]} 0 0
   [junit4]   2> 1090049 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[3241 (1655471864282087424)]} 0 0
   [junit4]   2> 1090049 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[2187 (1655471864282087425)]} 0 0
   [junit4]   2> 1090050 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5164 (1655471864283136000)]} 0 0
   [junit4]   2> 1090050 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5038 (1655471864283136001)]} 0 0
   [junit4]   2> 1090050 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[4248 (1655471864283136002)]} 0 0
   [junit4]   2> 1090051 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[2973 (1655471864283136003)]} 0 0
   [junit4]   2> 1090051 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[6729 (1655471864284184576)]} 0 0
   [junit4]   2> 1090051 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5207 (1655471864284184577)]} 0 0
   [junit4]   2> 1090085 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4f57fecb[collection1] realtime]
   [junit4]   2> 1090087 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c8005da[collection1] realtime]
   [junit4]   2> 1090088 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{deleteByQuery=val_i_dvo:816 (-1655471864284184578)} 0 36
   [junit4]   2> 1090089 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[7962 (1655471864322981888)]} 0 0
   [junit4]   2> 1090089 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5484 (1655471864324030464)]} 0 0
   [junit4]   2> 1090089 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[8273 (1655471864324030465)]} 0 0
   [junit4]   2> 1090104 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@52323440[collection1] realtime]
   [junit4]   2> 1090107 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@31db66e7[collection1] realtime]
   [junit4]   2> 1090107 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{deleteByQuery=val_i_dvo:162 (-1655471864324030466)} 0 18
   [junit4]   2> 1090110 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3a1cb116[collection1] realtime]
   [junit4]   2> 1090110 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{deleteByQuery=val_i_dvo:45 (-1655471864343953408)} 0 2
   [junit4]   2> 1090111 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[9364 (1655471864346050560)]} 0 0
   [junit4]   2> 1090111 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[9441 (1655471864347099136)]} 0 0
   [junit4]   2> 1090112 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[9364 (1655471864348147712)]} 0 0
   [junit4]   2> 1090112 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[7508 (1655471864348147713)]} 0 0
   [junit4]   2> 1090112 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5159 (1655471864348147714)]} 0 0
   [junit4]   2> 1090112 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[7145 (1655471864348147715)]} 0 0
   [junit4]   2> 1090114 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[9364 (1655471864350244864)]} 0 1
   [junit4]   2> 1090114 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[7129 (1655471864350244865)]} 0 0
   [junit4]   2> 1090114 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[553 (1655471864350244866)]} 0 0
   [junit4]   2> 1090114 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[1212 (1655471864350244867)]} 0 0
   [junit4]   2> 1090115 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[7129 (1655471864350244868)]} 0 0
   [junit4]   2> 1090115 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[6908 (1655471864351293440)]} 0 0
   [junit4]   2> 1090115 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{delete=[9441 (-1655471864351293441)]} 0 0
   [junit4]   2> 1090136 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5543d6c5[collection1] realtime]
   [junit4]   2> 1090139 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@30751b86[collection1] realtime]
   [junit4]   2> 1090139 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{deleteByQuery=val_i_dvo:272 (-1655471864351293442)} 0 23
   [junit4]   2> 1090140 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[7851 (1655471864376459264)]} 0 0
   [junit4]   2> 1090140 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[7508 (1655471864377507840)]} 0 0
   [junit4]   2> 1090140 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[9361 (1655471864377507841)]} 0 0
   [junit4]   2> 1090140 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{delete=[553 (-1655471864377507842)]} 0 0
   [junit4]   2> 1090141 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[4246 (1655471864378556416)]} 0 0
   [junit4]   2> 1090141 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5484 (1655471864378556417)]} 0 0
   [junit4]   2> 1090141 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[4246 (1655471864378556418)]} 0 0
   [junit4]   2> 1090142 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[2973 (1655471864379604992)]} 0 0
   [junit4]   2> 1090142 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[2698 (1655471864379604993)]} 0 0
   [junit4]   2> 1090142 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[9179 (1655471864379604994)]} 0 0
   [junit4]   2> 1090142 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[8008 (1655471864379604995)]} 0 0
   [junit4]   2> 1090142 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[7145 (1655471864379604996)]} 0 0
   [junit4]   2> 1090142 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[4135 (1655471864379604997)]} 0 0
   [junit4]   2> 1090143 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[8548 (1655471864380653568)]} 0 0
   [junit4]   2> 1090143 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[717 (1655471864380653569)]} 0 0
   [junit4]   2> 1090143 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[7145 (1655471864380653570)]} 0 0
   [junit4]   2> 1090143 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[2620 (1655471864380653571)]} 0 0
   [junit4]   2> 1090143 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[9361 (1655471864380653572)]} 0 0
   [junit4]   2> 1090144 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[4246 (1655471864381702144)]} 0 0
   [junit4]   2> 1090144 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[8493 (1655471864381702145)]} 0 0
   [junit4]   2> 1090144 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[6459 (1655471864381702146)]} 0 0
   [junit4]   2> 1090144 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[2024 (1655471864381702147)]} 0 0
   [junit4]   2> 1090145 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{delete=[4135 (-1655471864381702148)]} 0 0
   [junit4]   2> 1090145 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[7129 (1655471864382750720)]} 0 0
   [junit4]   2> 1090145 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[674 (1655471864382750721)]} 0 0
   [junit4]   2> 1090145 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[6908 (1655471864382750722)]} 0 0
   [junit4]   2> 1090145 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{delete=[2698 (-1655471864382750723)]} 0 0
   [junit4]   2> 1090146 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[7842 (1655471864382750724)]} 0 0
   [junit4]   2> 1090146 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[8834 (1655471864383799296)]} 0 0
   [junit4]   2> 1090146 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[3832 (1655471864383799297)]} 0 0
   [junit4]   2> 1090146 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5484 (1655471864383799298)]} 0 0
   [junit4]   2> 1090146 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[3241 (1655471864383799299)]} 0 0
   [junit4]   2> 1090146 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[3818 (1655471864383799300)]} 0 0
   [junit4]   2> 1090147 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[8493 (1655471864384847872)]} 0 0
   [junit4]   2> 1090147 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[9364 (1655471864384847873)]} 0 0
   [junit4]   2> 1090147 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[674 (1655471864384847874)]} 0 0
   [junit4]   2> 1090148 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[8660 (1655471864385896448)]} 0 0
   [junit4]   2> 1090148 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[6459 (1655471864385896449)]} 0 0
   [junit4]   2> 1090176 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@a1dd64d[collection1] realtime]
   [junit4]   2> 1090180 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@12b8e297[collection1] realtime]
   [junit4]   2> 1090180 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{deleteByQuery=val_i_dvo:87 (-1655471864385896450)} 0 32
   [junit4]   2> 1090181 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[8735 (1655471864420499456)]} 0 0
   [junit4]   2> 1090181 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[1428 (1655471864420499457)]} 0 0
   [junit4]   2> 1090181 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[2410 (1655471864420499458)]} 0 0
   [junit4]   2> 1090182 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[7851 (1655471864421548032)]} 0 0
   [junit4]   2> 1090182 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[9364 (1655471864421548033)]} 0 0
   [junit4]   2> 1090182 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[1634 (1655471864421548034)]} 0 0
   [junit4]   2> 1090182 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[767 (1655471864421548035)]} 0 0
   [junit4]   2> 1090182 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[3832 (1655471864421548036)]} 0 0
   [junit4]   2> 1090183 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5159 (1655471864422596608)]} 0 0
   [junit4]   2> 1090183 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[8330 (1655471864422596609)]} 0 0
   [junit4]   2> 1090183 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[1840 (1655471864422596610)]} 0 0
   [junit4]   2> 1090183 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[7145 (1655471864422596611)]} 0 0
   [junit4]   2> 1090183 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[2641 (1655471864422596612)]} 0 0
   [junit4]   2> 1090184 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5164 (1655471864423645184)]} 0 0
   [junit4]   2> 1090184 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[8726 (1655471864423645185)]} 0 0
   [junit4]   2> 1090184 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[767 (1655471864423645186)]} 0 0
   [junit4]   2> 1090184 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[8548 (1655471864423645187)]} 0 0
   [junit4]   2> 1090184 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[9070 (1655471864423645188)]} 0 0
   [junit4]   2> 1090184 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[6026 (1655471864423645189)]} 0 0
   [junit4]   2> 1090185 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5164 (1655471864424693760)]} 0 0
   [junit4]   2> 1090185 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[8399 (1655471864424693761)]} 0 0
   [junit4]   2> 1090185 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[6341 (1655471864424693762)]} 0 0
   [junit4]   2> 1090185 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[6017 (1655471864424693763)]} 0 0
   [junit4]   2> 1090185 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[4924 (1655471864424693764)]} 0 0
   [junit4]   2> 1090186 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[1888 (1655471864425742336)]} 0 0
   [junit4]   2> 1090218 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4fe8fc50[collection1] realtime]
   [junit4]   2> 1090226 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7a9f1d4d[collection1] realtime]
   [junit4]   2> 1090226 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{deleteByQuery=val_i_dvo:911 (-1655471864425742337)} 0 40
   [junit4]   2> 1090227 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[7063 (1655471864467685376)]} 0 0
   [junit4]   2> 1090227 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5373 (1655471864468733952)]} 0 0
   [junit4]   2> 1090228 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[8905 (1655471864468733953)]} 0 0
   [junit4]   2> 1090228 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[4248 (1655471864469782528)]} 0 0
   [junit4]   2> 1090228 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[4248 (1655471864469782529)]} 0 0
   [junit4]   2> 1090228 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[2826 (1655471864469782530)]} 0 0
   [junit4]   2> 1090229 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[9497 (1655471864470831104)]} 0 0
   [junit4]   2> 1090229 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[7367 (1655471864470831105)]} 0 0
   [junit4]   2> 1090229 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[9364 (1655471864470831106)]} 0 0
   [junit4]   2> 1090230 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[7538 (1655471864470831107)]} 0 0
   [junit4]   2> 1090230 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5164 (1655471864471879680)]} 0 0
   [junit4]   2> 1090230 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[3241 (1655471864471879681)]} 0 0
   [junit4]   2> 1090230 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[6898 (1655471864471879682)]} 0 0
   [junit4]   2> 1090231 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[8120 (1655471864471879683)]} 0 0
   [junit4]   2> 1090231 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[1875 (1655471864472928256)]} 0 0
   [junit4]   2> 1090231 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5697 (1655471864472928257)]} 0 0
   [junit4]   2> 1090231 INFO  (TEST-TestRecovery.stressLogReplay-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[4651 (1655471864472928258)]} 0 0
   [junit4]   2> 1090231 INFO

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

QTime=0
   [junit4]   2> 1140907 INFO  (replayUpdatesExecutor-4657-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.u.DirectUpdateHandler2 Reordered DBQs detected.  Update=add{flags=a,_version_=74,id=RDBQ4_1} DBQs=[DBQ{version=75,q=id:RDBQ4_2}]
   [junit4]   2> 1140921 INFO  (replayUpdatesExecutor-4657-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@39134799[collection1] realtime]
   [junit4]   2> 1141168 INFO  (recoveryExecutor-4666-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.u.DirectUpdateHandler2 start commit{flags=2,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1141168 INFO  (recoveryExecutor-4666-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@596307d5 commitCommandVersion:0
   [junit4]   2> 1141262 INFO  (recoveryExecutor-4666-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@71c8ffcf[collection1] main]
   [junit4]   2> 1141262 INFO  (recoveryExecutor-4666-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1141264 INFO  (searcherExecutor-4664-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@71c8ffcf[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.5.0):C4:[diagnostics={os=Mac OS X, java.vendor=AdoptOpenJDK, java.version=13.0.1, java.vm.version=13.0.1+9, lucene.version=8.5.0, os.arch=x86_64, java.runtime.version=13.0.1+9, source=flush, os.version=10.11.6, timestamp=1578781049364}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1141265 INFO  (recoveryExecutor-4666-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {deleteByQuery=id:RDBQ4_2 (-75),add=[RDBQ4_1 (74)]} 0 362
   [junit4]   2> 1141265 WARN  (recoveryExecutor-4666-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=1 deletes=0 deleteByQuery=1 errors=0 positionOfStart=0}
   [junit4]   2> 1141265 INFO  (TEST-TestRecovery.testLogReplayWithReorderedDBQInsertingChildnodes-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 1141266 INFO  (TEST-TestRecovery.testLogReplayWithReorderedDBQInsertingChildnodes-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1141266 INFO  (TEST-TestRecovery.testLogReplayWithReorderedDBQInsertingChildnodes-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=777478959
   [junit4]   2> 1141266 INFO  (coreCloseExecutor-4669-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@13a9a715
   [junit4]   2> 1141266 INFO  (coreCloseExecutor-4669-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=SolrCore@13a9a715
   [junit4]   2> 1141267 INFO  (coreCloseExecutor-4669-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@d8ac784: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@25fccfc8
   [junit4]   2> 1141303 INFO  (TEST-TestRecovery.testLogReplayWithReorderedDBQInsertingChildnodes-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
   [junit4]   2> 1141303 INFO  (TEST-TestRecovery.testLogReplayWithReorderedDBQInsertingChildnodes-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@79663e5c: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@24cb3d9d
   [junit4]   2> 1141325 INFO  (TEST-TestRecovery.testLogReplayWithReorderedDBQInsertingChildnodes-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
   [junit4]   2> 1141325 INFO  (TEST-TestRecovery.testLogReplayWithReorderedDBQInsertingChildnodes-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3a6bcc91: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@7fbac0d4
   [junit4]   2> 1141350 INFO  (TEST-TestRecovery.testLogReplayWithReorderedDBQInsertingChildnodes-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
   [junit4]   2> 1141350 INFO  (TEST-TestRecovery.testLogReplayWithReorderedDBQInsertingChildnodes-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6a7bd4df: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@4e13dc6c
   [junit4]   2> 1141353 INFO  (TEST-TestRecovery.testLogReplayWithReorderedDBQInsertingChildnodes-seed#[B727845A6BFBB934]) [    x:collection1 ] o.a.s.SolrTestCaseJ4 ###Ending testLogReplayWithReorderedDBQInsertingChildnodes
   [junit4]   2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.search.TestRecovery_B727845A6BFBB934-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene84): {_root_=PostingsFormat(name=Asserting), id=PostingsFormat(name=LuceneFixedGap)}, docValues:{_version_=DocValuesFormat(name=Direct), val_i_dvo=DocValuesFormat(name=Asserting), val_i=DocValuesFormat(name=Lucene80), _root_=DocValuesFormat(name=Direct), id=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=1602, maxMBSortInHeap=6.2165265748364735, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@71d7e2da), locale=zh, timezone=Europe/Sarajevo
   [junit4]   2> NOTE: Mac OS X 10.11.6 x86_64/AdoptOpenJDK 13.0.1 (64-bit)/cpus=3,threads=1,free=256838488,total=536870912
   [junit4]   2> NOTE: All tests run in this JVM: [JSONWriterTest, ShardsWhitelistTest, TestJoin, TestSolrCloudWithSecureImpersonation, LegacyCloudClusterPropTest, CustomHighlightComponentTest, FullSolrCloudDistribCmdsTest, MultiSolrCloudTestCaseTest, MetricsHistoryWithAuthIntegrationTest, SuggestComponentTest, CdcrBootstrapTest, TestQueryUtils, TestCSVLoader, AutoAddReplicasIntegrationTest, DocValuesTest, TestSurroundQueryParser, ExternalFileFieldSortTest, GraphQueryTest, TestSimNodeLostTrigger, SolrCloudReportersTest, JvmMetricsTest, TestLegacyTerms, MetricTriggerIntegrationTest, TestAnalyzedSuggestions, TestInPlaceUpdatesDistrib, TestHdfsUpdateLog, CreateCollectionCleanupTest, TestNumericRangeQuery32, PackageManagerCLITest, TestDocTermOrds, TimeZoneUtilsTest, TestSolr4Spatial, TestXmlQParserPlugin, TestPayloadCheckQParserPlugin, StressHdfsTest, TestDefaultStatsCache, TestInitParams, TestCoreBackup, PreAnalyzedFieldManagedSchemaCloudTest, TestJettySolrRunner, CategoryRoutedAliasUpdateProcessorTest, ConvertedLegacyTest, BaseCdcrDistributedZkTest, AddBlockUpdateTest, TestCorePropertiesReload, HdfsDirectoryTest, CollectionPropsTest, TestInitQParser, PeerSyncWithBufferUpdatesTest, HttpPartitionWithTlogReplicasTest, TestSubQueryTransformerCrossCore, ShardRoutingCustomTest, SolrXmlInZkTest, TestSolrXml, SimpleMLTQParserTest, ClusterStateTest, UUIDFieldTest, SuggesterTSTTest, TestElisionMultitermQuery, TestOnReconnectListenerSupport, PluginInfoTest, OutOfBoxZkACLAndCredentialsProvidersTest, BasicZkTest, SolrInfoBeanTest, TestSolrConfigHandlerConcurrent, NodeAddedTriggerIntegrationTest, TestReplicationHandlerDiskOverFlow, StatsComponentTest, XmlInterpolationTest, WordBreakSolrSpellCheckerTest, TestBackupRepositoryFactory, TestUnifiedSolrHighlighter, CloneFieldUpdateProcessorFactoryTest, TestExactSharedStatsCache, TestManagedSchema, ZkShardTermsTest, TestRestoreCore, TestAuthenticationFramework, RollingRestartTest, LeaderElectionIntegrationTest, TestRecovery]
   [junit4] Completed [169/895 (1!)] on J0 in 54.29s, 20 tests, 1 error <<< FAILURES!

[...truncated 47387 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/507/consoleText

[repro] Revision: 72dea4919ebc79721167d451e7c7afa022aeee05

[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseG1GC"
[repro] Repro line:  ant test  -Dtestcase=TestRecovery -Dtests.method=stressLogReplay -Dtests.seed=B727845A6BFBB934 -Dtests.slow=true -Dtests.locale=zh -Dtests.timezone=Europe/Sarajevo -Dtests.asserts=true -Dtests.file.encoding=US-ASCII

[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean

[...truncated 6 lines...]
[repro] Test suites by module:
[repro]    solr/core
[repro]       TestRecovery
[repro] ant compile-test

[...truncated 3629 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestRecovery" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseG1GC" -Dtests.seed=B727845A6BFBB934 -Dtests.slow=true -Dtests.locale=zh -Dtests.timezone=Europe/Sarajevo -Dtests.asserts=true -Dtests.file.encoding=US-ASCII

[...truncated 167 lines...]
[repro] Failures w/original seeds:
[repro]   0/5 failed: org.apache.solr.search.TestRecovery
[repro] Exiting with code 0

[...truncated 73 lines...]

[JENKINS] Lucene-Solr-8.x-MacOSX (64bit/jdk-11.0.4) - Build # 508 - Failure!

Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/508/
Java: 64bit/jdk-11.0.4 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC

All tests passed

Build Log:
[...truncated 2197 lines...]
   [junit4] JVM J0: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/core/test/temp/junit4-J0-20200112_111030_57815365032455029882093.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/core/test/temp/junit4-J1-20200112_111030_57813558570568186125269.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 302 lines...]
   [junit4] JVM J0: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/test-framework/test/temp/junit4-J0-20200112_112210_10310429741928776310660.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/test-framework/test/temp/junit4-J1-20200112_112210_10315679791630304527135.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 1096 lines...]
   [junit4] JVM J0: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/analysis/common/test/temp/junit4-J0-20200112_112358_0457743513785590726870.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/analysis/common/test/temp/junit4-J1-20200112_112358_04613376233161723490140.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 256 lines...]
   [junit4] JVM J1: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/analysis/icu/test/temp/junit4-J1-20200112_112625_1337673232788648865415.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/analysis/icu/test/temp/junit4-J0-20200112_112625_1324101316646339039642.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 222 lines...]
   [junit4] JVM J0: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/analysis/kuromoji/test/temp/junit4-J0-20200112_112636_1905071411094915867406.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/analysis/kuromoji/test/temp/junit4-J1-20200112_112636_1903976569059253401173.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/analysis/morfologik/test/temp/junit4-J0-20200112_112730_322398187049293845731.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/analysis/morfologik/test/temp/junit4-J1-20200112_112730_32211716798630445099377.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 191 lines...]
   [junit4] JVM J0: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/analysis/nori/test/temp/junit4-J0-20200112_112736_7753489172665379956636.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/analysis/nori/test/temp/junit4-J1-20200112_112736_77611019322973159092526.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 171 lines...]
   [junit4] JVM J0: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/analysis/opennlp/test/temp/junit4-J0-20200112_112801_1089830904489536754553.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/analysis/opennlp/test/temp/junit4-J1-20200112_112801_10811133165509100441295.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 176 lines...]
   [junit4] JVM J1: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/analysis/phonetic/test/temp/junit4-J1-20200112_112805_5568196936534629396997.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/analysis/phonetic/test/temp/junit4-J0-20200112_112805_5568592180939163791077.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 159 lines...]
   [junit4] JVM J0: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/analysis/smartcn/test/temp/junit4-J0-20200112_112815_3038471826702977588169.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/analysis/smartcn/test/temp/junit4-J1-20200112_112815_30313302210450722931653.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/analysis/stempel/test/temp/junit4-J0-20200112_112823_09311265885966634727998.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/analysis/stempel/test/temp/junit4-J1-20200112_112823_09316332840084895424155.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 J0: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/backward-codecs/test/temp/junit4-J0-20200112_112828_34412792157210015271587.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 28 lines...]
   [junit4] JVM J1: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/backward-codecs/test/temp/junit4-J1-20200112_112828_344988721853761743603.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 1567 lines...]
   [junit4] JVM J0: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/benchmark/test/temp/junit4-J0-20200112_113130_79313310707583522418340.syserr
   [junit4] JVM J1: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/benchmark/test/temp/junit4-J1-20200112_113130_7934488327149327136863.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 J0: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/classification/test/temp/junit4-J0-20200112_113143_9846336734396184959846.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/classification/test/temp/junit4-J1-20200112_113143_9846483157095511560329.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 282 lines...]
   [junit4] JVM J0: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/codecs/test/temp/junit4-J0-20200112_113211_59815891521301175069050.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/codecs/test/temp/junit4-J1-20200112_113211_5988181233881904734732.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 234 lines...]
   [junit4] JVM J1: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/demo/test/temp/junit4-J1-20200112_113459_81010443379415340433040.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/demo/test/temp/junit4-J0-20200112_113459_77512888643262794335977.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 173 lines...]
   [junit4] JVM J0: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/expressions/test/temp/junit4-J0-20200112_113506_65812500084382604559858.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/expressions/test/temp/junit4-J1-20200112_113506_6624097011994917577637.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 234 lines...]
   [junit4] JVM J1: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/facet/test/temp/junit4-J1-20200112_113516_71711794844886963519530.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/facet/test/temp/junit4-J0-20200112_113516_71714714014089539278595.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 185 lines...]
   [junit4] JVM J0: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/grouping/test/temp/junit4-J0-20200112_113600_70711735069414611060875.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/grouping/test/temp/junit4-J1-20200112_113600_70817091556413919844614.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 264 lines...]
   [junit4] JVM J1: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/highlighter/test/temp/junit4-J1-20200112_113610_8332232132540156277483.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/highlighter/test/temp/junit4-J0-20200112_113610_83315462693172499234702.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 166 lines...]
   [junit4] JVM J1: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/join/test/temp/junit4-J1-20200112_113645_0904905359024861738493.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/join/test/temp/junit4-J0-20200112_113645_0903151631195004197845.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 305 lines...]
   [junit4] JVM J1: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/luke/test/temp/junit4-J1-20200112_113715_5211681803010934624351.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/luke/test/temp/junit4-J0-20200112_113715_52014280897862903714502.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 155 lines...]
   [junit4] JVM J0: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/memory/test/temp/junit4-J0-20200112_113729_00913353134038064047169.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/memory/test/temp/junit4-J1-20200112_113729_0126614356676132568987.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 193 lines...]
   [junit4] JVM J0: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/misc/test/temp/junit4-J0-20200112_113739_3949304965505651936637.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/misc/test/temp/junit4-J1-20200112_113739_40311039726690784231728.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 261 lines...]
   [junit4] JVM J0: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/monitor/test/temp/junit4-J0-20200112_113825_09418429677458100424200.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/monitor/test/temp/junit4-J1-20200112_113825_0948658498133294762253.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 253 lines...]
   [junit4] JVM J0: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/queries/test/temp/junit4-J0-20200112_113839_06410975816651888537823.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/queries/test/temp/junit4-J1-20200112_113839_0656278624460382000502.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 231 lines...]
   [junit4] JVM J1: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/queryparser/test/temp/junit4-J1-20200112_113856_9309254582784028397453.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/queryparser/test/temp/junit4-J0-20200112_113856_92913957081243912467156.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/replicator/test/temp/junit4-J0-20200112_113914_70617651827241326247254.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 26 lines...]
   [junit4] JVM J1: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/replicator/test/temp/junit4-J1-20200112_113914_7063005532394261840131.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 293 lines...]
   [junit4] JVM J1: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/sandbox/test/temp/junit4-J1-20200112_113935_73718087028020941800286.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 5 lines...]
   [junit4] JVM J0: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/sandbox/test/temp/junit4-J0-20200112_113935_73612948348624373825036.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 301 lines...]
   [junit4] JVM J0: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/spatial-extras/test/temp/junit4-J0-20200112_114123_551904414854329541325.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/spatial-extras/test/temp/junit4-J1-20200112_114123_5514161759097668461508.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 151 lines...]
   [junit4] JVM J0: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/spatial/test/temp/junit4-J0-20200112_114219_3611412978547882485780.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 177 lines...]
   [junit4] JVM J0: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/spatial3d/test/temp/junit4-J0-20200112_114222_44816983760774782212465.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 14 lines...]
   [junit4] JVM J1: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/spatial3d/test/temp/junit4-J1-20200112_114222_4487266806223898673022.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 246 lines...]
   [junit4] JVM J0: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/suggest/test/temp/junit4-J0-20200112_114252_3214043002185654158530.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 18 lines...]
   [junit4] JVM J1: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/build/suggest/test/temp/junit4-J1-20200112_114252_32110203092053757867784.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 4743 lines...]
   [junit4] JVM J0: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/temp/junit4-J0-20200112_114436_99510748995679497905010.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/temp/junit4-J1-20200112_114436_995545146131679400504.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 1104 lines...]
   [junit4] JVM J1: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-solrj/test/temp/junit4-J1-20200112_130442_27517364108117248152355.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-solrj/test/temp/junit4-J0-20200112_130442_2757174393101418393918.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 1446 lines...]
   [junit4] JVM J1: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/contrib/solr-analysis-extras/test/temp/junit4-J1-20200112_131529_0874882676827325105514.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/contrib/solr-analysis-extras/test/temp/junit4-J0-20200112_131529_0873560361357702454713.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 800 lines...]
   [junit4] JVM J1: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/contrib/solr-analytics/test/temp/junit4-J1-20200112_131552_00611485720916161943037.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/contrib/solr-analytics/test/temp/junit4-J0-20200112_131552_0067462144440486897025.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 571 lines...]
   [junit4] JVM J0: stderr was not empty, see: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/contrib/solr-clustering/test/temp/junit4-J0-20200112_131734_57811595740930323534199.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: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/contrib/solr-clustering/test/temp/junit4-J1-20200112_131734_57811797276313181345942.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 104 lines...]
# A fatal error has been detected by the Java Runtime Environment:
#
#  Internal Error (sharedRuntime.cpp:902), pid=86636, tid=7171
#  guarantee(cm != NULL) failed: must have containing compiled method for implicit division-by-zero exceptions
#
# JRE version: OpenJDK Runtime Environment (11.0.4+11) (build 11.0.4+11)
# Java VM: OpenJDK 64-Bit Server VM (11.0.4+11, mixed mode, tiered, compressed oops, g1 gc, bsd-amd64)
# No core dump will be written. Core dumps have been disabled. To enable core dumping, try "ulimit -c unlimited" before starting Java again
#
# An error report file with more information is saved as:
# /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/hs_err_pid86636.log
#
# If you would like to submit a bug report, please visit:
#   https://github.com/AdoptOpenJDK/openjdk-build/issues
#
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2