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/23 09:51:35 UTC
[JENKINS] Lucene-Solr-master-MacOSX (64bit/jdk-12.0.2) - Build #
5562 - Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/5562/
Java: 64bit/jdk-12.0.2 -XX:-UseCompressedOops -XX:+UseSerialGC
2 tests failed.
FAILED: org.apache.solr.search.TestRecovery.testExistOldBufferLog
Error Message:
Test abandoned because suite timeout was reached.
Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
at __randomizedtesting.SeedInfo.seed([6042CA86275A0531]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.search.TestRecovery
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([6042CA86275A0531]:0)
Build Log:
[...truncated 16340 lines...]
[junit4] Suite: org.apache.solr.search.TestRecovery
[junit4] 2> 1937802 INFO (SUITE-TestRecovery-seed#[6042CA86275A0531]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1937805 INFO (SUITE-TestRecovery-seed#[6042CA86275A0531]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.search.TestRecovery_6042CA86275A0531-001/data-dir-205-001
[junit4] 2> 1937805 WARN (SUITE-TestRecovery-seed#[6042CA86275A0531]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=108 numCloses=108
[junit4] 2> 1937805 INFO (SUITE-TestRecovery-seed#[6042CA86275A0531]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1937806 INFO (SUITE-TestRecovery-seed#[6042CA86275A0531]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) 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> 1937816 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testVersionsOnRestart
[junit4] 2> 1937816 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 updateLog impl=solr.CdcrUpdateLog
[junit4] 2> 1937817 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 1937818 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib, /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1937882 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1937914 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 1938231 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1938615 WARN (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.f.DistribPackageStore Unable to create [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/filestore] directory in SOLR_HOME [/Users/jenkins/workspace/Lucene-Solr-master-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-master-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-master-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:1044) ~[?:?]
[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:619) ~[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:814) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:804) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:665) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:654) ~[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:1754) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:976) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) ~[randomizedtesting-runner-2.7.6.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.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) ~[randomizedtesting-runner-2.7.6.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.6.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.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.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.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:835) [?:?]
[junit4] 2> 1938615 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1938631 WARN (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4892c32c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1938632 WARN (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4892c32c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1938668 WARN (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4e4a7b23[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1938668 WARN (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4e4a7b23[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1938670 WARN (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] 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> 1938890 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1938891 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1938987 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75202365
[junit4] 2> 1938998 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75202365
[junit4] 2> 1938998 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75202365
[junit4] 2> 1939000 INFO (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib, /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1939059 INFO (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1939120 INFO (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1939352 INFO (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1939388 INFO (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 1939388 INFO (coreLoadExecutor-9028-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@75202365
[junit4] 2> 1939389 INFO (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.search.TestRecovery_6042CA86275A0531-001/data-dir-205-001/]
[junit4] 2> 1939393 INFO (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=345, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3951854026626319]
[junit4] 2> 1939564 WARN (coreLoadExecutor-9028-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> 1939755 INFO (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog
[junit4] 2> 1939755 INFO (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1939781 INFO (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1939781 INFO (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1939800 INFO (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=273, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7754277102425448]
[junit4] 2> 1939805 INFO (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@77d9488a[collection1] main]
[junit4] 2> 1939805 WARN (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage Cannot write to config directory /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 1939806 INFO (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1939806 INFO (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1656503499535417344
[junit4] 2> 1939810 INFO (searcherExecutor-9029-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@77d9488a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1939817 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 1939820 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1939824 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@179e99d3[collection1] realtime]
[junit4] 2> 1939824 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] 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 5
[junit4] 2> 1939824 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1656503499554291712,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1939824 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4bb945fa commitCommandVersion:1656503499554291712
[junit4] 2> 1940034 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2269614c[collection1] main]
[junit4] 2> 1940050 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1940059 INFO (searcherExecutor-9029-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2269614c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1940060 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 235
[junit4] 2> 1940199 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[D1 (1656503499803852800)]} 0 136
[junit4] 2> 1940200 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[D2 (1656503499947507712)]} 0 0
[junit4] 2> 1940200 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1656503499948556288,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1940200 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4bb945fa commitCommandVersion:1656503499948556288
[junit4] 2> 1941897 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@573b9393[collection1] main]
[junit4] 2> 1941898 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1941900 INFO (searcherExecutor-9029-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@573b9393[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C2:[diagnostics={lucene.version=9.0.0, source=flush, os.arch=x86_64, java.runtime.version=12.0.2+10, os.version=10.14.6, os=Mac OS X, java.vendor=AdoptOpenJDK, timestamp=1579764843049, java.version=12.0.2, java.vm.version=12.0.2+10}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1941901 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 1700
[junit4] 2> 1941902 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:D1&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1941903 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:D2&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1941903 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1335650550
[junit4] 2> 1941904 INFO (coreCloseExecutor-9034-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@25d6e843
[junit4] 2> 1941904 INFO (coreCloseExecutor-9034-thread-1) [ x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=SolrCore@25d6e843
[junit4] 2> 1941904 INFO (coreCloseExecutor-9034-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6e39ef34: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@6736f5c4
[junit4] 2> 1941920 INFO (coreCloseExecutor-9034-thread-1) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 1942112 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1942112 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@238c5f9d: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@1c075525
[junit4] 2> 1942131 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1942131 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1deeaa68: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@3e2144e1
[junit4] 2> 1942135 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1942135 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6f733c5e: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@11e5f819
[junit4] 2> 1942172 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib, /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1942224 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1942269 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 1942481 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1942774 WARN (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.f.DistribPackageStore Unable to create [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/filestore] directory in SOLR_HOME [/Users/jenkins/workspace/Lucene-Solr-master-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-master-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-master-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:1044) ~[?:?]
[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:619) ~[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:814) ~[java/:?]
[junit4] 2> at org.apache.solr.search.TestRecovery.testVersionsOnRestart(TestRecovery.java:1106) ~[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:1754) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) ~[randomizedtesting-runner-2.7.6.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.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) ~[randomizedtesting-runner-2.7.6.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.6.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.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.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.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:835) [?:?]
[junit4] 2> 1942786 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1942947 WARN (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@54dbfb56[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1942948 WARN (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@54dbfb56[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1942965 WARN (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@19bab902[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1942965 WARN (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@19bab902[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1942968 WARN (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] 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> 1943178 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1943179 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1943325 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75202365
[junit4] 2> 1943346 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75202365
[junit4] 2> 1943346 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75202365
[junit4] 2> 1943349 INFO (coreLoadExecutor-9044-thread-1) [ x:collection1 ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib, /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1943395 INFO (coreLoadExecutor-9044-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1943454 INFO (coreLoadExecutor-9044-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1943634 INFO (coreLoadExecutor-9044-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1943784 INFO (coreLoadExecutor-9044-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 1943785 INFO (coreLoadExecutor-9044-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@75202365
[junit4] 2> 1943785 INFO (coreLoadExecutor-9044-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.search.TestRecovery_6042CA86275A0531-001/data-dir-205-001/]
[junit4] 2> 1943799 WARN (coreLoadExecutor-9044-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> 1944300 INFO (coreLoadExecutor-9044-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog
[junit4] 2> 1944300 INFO (coreLoadExecutor-9044-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1944303 INFO (coreLoadExecutor-9044-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1944304 INFO (coreLoadExecutor-9044-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1944312 INFO (coreLoadExecutor-9044-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=345, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3951854026626319]
[junit4] 2> 1944492 INFO (coreLoadExecutor-9044-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2d05fe72[collection1] main]
[junit4] 2> 1944493 WARN (coreLoadExecutor-9044-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage Cannot write to config directory /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 1944493 INFO (coreLoadExecutor-9044-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1944505 INFO (searcherExecutor-9045-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2d05fe72[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C2:[diagnostics={lucene.version=9.0.0, source=flush, os.arch=x86_64, java.runtime.version=12.0.2+10, os.version=10.14.6, java.vendor=AdoptOpenJDK, os=Mac OS X, timestamp=1579764843049, java.version=12.0.2, java.vm.version=12.0.2+10}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1944616 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[D1 (1656503504501473280)]} 0 73
[junit4] 2> 1944617 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1656503504580116480,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1944617 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@794ff119 commitCommandVersion:1656503504580116480
[junit4] 2> 1946713 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2da98cca[collection1] main]
[junit4] 2> 1946718 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1946724 INFO (searcherExecutor-9045-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2da98cca[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C2/1:[diagnostics={lucene.version=9.0.0, source=flush, os.arch=x86_64, java.runtime.version=12.0.2+10, os.version=10.14.6, java.vendor=AdoptOpenJDK, os=Mac OS X, timestamp=1579764843049, java.version=12.0.2, java.vm.version=12.0.2+10}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]:delGen=1) Uninverting(_1(9.0.0):C1:[diagnostics={lucene.version=9.0.0, source=flush, os.arch=x86_64, java.runtime.version=12.0.2+10, os.version=10.14.6, os=Mac OS X, java.vendor=AdoptOpenJDK, timestamp=1579764847435, java.version=12.0.2, java.vm.version=12.0.2+10}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1946738 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 2121
[junit4] 2> 1946739 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:D1&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1946742 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&getVersions=2&wt=xml} status=0 QTime=3
[junit4] 2> 1946742 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 1946742 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=850936715
[junit4] 2> 1946851 INFO (coreCloseExecutor-9050-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4dc84f2c
[junit4] 2> 1946851 INFO (coreCloseExecutor-9050-thread-1) [ x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=SolrCore@4dc84f2c
[junit4] 2> 1946851 INFO (coreCloseExecutor-9050-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@55e88bb9: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@6923d3b6
[junit4] 2> 1946907 INFO (coreCloseExecutor-9050-thread-1) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 1947164 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1947164 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@16e03f70: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@234da325
[junit4] 2> 1947174 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1947174 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2700f18: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@3e2144e1
[junit4] 2> 1947177 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1947177 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1782daec: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@11e5f819
[junit4] 2> 1947192 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testVersionsOnRestart
[junit4] 2> 1947235 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDropBuffered
[junit4] 2> 1947235 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 updateLog impl=solr.UpdateLog
[junit4] 2> 1947236 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 1947236 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib, /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1947285 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1947338 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.search.TestRecovery_6042CA86275A0531-001/data-dir-206-001
[junit4] 2> 1947345 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 1947460 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1947701 WARN (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.f.DistribPackageStore Unable to create [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/filestore] directory in SOLR_HOME [/Users/jenkins/workspace/Lucene-Solr-master-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-master-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-master-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:1044) ~[?:?]
[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:619) ~[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:814) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:804) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:665) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:654) ~[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:1754) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:976) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) ~[randomizedtesting-runner-2.7.6.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.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) ~[randomizedtesting-runner-2.7.6.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.6.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.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.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.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:835) [?:?]
[junit4] 2> 1947701 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1947707 WARN (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7af167f5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1947707 WARN (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7af167f5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1947732 WARN (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3fd8331e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1947732 WARN (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3fd8331e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1947742 WARN (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] 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> 1947855 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1947861 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1947935 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75202365
[junit4] 2> 1947952 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75202365
[junit4] 2> 1947952 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75202365
[junit4] 2> 1947960 INFO (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib, /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1947997 INFO (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1948020 INFO (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1948191 INFO (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1948210 INFO (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 1948210 INFO (coreLoadExecutor-9060-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@75202365
[junit4] 2> 1948210 INFO (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.search.TestRecovery_6042CA86275A0531-001/data-dir-206-001/]
[junit4] 2> 1948225 INFO (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=345, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3951854026626319]
[junit4] 2> 1948409 WARN (coreLoadExecutor-9060-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> 1948626 INFO (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1948626 INFO (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1948629 INFO (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1948629 INFO (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1948643 INFO (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=273, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7754277102425448]
[junit4] 2> 1948644 INFO (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7faa9055[collection1] main]
[junit4] 2> 1948645 WARN (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage Cannot write to config directory /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 1948645 INFO (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1948645 INFO (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1656503508803780608
[junit4] 2> 1948647 INFO (searcherExecutor-9061-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7faa9055[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1948649 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 1948650 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1948651 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@55de34d9[collection1] realtime]
[junit4] 2> 1948651 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] 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> 1948652 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1656503508811120640,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1948652 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@51777078 commitCommandVersion:1656503508811120640
[junit4] 2> 1948874 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@cf80d7b[collection1] main]
[junit4] 2> 1948874 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1948876 INFO (searcherExecutor-9061-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@cf80d7b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1948877 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 225
[junit4] 2> 1948883 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 1948884 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 1948893 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C1 (2)]} 0 8
[junit4] 2> 1948893 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C2 (3)]} 0 0
[junit4] 2> 1948893 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C3 (4)]} 0 0
[junit4] 2> 1948894 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.UpdateLog Dropping buffered updates FSUpdateLog{state=BUFFERING, tlog=null}
[junit4] 2> 1948897 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 1948898 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C4 (5)]} 0 0
[junit4] 2> 1948898 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C5 (6)]} 0 0
[junit4] 2> 1948903 WARN (recoveryExecutor-9063-thread-1) [ ] o.a.s.u.UpdateLog Starting log replay tlog{file=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.search.TestRecovery_6042CA86275A0531-001/data-dir-206-001/tlog/buffer.tlog.0000028350895351907 refcount=2} active=true starting pos=0 inSortedOrder=false
[junit4] 2> 1949169 INFO (recoveryExecutor-9063-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{flags=2,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1949169 INFO (recoveryExecutor-9063-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@51777078 commitCommandVersion:0
[junit4] 2> 1950318 INFO (recoveryExecutor-9063-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@47c5911a[collection1] main]
[junit4] 2> 1950323 INFO (recoveryExecutor-9063-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1950324 INFO (searcherExecutor-9061-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@47c5911a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C2:[diagnostics={lucene.version=9.0.0, source=flush, os.arch=x86_64, java.runtime.version=12.0.2+10, os.version=10.14.6, os=Mac OS X, java.vendor=AdoptOpenJDK, timestamp=1579764851968, java.version=12.0.2, java.vm.version=12.0.2+10}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1950331 INFO (recoveryExecutor-9063-thread-1) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[C4 (5), C5 (6)]} 0 1427
[junit4] 2> 1950331 INFO (recoveryExecutor-9063-thread-1) [ ] o.a.s.u.UpdateLog Re-computing max version from index after log re-play.
[junit4] 2> 1950333 WARN (recoveryExecutor-9063-thread-1) [ ] o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=2 deletes=0 deleteByQuery=0 errors=0 positionOfStart=0}
[junit4] 2> 1950335 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&getVersions=2&wt=xml} status=0 QTime=0
[junit4] 2> 1950337 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C100 (7)]} 0 2
[junit4] 2> 1950337 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C101 (8)]} 0 0
[junit4] 2> 1950337 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=tlog{file=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.search.TestRecovery_6042CA86275A0531-001/data-dir-206-001/tlog/tlog.0000000000000000002 refcount=1}}
[junit4] 2> 1950342 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C103 (9)]} 0 4
[junit4] 2> 1950342 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C104 (10)]} 0 0
[junit4] 2> 1950342 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.UpdateLog Dropping buffered updates FSUpdateLog{state=BUFFERING, tlog=tlog{file=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.search.TestRecovery_6042CA86275A0531-001/data-dir-206-001/tlog/tlog.0000000000000000002 refcount=1}}
[junit4] 2> 1950344 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=tlog{file=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.search.TestRecovery_6042CA86275A0531-001/data-dir-206-001/tlog/tlog.0000000000000000002 refcount=1}}
[junit4] 2> 1950344 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C105 (11)]} 0 0
[junit4] 2> 1950345 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C106 (12)]} 0 0
[junit4] 2> 1950347 WARN (recoveryExecutor-9063-thread-1) [ ] o.a.s.u.UpdateLog Starting log replay tlog{file=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.search.TestRecovery_6042CA86275A0531-001/data-dir-206-001/tlog/buffer.tlog.0000028352342164294 refcount=2} active=true starting pos=0 inSortedOrder=false
[junit4] 2> 1950348 INFO (recoveryExecutor-9063-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{flags=2,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1950351 INFO (recoveryExecutor-9063-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@51777078 commitCommandVersion:0
[junit4] 2> 1951803 INFO (recoveryExecutor-9063-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2bf07d98[collection1] main]
[junit4] 2> 1951805 INFO (recoveryExecutor-9063-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1951811 INFO (searcherExecutor-9061-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2bf07d98[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C2:[diagnostics={lucene.version=9.0.0, source=flush, os.arch=x86_64, java.runtime.version=12.0.2+10, os.version=10.14.6, os=Mac OS X, java.vendor=AdoptOpenJDK, timestamp=1579764851968, java.version=12.0.2, java.vm.version=12.0.2+10}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_1(9.0.0):C4:[diagnostics={lucene.version=9.0.0, source=flush, os.arch=x86_64, java.runtime.version=12.0.2+10, os.version=10.14.6, os=Mac OS X, java.vendor=AdoptOpenJDK, timestamp=1579764853102, java.version=12.0.2, java.vm.version=12.0.2+10}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1951811 INFO (recoveryExecutor-9063-thread-1) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[C105 (11), C106 (12)]} 0 1464
[junit4] 2> 1951811 INFO (recoveryExecutor-9063-thread-1) [ ] o.a.s.u.UpdateLog Re-computing max version from index after log re-play.
[junit4] 2> 1951816 WARN (recoveryExecutor-9063-thread-1) [ ] o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=2 deletes=0 deleteByQuery=0 errors=0 positionOfStart=0}
[junit4] 2> 1951820 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=id,_version_&sort=_version_+asc&wt=xml} hits=6 status=0 QTime=3
[junit4] 2> 1951821 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&getVersions=6&wt=xml} status=0 QTime=0
[junit4] 2> 1951821 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 1951822 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C301 (15)]} 0 0
[junit4] 2> 1951822 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C302 (16)]} 0 0
[junit4] 2> 1951822 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.UpdateLog Dropping buffered updates FSUpdateLog{state=BUFFERING, tlog=null}
[junit4] 2> 1951836 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C301 (13)]} 0 4
[junit4] 2> 1951836 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C302 (14)]} 0 0
[junit4] 2> 1951836 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1656503512149786624,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1951836 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@51777078 commitCommandVersion:1656503512149786624
[junit4] 2> 1954274 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@9f81232[collection1] main]
[junit4] 2> 1954275 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1954277 INFO (searcherExecutor-9061-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@9f81232[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C2:[diagnostics={lucene.version=9.0.0, source=flush, os.arch=x86_64, java.runtime.version=12.0.2+10, os.version=10.14.6, os=Mac OS X, java.vendor=AdoptOpenJDK, timestamp=1579764851968, java.version=12.0.2, java.vm.version=12.0.2+10}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_1(9.0.0):C4:[diagnostics={lucene.version=9.0.0, source=flush, os.arch=x86_64, java.runtime.version=12.0.2+10, os.version=10.14.6, os=Mac OS X, java.vendor=AdoptOpenJDK, timestamp=1579764853102, java.version=12.0.2, java.vm.version=12.0.2+10}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_2(9.0.0):C2:[diagnostics={lucene.version=9.0.0, source=flush, os.arch=x86_64, java.runtime.version=12.0.2+10, os.version=10.14.6, os=Mac OS X, java.vendor=AdoptOpenJDK, timestamp=1579764854585, java.version=12.0.2, java.vm.version=12.0.2+10}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1954277 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 2440
[junit4] 2> 1954278 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&getVersions=2&wt=xml} status=0 QTime=0
[junit4] 2> 1954347 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=id,_version_&sort=_version_+desc&rows=2&wt=xml} hits=8 status=0 QTime=69
[junit4] 2> 1954351 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C2 (14)]} 0 2
[junit4] 2> 1954351 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 1954351 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=802503257
[junit4] 2> 1954388 INFO (coreCloseExecutor-9066-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@44d42228
[junit4] 2> 1954388 INFO (coreCloseExecutor-9066-thread-1) [ x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=SolrCore@44d42228
[junit4] 2> 1954388 INFO (coreCloseExecutor-9066-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5c424011: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@5737815b
[junit4] 2> 1954406 INFO (coreCloseExecutor-9066-thread-1) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() .
[junit4] 2> 1954406 INFO (coreCloseExecutor-9066-thread-1) [ x:collection1 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@51777078 commitCommandVersion:0
[junit4] 2> 1958610 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1958610 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5adddeb4: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@21b7e0f9
[junit4] 2> 1958637 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1958637 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2d26f5b1: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@3e2144e1
[junit4] 2> 1958644 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1958644 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@8a8ca8c: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@11e5f819
[junit4] 2> 1958675 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDropBuffered
[junit4] 2> 1958694 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBufferedMultipleCalls
[junit4] 2> 1958694 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 updateLog impl=solr.UpdateLog
[junit4] 2> 1958694 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 1958695 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[6042CA86275A0531]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib, /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1958886 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[6042CA86275A0531]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1958920 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.search.TestRecovery_6042CA86275A0531-001/data-dir-207-001
[junit4] 2> 1958926 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[6042CA86275A0531]) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 1959111 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[6042CA86275A0531]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1959405 WARN (TEST-TestRecovery.testBufferedMultipleCalls-seed#[6042CA86275A0531]) [ ] o.a.s.f.DistribPackageStore Unable to create [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/filestore] directory in SOLR_HOME [/Users/jenkins/workspace/Lucene-Solr-master-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-master-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-master-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:1044) ~[?:?]
[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:619) ~[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:814) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:804) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:665) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:654) ~[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:1754) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:976) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) ~[randomizedtesting-runner-2.7.6.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.S
[...truncated too long message...]
/0x00000001441d09b8.run(Unknown Source)
[junit4] 2> at java.base@12.0.2/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
[junit4] 2> at java.base@12.0.2/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
[junit4] 2> at java.base@12.0.2/java.lang.Thread.run(Thread.java:835)
[junit4] 2> 8) Thread[id=30044, name=recoveryExecutor-9319-thread-1-processing-x:collection1, state=BLOCKED, group=TGRP-TestRecovery]
[junit4] 2> at app//org.apache.solr.update.DefaultSolrCoreState.getIndexWriter(DefaultSolrCoreState.java:127)
[junit4] 2> at app//org.apache.solr.update.DirectUpdateHandler2.commit(DirectUpdateHandler2.java:652)
[junit4] 2> at app//org.apache.solr.update.UpdateLog$LogReplayer.doReplay(UpdateLog.java:1957)
[junit4] 2> at app//org.apache.solr.update.UpdateLog$LogReplayer.run(UpdateLog.java:1752)
[junit4] 2> at java.base@12.0.2/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
[junit4] 2> at java.base@12.0.2/java.util.concurrent.FutureTask.run(FutureTask.java:264)
[junit4] 2> at java.base@12.0.2/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
[junit4] 2> at java.base@12.0.2/java.util.concurrent.FutureTask.run(FutureTask.java:264)
[junit4] 2> at app//org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:210)
[junit4] 2> at app//org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$$Lambda$216/0x00000001441d09b8.run(Unknown Source)
[junit4] 2> at java.base@12.0.2/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
[junit4] 2> at java.base@12.0.2/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
[junit4] 2> at java.base@12.0.2/java.lang.Thread.run(Thread.java:835)
[junit4] 2> 9) Thread[id=30043, name=searcherExecutor-9317-thread-1, state=WAITING, group=TGRP-TestRecovery]
[junit4] 2> at java.base@12.0.2/jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> at java.base@12.0.2/java.util.concurrent.locks.LockSupport.park(LockSupport.java:194)
[junit4] 2> at java.base@12.0.2/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2081)
[junit4] 2> at java.base@12.0.2/java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:433)
[junit4] 2> at java.base@12.0.2/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1054)
[junit4] 2> at java.base@12.0.2/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1114)
[junit4] 2> at java.base@12.0.2/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
[junit4] 2> at java.base@12.0.2/java.lang.Thread.run(Thread.java:835)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {val_i=PostingsFormat(name=LuceneVarGapDocFreqInterval), _root_=BlockTreeOrds(blocksize=128), id=PostingsFormat(name=LuceneVarGapDocFreqInterval)}, docValues:{_version_=DocValuesFormat(name=Lucene80), val_i=DocValuesFormat(name=Asserting), val_i_dvo=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=520, maxMBSortInHeap=7.668626555638418, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@1b721744), locale=pa-Guru, timezone=America/Guyana
[junit4] 2> NOTE: Mac OS X 10.14.6 x86_64/AdoptOpenJDK 12.0.2 (64-bit)/cpus=6,threads=12,free=221652648,total=391081984
[junit4] 2> NOTE: All tests run in this JVM: [PrimitiveFieldTypeTest, SuggestComponentTest, TestShardHandlerFactory, LeaderFailureAfterFreshStartTest, TestWordDelimiterFilterFactory, CollectionReloadTest, TestReplicaProperties, DirectSolrConnectionTest, RollingRestartTest, TestTermsQParserPlugin, TestExactSharedStatsCacheCloud, TestElisionMultitermQuery, SpellCheckCollatorWithCollapseTest, HLLUtilTest, FieldAnalysisRequestHandlerTest, TestLegacyNumericRangeQueryBuilder, TestSolrCoreSnapshots, ForceLeaderWithTlogReplicasTest, TestStressThreadBackup, IndexSizeTriggerSizeEstimationTest, TestPackages, TestRestManager, TestSolrJ, BJQParserTest, AnalysisErrorHandlingTest, MetricsHistoryWithAuthIntegrationTest, TestSubQueryTransformer, HdfsTlogReplayBufferedWhileIndexingTest, DateRangeFieldTest, TestHighFrequencyDictionaryFactory, TestRTimerTree, TestIBSimilarityFactory, SampleTest, ChaosMonkeyNothingIsSafeTest, HdfsBasicDistributedZk2Test, SuggesterFSTTest, TestNestedUpdateProcessor, TestSimNodeLostTrigger, HttpPartitionWithTlogReplicasTest, RulesTest, TestStressRecovery, WrapperMergePolicyFactoryTest, DistributedFacetExistsSmallTest, TestImplicitCoreProperties, PreAnalyzedUpdateProcessorTest, HdfsDirectoryFactoryTest, TestFieldCacheReopen, TestFoldingMultitermQuery, QueryEqualityTest, CurrencyRangeFacetCloudTest, TestTestInjection, TestRestoreCore, SystemLogListenerTest, ShardRoutingTest, CustomHighlightComponentTest, PeerSyncWithLeaderTest, TestRetrieveFieldsOptimizer, DistributedSpellCheckComponentTest, TestFieldCollectionResource, CachingDirectoryFactoryTest, TestHttpServletCarrier, DefaultValueUpdateProcessorTest, TestManagedSchema, TestFunctionQuery, TestPerFieldSimilarity, CreateCollectionCleanupTest, DOMUtilTest, TestDistribIDF, CoreAdminOperationTest, OutOfBoxZkACLAndCredentialsProvidersTest, TestMergePolicyConfig, TestExpandComponent, TestQueryTypes, TestSolrCloudWithKerberosAlt, SearchHandlerTest, TestSchemaResource, TestDeleteCollectionOnDownNodes, MoveReplicaTest, TestNoOpRegenerator, PackageManagerCLITest, TestSegmentSorting, AliasIntegrationTest, TestWaitForStateWithJettyShutdowns, SolrLogAuditLoggerPluginTest, TestCustomStream, TestJoin, TestRandomFaceting, TestTrie, TokenizerChainTest, TestLuceneIndexBackCompat, ChaosMonkeySafeLeaderWithPullReplicasTest, CloudExitableDirectoryReaderTest, CreateRoutedAliasTest, DeleteStatusTest, DistribJoinFromCollectionTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerRolesTest, RecoveryAfterSoftCommitTest, RecoveryZkTest, RemoteQueryErrorTest, SharedFSAutoReplicaFailoverTest, SliceStateTest, SolrCLIZkUtilsTest, SolrCloudExampleTest, TestCloudPseudoReturnFields, TestCloudRecovery, TestCloudSearcherWarming, TestPullReplica, TestPullReplicaErrorHandling, TestStressCloudBlindAtomicUpdates, TestZkChroot, TlogReplayBufferedWhileIndexingTest, TriLevelCompositeIdRoutingTest, UnloadDistributedZkTest, ZkControllerTest, ZkFailoverTest, CollectionsAPIDistributedZkTest, AutoScalingHandlerTest, IndexSizeTriggerTest, MetricTriggerIntegrationTest, NodeAddedTriggerIntegrationTest, NodeAddedTriggerTest, RestoreTriggerStateTest, TriggerCooldownIntegrationTest, TestSimExecutePlanAction, TestSimPolicyCloud, TestSimTriggerIntegration, CdcrOpsAndBoundariesTest, CdcrReplicationHandlerTest, HdfsBasicDistributedZkTest, HdfsChaosMonkeyNothingIsSafeTest, HdfsChaosMonkeySafeLeaderTest, HdfsNNFailoverTest, PluginInfoTest, QueryResultKeyTest, SolrCoreCheckLockOnStartupTest, TestConfig, TestConfigOverlay, TestConfigSetImmutable, JsonLoaderTest, PingRequestHandlerTest, TestHdfsBackupRestoreCore, CoreMergeIndexesAdminHandlerTest, IndexSizeEstimatorTest, StatsReloadRaceTest, DistributedFacetPivotLargeTest, DistributedTermsComponentTest, TestNumericTokenStream, TestLogWatcher, JvmMetricsTest, SolrJmxReporterCloudTest, TestWriterPerf, TestBinaryResponseWriter, TestGeoJSONResponseWriter, TestSchemaVersionResource, TestManagedSynonymGraphFilterFactory, BooleanFieldTest, EnumFieldTest, IndexSchemaTest, NotRequiredUniqueKeyTest, SpatialRPTFieldTypeTest, TestManagedSchemaAPI, TestSchemaField, TestSchemaManager, TestSchemalessBufferedUpdates, TestSortableTextField, TestTextField, TestUseDocValuesAsStored, TestReRankQParserPlugin, TestRealTimeGet, TestRecovery]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestRecovery -Dtests.seed=6042CA86275A0531 -Dtests.slow=true -Dtests.locale=pa-Guru -Dtests.timezone=America/Guyana -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J4 | TestRecovery (suite) <<<
[junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
[junit4] > at __randomizedtesting.SeedInfo.seed([6042CA86275A0531]:0)
[junit4] Completed [900/900 (1!)] on J4 in 7212.90s, 9 tests, 2 errors <<< FAILURES!
[...truncated 44662 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/5562/consoleText
[repro] Revision: c53cbb12f4df045432d9b3a831568d9543d81c88
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseSerialGC"
[repro] Repro line: ant test -Dtestcase=TestRecovery -Dtests.method=testExistOldBufferLog -Dtests.seed=6042CA86275A0531 -Dtests.slow=true -Dtests.locale=pa-Guru -Dtests.timezone=America/Guyana -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] Repro line: ant test -Dtestcase=TestRecovery -Dtests.seed=6042CA86275A0531 -Dtests.slow=true -Dtests.locale=pa-Guru -Dtests.timezone=America/Guyana -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[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 3407 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestRecovery" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=6042CA86275A0531 -Dtests.slow=true -Dtests.locale=pa-Guru -Dtests.timezone=America/Guyana -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 176 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.search.TestRecovery
[repro] Exiting with code 0
[...truncated 73 lines...]