You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2018/07/02 03:21:59 UTC
[JENKINS] Lucene-Solr-7.x-MacOSX (64bit/jdk1.8.0) - Build # 721 -
Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-MacOSX/721/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseG1GC
5 tests failed.
FAILED: org.apache.solr.cloud.api.collections.TestLocalFSCloudBackupRestore.test
Error Message:
expected:<COMPLETED> but was:<FAILED>
Stack Trace:
java.lang.AssertionError: expected:<COMPLETED> but was:<FAILED>
at __randomizedtesting.SeedInfo.seed([7E594F1269001D53:F60D70C8C7FC70AB]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:147)
at org.apache.solr.cloud.api.collections.AbstractCloudBackupRestoreTestCase.testBackupAndRestore(AbstractCloudBackupRestoreTestCase.java:327)
at org.apache.solr.cloud.api.collections.AbstractCloudBackupRestoreTestCase.test(AbstractCloudBackupRestoreTestCase.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:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.cloud.api.collections.TestLocalFSCloudBackupRestore.test
Error Message:
expected:<COMPLETED> but was:<FAILED>
Stack Trace:
java.lang.AssertionError: expected:<COMPLETED> but was:<FAILED>
at __randomizedtesting.SeedInfo.seed([7E594F1269001D53:F60D70C8C7FC70AB]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:147)
at org.apache.solr.cloud.api.collections.AbstractCloudBackupRestoreTestCase.testBackupAndRestore(AbstractCloudBackupRestoreTestCase.java:327)
at org.apache.solr.cloud.api.collections.AbstractCloudBackupRestoreTestCase.test(AbstractCloudBackupRestoreTestCase.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:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.cloud.api.collections.TestLocalFSCloudBackupRestore.test
Error Message:
expected:<COMPLETED> but was:<FAILED>
Stack Trace:
java.lang.AssertionError: expected:<COMPLETED> but was:<FAILED>
at __randomizedtesting.SeedInfo.seed([7E594F1269001D53:F60D70C8C7FC70AB]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:147)
at org.apache.solr.cloud.api.collections.AbstractCloudBackupRestoreTestCase.testBackupAndRestore(AbstractCloudBackupRestoreTestCase.java:327)
at org.apache.solr.cloud.api.collections.AbstractCloudBackupRestoreTestCase.test(AbstractCloudBackupRestoreTestCase.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:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.cloud.api.collections.TestLocalFSCloudBackupRestore.test
Error Message:
expected:<COMPLETED> but was:<FAILED>
Stack Trace:
java.lang.AssertionError: expected:<COMPLETED> but was:<FAILED>
at __randomizedtesting.SeedInfo.seed([7E594F1269001D53:F60D70C8C7FC70AB]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:147)
at org.apache.solr.cloud.api.collections.AbstractCloudBackupRestoreTestCase.testBackupAndRestore(AbstractCloudBackupRestoreTestCase.java:327)
at org.apache.solr.cloud.api.collections.AbstractCloudBackupRestoreTestCase.test(AbstractCloudBackupRestoreTestCase.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:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.cloud.api.collections.TestLocalFSCloudBackupRestore.test
Error Message:
expected:<COMPLETED> but was:<FAILED>
Stack Trace:
java.lang.AssertionError: expected:<COMPLETED> but was:<FAILED>
at __randomizedtesting.SeedInfo.seed([7E594F1269001D53:F60D70C8C7FC70AB]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:147)
at org.apache.solr.cloud.api.collections.AbstractCloudBackupRestoreTestCase.testBackupAndRestore(AbstractCloudBackupRestoreTestCase.java:327)
at org.apache.solr.cloud.api.collections.AbstractCloudBackupRestoreTestCase.test(AbstractCloudBackupRestoreTestCase.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:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 14227 lines...]
[junit4] Suite: org.apache.solr.cloud.api.collections.TestLocalFSCloudBackupRestore
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.TestLocalFSCloudBackupRestore_7E594F1269001D53-001/init-core-data-001
[junit4] 2> 2517947 WARN (SUITE-TestLocalFSCloudBackupRestore-seed#[7E594F1269001D53]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=11 numCloses=11
[junit4] 2> 2517948 INFO (SUITE-TestLocalFSCloudBackupRestore-seed#[7E594F1269001D53]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 2517952 INFO (SUITE-TestLocalFSCloudBackupRestore-seed#[7E594F1269001D53]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 2517952 INFO (SUITE-TestLocalFSCloudBackupRestore-seed#[7E594F1269001D53]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 2517952 INFO (SUITE-TestLocalFSCloudBackupRestore-seed#[7E594F1269001D53]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.TestLocalFSCloudBackupRestore_7E594F1269001D53-001/tempDir-001
[junit4] 2> 2517953 INFO (SUITE-TestLocalFSCloudBackupRestore-seed#[7E594F1269001D53]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2517953 INFO (Thread-4723) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2517953 INFO (Thread-4723) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2517956 ERROR (Thread-4723) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 2518063 INFO (SUITE-TestLocalFSCloudBackupRestore-seed#[7E594F1269001D53]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:57501
[junit4] 2> 2518073 INFO (zkConnectionManagerCallback-10687-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2518079 INFO (jetty-launcher-10684-thread-1) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 2518079 INFO (jetty-launcher-10684-thread-2) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 2518081 INFO (jetty-launcher-10684-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2518081 INFO (jetty-launcher-10684-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2518081 INFO (jetty-launcher-10684-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2518081 INFO (jetty-launcher-10684-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1fa3b742{/solr,null,AVAILABLE}
[junit4] 2> 2518082 INFO (jetty-launcher-10684-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@25a878e{HTTP/1.1,[http/1.1]}{127.0.0.1:57503}
[junit4] 2> 2518082 INFO (jetty-launcher-10684-thread-1) [ ] o.e.j.s.Server Started @2518164ms
[junit4] 2> 2518082 INFO (jetty-launcher-10684-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2518082 INFO (jetty-launcher-10684-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=57503}
[junit4] 2> 2518082 INFO (jetty-launcher-10684-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2518082 INFO (jetty-launcher-10684-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2518082 ERROR (jetty-launcher-10684-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2518082 INFO (jetty-launcher-10684-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2518082 INFO (jetty-launcher-10684-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.5.0
[junit4] 2> 2518082 INFO (jetty-launcher-10684-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2518082 INFO (jetty-launcher-10684-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2518082 INFO (jetty-launcher-10684-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-07-02T02:39:51.043Z
[junit4] 2> 2518082 INFO (jetty-launcher-10684-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6452cd0b{/solr,null,AVAILABLE}
[junit4] 2> 2518083 INFO (jetty-launcher-10684-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2b844672{HTTP/1.1,[http/1.1]}{127.0.0.1:57504}
[junit4] 2> 2518083 INFO (jetty-launcher-10684-thread-2) [ ] o.e.j.s.Server Started @2518165ms
[junit4] 2> 2518083 INFO (jetty-launcher-10684-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=57504}
[junit4] 2> 2518083 ERROR (jetty-launcher-10684-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2518083 INFO (jetty-launcher-10684-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2518083 INFO (jetty-launcher-10684-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.5.0
[junit4] 2> 2518083 INFO (jetty-launcher-10684-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2518083 INFO (jetty-launcher-10684-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2518083 INFO (jetty-launcher-10684-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-07-02T02:39:51.044Z
[junit4] 2> 2518085 INFO (zkConnectionManagerCallback-10689-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2518085 INFO (zkConnectionManagerCallback-10691-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2518085 INFO (jetty-launcher-10684-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2518085 INFO (jetty-launcher-10684-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2518246 INFO (jetty-launcher-10684-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57501/solr
[junit4] 2> 2518248 INFO (zkConnectionManagerCallback-10695-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2518251 INFO (jetty-launcher-10684-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57501/solr
[junit4] 2> 2518251 INFO (zkConnectionManagerCallback-10698-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2518252 INFO (zkConnectionManagerCallback-10701-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2518255 INFO (zkConnectionManagerCallback-10703-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2518443 INFO (jetty-launcher-10684-thread-1) [n:127.0.0.1:57503_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2518443 INFO (jetty-launcher-10684-thread-2) [n:127.0.0.1:57504_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2518444 INFO (jetty-launcher-10684-thread-1) [n:127.0.0.1:57503_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:57503_solr
[junit4] 2> 2518445 INFO (jetty-launcher-10684-thread-2) [n:127.0.0.1:57504_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57504_solr
[junit4] 2> 2518446 INFO (jetty-launcher-10684-thread-1) [n:127.0.0.1:57503_solr ] o.a.s.c.Overseer Overseer (id=72298513665032196-127.0.0.1:57503_solr-n_0000000000) starting
[junit4] 2> 2518446 INFO (zkCallback-10702-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2518447 INFO (zkCallback-10697-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2518458 INFO (zkConnectionManagerCallback-10712-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2518460 INFO (jetty-launcher-10684-thread-1) [n:127.0.0.1:57503_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2518461 INFO (jetty-launcher-10684-thread-1) [n:127.0.0.1:57503_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57501/solr ready
[junit4] 2> 2518468 INFO (jetty-launcher-10684-thread-1) [n:127.0.0.1:57503_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2518468 INFO (jetty-launcher-10684-thread-1) [n:127.0.0.1:57503_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57503_solr
[junit4] 2> 2518470 INFO (zkCallback-10711-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2518470 INFO (zkCallback-10702-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2518471 INFO (zkCallback-10697-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2518499 INFO (jetty-launcher-10684-thread-1) [n:127.0.0.1:57503_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2518502 INFO (zkConnectionManagerCallback-10718-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2518505 INFO (jetty-launcher-10684-thread-2) [n:127.0.0.1:57504_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2518506 INFO (jetty-launcher-10684-thread-2) [n:127.0.0.1:57504_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57501/solr ready
[junit4] 2> 2518507 INFO (jetty-launcher-10684-thread-2) [n:127.0.0.1:57504_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2518550 INFO (jetty-launcher-10684-thread-2) [n:127.0.0.1:57504_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57504.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3cd19133
[junit4] 2> 2518550 INFO (jetty-launcher-10684-thread-1) [n:127.0.0.1:57503_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57503.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3cd19133
[junit4] 2> 2518557 INFO (jetty-launcher-10684-thread-2) [n:127.0.0.1:57504_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57504.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3cd19133
[junit4] 2> 2518557 INFO (jetty-launcher-10684-thread-2) [n:127.0.0.1:57504_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57504.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3cd19133
[junit4] 2> 2518557 INFO (jetty-launcher-10684-thread-1) [n:127.0.0.1:57503_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57503.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3cd19133
[junit4] 2> 2518558 INFO (jetty-launcher-10684-thread-1) [n:127.0.0.1:57503_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57503.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3cd19133
[junit4] 2> 2518558 INFO (jetty-launcher-10684-thread-2) [n:127.0.0.1:57504_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.TestLocalFSCloudBackupRestore_7E594F1269001D53-001/tempDir-001/node2/.
[junit4] 2> 2518558 INFO (jetty-launcher-10684-thread-1) [n:127.0.0.1:57503_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.TestLocalFSCloudBackupRestore_7E594F1269001D53-001/tempDir-001/node1/.
[junit4] 2> 2518596 INFO (zkConnectionManagerCallback-10721-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2518601 INFO (zkConnectionManagerCallback-10726-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2518603 INFO (SUITE-TestLocalFSCloudBackupRestore-seed#[7E594F1269001D53]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2518605 INFO (SUITE-TestLocalFSCloudBackupRestore-seed#[7E594F1269001D53]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57501/solr ready
[junit4] 2> 2518631 INFO (TEST-TestLocalFSCloudBackupRestore.test-seed#[7E594F1269001D53]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 2518632 INFO (qtp1298319516-31480) [n:127.0.0.1:57503_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=1&property.customKey=customValue&collection.configName=conf1&maxShardsPerNode=3&autoAddReplicas=true&name=backuprestore&nrtReplicas=2&action=CREATE&numShards=2&tlogReplicas=0&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2518635 INFO (OverseerThreadFactory-11436-thread-1) [ ] o.a.s.c.a.c.CreateCollectionCmd Create collection backuprestore
[junit4] 2> 2518636 WARN (OverseerThreadFactory-11436-thread-1) [ ] o.a.s.c.a.c.CreateCollectionCmd Specified number of replicas of 3 on collection backuprestore is higher than the number of Solr instances currently live or live and part of your createNodeSet(2). It's unusual to run two replica of the same slice on the same Solr-instance.
[junit4] 2> 2518758 INFO (OverseerStateUpdate-72298513665032196-127.0.0.1:57503_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"backuprestore",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"backuprestore_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:57503/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2518762 INFO (OverseerStateUpdate-72298513665032196-127.0.0.1:57503_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"backuprestore",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"backuprestore_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:57504/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2518767 INFO (OverseerStateUpdate-72298513665032196-127.0.0.1:57503_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"backuprestore",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"backuprestore_shard1_replica_p4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:57503/solr",
[junit4] 2> "type":"PULL",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2518771 INFO (OverseerStateUpdate-72298513665032196-127.0.0.1:57503_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"backuprestore",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"backuprestore_shard2_replica_n6",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:57504/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2518774 INFO (OverseerStateUpdate-72298513665032196-127.0.0.1:57503_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"backuprestore",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"backuprestore_shard2_replica_n8",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:57503/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2518778 INFO (OverseerStateUpdate-72298513665032196-127.0.0.1:57503_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"backuprestore",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"backuprestore_shard2_replica_p10",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:57504/solr",
[junit4] 2> "type":"PULL",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2518994 INFO (qtp1298319516-31475) [n:127.0.0.1:57503_solr x:backuprestore_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&collection=backuprestore&version=2&replicaType=NRT&property.customKey=customValue&coreNodeName=core_node3&name=backuprestore_shard1_replica_n1&action=CREATE&numShards=2&shard=shard1&wt=javabin
[junit4] 2> 2518994 INFO (qtp1994259177-31488) [n:127.0.0.1:57504_solr x:backuprestore_shard2_replica_n6] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&collection=backuprestore&version=2&replicaType=NRT&property.customKey=customValue&coreNodeName=core_node9&name=backuprestore_shard2_replica_n6&action=CREATE&numShards=2&shard=shard2&wt=javabin
[junit4] 2> 2518995 INFO (qtp1298319516-31478) [n:127.0.0.1:57503_solr x:backuprestore_shard1_replica_p4] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&collection=backuprestore&version=2&replicaType=PULL&property.customKey=customValue&coreNodeName=core_node7&name=backuprestore_shard1_replica_p4&action=CREATE&numShards=2&shard=shard1&wt=javabin
[junit4] 2> 2518995 INFO (qtp1994259177-31488) [n:127.0.0.1:57504_solr x:backuprestore_shard2_replica_n6] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2518995 INFO (qtp1994259177-31490) [n:127.0.0.1:57504_solr x:backuprestore_shard1_replica_n2] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&collection=backuprestore&version=2&replicaType=NRT&property.customKey=customValue&coreNodeName=core_node5&name=backuprestore_shard1_replica_n2&action=CREATE&numShards=2&shard=shard1&wt=javabin
[junit4] 2> 2518998 INFO (qtp1298319516-31484) [n:127.0.0.1:57503_solr x:backuprestore_shard2_replica_n8] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&collection=backuprestore&version=2&replicaType=NRT&property.customKey=customValue&coreNodeName=core_node11&name=backuprestore_shard2_replica_n8&action=CREATE&numShards=2&shard=shard2&wt=javabin
[junit4] 2> 2519002 INFO (qtp1994259177-31483) [n:127.0.0.1:57504_solr x:backuprestore_shard2_replica_p10] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&collection=backuprestore&version=2&replicaType=PULL&property.customKey=customValue&coreNodeName=core_node12&name=backuprestore_shard2_replica_p10&action=CREATE&numShards=2&shard=shard2&wt=javabin
[junit4] 2> 2520065 INFO (qtp1298319516-31484) [n:127.0.0.1:57503_solr c:backuprestore s:shard2 r:core_node11 x:backuprestore_shard2_replica_n8] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 2520066 INFO (qtp1298319516-31478) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node7 x:backuprestore_shard1_replica_p4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 2520067 INFO (qtp1994259177-31483) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node12 x:backuprestore_shard2_replica_p10] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 2520073 INFO (qtp1298319516-31475) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node3 x:backuprestore_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 2520076 INFO (qtp1994259177-31488) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node9 x:backuprestore_shard2_replica_n6] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 2520078 INFO (qtp1994259177-31490) [n:127.0.0.1:57504_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 2520100 INFO (qtp1994259177-31483) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node12 x:backuprestore_shard2_replica_p10] o.a.s.s.IndexSchema [backuprestore_shard2_replica_p10] Schema name=minimal
[junit4] 2> 2520100 INFO (qtp1298319516-31484) [n:127.0.0.1:57503_solr c:backuprestore s:shard2 r:core_node11 x:backuprestore_shard2_replica_n8] o.a.s.s.IndexSchema [backuprestore_shard2_replica_n8] Schema name=minimal
[junit4] 2> 2520100 INFO (qtp1298319516-31478) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node7 x:backuprestore_shard1_replica_p4] o.a.s.s.IndexSchema [backuprestore_shard1_replica_p4] Schema name=minimal
[junit4] 2> 2520104 INFO (qtp1298319516-31478) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node7 x:backuprestore_shard1_replica_p4] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2520104 INFO (qtp1298319516-31484) [n:127.0.0.1:57503_solr c:backuprestore s:shard2 r:core_node11 x:backuprestore_shard2_replica_n8] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2520104 INFO (qtp1298319516-31478) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node7 x:backuprestore_shard1_replica_p4] o.a.s.c.CoreContainer Creating SolrCore 'backuprestore_shard1_replica_p4' using configuration from collection backuprestore, trusted=true
[junit4] 2> 2520104 INFO (qtp1994259177-31483) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node12 x:backuprestore_shard2_replica_p10] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2520104 INFO (qtp1298319516-31484) [n:127.0.0.1:57503_solr c:backuprestore s:shard2 r:core_node11 x:backuprestore_shard2_replica_n8] o.a.s.c.CoreContainer Creating SolrCore 'backuprestore_shard2_replica_n8' using configuration from collection backuprestore, trusted=true
[junit4] 2> 2520104 INFO (qtp1994259177-31483) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node12 x:backuprestore_shard2_replica_p10] o.a.s.c.CoreContainer Creating SolrCore 'backuprestore_shard2_replica_p10' using configuration from collection backuprestore, trusted=true
[junit4] 2> 2520104 INFO (qtp1994259177-31483) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node12 x:backuprestore_shard2_replica_p10] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57504.solr.core.backuprestore.shard2.replica_p10' (registry 'solr.core.backuprestore.shard2.replica_p10') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3cd19133
[junit4] 2> 2520105 INFO (qtp1298319516-31484) [n:127.0.0.1:57503_solr c:backuprestore s:shard2 r:core_node11 x:backuprestore_shard2_replica_n8] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57503.solr.core.backuprestore.shard2.replica_n8' (registry 'solr.core.backuprestore.shard2.replica_n8') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3cd19133
[junit4] 2> 2520105 INFO (qtp1298319516-31478) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node7 x:backuprestore_shard1_replica_p4] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57503.solr.core.backuprestore.shard1.replica_p4' (registry 'solr.core.backuprestore.shard1.replica_p4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3cd19133
[junit4] 2> 2520105 INFO (qtp1994259177-31483) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node12 x:backuprestore_shard2_replica_p10] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2520105 INFO (qtp1298319516-31484) [n:127.0.0.1:57503_solr c:backuprestore s:shard2 r:core_node11 x:backuprestore_shard2_replica_n8] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2520105 INFO (qtp1994259177-31483) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node12 x:backuprestore_shard2_replica_p10] o.a.s.c.SolrCore [[backuprestore_shard2_replica_p10] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.TestLocalFSCloudBackupRestore_7E594F1269001D53-001/tempDir-001/node2/backuprestore_shard2_replica_p10], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.TestLocalFSCloudBackupRestore_7E594F1269001D53-001/tempDir-001/node2/./backuprestore_shard2_replica_p10/data/]
[junit4] 2> 2520105 INFO (qtp1298319516-31484) [n:127.0.0.1:57503_solr c:backuprestore s:shard2 r:core_node11 x:backuprestore_shard2_replica_n8] o.a.s.c.SolrCore [[backuprestore_shard2_replica_n8] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.TestLocalFSCloudBackupRestore_7E594F1269001D53-001/tempDir-001/node1/backuprestore_shard2_replica_n8], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.TestLocalFSCloudBackupRestore_7E594F1269001D53-001/tempDir-001/node1/./backuprestore_shard2_replica_n8/data/]
[junit4] 2> 2520105 INFO (qtp1298319516-31478) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node7 x:backuprestore_shard1_replica_p4] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2520105 INFO (qtp1298319516-31478) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node7 x:backuprestore_shard1_replica_p4] o.a.s.c.SolrCore [[backuprestore_shard1_replica_p4] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.TestLocalFSCloudBackupRestore_7E594F1269001D53-001/tempDir-001/node1/backuprestore_shard1_replica_p4], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.TestLocalFSCloudBackupRestore_7E594F1269001D53-001/tempDir-001/node1/./backuprestore_shard1_replica_p4/data/]
[junit4] 2> 2520108 INFO (qtp1298319516-31475) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node3 x:backuprestore_shard1_replica_n1] o.a.s.s.IndexSchema [backuprestore_shard1_replica_n1] Schema name=minimal
[junit4] 2> 2520108 INFO (qtp1994259177-31488) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node9 x:backuprestore_shard2_replica_n6] o.a.s.s.IndexSchema [backuprestore_shard2_replica_n6] Schema name=minimal
[junit4] 2> 2520109 INFO (qtp1994259177-31490) [n:127.0.0.1:57504_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.s.IndexSchema [backuprestore_shard1_replica_n2] Schema name=minimal
[junit4] 2> 2520110 INFO (qtp1994259177-31488) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node9 x:backuprestore_shard2_replica_n6] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2520110 INFO (qtp1298319516-31475) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node3 x:backuprestore_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2520110 INFO (qtp1994259177-31488) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node9 x:backuprestore_shard2_replica_n6] o.a.s.c.CoreContainer Creating SolrCore 'backuprestore_shard2_replica_n6' using configuration from collection backuprestore, trusted=true
[junit4] 2> 2520110 INFO (qtp1298319516-31475) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node3 x:backuprestore_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'backuprestore_shard1_replica_n1' using configuration from collection backuprestore, trusted=true
[junit4] 2> 2520111 INFO (qtp1994259177-31490) [n:127.0.0.1:57504_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2520111 INFO (qtp1994259177-31490) [n:127.0.0.1:57504_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'backuprestore_shard1_replica_n2' using configuration from collection backuprestore, trusted=true
[junit4] 2> 2520111 INFO (qtp1994259177-31490) [n:127.0.0.1:57504_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57504.solr.core.backuprestore.shard1.replica_n2' (registry 'solr.core.backuprestore.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3cd19133
[junit4] 2> 2520111 INFO (qtp1994259177-31488) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node9 x:backuprestore_shard2_replica_n6] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57504.solr.core.backuprestore.shard2.replica_n6' (registry 'solr.core.backuprestore.shard2.replica_n6') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3cd19133
[junit4] 2> 2520111 INFO (qtp1994259177-31490) [n:127.0.0.1:57504_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2520111 INFO (qtp1994259177-31488) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node9 x:backuprestore_shard2_replica_n6] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2520111 INFO (qtp1994259177-31490) [n:127.0.0.1:57504_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.c.SolrCore [[backuprestore_shard1_replica_n2] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.TestLocalFSCloudBackupRestore_7E594F1269001D53-001/tempDir-001/node2/backuprestore_shard1_replica_n2], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.TestLocalFSCloudBackupRestore_7E594F1269001D53-001/tempDir-001/node2/./backuprestore_shard1_replica_n2/data/]
[junit4] 2> 2520111 INFO (qtp1994259177-31488) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node9 x:backuprestore_shard2_replica_n6] o.a.s.c.SolrCore [[backuprestore_shard2_replica_n6] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.TestLocalFSCloudBackupRestore_7E594F1269001D53-001/tempDir-001/node2/backuprestore_shard2_replica_n6], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.TestLocalFSCloudBackupRestore_7E594F1269001D53-001/tempDir-001/node2/./backuprestore_shard2_replica_n6/data/]
[junit4] 2> 2520111 INFO (qtp1298319516-31475) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node3 x:backuprestore_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57503.solr.core.backuprestore.shard1.replica_n1' (registry 'solr.core.backuprestore.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3cd19133
[junit4] 2> 2520112 INFO (qtp1298319516-31475) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node3 x:backuprestore_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2520112 INFO (qtp1298319516-31475) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node3 x:backuprestore_shard1_replica_n1] o.a.s.c.SolrCore [[backuprestore_shard1_replica_n1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.TestLocalFSCloudBackupRestore_7E594F1269001D53-001/tempDir-001/node1/backuprestore_shard1_replica_n1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.TestLocalFSCloudBackupRestore_7E594F1269001D53-001/tempDir-001/node1/./backuprestore_shard1_replica_n1/data/]
[junit4] 2> 2520296 INFO (qtp1298319516-31475) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node3 x:backuprestore_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2520297 INFO (qtp1298319516-31475) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node3 x:backuprestore_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2520317 INFO (qtp1298319516-31475) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node3 x:backuprestore_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2520317 INFO (qtp1298319516-31475) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node3 x:backuprestore_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2520319 INFO (qtp1298319516-31484) [n:127.0.0.1:57503_solr c:backuprestore s:shard2 r:core_node11 x:backuprestore_shard2_replica_n8] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2520319 INFO (qtp1298319516-31484) [n:127.0.0.1:57503_solr c:backuprestore s:shard2 r:core_node11 x:backuprestore_shard2_replica_n8] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2520322 INFO (qtp1994259177-31490) [n:127.0.0.1:57504_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2520322 INFO (qtp1994259177-31490) [n:127.0.0.1:57504_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2520323 INFO (qtp1298319516-31475) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node3 x:backuprestore_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@f331bbf[backuprestore_shard1_replica_n1] main]
[junit4] 2> 2520328 INFO (qtp1298319516-31484) [n:127.0.0.1:57503_solr c:backuprestore s:shard2 r:core_node11 x:backuprestore_shard2_replica_n8] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2520328 INFO (qtp1298319516-31484) [n:127.0.0.1:57503_solr c:backuprestore s:shard2 r:core_node11 x:backuprestore_shard2_replica_n8] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2520328 INFO (qtp1994259177-31490) [n:127.0.0.1:57504_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2520329 INFO (qtp1994259177-31490) [n:127.0.0.1:57504_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2520330 INFO (qtp1298319516-31484) [n:127.0.0.1:57503_solr c:backuprestore s:shard2 r:core_node11 x:backuprestore_shard2_replica_n8] o.a.s.s.SolrIndexSearcher Opening [Searcher@7178b45c[backuprestore_shard2_replica_n8] main]
[junit4] 2> 2520330 INFO (qtp1994259177-31490) [n:127.0.0.1:57504_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@6ad5644f[backuprestore_shard1_replica_n2] main]
[junit4] 2> 2520331 INFO (qtp1298319516-31478) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node7 x:backuprestore_shard1_replica_p4] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2520331 INFO (qtp1298319516-31478) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node7 x:backuprestore_shard1_replica_p4] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2520332 INFO (qtp1298319516-31478) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node7 x:backuprestore_shard1_replica_p4] o.a.s.s.SolrIndexSearcher Opening [Searcher@1eb7fed9[backuprestore_shard1_replica_p4] main]
[junit4] 2> 2520332 INFO (qtp1994259177-31488) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node9 x:backuprestore_shard2_replica_n6] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2520332 INFO (qtp1994259177-31488) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node9 x:backuprestore_shard2_replica_n6] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2520335 INFO (qtp1994259177-31488) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node9 x:backuprestore_shard2_replica_n6] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2520335 INFO (qtp1994259177-31488) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node9 x:backuprestore_shard2_replica_n6] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2520336 INFO (qtp1994259177-31488) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node9 x:backuprestore_shard2_replica_n6] o.a.s.s.SolrIndexSearcher Opening [Searcher@681ff7bb[backuprestore_shard2_replica_n6] main]
[junit4] 2> 2520337 INFO (qtp1994259177-31483) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node12 x:backuprestore_shard2_replica_p10] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2520337 INFO (qtp1994259177-31483) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node12 x:backuprestore_shard2_replica_p10] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2520339 INFO (qtp1994259177-31483) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node12 x:backuprestore_shard2_replica_p10] o.a.s.s.SolrIndexSearcher Opening [Searcher@38d0859[backuprestore_shard2_replica_p10] main]
[junit4] 2> 2520339 INFO (qtp1994259177-31490) [n:127.0.0.1:57504_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 2520339 INFO (qtp1994259177-31488) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node9 x:backuprestore_shard2_replica_n6] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 2520340 INFO (qtp1994259177-31490) [n:127.0.0.1:57504_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2520340 INFO (qtp1994259177-31490) [n:127.0.0.1:57504_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2520341 INFO (qtp1994259177-31483) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node12 x:backuprestore_shard2_replica_p10] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 2520341 INFO (searcherExecutor-11450-thread-1-processing-n:127.0.0.1:57504_solr x:backuprestore_shard1_replica_n2 c:backuprestore s:shard1 r:core_node5) [n:127.0.0.1:57504_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.c.SolrCore [backuprestore_shard1_replica_n2] Registered new searcher Searcher@6ad5644f[backuprestore_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2520341 INFO (qtp1994259177-31488) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node9 x:backuprestore_shard2_replica_n6] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2520341 INFO (qtp1994259177-31490) [n:127.0.0.1:57504_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1604844722115837952
[junit4] 2> 2520342 INFO (qtp1994259177-31488) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node9 x:backuprestore_shard2_replica_n6] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2520342 INFO (searcherExecutor-11448-thread-1-processing-n:127.0.0.1:57504_solr x:backuprestore_shard2_replica_n6 c:backuprestore s:shard2 r:core_node9) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node9 x:backuprestore_shard2_replica_n6] o.a.s.c.SolrCore [backuprestore_shard2_replica_n6] Registered new searcher Searcher@681ff7bb[backuprestore_shard2_replica_n6] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2520342 INFO (qtp1994259177-31488) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node9 x:backuprestore_shard2_replica_n6] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1604844722116886528
[junit4] 2> 2520342 INFO (qtp1994259177-31483) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node12 x:backuprestore_shard2_replica_p10] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2520342 INFO (qtp1994259177-31483) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node12 x:backuprestore_shard2_replica_p10] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2520346 INFO (searcherExecutor-11447-thread-1-processing-n:127.0.0.1:57504_solr x:backuprestore_shard2_replica_p10 c:backuprestore s:shard2 r:core_node12) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node12 x:backuprestore_shard2_replica_p10] o.a.s.c.SolrCore [backuprestore_shard2_replica_p10] Registered new searcher Searcher@38d0859[backuprestore_shard2_replica_p10] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2520350 INFO (qtp1298319516-31478) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node7 x:backuprestore_shard1_replica_p4] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 2520351 INFO (qtp1298319516-31484) [n:127.0.0.1:57503_solr c:backuprestore s:shard2 r:core_node11 x:backuprestore_shard2_replica_n8] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 2520351 INFO (qtp1298319516-31478) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node7 x:backuprestore_shard1_replica_p4] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2520352 INFO (qtp1298319516-31484) [n:127.0.0.1:57503_solr c:backuprestore s:shard2 r:core_node11 x:backuprestore_shard2_replica_n8] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2520352 INFO (qtp1298319516-31478) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node7 x:backuprestore_shard1_replica_p4] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2520352 INFO (qtp1298319516-31484) [n:127.0.0.1:57503_solr c:backuprestore s:shard2 r:core_node11 x:backuprestore_shard2_replica_n8] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2520353 INFO (searcherExecutor-11445-thread-1-processing-n:127.0.0.1:57503_solr x:backuprestore_shard1_replica_p4 c:backuprestore s:shard1 r:core_node7) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node7 x:backuprestore_shard1_replica_p4] o.a.s.c.SolrCore [backuprestore_shard1_replica_p4] Registered new searcher Searcher@1eb7fed9[backuprestore_shard1_replica_p4] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2520353 INFO (searcherExecutor-11446-thread-1-processing-n:127.0.0.1:57503_solr x:backuprestore_shard2_replica_n8 c:backuprestore s:shard2 r:core_node11) [n:127.0.0.1:57503_solr c:backuprestore s:shard2 r:core_node11 x:backuprestore_shard2_replica_n8] o.a.s.c.SolrCore [backuprestore_shard2_replica_n8] Registered new searcher Searcher@7178b45c[backuprestore_shard2_replica_n8] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2520353 INFO (qtp1298319516-31484) [n:127.0.0.1:57503_solr c:backuprestore s:shard2 r:core_node11 x:backuprestore_shard2_replica_n8] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1604844722128420864
[junit4] 2> 2520357 INFO (qtp1994259177-31490) [n:127.0.0.1:57504_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.c.ZkShardTerms Successful update of terms at /collections/backuprestore/terms/shard1 to Terms{values={core_node5=0}, version=0}
[junit4] 2> 2520359 INFO (qtp1298319516-31478) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node7 x:backuprestore_shard1_replica_p4] o.a.s.c.ZkController backuprestore_shard1_replica_p4 starting background replication from leader
[junit4] 2> 2520359 INFO (qtp1298319516-31478) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node7 x:backuprestore_shard1_replica_p4] o.a.s.c.ReplicateFromLeader Will start replication from leader with poll interval: 00:00:03
[junit4] 2> 2520363 INFO (qtp1298319516-31484) [n:127.0.0.1:57503_solr c:backuprestore s:shard2 r:core_node11 x:backuprestore_shard2_replica_n8] o.a.s.c.ZkShardTerms Successful update of terms at /collections/backuprestore/terms/shard2 to Terms{values={core_node11=0}, version=0}
[junit4] 2> 2520364 INFO (qtp1298319516-31478) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node7 x:backuprestore_shard1_replica_p4] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 3000ms
[junit4] 2> 2520364 INFO (qtp1298319516-31478) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node7 x:backuprestore_shard1_replica_p4] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2520365 INFO (qtp1994259177-31483) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node12 x:backuprestore_shard2_replica_p10] o.a.s.c.ZkController backuprestore_shard2_replica_p10 starting background replication from leader
[junit4] 2> 2520365 INFO (qtp1994259177-31483) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node12 x:backuprestore_shard2_replica_p10] o.a.s.c.ReplicateFromLeader Will start replication from leader with poll interval: 00:00:03
[junit4] 2> 2520367 INFO (qtp1994259177-31488) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node9 x:backuprestore_shard2_replica_n6] o.a.s.c.ZkShardTerms Successful update of terms at /collections/backuprestore/terms/shard2 to Terms{values={core_node11=0, core_node9=0}, version=1}
[junit4] 2> 2520368 INFO (qtp1994259177-31483) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node12 x:backuprestore_shard2_replica_p10] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 3000ms
[junit4] 2> 2520368 INFO (qtp1994259177-31483) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node12 x:backuprestore_shard2_replica_p10] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2520374 INFO (qtp1994259177-31490) [n:127.0.0.1:57504_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=2 found=1 timeoutin=9998ms
[junit4] 2> 2520376 INFO (qtp1298319516-31484) [n:127.0.0.1:57503_solr c:backuprestore s:shard2 r:core_node11 x:backuprestore_shard2_replica_n8] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2520376 INFO (qtp1298319516-31484) [n:127.0.0.1:57503_solr c:backuprestore s:shard2 r:core_node11 x:backuprestore_shard2_replica_n8] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2520376 INFO (qtp1298319516-31484) [n:127.0.0.1:57503_solr c:backuprestore s:shard2 r:core_node11 x:backuprestore_shard2_replica_n8] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:57503/solr/backuprestore_shard2_replica_n8/
[junit4] 2> 2520376 INFO (qtp1298319516-31475) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node3 x:backuprestore_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 2520376 INFO (qtp1298319516-31475) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node3 x:backuprestore_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2520377 INFO (qtp1298319516-31484) [n:127.0.0.1:57503_solr c:backuprestore s:shard2 r:core_node11 x:backuprestore_shard2_replica_n8] o.a.s.u.PeerSync PeerSync: core=backuprestore_shard2_replica_n8 url=http://127.0.0.1:57503/solr START replicas=[http://127.0.0.1:57504/solr/backuprestore_shard2_replica_n6/] nUpdates=100
[junit4] 2> 2520377 INFO (qtp1298319516-31484) [n:127.0.0.1:57503_solr c:backuprestore s:shard2 r:core_node11 x:backuprestore_shard2_replica_n8] o.a.s.u.PeerSync PeerSync: core=backuprestore_shard2_replica_n8 url=http://127.0.0.1:57503/solr DONE. We have no versions. sync failed.
[junit4] 2> 2520379 INFO (qtp1298319516-31475) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node3 x:backuprestore_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2520381 INFO (qtp1994259177-31474) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node9 x:backuprestore_shard2_replica_n6] o.a.s.c.S.Request [backuprestore_shard2_replica_n6] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 2520381 INFO (searcherExecutor-11449-thread-1-processing-n:127.0.0.1:57503_solr x:backuprestore_shard1_replica_n1 c:backuprestore s:shard1 r:core_node3) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node3 x:backuprestore_shard1_replica_n1] o.a.s.c.SolrCore [backuprestore_shard1_replica_n1] Registered new searcher Searcher@f331bbf[backuprestore_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2520381 INFO (qtp1298319516-31475) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node3 x:backuprestore_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1604844722157780992
[junit4] 2> 2520381 INFO (qtp1298319516-31484) [n:127.0.0.1:57503_solr c:backuprestore s:shard2 r:core_node11 x:backuprestore_shard2_replica_n8] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 2520381 INFO (qtp1298319516-31484) [n:127.0.0.1:57503_solr c:backuprestore s:shard2 r:core_node11 x:backuprestore_shard2_replica_n8] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 2520381 INFO (qtp1298319516-31484) [n:127.0.0.1:57503_solr c:backuprestore s:shard2 r:core_node11 x:backuprestore_shard2_replica_n8] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 2520387 INFO (qtp1298319516-31475) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node3 x:backuprestore_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/backuprestore/terms/shard1 to Terms{values={core_node3=0, core_node5=0}, version=1}
[junit4] 2> 2520389 INFO (qtp1298319516-31484) [n:127.0.0.1:57503_solr c:backuprestore s:shard2 r:core_node11 x:backuprestore_shard2_replica_n8] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:57503/solr/backuprestore_shard2_replica_n8/ shard2
[junit4] 2> 2520522 INFO (qtp1298319516-31484) [n:127.0.0.1:57503_solr c:backuprestore s:shard2 r:core_node11 x:backuprestore_shard2_replica_n8] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2520526 INFO (qtp1298319516-31484) [n:127.0.0.1:57503_solr c:backuprestore s:shard2 r:core_node11 x:backuprestore_shard2_replica_n8] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&newCollection=true&collection=backuprestore&version=2&replicaType=NRT&property.customKey=customValue&coreNodeName=core_node11&name=backuprestore_shard2_replica_n8&action=CREATE&numShards=2&shard=shard2&wt=javabin} status=0 QTime=1528
[junit4] 2> 2520630 INFO (zkCallback-10697-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/backuprestore/state.json] for collection [backuprestore] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2520882 INFO (qtp1994259177-31490) [n:127.0.0.1:57504_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2520883 INFO (qtp1994259177-31490) [n:127.0.0.1:57504_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2520883 INFO (qtp1994259177-31490) [n:127.0.0.1:57504_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:57504/solr/backuprestore_shard1_replica_n2/
[junit4] 2> 2520884 INFO (qtp1994259177-31490) [n:127.0.0.1:57504_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.u.PeerSync PeerSync: core=backuprestore_shard1_replica_n2 url=http://127.0.0.1:57504/solr START replicas=[http://127.0.0.1:57503/solr/backuprestore_shard1_replica_n1/] nUpdates=100
[junit4] 2> 2520884 INFO (qtp1994259177-31490) [n:127.0.0.1:57504_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.u.PeerSync PeerSync: core=backuprestore_shard1_replica_n2 url=http://127.0.0.1:57504/solr DONE. We have no versions. sync failed.
[junit4] 2> 2520886 INFO (qtp1298319516-31481) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node3 x:backuprestore_shard1_replica_n1] o.a.s.c.S.Request [backuprestore_shard1_replica_n1] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2520886 INFO (qtp1994259177-31490) [n:127.0.0.1:57504_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 2520886 INFO (qtp1994259177-31490) [n:127.0.0.1:57504_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 2520886 INFO (qtp1994259177-31490) [n:127.0.0.1:57504_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 2520893 INFO (qtp1994259177-31490) [n:127.0.0.1:57504_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:57504/solr/backuprestore_shard1_replica_n2/ shard1
[junit4] 2> 2521002 INFO (zkCallback-10697-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/backuprestore/state.json] for collection [backuprestore] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2521051 INFO (indexFetcher-11474-thread-1) [ ] o.a.s.h.IndexFetcher Replica core_node5 is leader but it's state is down, skipping replication
[junit4] 2> 2521058 INFO (qtp1994259177-31490) [n:127.0.0.1:57504_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2521061 INFO (qtp1994259177-31490) [n:127.0.0.1:57504_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&newCollection=true&collection=backuprestore&version=2&replicaType=NRT&property.customKey=customValue&coreNodeName=core_node5&name=backuprestore_shard1_replica_n2&action=CREATE&numShards=2&shard=shard1&wt=javabin} status=0 QTime=2065
[junit4] 2> 2521162 INFO (zkCallback-10702-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/backuprestore/state.json] for collection [backuprestore] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2521162 INFO (zkCallback-10697-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/backuprestore/state.json] for collection [backuprestore] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2521371 INFO (qtp1298319516-31478) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node7 x:backuprestore_shard1_replica_p4] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&newCollection=true&collection=backuprestore&version=2&replicaType=PULL&property.customKey=customValue&coreNodeName=core_node7&name=backuprestore_shard1_replica_p4&action=CREATE&numShards=2&shard=shard1&wt=javabin} status=0 QTime=2376
[junit4] 2> 2521373 INFO (qtp1994259177-31483) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node12 x:backuprestore_shard2_replica_p10] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&newCollection=true&collection=backuprestore&version=2&replicaType=PULL&property.customKey=customValue&coreNodeName=core_node12&name=backuprestore_shard2_replica_p10&action=CREATE&numShards=2&shard=shard2&wt=javabin} status=0 QTime=2371
[junit4] 2> 2521386 INFO (qtp1994259177-31488) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node9 x:backuprestore_shard2_replica_n6] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&newCollection=true&collection=backuprestore&version=2&replicaType=NRT&property.customKey=customValue&coreNodeName=core_node9&name=backuprestore_shard2_replica_n6&action=CREATE&numShards=2&shard=shard2&wt=javabin} status=0 QTime=2392
[junit4] 2> 2521399 INFO (qtp1298319516-31475) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node3 x:backuprestore_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&newCollection=true&collection=backuprestore&version=2&replicaType=NRT&property.customKey=customValue&coreNodeName=core_node3&name=backuprestore_shard1_replica_n1&action=CREATE&numShards=2&shard=shard1&wt=javabin} status=0 QTime=2405
[junit4] 2> 2521403 INFO (qtp1298319516-31480) [n:127.0.0.1:57503_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
[junit4] 2> 2521500 INFO (zkCallback-10702-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/backuprestore/state.json] for collection [backuprestore] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2521501 INFO (zkCallback-10697-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/backuprestore/state.json] for collection [backuprestore] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2522213 INFO (indexFetcher-11476-thread-1) [ ] o.a.s.h.IndexFetcher Updated masterUrl to http://127.0.0.1:57503/solr/backuprestore_shard2_replica_n8/
[junit4] 2> 2522213 INFO (qtp1298319516-31484) [n:127.0.0.1:57503_solr c:backuprestore s:shard2 r:core_node11 x:backuprestore_shard2_replica_n8] o.a.s.c.S.Request [backuprestore_shard2_replica_n8] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 2522214 INFO (indexFetcher-11476-thread-1) [ ] o.a.s.h.IndexFetcher Master's generation: 1
[junit4] 2> 2522214 INFO (indexFetcher-11476-thread-1) [ ] o.a.s.h.IndexFetcher Master's version: 0
[junit4] 2> 2522214 INFO (indexFetcher-11476-thread-1) [ ] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 2522214 INFO (indexFetcher-11476-thread-1) [ ] o.a.s.h.IndexFetcher Slave's version: 0
[junit4] 2> 2522214 INFO (indexFetcher-11476-thread-1) [ ] o.a.s.h.IndexFetcher New index in Master. Deleting mine...
[junit4] 2> 2522214 INFO (indexFetcher-11476-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2522214 INFO (indexFetcher-11476-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@498ba970 commitCommandVersion:0
[junit4] 2> 2522215 INFO (indexFetcher-11476-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@671540a[backuprestore_shard2_replica_p10] main]
[junit4] 2> 2522215 INFO (indexFetcher-11476-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2522216 INFO (searcherExecutor-11447-thread-1) [ ] o.a.s.c.SolrCore [backuprestore_shard2_replica_p10] Registered new searcher Searcher@671540a[backuprestore_shard2_replica_p10] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2522413 INFO (qtp1298319516-31480) [n:127.0.0.1:57503_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={pullReplicas=1&property.customKey=customValue&collection.configName=conf1&maxShardsPerNode=3&autoAddReplicas=true&name=backuprestore&nrtReplicas=2&action=CREATE&numShards=2&tlogReplicas=0&wt=javabin&version=2} status=0 QTime=3780
[junit4] 2> 2522419 INFO (qtp1298319516-31478) [n:127.0.0.1:57503_solr c:backuprestore s:shard2 r:core_node11 x:backuprestore_shard2_replica_n8] o.a.s.c.ZkShardTerms Successful update of terms at /collections/backuprestore/terms/shard2 to Terms{values={core_node11=1, core_node9=1}, version=2}
[junit4] 2> 2522419 INFO (qtp1994259177-31486) [n:127.0.0.1:57504_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.c.ZkShardTerms Successful update of terms at /collections/backuprestore/terms/shard1 to Terms{values={core_node3=1, core_node5=1}, version=2}
[junit4] 2> 2522421 INFO (qtp1994259177-31489) [n:127.0.0.1:57504_solr c:backuprestore s:shard2 r:core_node9 x:backuprestore_shard2_replica_n6] o.a.s.u.p.LogUpdateProcessorFactory [backuprestore_shard2_replica_n6] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:57503/solr/backuprestore_shard2_replica_n8/&wt=javabin&version=2}{add=[2 (1604844724290584576), 3 (1604844724292681728), 5 (1604844724292681729), 6 (1604844724292681730), 7 (1604844724292681731), 9 (1604844724292681732), 17 (1604844724292681733), 18 (1604844724292681734), 19 (1604844724292681735), 21 (1604844724292681736), ... (20 adds)]} 0 2
[junit4] 2> 2522422 INFO (qtp1298319516-31478) [n:127.0.0.1:57503_solr c:backuprestore s:shard2 r:core_node11 x:backuprestore_shard2_replica_n8] o.a.s.u.p.LogUpdateProcessorFactory [backuprestore_shard2_replica_n8] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2 (1604844724290584576), 3 (1604844724292681728), 5 (1604844724292681729), 6 (1604844724292681730), 7 (1604844724292681731), 9 (1604844724292681732), 17 (1604844724292681733), 18 (1604844724292681734), 19 (1604844724292681735), 21 (1604844724292681736), ... (20 adds)]} 0 6
[junit4] 2> 2522422 INFO (qtp1298319516-31484) [n:127.0.0.1:57503_solr c:backuprestore s:shard1 r:core_node3 x:backuprestore_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [backuprestore_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:57504/solr/backuprestore_shard1_replica_n2/&wt=javabin&version=2}{add=[0 (1604844724291633152), 1 (1604844724292681728), 4 (1604844724292681729), 8 (1604844724292681730), 10 (1604844724292681731), 11 (1604844724292681732), 12 (1604844724292681733), 13 (1604844724292681734), 14 (1604844724292681735), 15 (1604844724292681736), ... (26 adds)]} 0 3
[junit4] 2> 2522423 INFO (qtp1994259177-31486) [n:127.0.0.1:57504_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [backuprestor
[...truncated too long message...]
107_solr c:backuprestore s:shard1 r:core_node3 x:backuprestore_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@39b23c8c: rootName = solr_65107, domain = solr.core.backuprestore.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.backuprestore.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@c287729
[junit4] 2> 52790 INFO (coreCloseExecutor-178-thread-3) [n:127.0.0.1:65106_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.backuprestore.shard1.replica_n2, tag=754a499e
[junit4] 2> 52790 INFO (coreCloseExecutor-178-thread-3) [n:127.0.0.1:65106_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1af9325b: rootName = solr_65106, domain = solr.core.backuprestore.shard1.replica_n2, service url = null, agent id = null] for registry solr.core.backuprestore.shard1.replica_n2 / com.codahale.metrics.MetricRegistry@67498ff7
[junit4] 2> 52808 INFO (coreCloseExecutor-177-thread-3) [n:127.0.0.1:65107_solr c:backuprestore s:shard1 r:core_node3 x:backuprestore_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.backuprestore.shard1.leader, tag=6b3b25fa
[junit4] 2> 52808 INFO (coreCloseExecutor-177-thread-1) [n:127.0.0.1:65107_solr c:backuprestore s:shard1 r:core_node7 x:backuprestore_shard1_replica_p4] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.backuprestore.shard1.replica_p4, tag=65f4ad6
[junit4] 2> 52808 INFO (coreCloseExecutor-177-thread-1) [n:127.0.0.1:65107_solr c:backuprestore s:shard1 r:core_node7 x:backuprestore_shard1_replica_p4] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3d963147: rootName = solr_65107, domain = solr.core.backuprestore.shard1.replica_p4, service url = null, agent id = null] for registry solr.core.backuprestore.shard1.replica_p4 / com.codahale.metrics.MetricRegistry@11d2166e
[junit4] 2> 52808 INFO (coreCloseExecutor-178-thread-3) [n:127.0.0.1:65106_solr c:backuprestore s:shard1 r:core_node5 x:backuprestore_shard1_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.backuprestore.shard1.leader, tag=754a499e
[junit4] 2> 52808 INFO (coreCloseExecutor-178-thread-1) [n:127.0.0.1:65106_solr c:backuprestore s:shard2 r:core_node12 x:backuprestore_shard2_replica_p10] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.backuprestore.shard2.replica_p10, tag=23db6113
[junit4] 2> 52808 INFO (coreCloseExecutor-178-thread-1) [n:127.0.0.1:65106_solr c:backuprestore s:shard2 r:core_node12 x:backuprestore_shard2_replica_p10] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3bdfb3cd: rootName = solr_65106, domain = solr.core.backuprestore.shard2.replica_p10, service url = null, agent id = null] for registry solr.core.backuprestore.shard2.replica_p10 / com.codahale.metrics.MetricRegistry@5ee1c9f6
[junit4] 2> 52822 INFO (coreCloseExecutor-177-thread-1) [n:127.0.0.1:65107_solr c:backuprestore s:shard1 r:core_node7 x:backuprestore_shard1_replica_p4] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.backuprestore.shard1.leader, tag=65f4ad6
[junit4] 2> 52822 INFO (coreCloseExecutor-178-thread-1) [n:127.0.0.1:65106_solr c:backuprestore s:shard2 r:core_node12 x:backuprestore_shard2_replica_p10] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.backuprestore.shard2.leader, tag=23db6113
[junit4] 2> 52833 INFO (jetty-closer-88-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72298670527807494-127.0.0.1:65106_solr-n_0000000000) closing
[junit4] 2> 52834 INFO (OverseerStateUpdate-72298670527807494-127.0.0.1:65106_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:65106_solr
[junit4] 2> 52834 WARN (OverseerAutoScalingTriggerThread-72298670527807494-127.0.0.1:65106_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 52835 INFO (jetty-closer-88-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3c26a4ff{/solr,null,UNAVAILABLE}
[junit4] 2> 52835 INFO (jetty-closer-88-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 52839 INFO (jetty-closer-88-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@63343617{/solr,null,UNAVAILABLE}
[junit4] 2> 52839 INFO (jetty-closer-88-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 52840 ERROR (SUITE-TestLocalFSCloudBackupRestore-seed#[7E594F1269001D53]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 52841 INFO (SUITE-TestLocalFSCloudBackupRestore-seed#[7E594F1269001D53]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:65104 65104
[junit4] 2> 52856 INFO (Thread-45) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:65104 65104
[junit4] 2> 53148 WARN (Thread-45) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 8 /solr/collections/backuprestore/terms/shard1
[junit4] 2> 7 /solr/collections/backuprestore/terms/shard2
[junit4] 2> 5 /solr/aliases.json
[junit4] 2> 5 /solr/clusterprops.json
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/configs/conf1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 9 /solr/collections/backuprestore/state.json
[junit4] 2> 5 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/live_nodes
[junit4] 2> 5 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.TestLocalFSCloudBackupRestore_7E594F1269001D53-003
[junit4] 2> Jul 02, 2018 3:19:52 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {shard_s=Lucene50(blocksize=128), id=PostingsFormat(name=Memory)}, docValues:{}, maxPointsInLeafNode=1559, maxMBSortInHeap=5.810684281280154, sim=RandomSimilarity(queryNorm=true): {}, locale=sk, timezone=SystemV/PST8PDT
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_172 (64-bit)/cpus=3,threads=1,free=125421608,total=266338304
[junit4] 2> NOTE: All tests run in this JVM: [TestLocalFSCloudBackupRestore, TestLocalFSCloudBackupRestore, TestLocalFSCloudBackupRestore]
[junit4] Completed [5/5 (5!)] on J0 in 8.87s, 1 test, 1 failure <<< FAILURES!
[junit4]
[junit4]
[junit4] Tests with failures [seed: 7E594F1269001D53]:
[junit4] - org.apache.solr.cloud.api.collections.TestLocalFSCloudBackupRestore.test
[junit4] - org.apache.solr.cloud.api.collections.TestLocalFSCloudBackupRestore.test
[junit4] - org.apache.solr.cloud.api.collections.TestLocalFSCloudBackupRestore.test
[junit4] - org.apache.solr.cloud.api.collections.TestLocalFSCloudBackupRestore.test
[junit4] - org.apache.solr.cloud.api.collections.TestLocalFSCloudBackupRestore.test
[junit4]
[junit4]
[junit4] JVM J0: 0.69 .. 54.38 = 53.69s
[junit4] JVM J1: 0.69 .. 46.45 = 45.77s
[junit4] Execution time total: 54 seconds
[junit4] Tests summary: 5 suites, 5 tests, 5 failures
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/lucene/common-build.xml:1568: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/lucene/common-build.xml:1092: There were test failures: 5 suites, 5 tests, 5 failures [seed: 7E594F1269001D53]
Total time: 56 seconds
[repro] Setting last failure code to 256
[repro] Failures:
[repro] 5/5 failed: org.apache.solr.cloud.api.collections.TestLocalFSCloudBackupRestore
[repro] Exiting with code 256
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all warnings.
<Git Blamer> GIT_COMMIT=9a395f83ccd83bca568056f178757dd032007140, workspace=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX
[WARNINGS] Computing warning deltas based on reference build #720
Recording test results
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2