You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2021/05/04 19:59:20 UTC
[JENKINS] Lucene » Lucene-Solr-BadApples-NightlyTests-8.x - Build # 38 - Unstable!
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-BadApples-NightlyTests-8.x/38/
3 tests failed.
FAILED: org.apache.solr.handler.TestRestoreCore.testFailedRestore
Error Message:
Backup failure: kxqo expected null, but was:<codec footer mismatch (file truncated?): actual footer=808464432 vs expected footer=-1071082520 (resource=BufferedChecksumIndexInput(MockIndexInputWrapper(RAMInputStream(name=_0.si))))>
Stack Trace:
java.lang.AssertionError: Backup failure: kxqo expected null, but was:<codec footer mismatch (file truncated?): actual footer=808464432 vs expected footer=-1071082520 (resource=BufferedChecksumIndexInput(MockIndexInputWrapper(RAMInputStream(name=_0.si))))>
at __randomizedtesting.SeedInfo.seed([2CEBB4CBF2F894FC:A19D9C0FF3785EEC]:0)
at org.junit.Assert.fail(Assert.java:89)
at org.junit.Assert.failNotNull(Assert.java:756)
at org.junit.Assert.assertNull(Assert.java:738)
at org.apache.solr.handler.BackupStatusChecker._checkBackupSuccess(BackupStatusChecker.java:208)
at org.apache.solr.handler.BackupStatusChecker.checkBackupSuccess(BackupStatusChecker.java:187)
at org.apache.solr.handler.BackupStatusChecker.waitForBackupSuccess(BackupStatusChecker.java:96)
at org.apache.solr.handler.BackupStatusChecker.waitForBackupSuccess(BackupStatusChecker.java:72)
at org.apache.solr.handler.TestRestoreCore.testFailedRestore(TestRestoreCore.java:208)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.handler.TestRestoreCore.testSimpleRestore
Error Message:
Backup failure: latest backup expected null, but was:<codec footer mismatch (file truncated?): actual footer=808464432 vs expected footer=-1071082520 (resource=BufferedChecksumIndexInput(MockIndexInputWrapper(RAMInputStream(name=_0.si))))>
Stack Trace:
java.lang.AssertionError: Backup failure: latest backup expected null, but was:<codec footer mismatch (file truncated?): actual footer=808464432 vs expected footer=-1071082520 (resource=BufferedChecksumIndexInput(MockIndexInputWrapper(RAMInputStream(name=_0.si))))>
at __randomizedtesting.SeedInfo.seed([2CEBB4CBF2F894FC:B7D18EE3526C6741]:0)
at org.junit.Assert.fail(Assert.java:89)
at org.junit.Assert.failNotNull(Assert.java:756)
at org.junit.Assert.assertNull(Assert.java:738)
at org.apache.solr.handler.BackupStatusChecker._checkBackupSuccess(BackupStatusChecker.java:208)
at org.apache.solr.handler.BackupStatusChecker.checkBackupSuccess(BackupStatusChecker.java:172)
at org.apache.solr.handler.BackupStatusChecker.waitForDifferentBackupDir(BackupStatusChecker.java:141)
at org.apache.solr.handler.BackupStatusChecker.waitForDifferentBackupDir(BackupStatusChecker.java:117)
at org.apache.solr.handler.TestRestoreCore.testSimpleRestore(TestRestoreCore.java:141)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.security.JWTIssuerConfigTest.jwksUrlwithHttpBehaviors
Error Message:
Expected exception SolrException but no exception was thrown
Stack Trace:
junit.framework.AssertionFailedError: Expected exception SolrException but no exception was thrown
at __randomizedtesting.SeedInfo.seed([2CEBB4CBF2F894FC:434F5841DAD10CB6]:0)
at org.apache.lucene.util.LuceneTestCase.expectThrows(LuceneTestCase.java:2744)
at org.apache.lucene.util.LuceneTestCase.expectThrows(LuceneTestCase.java:2734)
at org.apache.solr.security.JWTIssuerConfigTest.jwksUrlwithHttpBehaviors(JWTIssuerConfigTest.java:145)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 13862 lines...]
[junit4] Suite: org.apache.solr.handler.TestRestoreCore
[junit4] 2> 1347667 INFO (SUITE-TestRestoreCore-seed#[2CEBB4CBF2F894FC]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/server/solr/configsets/_default/conf'
[junit4] 2> 1347667 INFO (SUITE-TestRestoreCore-seed#[2CEBB4CBF2F894FC]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/data-dir-39-001
[junit4] 2> 1347667 WARN (SUITE-TestRestoreCore-seed#[2CEBB4CBF2F894FC]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 1347667 INFO (SUITE-TestRestoreCore-seed#[2CEBB4CBF2F894FC]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1347669 INFO (SUITE-TestRestoreCore-seed#[2CEBB4CBF2F894FC]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
[junit4] 2> 1347669 INFO (SUITE-TestRestoreCore-seed#[2CEBB4CBF2F894FC]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1347673 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testFailedRestore
[junit4] 2> 1347673 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/solr-instance-001/collection1
[junit4] 2> 1347678 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1347678 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1347678 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_281-b09
[junit4] 2> 1347679 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1347679 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1347679 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1347679 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5bbde7d9{/solr,null,AVAILABLE}
[junit4] 2> 1347680 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3722b2b8{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:42535}
[junit4] 2> 1347680 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.s.Server Started @1347706ms
[junit4] 2> 1347680 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=42535}
[junit4] 2> 1347680 ERROR (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1347680 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1347680 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.9.0
[junit4] 2> 1347680 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1347680 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr
[junit4] 2> 1347680 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-05-04T18:35:51.682Z
[junit4] 2> 1347680 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/solr-instance-001/solr.xml
[junit4] 2> 1347682 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1347682 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1347684 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@40372bad, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1348436 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1348437 WARN (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4593b335[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1348437 WARN (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4593b335[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1348440 WARN (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4f912b25[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1348440 WARN (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4f912b25[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1348442 WARN (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] 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> 1348454 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1348454 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1348472 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@40372bad
[junit4] 2> 1348479 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@40372bad
[junit4] 2> 1348480 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@40372bad
[junit4] 2> 1348481 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/solr-instance-001
[junit4] 2> 1348481 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1348488 INFO (coreLoadExecutor-11397-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 1348490 INFO (coreLoadExecutor-11397-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 1348490 INFO (coreLoadExecutor-11397-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1348490 INFO (coreLoadExecutor-11397-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/solr-instance-001/collection1, trusted=true
[junit4] 2> 1348490 INFO (coreLoadExecutor-11397-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@40372bad
[junit4] 2> 1348491 INFO (coreLoadExecutor-11397-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/solr-instance-001/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/solr-instance-001/collection1/data/]
[junit4] 2> 1348493 INFO (coreLoadExecutor-11397-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=32, maxMergedSegmentMB=15.591796875, floorSegmentMB=1.70703125, forceMergeDeletesPctAllowed=20.365968356542236, segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=44.65678244880206
[junit4] 2> 1348549 INFO (coreLoadExecutor-11397-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1348549 INFO (coreLoadExecutor-11397-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1348551 INFO (coreLoadExecutor-11397-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=40, maxMergedSegmentMB=109.5361328125, floorSegmentMB=0.3193359375, forceMergeDeletesPctAllowed=24.672062562672696, segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2541922846164847, deletesPctAllowed=43.16166810581312
[junit4] 2> 1348551 INFO (coreLoadExecutor-11397-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/solr-instance-001/collection1/conf
[junit4] 2> 1348552 INFO (coreLoadExecutor-11397-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 1348552 INFO (coreLoadExecutor-11397-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1348553 INFO (searcherExecutor-11399-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1348556 INFO (qtp2125916903-15952) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1348556 INFO (qtp2125916903-15952) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0
[junit4] 2> 1348556 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.h.BackupRestoreUtils Indexing 83 test docs
[junit4] 2> 1348562 INFO (qtp2125916903-15953) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9, ... (83 adds)]} 0 4
[junit4] 2> 1348598 INFO (searcherExecutor-11399-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1348598 INFO (qtp2125916903-15954) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 35
[junit4] 2> 1348602 INFO (qtp2125916903-15951) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={name=kxqo&location=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/tempDir-001&wt=xml&command=backup} status=0 QTime=0
[junit4] 2> 1348602 INFO (Thread-2487) [ ] o.a.s.h.SnapShooter Creating backup snapshot kxqo at file:///home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/tempDir-001/
[junit4] 2> 1348603 INFO (qtp2125916903-15952) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 1348603 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.h.BackupStatusChecker Checking Status of kxqo: {responseHeader={status=0,QTime=0},status=OK,details={indexSize=16.1 KB,indexPath=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/solr-instance-001/collection1/data/index/,commits=[{indexVersion=1620153352564,generation=2,filelist=[_0.scf, _0.si, segments_2]}],isMaster=true,isSlave=false,indexVersion=1620153352564,generation=2,master={confFiles=schema.xml,xslt/dummy.xsl,replicateAfter=[commit],replicationEnabled=true,replicableVersion=1620153352564,replicableGeneration=2}}}
[junit4] 2> 1348604 ERROR (Thread-2487) [ ] o.a.s.h.SnapShooter Exception while creating snapshot
[junit4] 2> => org.apache.lucene.index.CorruptIndexException: codec footer mismatch (file truncated?): actual footer=808464432 vs expected footer=-1071082520 (resource=BufferedChecksumIndexInput(MockIndexInputWrapper(RAMInputStream(name=_0.si))))
[junit4] 2> at org.apache.lucene.codecs.CodecUtil.validateFooter(CodecUtil.java:523)
[junit4] 2> org.apache.lucene.index.CorruptIndexException: codec footer mismatch (file truncated?): actual footer=808464432 vs expected footer=-1071082520 (resource=BufferedChecksumIndexInput(MockIndexInputWrapper(RAMInputStream(name=_0.si))))
[junit4] 2> at org.apache.lucene.codecs.CodecUtil.validateFooter(CodecUtil.java:523) ~[java/:?]
[junit4] 2> at org.apache.lucene.codecs.CodecUtil.checkFooter(CodecUtil.java:414) ~[java/:?]
[junit4] 2> at org.apache.solr.core.backup.repository.BackupRepository.copyIndexFileFrom(BackupRepository.java:217) ~[java/:?]
[junit4] 2> at org.apache.solr.core.backup.repository.LocalFileSystemRepository.copyIndexFileFrom(LocalFileSystemRepository.java:165) ~[java/:?]
[junit4] 2> at org.apache.solr.core.backup.repository.BackupRepository.copyFileFrom(BackupRepository.java:179) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.SnapShooter.createSnapshot(SnapShooter.java:271) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.SnapShooter.createSnapshot(SnapShooter.java:158) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.SnapShooter.lambda$createSnapAsync$1(SnapShooter.java:226) ~[java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_281]
[junit4] 2> 1348654 INFO (qtp2125916903-15953) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 1348654 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.h.BackupStatusChecker Checking Status of kxqo: {responseHeader={status=0,QTime=0},status=OK,details={indexSize=16.1 KB,indexPath=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/solr-instance-001/collection1/data/index/,commits=[{indexVersion=1620153352564,generation=2,filelist=[_0.scf, _0.si, segments_2]}],isMaster=true,isSlave=false,indexVersion=1620153352564,generation=2,master={confFiles=schema.xml,xslt/dummy.xsl,replicateAfter=[commit],replicationEnabled=true,replicableVersion=1620153352564,replicableGeneration=2},backup={exception=codec footer mismatch (file truncated?): actual footer=808464432 vs expected footer=-1071082520 (resource=BufferedChecksumIndexInput(MockIndexInputWrapper(RAMInputStream(name=_0.si))))}}}
[junit4] 2> 1348655 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testFailedRestore
[junit4] 2> 1348657 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=392378683
[junit4] 2> 1348658 INFO (coreCloseExecutor-11405-thread-1) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@73f9b341
[junit4] 2> 1348658 INFO (coreCloseExecutor-11405-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1 tag=SolrCore@73f9b341
[junit4] 2> 1348658 INFO (coreCloseExecutor-11405-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3d61f756: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1/com.codahale.metrics.MetricRegistry@3b50b1db
[junit4] 2> 1348676 INFO (coreCloseExecutor-11405-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 1348678 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 1348678 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6e4fef5b: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@3fef0236
[junit4] 2> 1348684 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 1348684 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@20096f8d: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@5503c0fa
[junit4] 2> 1348686 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 1348686 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1b280be4: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@65864332
[junit4] 2> 1348688 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@3722b2b8{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1348688 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5bbde7d9{/solr,null,STOPPED}
[junit4] 2> 1348688 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestRestoreCore -Dtests.method=testFailedRestore -Dtests.seed=2CEBB4CBF2F894FC -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.badapples=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/test-data/enwiki.random.lines.txt -Dtests.locale=fr-CH -Dtests.timezone=Asia/Samarkand -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 1.02s J2 | TestRestoreCore.testFailedRestore <<<
[junit4] > Throwable #1: java.lang.AssertionError: Backup failure: kxqo expected null, but was:<codec footer mismatch (file truncated?): actual footer=808464432 vs expected footer=-1071082520 (resource=BufferedChecksumIndexInput(MockIndexInputWrapper(RAMInputStream(name=_0.si))))>
[junit4] > at __randomizedtesting.SeedInfo.seed([2CEBB4CBF2F894FC:A19D9C0FF3785EEC]:0)
[junit4] > at org.apache.solr.handler.BackupStatusChecker._checkBackupSuccess(BackupStatusChecker.java:208)
[junit4] > at org.apache.solr.handler.BackupStatusChecker.checkBackupSuccess(BackupStatusChecker.java:187)
[junit4] > at org.apache.solr.handler.BackupStatusChecker.waitForBackupSuccess(BackupStatusChecker.java:96)
[junit4] > at org.apache.solr.handler.BackupStatusChecker.waitForBackupSuccess(BackupStatusChecker.java:72)
[junit4] > at org.apache.solr.handler.TestRestoreCore.testFailedRestore(TestRestoreCore.java:208)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 1348694 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSimpleRestore
[junit4] 2> 1348694 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/solr-instance-002/collection1
[junit4] 2> 1348697 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1348697 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1348697 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_281-b09
[junit4] 2> 1348698 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1348698 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1348698 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1348701 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2a575e4e{/solr,null,AVAILABLE}
[junit4] 2> 1348701 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@27f334ec{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:33793}
[junit4] 2> 1348701 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.s.Server Started @1348728ms
[junit4] 2> 1348701 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=33793}
[junit4] 2> 1348701 ERROR (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1348701 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1348701 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.9.0
[junit4] 2> 1348701 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1348701 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr
[junit4] 2> 1348701 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-05-04T18:35:52.703Z
[junit4] 2> 1348701 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/solr-instance-002/solr.xml
[junit4] 2> 1348704 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1348704 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1348705 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@40372bad, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1349368 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1349369 WARN (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@34930e9a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1349369 WARN (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@34930e9a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1349371 WARN (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1155473f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1349371 WARN (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1155473f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1349372 WARN (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] 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> 1349383 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1349383 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1349402 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@40372bad
[junit4] 2> 1349412 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@40372bad
[junit4] 2> 1349413 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@40372bad
[junit4] 2> 1349414 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/solr-instance-002
[junit4] 2> 1349414 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1349423 INFO (coreLoadExecutor-11419-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 1349424 INFO (coreLoadExecutor-11419-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 1349424 INFO (coreLoadExecutor-11419-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1349424 INFO (coreLoadExecutor-11419-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/solr-instance-002/collection1, trusted=true
[junit4] 2> 1349424 INFO (coreLoadExecutor-11419-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@40372bad
[junit4] 2> 1349425 INFO (coreLoadExecutor-11419-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/solr-instance-002/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/solr-instance-002/collection1/data/]
[junit4] 2> 1349427 INFO (coreLoadExecutor-11419-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=32, maxMergedSegmentMB=15.591796875, floorSegmentMB=1.70703125, forceMergeDeletesPctAllowed=20.365968356542236, segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=44.65678244880206
[junit4] 2> 1349474 INFO (coreLoadExecutor-11419-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1349474 INFO (coreLoadExecutor-11419-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1349475 INFO (coreLoadExecutor-11419-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=40, maxMergedSegmentMB=109.5361328125, floorSegmentMB=0.3193359375, forceMergeDeletesPctAllowed=24.672062562672696, segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2541922846164847, deletesPctAllowed=43.16166810581312
[junit4] 2> 1349476 INFO (coreLoadExecutor-11419-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/solr-instance-002/collection1/conf
[junit4] 2> 1349476 INFO (coreLoadExecutor-11419-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 1349476 INFO (coreLoadExecutor-11419-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1349477 INFO (searcherExecutor-11421-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1349980 INFO (qtp411443140-15980) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1349981 INFO (qtp411443140-15980) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0
[junit4] 2> 1349981 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.h.BackupRestoreUtils Indexing 96 test docs
[junit4] 2> 1349983 INFO (qtp411443140-15981) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9, ... (96 adds)]} 0 2
[junit4] 2> 1349994 INFO (searcherExecutor-11421-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1349994 INFO (qtp411443140-15982) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 10
[junit4] 2> 1349995 INFO (qtp411443140-15983) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 1349996 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.h.BackupStatusChecker Checking Status of latest backup: {responseHeader={status=0,QTime=0},status=OK,details={indexSize=18.33 KB,indexPath=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/solr-instance-002/collection1/data/index/,commits=[{indexVersion=1620153353987,generation=2,filelist=[_0.scf, _0.si, segments_2]}],isMaster=true,isSlave=false,indexVersion=1620153353987,generation=2,master={confFiles=schema.xml,xslt/dummy.xsl,replicateAfter=[commit],replicationEnabled=true,replicableVersion=1620153353987,replicableGeneration=2}}}
[junit4] 2> 1349998 INFO (qtp411443140-15980) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=backup} status=0 QTime=0
[junit4] 2> 1349998 INFO (Thread-2497) [ ] o.a.s.h.SnapShooter Creating backup snapshot <not named> at file:///home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/solr-instance-002/collection1/data/
[junit4] 2> 1349998 INFO (qtp411443140-15981) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 1349998 ERROR (Thread-2497) [ ] o.a.s.h.SnapShooter Exception while creating snapshot
[junit4] 2> => org.apache.lucene.index.CorruptIndexException: codec footer mismatch (file truncated?): actual footer=808464432 vs expected footer=-1071082520 (resource=BufferedChecksumIndexInput(MockIndexInputWrapper(RAMInputStream(name=_0.si))))
[junit4] 2> at org.apache.lucene.codecs.CodecUtil.validateFooter(CodecUtil.java:523)
[junit4] 2> org.apache.lucene.index.CorruptIndexException: codec footer mismatch (file truncated?): actual footer=808464432 vs expected footer=-1071082520 (resource=BufferedChecksumIndexInput(MockIndexInputWrapper(RAMInputStream(name=_0.si))))
[junit4] 2> at org.apache.lucene.codecs.CodecUtil.validateFooter(CodecUtil.java:523) ~[java/:?]
[junit4] 2> at org.apache.lucene.codecs.CodecUtil.checkFooter(CodecUtil.java:414) ~[java/:?]
[junit4] 2> at org.apache.solr.core.backup.repository.BackupRepository.copyIndexFileFrom(BackupRepository.java:217) ~[java/:?]
[junit4] 2> at org.apache.solr.core.backup.repository.LocalFileSystemRepository.copyIndexFileFrom(LocalFileSystemRepository.java:165) ~[java/:?]
[junit4] 2> at org.apache.solr.core.backup.repository.BackupRepository.copyFileFrom(BackupRepository.java:179) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.SnapShooter.createSnapshot(SnapShooter.java:271) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.SnapShooter.createSnapshot(SnapShooter.java:158) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.SnapShooter.lambda$createSnapAsync$1(SnapShooter.java:226) ~[java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_281]
[junit4] 2> 1349999 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.h.BackupStatusChecker Checking Status of latest backup: {responseHeader={status=0,QTime=0},status=OK,details={indexSize=18.33 KB,indexPath=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/solr-instance-002/collection1/data/index/,commits=[{indexVersion=1620153353987,generation=2,filelist=[_0.scf, _0.si, segments_2]}],isMaster=true,isSlave=false,indexVersion=1620153353987,generation=2,master={confFiles=schema.xml,xslt/dummy.xsl,replicateAfter=[commit],replicationEnabled=true,replicableVersion=1620153353987,replicableGeneration=2}}}
[junit4] 2> 1350049 INFO (qtp411443140-15982) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 1350050 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.h.BackupStatusChecker Checking Status of latest backup: {responseHeader={status=0,QTime=0},status=OK,details={indexSize=18.33 KB,indexPath=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/solr-instance-002/collection1/data/index/,commits=[{indexVersion=1620153353987,generation=2,filelist=[_0.scf, _0.si, segments_2]}],isMaster=true,isSlave=false,indexVersion=1620153353987,generation=2,master={confFiles=schema.xml,xslt/dummy.xsl,replicateAfter=[commit],replicationEnabled=true,replicableVersion=1620153353987,replicableGeneration=2},backup={exception=codec footer mismatch (file truncated?): actual footer=808464432 vs expected footer=-1071082520 (resource=BufferedChecksumIndexInput(MockIndexInputWrapper(RAMInputStream(name=_0.si))))}}}
[junit4] 2> 1350050 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSimpleRestore
[junit4] 2> 1350052 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1251720302
[junit4] 2> 1350053 INFO (coreCloseExecutor-11427-thread-1) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@7d972e7
[junit4] 2> 1350053 INFO (coreCloseExecutor-11427-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1 tag=SolrCore@7d972e7
[junit4] 2> 1350053 INFO (coreCloseExecutor-11427-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3b6abe89: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1/com.codahale.metrics.MetricRegistry@566c3f71
[junit4] 2> 1350064 INFO (coreCloseExecutor-11427-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 1350066 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 1350066 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5e49e753: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@4cbc3e7f
[junit4] 2> 1350071 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 1350071 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7856e0c5: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@5503c0fa
[junit4] 2> 1350074 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 1350074 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@13a1c8af: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@65864332
[junit4] 2> 1350075 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@27f334ec{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1350076 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2a575e4e{/solr,null,STOPPED}
[junit4] 2> 1350076 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestRestoreCore -Dtests.method=testSimpleRestore -Dtests.seed=2CEBB4CBF2F894FC -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.badapples=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/test-data/enwiki.random.lines.txt -Dtests.locale=fr-CH -Dtests.timezone=Asia/Samarkand -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 1.39s J2 | TestRestoreCore.testSimpleRestore <<<
[junit4] > Throwable #1: java.lang.AssertionError: Backup failure: latest backup expected null, but was:<codec footer mismatch (file truncated?): actual footer=808464432 vs expected footer=-1071082520 (resource=BufferedChecksumIndexInput(MockIndexInputWrapper(RAMInputStream(name=_0.si))))>
[junit4] > at __randomizedtesting.SeedInfo.seed([2CEBB4CBF2F894FC:B7D18EE3526C6741]:0)
[junit4] > at org.apache.solr.handler.BackupStatusChecker._checkBackupSuccess(BackupStatusChecker.java:208)
[junit4] > at org.apache.solr.handler.BackupStatusChecker.checkBackupSuccess(BackupStatusChecker.java:172)
[junit4] > at org.apache.solr.handler.BackupStatusChecker.waitForDifferentBackupDir(BackupStatusChecker.java:141)
[junit4] > at org.apache.solr.handler.BackupStatusChecker.waitForDifferentBackupDir(BackupStatusChecker.java:117)
[junit4] > at org.apache.solr.handler.TestRestoreCore.testSimpleRestore(TestRestoreCore.java:141)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 1350082 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBackupFailsMissingAllowPaths
[junit4] 2> 1350083 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/solr-instance-003/collection1
[junit4] 2> 1350086 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1350086 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1350086 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_281-b09
[junit4] 2> 1350087 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1350087 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1350087 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1350088 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@294f029d{/solr,null,AVAILABLE}
[junit4] 2> 1350088 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@da8cb1e{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:46297}
[junit4] 2> 1350088 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.s.Server Started @1350115ms
[junit4] 2> 1350088 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/solr-instance-003/collection1/data, hostContext=/solr, hostPort=46297}
[junit4] 2> 1350088 ERROR (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1350088 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1350088 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.9.0
[junit4] 2> 1350088 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1350088 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr
[junit4] 2> 1350088 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-05-04T18:35:54.090Z
[junit4] 2> 1350088 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/solr-instance-003/solr.xml
[junit4] 2> 1350091 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1350091 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1350092 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@40372bad, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1350458 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1350459 WARN (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@444e375b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1350459 WARN (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@444e375b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1350462 WARN (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@35f28665[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1350462 WARN (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@35f28665[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1350463 WARN (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] 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> 1350474 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1350474 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1350493 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@40372bad
[junit4] 2> 1350501 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@40372bad
[junit4] 2> 1350501 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@40372bad
[junit4] 2> 1350502 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/solr-instance-003
[junit4] 2> 1350502 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1350510 INFO (coreLoadExecutor-11441-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 1350512 INFO (coreLoadExecutor-11441-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 1350512 INFO (coreLoadExecutor-11441-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1350512 INFO (coreLoadExecutor-11441-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/solr-instance-003/collection1, trusted=true
[junit4] 2> 1350512 INFO (coreLoadExecutor-11441-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@40372bad
[junit4] 2> 1350512 INFO (coreLoadExecutor-11441-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/solr-instance-003/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/solr-instance-003/collection1/data/]
[junit4] 2> 1350514 INFO (coreLoadExecutor-11441-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=32, maxMergedSegmentMB=15.591796875, floorSegmentMB=1.70703125, forceMergeDeletesPctAllowed=20.365968356542236, segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=44.65678244880206
[junit4] 2> 1350552 INFO (coreLoadExecutor-11441-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1350552 INFO (coreLoadExecutor-11441-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1350553 INFO (coreLoadExecutor-11441-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=40, maxMergedSegmentMB=109.5361328125, floorSegmentMB=0.3193359375, forceMergeDeletesPctAllowed=24.672062562672696, segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2541922846164847, deletesPctAllowed=43.16166810581312
[junit4] 2> 1350554 INFO (coreLoadExecutor-11441-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/solr-instance-003/collection1/conf
[junit4] 2> 1350554 INFO (coreLoadExecutor-11441-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 1350554 INFO (coreLoadExecutor-11441-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1350555 INFO (searcherExecutor-11443-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1350558 ERROR (qtp930860703-16007) [ x:collection1 ] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: Path /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/tempDir-002 must be relative to SOLR_HOME, SOLR_DATA_HOME coreRootDirectory. Set system property 'solr.allowPaths' to add other allowed paths.
[junit4] 2> at org.apache.solr.core.SolrPaths.assertPathAllowed(SolrPaths.java:139)
[junit4] 2> at org.apache.solr.core.CoreContainer.assertPathAllowed(CoreContainer.java:1374)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.doSnapShoot(ReplicationHandler.java:614)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:280)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:216)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2637)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:794)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:567)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:427)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:357)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:548)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1612)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1434)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:501)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1582)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1349)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:766)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:516)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:383)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:556)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:273)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105)
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:773)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:905)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 1350559 INFO (qtp930860703-16007) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={location=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001/tempDir-002&wt=xml&command=backup} status=400 QTime=1
[junit4] 2> 1350559 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testBackupFailsMissingAllowPaths
[junit4] 2> 1350571 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2125106524
[junit4] 2> 1350572 INFO (coreCloseExecutor-11449-thread-1) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@566ef07
[junit4] 2> 1350572 INFO (coreCloseExecutor-11449-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1 tag=SolrCore@566ef07
[junit4] 2> 1350572 INFO (coreCloseExecutor-11449-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4779d4bc: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1/com.codahale.metrics.MetricRegistry@655bd3f8
[junit4] 2> 1350582 INFO (coreCloseExecutor-11449-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 1350583 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 1350583 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@116582b2: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@4e7784c6
[junit4] 2> 1350589 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 1350589 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@13e0ef1d: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@5503c0fa
[junit4] 2> 1350591 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 1350591 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@51579de6: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@65864332
[junit4] 2> 1350592 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@da8cb1e{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1350593 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@294f029d{/solr,null,STOPPED}
[junit4] 2> 1350593 INFO (TEST-TestRestoreCore.testBackupFailsMissingAllowPaths-seed#[2CEBB4CBF2F894FC]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.handler.TestRestoreCore_2CEBB4CBF2F894FC-001
[junit4] 2> NOTE: test params are: codec=SimpleText, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=fr-CH, timezone=Asia/Samarkand
[junit4] 2> NOTE: Linux 4.15.0-142-generic amd64/Oracle Corporation 1.8.0_281 (64-bit)/cpus=4,threads=1,free=262011904,total=506986496
[junit4] 2> NOTE: All tests run in this JVM: [TestTlogReplayVsRecovery, TestCloudJSONFacetSKGEquiv, ClusterStateUpdateTest, TestReplicationHandler, TaggingAttributeTest, CacheHeaderTest, TestCloudRecovery, DistributedUpdateProcessorTest, LoggingHandlerTest, SolrCoreMetricManagerTest, PhrasesIdentificationComponentTest, SOLR749Test, SolrCloudExampleTest, EmbeddedSolrNoSerializeTest, TestConfigSetsAPI, TlogReplayBufferedWhileIndexingTest, AuditLoggerPluginTest, TestOnReconnectListenerSupport, XsltUpdateRequestHandlerTest, AlternateDirectoryTest, PeerSyncReplicationTest, QueryParsingTest, TestSolrJacksonAnnotation, TestJsonFacetsStatsParsing, TestLegacyBM25SimilarityFactory, HdfsDirectoryTest, DynamicMapsTest, LeaderVoteWaitTimeoutTest, TestSweetSpotSimilarityFactory, TestDFRSimilarityFactory, DeleteInactiveReplicaTest, TestSearcherReuse, SuggestComponentContextFilterQueryTest, TestQueryingOnDownCollection, TestLogWatcher, CdcrWithNodesRestartsTest, TestCollectionsAPIViaSolrCloudCluster, EchoParamsTest, TestRestoreCore]
[junit4] Completed [219/937 (1!)] on J2 in 3.01s, 3 tests, 2 failures <<< FAILURES!
[...truncated 2139 lines...]
[junit4] Suite: org.apache.solr.security.JWTIssuerConfigTest
[junit4] 2> 4927891 INFO (SUITE-JWTIssuerConfigTest-seed#[2CEBB4CBF2F894FC]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/checkout/solr/server/solr/configsets/_default/conf'
[junit4] 2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=JWTIssuerConfigTest -Dtests.method=jwksUrlwithHttpBehaviors -Dtests.seed=2CEBB4CBF2F894FC -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.badapples=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-NightlyTests-8.x/test-data/enwiki.random.lines.txt -Dtests.locale=mt-MT -Dtests.timezone=Greenwich -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 0.01s J1 | JWTIssuerConfigTest.jwksUrlwithHttpBehaviors <<<
[junit4] > Throwable #1: junit.framework.AssertionFailedError: Expected exception SolrException but no exception was thrown
[junit4] > at __randomizedtesting.SeedInfo.seed([2CEBB4CBF2F894FC:434F5841DAD10CB6]:0)
[junit4] > at org.apache.lucene.util.LuceneTestCase.expectThrows(LuceneTestCase.java:2744)
[junit4] > at org.apache.lucene.util.LuceneTestCase.expectThrows(LuceneTestCase.java:2734)
[junit4] > at org.apache.solr.security.JWTIssuerConfigTest.jwksUrlwithHttpBehaviors(JWTIssuerConfigTest.java:145)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene87): {}, docValues:{}, maxPointsInLeafNode=1211, maxMBSortInHeap=6.766690081875632, sim=Asserting(RandomSimilarity(queryNorm=true): {}), locale=mt-MT, timezone=Greenwich
[junit4] 2> NOTE: Linux 4.15.0-142-generic amd64/Oracle Corporation 1.8.0_281 (64-bit)/cpus=4,threads=1,free=320038016,total=521666560
[junit4] 2> NOTE: All tests run in this JVM: [TestStressLiveNodes, TestCloudJSONFacetSKG, ClusterStateTest, TestIncrementalCoreBackup, TaggerTest, TestZkAclsWithHadoopAuth, TestCloudPseudoReturnFields, DimensionalRoutedAliasUpdateProcessorTest, InfoHandlerTest, MetricsDisabledCloudTest, MoreLikeThisComponentTest, ResourceLoaderTest, SolrCLIZkUtilsTest, JavabinLoaderTest, TestClusterProperties, TestZkChroot, AuditLoggerIntegrationTest, TestMiniSolrCloudClusterSSL, XmlUpdateRequestHandlerTest, ZkDynamicConfigTest, PackageManagerCLITest, QueryEqualityTest, TestSolrCLIRunExample, TestJsonFacets, TestLMJelinekMercerSimilarityFactory, BufferStoreTest, DistanceUnitsTest, LeaderTragicEventTest, TestPerFieldSimilarityWithDefaultOverride, TestDFISimilarityFactory, CreateRoutedAliasTest, TestSearchPerf, StatsComponentTest, TestPullReplicaErrorHandling, TestNumericTokenStream, CdcrVersionReplicationTest, TestCollectionAPI, DistributedIntervalFacetingTest, TestReqParamsAPI, VMParamsZkACLAndCredentialsProvidersTest, TestNumericTerms32, TestSolrCoreProperties, TestSimUtils, LeaderElectionIntegrationTest, FullHLLTest, BasicFunctionalityTest, PingRequestHandlerTest, ResponseLogComponentTest, TestDistributedMap, TestHttpShardHandlerFactory, TestSchemaVersionResource, AnalyticsMergeStrategyTest, HttpPartitionOnCommitTest, TestCustomSort, AggValueSourceTest, TermVectorComponentTest, TestLegacyTerms, TestLazyCores, TestCrossCoreJoin, TestRandomCollapseQParserPlugin, DistribDocExpirationUpdateProcessorTest, DistributedFacetSimpleRefinementLongTailTest, BasicAuthStandaloneTest, BackupIdTest, CollectionsAPIAsyncDistributedZkTest, ZkStateWriterTest, OpenExchangeRatesOrgProviderTest, DocValuesNotIndexedTest, ByteBuffersDirectoryFactoryTest, UpdateLogTest, DistributedSuggestComponentTest, TestSimComputePlanAction, TestChildDocTransformer, PeerSyncWithIndexFingerprintCachingTest, RoutingToNodesWithPropertiesTest, TestBlendedInfixSuggestions, SolrPluginUtilsTest, TestStressUserVersions, TestSimpleTextCodec, CoreAdminCreateDiscoverTest, TestCollapseQParserPlugin, TestAuthorizationFramework, ClassificationUpdateProcessorIntegrationTest, TestManagedSynonymFilterFactory, TestPostingsSolrHighlighter, TestReversedWildcardFilterFactory, ReplicationFactorTest, TestConfigSets, NestedShardedAtomicUpdateTest, JWTVerificationkeyResolverTest, UninvertDocValuesMergePolicyTest, PurgeGraphTest, ConjunctionSolrSpellCheckerTest, UUIDUpdateProcessorFallbackTest, SearchRateTriggerTest, TestHashPartitioner, DirectoryFactoriesTest, TestShortCircuitedRequests, PluginInfoTest, TestDocTermOrdsUninvertLimit, SparseHLLTest, ScriptEngineTest, TestBackupRepositoryFactory, OverseerCollectionConfigSetProcessorTest, OrderedExecutorTest, TestConfig, SolrSlf4jReporterTest, SolrMetricsIntegrationTest, TestRawResponseWriter, TestSolrCoreSnapshots, TestDynamicFieldResource, DistributedFacetPivotSmallAdvancedTest, TestSchemaField, TestTrieFacet, CollectionsAPISolrJTest, TestCloudManagedSchema, TestManagedSchemaThreadSafety, TestOmitPositions, TestSortableTextField, TestTextField, TestUseDocValuesAsStored, TestUseDocValuesAsStored2, LargeFieldTest, MaxScoreCollectorTest, MergeStrategyTest, TestFilteredDocIdSet, TestFoldingMultitermQuery, TestHashQParserPlugin, TestInitQParser, TestLRUCache, TestLegacyNumericRangeQueryBuilder, TestMaxScoreQueryParser, TestMinHashQParser, TestNoOpRegenerator, TestPayloadCheckQParserPlugin, TestPseudoReturnFields, TestQueryUtils, TestRTGBase, TestRealTimeGet, TestReload, TestReloadDeadlock, TestSolr4Spatial, TestSolrFieldCacheBean, TestSolrJ, TestSolrQueryParser, TestSort, TestXmlQParserPlugin, TestJsonFacetRefinement, TestOrdValues, TestSortByMinMaxFunction, DistanceFunctionTest, BlockJoinFacetRandomTest, BlockJoinFacetSimpleTest, GraphQueryTest, TestCloudNestedDocsSort, TestBooleanSimilarityFactory, TestPerFieldSimilarity, TestExactSharedStatsCache, JWTIssuerConfigTest]
[junit4] Completed [844/937 (2!)] on J1 in 0.25s, 13 tests, 1 failure <<< FAILURES!
[...truncated 51141 lines...]