You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2020/01/12 19:33:42 UTC
[JENKINS] Lucene-Solr-8.4-Windows (64bit/jdk1.8.0_201) - Build #
237 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.4-Windows/237/
Java: 64bit/jdk1.8.0_201 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
3 tests failed.
FAILED: org.apache.solr.handler.TestRestoreCore.testFailedRestore
Error Message:
Expected exception AssertionError but no exception was thrown
Stack Trace:
junit.framework.AssertionFailedError: Expected exception AssertionError but no exception was thrown
at __randomizedtesting.SeedInfo.seed([786E2189D008B9D9:F518094DD18873C9]:0)
at org.apache.lucene.util.LuceneTestCase.expectThrows(LuceneTestCase.java:2722)
at org.apache.lucene.util.LuceneTestCase.expectThrows(LuceneTestCase.java:2712)
at org.apache.solr.handler.TestRestoreCore.testFailedRestore(TestRestoreCore.java:210)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at 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.update.processor.DimensionalRoutedAliasUpdateProcessorTest.testCatTime
Error Message:
took over 10 seconds after collection creation to update aliases
Stack Trace:
java.lang.AssertionError: took over 10 seconds after collection creation to update aliases
at __randomizedtesting.SeedInfo.seed([786E2189D008B9D9:7F656E8AEFFBE831]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.apache.solr.update.processor.RoutedAliasUpdateProcessorTest.waitColAndAlias(RoutedAliasUpdateProcessorTest.java:77)
at org.apache.solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest.testCatTime(DimensionalRoutedAliasUpdateProcessorTest.java:481)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at 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.update.processor.DimensionalRoutedAliasUpdateProcessorTest.testTimeCat
Error Message:
took over 10 seconds after collection creation to update aliases
Stack Trace:
java.lang.AssertionError: took over 10 seconds after collection creation to update aliases
at __randomizedtesting.SeedInfo.seed([786E2189D008B9D9:4196603A9029F5B2]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.apache.solr.update.processor.RoutedAliasUpdateProcessorTest.waitColAndAlias(RoutedAliasUpdateProcessorTest.java:77)
at org.apache.solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest.testTimeCat(DimensionalRoutedAliasUpdateProcessorTest.java:220)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at 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 13862 lines...]
[junit4] Suite: org.apache.solr.handler.TestRestoreCore
[junit4] 2> 1061842 INFO (SUITE-TestRestoreCore-seed#[786E2189D008B9D9]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\data-dir-81-001
[junit4] 2> 1061843 WARN (SUITE-TestRestoreCore-seed#[786E2189D008B9D9]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 1061843 INFO (SUITE-TestRestoreCore-seed#[786E2189D008B9D9]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1061853 INFO (SUITE-TestRestoreCore-seed#[786E2189D008B9D9]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
[junit4] 2> 1061853 INFO (SUITE-TestRestoreCore-seed#[786E2189D008B9D9]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1061857 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testFailedRestore
[junit4] 2> 1061858 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\solr-instance-001\collection1
[junit4] 2> 1061923 WARN (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1061927 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1061927 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1061927 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 1.8.0_201-b09
[junit4] 2> 1061928 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1061928 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1061928 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1061930 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@23416fd5{/solr,null,AVAILABLE}
[junit4] 2> 1061934 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@b109137{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:51822}
[junit4] 2> 1061934 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.e.j.s.Server Started @1064616ms
[junit4] 2> 1061934 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\solr-instance-001\collection1\data, hostContext=/solr, hostPort=51822}
[junit4] 2> 1061934 ERROR (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1061934 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1061934 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.4.1
[junit4] 2> 1061934 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1061934 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1061934 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-12T17:20:51.711Z
[junit4] 2> 1061935 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\solr-instance-001\solr.xml
[junit4] 2> 1061944 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1061944 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1061958 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@67b219b, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1062644 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1062650 WARN (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6bbdb561[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1062650 WARN (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6bbdb561[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1062675 WARN (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4a264cf2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1062675 WARN (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4a264cf2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1062677 WARN (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1062742 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1062742 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1062778 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@67b219b
[junit4] 2> 1062785 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@67b219b
[junit4] 2> 1062785 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@67b219b
[junit4] 2> 1062793 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\solr-instance-001\.
[junit4] 2> 1062793 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1063154 INFO (coreLoadExecutor-4267-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.4.1
[junit4] 2> 1063164 INFO (coreLoadExecutor-4267-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1063171 INFO (coreLoadExecutor-4267-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1063172 INFO (coreLoadExecutor-4267-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\solr-instance-001\.\collection1, trusted=true
[junit4] 2> 1063172 INFO (coreLoadExecutor-4267-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@67b219b
[junit4] 2> 1063172 INFO (coreLoadExecutor-4267-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\solr-instance-001\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\solr-instance-001\.\collection1\data\]
[junit4] 2> 1063176 INFO (coreLoadExecutor-4267-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=156472174, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 1064690 INFO (coreLoadExecutor-4267-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1064690 INFO (coreLoadExecutor-4267-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1064709 INFO (coreLoadExecutor-4267-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=49, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1064715 INFO (coreLoadExecutor-4267-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@22e0725e[collection1] main]
[junit4] 2> 1064716 INFO (coreLoadExecutor-4267-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\solr-instance-001\collection1\conf
[junit4] 2> 1064717 INFO (coreLoadExecutor-4267-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 1064717 INFO (coreLoadExecutor-4267-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1064717 INFO (searcherExecutor-4268-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@22e0725e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1065350 INFO (qtp1380584436-11476) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1065350 INFO (qtp1380584436-11476) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0
[junit4] 2> 1065352 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.h.BackupRestoreUtils Indexing 69 test docs
[junit4] 2> 1065427 INFO (qtp1380584436-11475) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9, ... (69 adds)]} 0 84
[junit4] 2> 1065429 INFO (qtp1380584436-11475) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1065429 INFO (qtp1380584436-11475) [ x:collection1 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@42263fab commitCommandVersion:0
[junit4] 2> 1065665 INFO (qtp1380584436-11475) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6fcc34c5[collection1] main]
[junit4] 2> 1065684 INFO (searcherExecutor-4268-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6fcc34c5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.4.1):C69:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.4.1, os.arch=amd64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.0, timestamp=1578849655351}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1065706 INFO (qtp1380584436-11475) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1065706 INFO (qtp1380584436-11475) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 308
[junit4] 2> 1065725 INFO (qtp1380584436-11473) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={name=u&location=C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\tempDir-001&wt=xml&command=backup} status=0 QTime=13
[junit4] 2> 1065743 INFO (Thread-1488) [ ] o.a.s.h.SnapShooter Creating backup snapshot u at file:///C:/Users/jenkins/workspace/Lucene-Solr-8.4-Windows/solr/build/solr-core/test/J0/temp/solr.handler.TestRestoreCore_786E2189D008B9D9-001/tempDir-001/
[junit4] 2> 1065805 INFO (qtp1380584436-11472) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=javabin&version=2&command=details} status=0 QTime=86
[junit4] 2> 1065808 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.h.BackupStatusChecker Checking Status of u: {responseHeader={status=0,QTime=86},status=OK,details={indexSize=2.7 KB,indexPath=C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\solr-instance-001\.\collection1\data\index/,commits=[{indexVersion=1578849655206,generation=2,filelist=[_0.fdt, _0.fdx, _0.fnm, _0.nvd, _0.nvm, _0.si, _0_FST50_0.doc, _0_FST50_0.tfp, segments_2]}],isMaster=true,isSlave=false,indexVersion=1578849655206,generation=2,master={confFiles=schema.xml,xslt/dummy.xsl,replicateAfter=[commit],replicationEnabled=true,replicableVersion=1578849655206,replicableGeneration=2}}}
[junit4] 2> 1065863 INFO (qtp1380584436-11471) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=javabin&version=2&command=details} status=0 QTime=12
[junit4] 2> 1065863 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.h.BackupStatusChecker Checking Status of u: {responseHeader={status=0,QTime=12},status=OK,details={indexSize=2.7 KB,indexPath=C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\solr-instance-001\.\collection1\data\index/,commits=[{indexVersion=1578849655206,generation=2,filelist=[_0.fdt, _0.fdx, _0.fnm, _0.nvd, _0.nvm, _0.si, _0_FST50_0.doc, _0_FST50_0.tfp, segments_2]}],isMaster=true,isSlave=false,indexVersion=1578849655206,generation=2,master={confFiles=schema.xml,xslt/dummy.xsl,replicateAfter=[commit],replicationEnabled=true,replicableVersion=1578849655206,replicableGeneration=2}}}
[junit4] 2> 1065972 INFO (qtp1380584436-11470) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=javabin&version=2&command=details} status=0 QTime=68
[junit4] 2> 1065972 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.h.BackupStatusChecker Checking Status of u: {responseHeader={status=0,QTime=68},status=OK,details={indexSize=2.7 KB,indexPath=C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\solr-instance-001\.\collection1\data\index/,commits=[{indexVersion=1578849655206,generation=2,filelist=[_0.fdt, _0.fdx, _0.fnm, _0.nvd, _0.nvm, _0.si, _0_FST50_0.doc, _0_FST50_0.tfp, segments_2]}],isMaster=true,isSlave=false,indexVersion=1578849655206,generation=2,master={confFiles=schema.xml,xslt/dummy.xsl,replicateAfter=[commit],replicationEnabled=true,replicableVersion=1578849655206,replicableGeneration=2}}}
[junit4] 2> 1066135 INFO (qtp1380584436-11478) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=javabin&version=2&command=details} status=0 QTime=131
[junit4] 2> 1066139 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.h.BackupStatusChecker Checking Status of u: {responseHeader={status=0,QTime=131},status=OK,details={indexSize=2.7 KB,indexPath=C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\solr-instance-001\.\collection1\data\index/,commits=[{indexVersion=1578849655206,generation=2,filelist=[_0.fdt, _0.fdx, _0.fnm, _0.nvd, _0.nvm, _0.si, _0_FST50_0.doc, _0_FST50_0.tfp, segments_2]}],isMaster=true,isSlave=false,indexVersion=1578849655206,generation=2,master={confFiles=schema.xml,xslt/dummy.xsl,replicateAfter=[commit],replicationEnabled=true,replicableVersion=1578849655206,replicableGeneration=2}}}
[junit4] 2> 1066273 INFO (qtp1380584436-11476) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=javabin&version=2&command=details} status=0 QTime=95
[junit4] 2> 1066273 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.h.BackupStatusChecker Checking Status of u: {responseHeader={status=0,QTime=95},status=OK,details={indexSize=2.7 KB,indexPath=C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\solr-instance-001\.\collection1\data\index/,commits=[{indexVersion=1578849655206,generation=2,filelist=[_0.fdt, _0.fdx, _0.fnm, _0.nvd, _0.nvm, _0.si, _0_FST50_0.doc, _0_FST50_0.tfp, segments_2]}],isMaster=true,isSlave=false,indexVersion=1578849655206,generation=2,master={confFiles=schema.xml,xslt/dummy.xsl,replicateAfter=[commit],replicationEnabled=true,replicableVersion=1578849655206,replicableGeneration=2}}}
[junit4] 2> 1066415 INFO (qtp1380584436-11475) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=javabin&version=2&command=details} status=0 QTime=106
[junit4] 2> 1066433 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.h.BackupStatusChecker Checking Status of u: {responseHeader={status=0,QTime=106},status=OK,details={indexSize=2.7 KB,indexPath=C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\solr-instance-001\.\collection1\data\index/,commits=[{indexVersion=1578849655206,generation=2,filelist=[_0.fdt, _0.fdx, _0.fnm, _0.nvd, _0.nvm, _0.si, _0_FST50_0.doc, _0_FST50_0.tfp, segments_2]}],isMaster=true,isSlave=false,indexVersion=1578849655206,generation=2,master={confFiles=schema.xml,xslt/dummy.xsl,replicateAfter=[commit],replicationEnabled=true,replicableVersion=1578849655206,replicableGeneration=2}}}
[junit4] 2> 1066519 INFO (Thread-1488) [ ] o.a.s.h.SnapShooter Done creating backup snapshot: u into file:///C:/Users/jenkins/workspace/Lucene-Solr-8.4-Windows/solr/build/solr-core/test/J0/temp/solr.handler.TestRestoreCore_786E2189D008B9D9-001/tempDir-001/snapshot.u
[junit4] 2> 1066519 INFO (qtp1380584436-11474) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=javabin&version=2&command=details} status=0 QTime=44
[junit4] 2> 1066520 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.h.BackupStatusChecker Checking Status of u: {responseHeader={status=0,QTime=44},status=OK,details={indexSize=2.7 KB,indexPath=C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\solr-instance-001\.\collection1\data\index/,commits=[{indexVersion=1578849655206,generation=2,filelist=[_0.fdt, _0.fdx, _0.fnm, _0.nvd, _0.nvm, _0.si, _0_FST50_0.doc, _0_FST50_0.tfp, segments_2]}],isMaster=true,isSlave=false,indexVersion=1578849655206,generation=2,master={confFiles=schema.xml,xslt/dummy.xsl,replicateAfter=[commit],replicationEnabled=true,replicableVersion=1578849655206,replicableGeneration=2},backup={startTime=Sun Jan 12 21:20:55 GMT+04:00 2020,fileCount=9,status=success,snapshotCompletedAt=Sun Jan 12 21:20:56 GMT+04:00 2020,snapshotName=u,directoryName=snapshot.u}}}
[junit4] 2> 1066528 INFO (qtp1380584436-11473) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={name=u&location=C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\tempDir-001&wt=xml&command=restore} status=0 QTime=5
[junit4] 2> 1066531 INFO (qtp1380584436-11472) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=restorestatus} status=0 QTime=0
[junit4] 2> 1066578 INFO (qtp1380584436-11471) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=restorestatus} status=0 QTime=0
[junit4] 2> 1066629 INFO (qtp1380584436-11470) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=restorestatus} status=0 QTime=0
[junit4] 2> 1066675 INFO (qtp1380584436-11478) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=restorestatus} status=0 QTime=0
[junit4] 2> 1066686 INFO (restoreExecutor-4269-thread-1-processing-x:collection1 snapshot.u C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\tempDir-001 collection1) [ x:collection1 ] o.a.s.h.RestoreCore Copying file _0.fdt to restore directory
[junit4] 2> 1066687 INFO (restoreExecutor-4269-thread-1-processing-x:collection1 snapshot.u C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\tempDir-001 collection1) [ x:collection1 ] o.a.s.h.RestoreCore Copying file _0.fdx to restore directory
[junit4] 2> 1066722 INFO (qtp1380584436-11476) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=restorestatus} status=0 QTime=0
[junit4] 2> 1066769 INFO (qtp1380584436-11475) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=restorestatus} status=0 QTime=0
[junit4] 2> 1066792 INFO (restoreExecutor-4269-thread-1-processing-x:collection1 snapshot.u C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\tempDir-001 collection1) [ x:collection1 ] o.a.s.h.RestoreCore Copying file _0.fnm to restore directory
[junit4] 2> 1066810 INFO (restoreExecutor-4269-thread-1-processing-x:collection1 snapshot.u C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\tempDir-001 collection1) [ x:collection1 ] o.a.s.h.RestoreCore Copying file _0.nvd to restore directory
[junit4] 2> 1066812 INFO (restoreExecutor-4269-thread-1-processing-x:collection1 snapshot.u C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\tempDir-001 collection1) [ x:collection1 ] o.a.s.h.RestoreCore Copying file _0.nvm to restore directory
[junit4] 2> 1066817 INFO (qtp1380584436-11473) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=restorestatus} status=0 QTime=0
[junit4] 2> 1066821 INFO (restoreExecutor-4269-thread-1-processing-x:collection1 snapshot.u C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\tempDir-001 collection1) [ x:collection1 ] o.a.s.h.RestoreCore Copying file _0.si to restore directory
[junit4] 2> 1066863 INFO (qtp1380584436-11472) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=restorestatus} status=0 QTime=0
[junit4] 2> 1066910 INFO (qtp1380584436-11474) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=restorestatus} status=0 QTime=0
[junit4] 2> 1066958 INFO (qtp1380584436-11471) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=restorestatus} status=0 QTime=0
[junit4] 2> 1066993 INFO (restoreExecutor-4269-thread-1-processing-x:collection1 snapshot.u C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\tempDir-001 collection1) [ x:collection1 ] o.a.s.h.RestoreCore Copying file _0_FST50_0.doc to restore directory
[junit4] 2> 1067004 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testFailedRestore
[junit4] 2> 1067011 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=621485069
[junit4] 2> 1067025 INFO (restoreExecutor-4269-thread-1-processing-x:collection1 snapshot.u C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\tempDir-001 collection1) [ x:collection1 ] o.a.s.h.RestoreCore Copying file _0_FST50_0.tfp to restore directory
[junit4] 2> 1067025 INFO (coreCloseExecutor-4272-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@407ff9ee
[junit4] 2> 1067025 INFO (coreCloseExecutor-4272-thread-1) [ x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=SolrCore@407ff9ee
[junit4] 2> 1067025 INFO (coreCloseExecutor-4272-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@f066ff2: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@3f638415
[junit4] 2> 1067027 INFO (restoreExecutor-4269-thread-1-processing-x:collection1 snapshot.u C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\tempDir-001 collection1) [ x:collection1 ] o.a.s.c.SolrCore Updating index properties... index=restore.20200112212056343
[junit4] 2> 1067135 INFO (restoreExecutor-4269-thread-1-processing-x:collection1 snapshot.u C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\tempDir-001 collection1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=42, maxMergedSegmentMB=85.267578125, floorSegmentMB=2.1611328125, forceMergeDeletesPctAllowed=10.860206126245611, segmentsPerTier=29.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, deletesPctAllowed=47.452601911513234
[junit4] 2> 1067137 WARN (restoreExecutor-4269-thread-1-processing-x:collection1 snapshot.u C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\tempDir-001 collection1) [ x:collection1 ] o.a.s.h.RestoreCore Could not switch to restored index. Rolling back to the current index
[junit4] 2> => org.apache.lucene.index.IndexNotFoundException: no segments* file found in LockValidatingDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@3df5cd8b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@63f5f872)): files: [_0.fdt, _0.fdx, _0.fnm, _0.nvd, _0.nvm, _0.si, _0_FST50_0.doc, _0_FST50_0.tfp]
[junit4] 2> at org.apache.lucene.index.IndexWriter.<init>(IndexWriter.java:841)
[junit4] 2> org.apache.lucene.index.IndexNotFoundException: no segments* file found in LockValidatingDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@3df5cd8b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@63f5f872)): files: [_0.fdt, _0.fdx, _0.fnm, _0.nvd, _0.nvm, _0.si, _0_FST50_0.doc, _0_FST50_0.tfp]
[junit4] 2> at org.apache.lucene.index.IndexWriter.<init>(IndexWriter.java:841) ~[java/:?]
[junit4] 2> at org.apache.solr.update.SolrIndexWriter.<init>(SolrIndexWriter.java:126) ~[java/:?]
[junit4] 2> at org.apache.solr.update.SolrIndexWriter.create(SolrIndexWriter.java:100) ~[java/:?]
[junit4] 2> at org.apache.solr.update.DefaultSolrCoreState.createMainIndexWriter(DefaultSolrCoreState.java:260) ~[java/:?]
[junit4] 2> at org.apache.solr.update.DefaultSolrCoreState.changeWriter(DefaultSolrCoreState.java:223) ~[java/:?]
[junit4] 2> at org.apache.solr.update.DefaultSolrCoreState.newIndexWriter(DefaultSolrCoreState.java:232) ~[java/:?]
[junit4] 2> at org.apache.solr.update.DirectUpdateHandler2.newIndexWriter(DirectUpdateHandler2.java:760) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.RestoreCore.doRestore(RestoreCore.java:108) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.RestoreCore.call(RestoreCore.java:56) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.RestoreCore.call(RestoreCore.java:38) ~[java/:?]
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_201]
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:210) ~[java/:?]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) ~[?:1.8.0_201]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) ~[?:1.8.0_201]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_201]
[junit4] 2> 1067147 INFO (restoreExecutor-4269-thread-1-processing-x:collection1 snapshot.u C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\tempDir-001 collection1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=94.5703125, floorSegmentMB=1.8564453125, forceMergeDeletesPctAllowed=16.718920025993484, segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=22.720927756897492
[junit4] 2> 1067167 INFO (restoreExecutor-4269-thread-1-processing-x:collection1 snapshot.u C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\tempDir-001 collection1) [ x:collection1 ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1067254 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1067254 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3aed665a: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@5982576f
[junit4] 2> 1067259 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1067259 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7c29ff3d: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@20b1dd4f
[junit4] 2> 1067260 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1067260 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7985c4ac: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@54edb5a0
[junit4] 2> 1067420 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@b109137{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 1067420 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@23416fd5{/solr,null,UNAVAILABLE}
[junit4] 2> 1067420 INFO (TEST-TestRestoreCore.testFailedRestore-seed#[786E2189D008B9D9]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestRestoreCore -Dtests.method=testFailedRestore -Dtests.seed=786E2189D008B9D9 -Dtests.slow=true -Dtests.locale=sr -Dtests.timezone=Europe/Astrakhan -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 5.57s J0 | TestRestoreCore.testFailedRestore <<<
[junit4] > Throwable #1: junit.framework.AssertionFailedError: Expected exception AssertionError but no exception was thrown
[junit4] > at __randomizedtesting.SeedInfo.seed([786E2189D008B9D9:F518094DD18873C9]:0)
[junit4] > at org.apache.lucene.util.LuceneTestCase.expectThrows(LuceneTestCase.java:2722)
[junit4] > at org.apache.lucene.util.LuceneTestCase.expectThrows(LuceneTestCase.java:2712)
[junit4] > at org.apache.solr.handler.TestRestoreCore.testFailedRestore(TestRestoreCore.java:210)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 1067428 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSimpleRestore
[junit4] 2> 1067430 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\solr-instance-002\collection1
[junit4] 2> 1067664 WARN (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1067666 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1067666 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1067666 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 1.8.0_201-b09
[junit4] 2> 1067666 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1067666 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1067666 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1067666 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1b6f037f{/solr,null,AVAILABLE}
[junit4] 2> 1067826 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1fd1e999{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:51831}
[junit4] 2> 1067826 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.e.j.s.Server Started @1070960ms
[junit4] 2> 1067826 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\solr-instance-002\collection1\data, hostContext=/solr, hostPort=51831}
[junit4] 2> 1067826 ERROR (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1067826 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1067826 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.4.1
[junit4] 2> 1067826 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1067826 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1067826 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-12T17:20:57.603Z
[junit4] 2> 1067827 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\solr-instance-002\solr.xml
[junit4] 2> 1067840 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1067840 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1067840 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@67b219b, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1067957 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1067988 WARN (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@21b97d8a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1067988 WARN (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@21b97d8a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1068008 WARN (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@11725774[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1068008 WARN (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@11725774[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1068010 WARN (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1068074 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1068074 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1068106 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@67b219b
[junit4] 2> 1068112 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@67b219b
[junit4] 2> 1068112 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@67b219b
[junit4] 2> 1068121 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\solr-instance-002\.
[junit4] 2> 1068121 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1068187 INFO (coreLoadExecutor-4282-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.4.1
[junit4] 2> 1068195 INFO (coreLoadExecutor-4282-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1068199 INFO (coreLoadExecutor-4282-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1068199 INFO (coreLoadExecutor-4282-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\solr-instance-002\.\collection1, trusted=true
[junit4] 2> 1068199 INFO (coreLoadExecutor-4282-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@67b219b
[junit4] 2> 1068199 INFO (coreLoadExecutor-4282-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\solr-instance-002\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\solr-instance-002\.\collection1\data\]
[junit4] 2> 1068226 INFO (coreLoadExecutor-4282-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=156472174, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 1069195 INFO (coreLoadExecutor-4282-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1069195 INFO (coreLoadExecutor-4282-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1069204 INFO (coreLoadExecutor-4282-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=49, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1069206 INFO (coreLoadExecutor-4282-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4aa4d068[collection1] main]
[junit4] 2> 1069236 INFO (coreLoadExecutor-4282-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\solr-instance-002\collection1\conf
[junit4] 2> 1069243 INFO (coreLoadExecutor-4282-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 1069243 INFO (coreLoadExecutor-4282-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1069244 INFO (searcherExecutor-4283-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4aa4d068[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1069727 INFO (qtp932395380-11505) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1069727 INFO (qtp932395380-11505) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0
[junit4] 2> 1069731 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.h.BackupRestoreUtils Indexing 26 test docs
[junit4] 2> 1069788 INFO (qtp932395380-11505) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9, ... (26 adds)]} 0 62
[junit4] 2> 1069805 INFO (qtp932395380-11505) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1069805 INFO (qtp932395380-11505) [ x:collection1 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@264aed92 commitCommandVersion:0
[junit4] 2> 1070006 INFO (qtp932395380-11505) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@64d62d97[collection1] main]
[junit4] 2> 1070007 INFO (qtp932395380-11505) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1070007 INFO (searcherExecutor-4283-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@64d62d97[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.4.1):C26:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.4.1, os.arch=amd64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.0, timestamp=1578849659589}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1070007 INFO (qtp932395380-11505) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 239
[junit4] 2> 1070186 INFO (qtp932395380-11505) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=javabin&version=2&command=details} status=0 QTime=197
[junit4] 2> 1070186 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.h.BackupStatusChecker Checking Status of latest backup: {responseHeader={status=0,QTime=197},status=OK,details={indexSize=1.94 KB,indexPath=C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\solr-instance-002\.\collection1\data\index/,commits=[{indexVersion=1578849659582,generation=2,filelist=[_0.fdt, _0.fdx, _0.fnm, _0.nvd, _0.nvm, _0.si, _0_FST50_0.doc, _0_FST50_0.tfp, segments_2]}],isMaster=true,isSlave=false,indexVersion=1578849659582,generation=2,master={confFiles=schema.xml,xslt/dummy.xsl,replicateAfter=[commit],replicationEnabled=true,replicableVersion=1578849659582,replicableGeneration=2}}}
[junit4] 2> 1070194 INFO (qtp932395380-11506) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={location=C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\tempDir-002&wt=xml&command=backup} status=0 QTime=1
[junit4] 2> 1070205 INFO (Thread-1500) [ ] o.a.s.h.SnapShooter Creating backup snapshot <not named> at file:///C:/Users/jenkins/workspace/Lucene-Solr-8.4-Windows/solr/build/solr-core/test/J0/temp/solr.handler.TestRestoreCore_786E2189D008B9D9-001/tempDir-002/
[junit4] 2> 1070216 INFO (qtp932395380-11506) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=javabin&version=2&command=details} status=0 QTime=22
[junit4] 2> 1070220 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.h.BackupStatusChecker Checking Status of latest backup: {responseHeader={status=0,QTime=22},status=OK,details={indexSize=1.94 KB,indexPath=C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\solr-instance-002\.\collection1\data\index/,commits=[{indexVersion=1578849659582,generation=2,filelist=[_0.fdt, _0.fdx, _0.fnm, _0.nvd, _0.nvm, _0.si, _0_FST50_0.doc, _0_FST50_0.tfp, segments_2]}],isMaster=true,isSlave=false,indexVersion=1578849659582,generation=2,master={confFiles=schema.xml,xslt/dummy.xsl,replicateAfter=[commit],replicationEnabled=true,replicableVersion=1578849659582,replicableGeneration=2}}}
[junit4] 2> 1070326 INFO (qtp932395380-11509) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=javabin&version=2&command=details} status=0 QTime=66
[junit4] 2> 1070326 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.h.BackupStatusChecker Checking Status of latest backup: {responseHeader={status=0,QTime=66},status=OK,details={indexSize=1.94 KB,indexPath=C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\solr-instance-002\.\collection1\data\index/,commits=[{indexVersion=1578849659582,generation=2,filelist=[_0.fdt, _0.fdx, _0.fnm, _0.nvd, _0.nvm, _0.si, _0_FST50_0.doc, _0_FST50_0.tfp, segments_2]}],isMaster=true,isSlave=false,indexVersion=1578849659582,generation=2,master={confFiles=schema.xml,xslt/dummy.xsl,replicateAfter=[commit],replicationEnabled=true,replicableVersion=1578849659582,replicableGeneration=2}}}
[junit4] 2> 1070399 INFO (qtp932395380-11511) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=javabin&version=2&command=details} status=0 QTime=29
[junit4] 2> 1070399 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.h.BackupStatusChecker Checking Status of latest backup: {responseHeader={status=0,QTime=29},status=OK,details={indexSize=1.94 KB,indexPath=C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\solr-instance-002\.\collection1\data\index/,commits=[{indexVersion=1578849659582,generation=2,filelist=[_0.fdt, _0.fdx, _0.fnm, _0.nvd, _0.nvm, _0.si, _0_FST50_0.doc, _0_FST50_0.tfp, segments_2]}],isMaster=true,isSlave=false,indexVersion=1578849659582,generation=2,master={confFiles=schema.xml,xslt/dummy.xsl,replicateAfter=[commit],replicationEnabled=true,replicableVersion=1578849659582,replicableGeneration=2}}}
[junit4] 2> 1070469 INFO (qtp932395380-11512) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=javabin&version=2&command=details} status=0 QTime=27
[junit4] 2> 1070471 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.h.BackupStatusChecker Checking Status of latest backup: {responseHeader={status=0,QTime=27},status=OK,details={indexSize=1.94 KB,indexPath=C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\solr-instance-002\.\collection1\data\index/,commits=[{indexVersion=1578849659582,generation=2,filelist=[_0.fdt, _0.fdx, _0.fnm, _0.nvd, _0.nvm, _0.si, _0_FST50_0.doc, _0_FST50_0.tfp, segments_2]}],isMaster=true,isSlave=false,indexVersion=1578849659582,generation=2,master={confFiles=schema.xml,xslt/dummy.xsl,replicateAfter=[commit],replicationEnabled=true,replicableVersion=1578849659582,replicableGeneration=2}}}
[junit4] 2> 1070545 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=javabin&version=2&command=details} status=0 QTime=32
[junit4] 2> 1070547 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.h.BackupStatusChecker Checking Status of latest backup: {responseHeader={status=0,QTime=32},status=OK,details={indexSize=1.94 KB,indexPath=C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\solr-instance-002\.\collection1\data\index/,commits=[{indexVersion=1578849659582,generation=2,filelist=[_0.fdt, _0.fdx, _0.fnm, _0.nvd, _0.nvm, _0.si, _0_FST50_0.doc, _0_FST50_0.tfp, segments_2]}],isMaster=true,isSlave=false,indexVersion=1578849659582,generation=2,master={confFiles=schema.xml,xslt/dummy.xsl,replicateAfter=[commit],replicationEnabled=true,replicableVersion=1578849659582,replicableGeneration=2}}}
[junit4] 2> 1070621 INFO (qtp932395380-11507) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=javabin&version=2&command=details} status=0 QTime=30
[junit4] 2> 1070625 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.h.BackupStatusChecker Checking Status of latest backup: {responseHeader={status=0,QTime=30},status=OK,details={indexSize=1.94 KB,indexPath=C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\solr-instance-002\.\collection1\data\index/,commits=[{indexVersion=1578849659582,generation=2,filelist=[_0.fdt, _0.fdx, _0.fnm, _0.nvd, _0.nvm, _0.si, _0_FST50_0.doc, _0_FST50_0.tfp, segments_2]}],isMaster=true,isSlave=false,indexVersion=1578849659582,generation=2,master={confFiles=schema.xml,xslt/dummy.xsl,replicateAfter=[commit],replicationEnabled=true,replicableVersion=1578849659582,replicableGeneration=2}}}
[junit4] 2> 1070716 INFO (qtp932395380-11508) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=javabin&version=2&command=details} status=0 QTime=47
[junit4] 2> 1070725 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.h.BackupStatusChecker Checking Status of latest backup: {responseHeader={status=0,QTime=47},status=OK,details={indexSize=1.94 KB,indexPath=C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\solr-instance-002\.\collection1\data\index/,commits=[{indexVersion=1578849659582,generation=2,filelist=[_0.fdt, _0.fdx, _0.fnm, _0.nvd, _0.nvm, _0.si, _0_FST50_0.doc, _0_FST50_0.tfp, segments_2]}],isMaster=true,isSlave=false,indexVersion=1578849659582,generation=2,master={confFiles=schema.xml,xslt/dummy.xsl,replicateAfter=[commit],replicationEnabled=true,replicableVersion=1578849659582,replicableGeneration=2}}}
[junit4] 2> 1070820 INFO (qtp932395380-11505) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=javabin&version=2&command=details} status=0 QTime=54
[junit4] 2> 1070824 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.h.BackupStatusChecker Checking Status of latest backup: {responseHeader={status=0,QTime=54},status=OK,details={indexSize=1.94 KB,indexPath=C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\solr-instance-002\.\collection1\data\index/,commits=[{indexVersion=1578849659582,generation=2,filelist=[_0.fdt, _0.fdx, _0.fnm, _0.nvd, _0.nvm, _0.si, _0_FST50_0.doc, _0_FST50_0.tfp, segments_2]}],isMaster=true,isSlave=false,indexVersion=1578849659582,generation=2,master={confFiles=schema.xml,xslt/dummy.xsl,replicateAfter=[commit],replicationEnabled=true,replicableVersion=1578849659582,replicableGeneration=2}}}
[junit4] 2> 1070903 INFO (Thread-1500) [ ] o.a.s.h.SnapShooter Done creating backup snapshot: <not named> into file:///C:/Users/jenkins/workspace/Lucene-Solr-8.4-Windows/solr/build/solr-core/test/J0/temp/solr.handler.TestRestoreCore_786E2189D008B9D9-001/tempDir-002/snapshot.20200112212059971
[junit4] 2> 1070924 INFO (qtp932395380-11506) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=javabin&version=2&command=details} status=0 QTime=59
[junit4] 2> 1070924 INFO (TEST-TestRestoreCore.testSimpleRestore-seed#[786E2189D008B9D9]) [ ] o.a.s.h.BackupStatusChecker Checking Status of latest backup: {responseHeader={status=0,QTime=59},status=OK,details={indexSize=1.94 KB,indexPath=C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore_786E2189D008B9D9-001\solr-instance-002\.\collection1\data\index/,commits=[{indexVersion=1578849659582,generation=2,filelist=[_0.fdt, _0.fdx, _0.fnm, _0.nvd, _0.nvm, _0.si, _0_FST50_0.doc, _0_FST50_0.tfp, segments_2]}],isMaster=true,isSlave=false,indexVersion=1578849659582,generation=2,master={confFiles=schema.xml,xslt/dummy.xsl,replicateAfter=[commit],replicationEnabled=true,replicableVersion=1578849659582,replicableGeneration=2},backup={startTime=Sun Jan 12 21:20:59 GMT+04:00 2020,fileCount=9,status=success,snapshotCompletedAt=Sun Jan 12 21:21:00 GMT+04:00 2020,snapshotName=null,directoryName=snapshot.20200112212059971}}}
[junit4] 2> 1070926 INFO (qtp932395380-11506) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=id:0} 0 1
[junit4] 2> 1070926 INFO (qtp932395380-11509) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=id:1} 0 0
[junit4] 2> 1070926 INFO (qtp932395380-11511) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=id:2} 0 0
[junit4] 2> 1070926 INFO (qtp932395380-11512) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=id:3} 0 0
[junit4] 2> 1070928 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=id:4} 0 0
[junit4] 2> 1070928 INFO (qtp932395380-11507) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=id:5} 0 0
[junit4] 2> 1070928 INFO (qtp932395380-11507) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=id:6} 0 0
[junit4] 2> 1070928 INFO (qtp932395380-11508) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=id:7} 0 0
[junit4] 2> 1070928 INFO (qtp932395380-11505) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=id:8} 0 0
[junit4] 2> 1070928 INFO (qtp932395380-11506) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=id:9} 0 0
[junit4] 2> 1070928 INFO (qtp932395380-11509) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=id:10} 0 0
[junit4] 2> 1070928 INFO (qtp932395380-11511) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=id:11} 0 0
[junit4] 2> 1070928 INFO (qtp932395380-11512) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1070928 INFO (qtp932395380-11512) [ x:collection1 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@264aed92 commitCommandVersion:0
[junit4] 2> 1070957 INFO (qtp932395380-11512) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@ea052da[collection1] main]
[junit4] 2> 1070957 INFO (qtp932395380-11512) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1070958 INFO (searcherExecutor-4283-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@ea052da[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.4.1):C26/12:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.4.1, os.arch=amd64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.0, timestamp=1578849659589}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]:delGen=1)))}
[junit4] 2> 1070959 INFO (qtp932395380-11512) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 30
[junit4] 2> 1071000 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[26]} 0 44
[junit4] 2> 1071049 INFO (qtp932395380-11507) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[27]} 0 5
[junit4] 2> 1071050 INFO (qtp932395380-11508) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[28]} 0 0
[junit4] 2> 1071050 INFO (qtp932395380-11505) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[29]} 0 0
[junit4] 2> 1071051 INFO (qtp932395380-11506) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[30]} 0 0
[junit4] 2> 1071052 INFO (qtp932395380-11509) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[31]} 0 0
[junit4] 2> 1071052 INFO (qtp932395380-11511) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[32]} 0 0
[junit4] 2> 1071053 INFO (qtp932395380-11512) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[33]} 0 0
[junit4] 2> 1071053 INFO (qtp932395380-11512) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[34]} 0 0
[junit4] 2> 1071054 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[35]} 0 0
[junit4] 2> 1071055 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[36]} 0 0
[junit4] 2> 1071055 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[37]} 0 0
[junit4] 2> 1071055 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[38]} 0 0
[junit4] 2> 1071055 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[39]} 0 0
[junit4] 2> 1071055 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[40]} 0 0
[junit4] 2> 1071055 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[41]} 0 0
[junit4] 2> 1071055 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[42]} 0 0
[junit4] 2> 1071055 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[43]} 0 0
[junit4] 2> 1071055 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[44]} 0 0
[junit4] 2> 1071055 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[45]} 0 0
[junit4] 2> 1071058 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[46]} 0 0
[junit4] 2> 1071059 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[47]} 0 0
[junit4] 2> 1071059 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[48]} 0 0
[junit4] 2> 1071059 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[49]} 0 0
[junit4] 2> 1071059 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[50]} 0 0
[junit4] 2> 1071059 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[51]} 0 0
[junit4] 2> 1071060 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[52]} 0 0
[junit4] 2> 1071060 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[53]} 0 0
[junit4] 2> 1071060 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[54]} 0 0
[junit4] 2> 1071060 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[55]} 0 0
[junit4] 2> 1071060 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[56]} 0 0
[junit4] 2> 1071060 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[57]} 0 0
[junit4] 2> 1071060 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[58]} 0 0
[junit4] 2> 1071060 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[59]} 0 0
[junit4] 2> 1071061 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[60]} 0 0
[junit4] 2> 1071062 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[61]} 0 0
[junit4] 2> 1071062 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[62]} 0 0
[junit4] 2> 1071062 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[63]} 0 0
[junit4] 2> 1071062 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[64]} 0 0
[junit4] 2> 1071062 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[65]} 0 0
[junit4] 2> 1071063 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[66]} 0 0
[junit4] 2> 1071063 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[67]} 0 0
[junit4] 2> 1071063 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[68]} 0 0
[junit4] 2> 1071063 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[69]} 0 0
[junit4] 2> 1071063 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[70]} 0 0
[junit4] 2> 1071063 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[71]} 0 0
[junit4] 2> 1071064 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[72]} 0 0
[junit4] 2> 1071064 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[73]} 0 0
[junit4] 2> 1071065 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[74]} 0 0
[junit4] 2> 1071065 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[75]} 0 0
[junit4] 2> 1071065 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[76]} 0 0
[junit4] 2> 1071065 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[77]} 0 0
[junit4] 2> 1071065 INFO (qtp932395380-11504) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [c
[...truncated too long message...]
meCat__TRA__2019-07-02__CRA__calico/state.json
[junit4] 2> 46 /solr/collections/testTimeCat__TRA__2019-07-02__CRA__shorthair/state.json
[junit4] 2> 37 /solr/collections/testTimeCat__TRA__2019-07-01__CRA__NEW_CATEGORY_ROUTED_ALIAS_WAITING_FOR_DATA_TEMP/state.json
[junit4] 2> 11 /solr/collections/testTimeCat/state.json
[junit4] 2> 9 /solr/collections/testTimeCat__TRA__2019-07-03__CRA__shorthair/state.json
[junit4] 2> 9 /solr/clusterstate.json
[junit4] 2> 5 /solr/collections/testTimeCat__TRA__2019-07-01__CRA__calico/collectionprops.json
[junit4] 2> 5 /solr/collections/testTimeCat__TRA__2019-07-02__CRA__tabby/collectionprops.json
[junit4] 2> 5 /solr/collections/testTimeCat__TRA__2019-07-01__CRA__tabby/collectionprops.json
[junit4] 2> 5 /solr/collections/testTimeCat__TRA__2019-07-03__CRA__calico/collectionprops.json
[junit4] 2> 5 /solr/collections/testTimeCat__TRA__2019-07-01__CRA__shorthair/collectionprops.json
[junit4] 2> 4 /solr/collections/testTimeCat__TRA__2019-07-04__CRA__calico/collectionprops.json
[junit4] 2> 4 /solr/collections/testTimeCat__TRA__2019-07-02__CRA__shorthair/collectionprops.json
[junit4] 2> 4 /solr/collections/testTimeCat__TRA__2019-07-02__CRA__calico/collectionprops.json
[junit4] 2> 2 /solr/collections/testTimeCat__TRA__2019-07-01__CRA__NEW_CATEGORY_ROUTED_ALIAS_WAITING_FOR_DATA_TEMP/collectionprops.json
[junit4] 2> 2 /solr/overseer_elect/election/72103868830777355-127.0.0.1:61227_solr-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 258 /solr/overseer/queue
[junit4] 2> 94 /solr/collections
[junit4] 2> 47 /solr/overseer/collection-queue-work
[junit4] 2> 24 /solr/live_nodes
[junit4] 2>
[junit4] 2> 2313844 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[786E2189D008B9D9]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:61207
[junit4] 2> 2313844 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[786E2189D008B9D9]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:61207
[junit4] 2> 2313844 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[786E2189D008B9D9]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 61207
[junit4] 2> 2316034 INFO (TEST-DimensionalRoutedAliasUpdateProcessorTest.testTimeCat-seed#[786E2189D008B9D9]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testTimeCat
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=DimensionalRoutedAliasUpdateProcessorTest -Dtests.method=testTimeCat -Dtests.seed=786E2189D008B9D9 -Dtests.slow=true -Dtests.locale=es-NI -Dtests.timezone=Etc/GMT+5 -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 78.7s J1 | DimensionalRoutedAliasUpdateProcessorTest.testTimeCat <<<
[junit4] > Throwable #1: java.lang.AssertionError: took over 10 seconds after collection creation to update aliases
[junit4] > at __randomizedtesting.SeedInfo.seed([786E2189D008B9D9:4196603A9029F5B2]:0)
[junit4] > at org.apache.solr.update.processor.RoutedAliasUpdateProcessorTest.waitColAndAlias(RoutedAliasUpdateProcessorTest.java:77)
[junit4] > at org.apache.solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest.testTimeCat(DimensionalRoutedAliasUpdateProcessorTest.java:220)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest_786E2189D008B9D9-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {}, docValues:{}, maxPointsInLeafNode=897, maxMBSortInHeap=7.8084192382492725, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@ecb5033), locale=es-NI, timezone=Etc/GMT+5
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_201 (64-bit)/cpus=3,threads=1,free=222870760,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [WordBreakSolrSpellCheckerTest, TestDeleteCollectionOnDownNodes, EmbeddedSolrNoSerializeTest, TestRandomDVFaceting, DirectUpdateHandlerTest, TestSubQueryTransformerDistrib, DistributedQueryComponentOptimizationTest, TestNestedUpdateProcessor, ConfigureRecoveryStrategyTest, TestElisionMultitermQuery, HdfsCollectionsAPIDistributedZkTest, BJQParserTest, TestCSVResponseWriter, DistributedFacetExistsSmallTest, DistributedDebugComponentTest, TestSolrCloudSnapshots, TestReqParamsAPI, CloudExitableDirectoryReaderTest, TestEmbeddedSolrServerSchemaAPI, ChangedSchemaMergeTest, TestSolrTestCaseJ4, FileUtilsTest, TestCloudRecovery, TestLRUStatsCache, ReturnFieldsTest, SortSpecParsingTest, SchemaVersionSpecificBehaviorTest, TestFieldCollectionResource, TestDistributedTracing, TestSolrCloudWithKerberosAlt, URLClassifyProcessorTest, HealthCheckHandlerTest, TlogReplayBufferedWhileIndexingTest, TransactionLogTest, TestBulkSchemaAPI, BadComponentTest, TestMultiValuedNumericRangeQuery, MBeansHandlerTest, TestSolrConfigHandlerCloud, TestMaxTokenLenTokenizer, TestConfigSetImmutable, LeaderVoteWaitTimeoutTest, AssignBackwardCompatibilityTest, NodeMarkersRegistrationTest, TestSolrFieldCacheBean, TestDynamicFieldNamesIndexCorrectly, TestInfoStreamLogging, TestDistributedMap, TestDynamicURP, TestSchemalessBufferedUpdates, IndexSizeEstimatorTest, TestLegacyFieldCache, SaslZkACLProviderTest, TestXIncludeConfig, TestRecovery, TestConfigSetsAPIZkFailure, TestDistribPackageStore, ZkCLITest, SearchHandlerTest, ChaosMonkeyNothingIsSafeTest, TestPostingsSolrHighlighter, TestMiniSolrCloudClusterSSL, RoutingToNodesWithPropertiesTest, TestAnalyzedSuggestions, MergeStrategyTest, TestSSLRandomization, HdfsTlogReplayBufferedWhileIndexingTest, TestPushWriter, TestCollectionAPIs, HdfsChaosMonkeyNothingIsSafeTest, WrapperMergePolicyFactoryTest, OverriddenZkACLAndCredentialsProvidersTest, RootFieldTest, TriggerSetPropertiesIntegrationTest, ResponseBuilderTest, TestLogWatcher, FieldAnalysisRequestHandlerTest, SolrMetricsIntegrationTest, ManagedSchemaRoundRobinCloudTest, TestMacros, CircularListTest, TestRandomCollapseQParserPlugin, MultiThreadedOCPTest, SplitShardTest, CollectionsAPIAsyncDistributedZkTest, HdfsRecoveryZkTest, TestSweetSpotSimilarityFactory, PeerSyncReplicationTest, CSVRequestHandlerTest, TestValueSourceCache, TestNumericTerms64, HLLUtilTest, DistributedFacetPivotSmallTest, TestNoOpRegenerator, RecoveryZkTest, TestQuerySenderListener, TestSimpleTextCodec, TestSimpleTrackingShardHandler, BasicFunctionalityTest, TestCloudConsistency, TemplateUpdateProcessorTest, TestQueryUtils, TestStressRecovery, TestHalfAndHalfDocValues, SpellingQueryConverterTest, MoveReplicaHDFSFailoverTest, ReplaceNodeTest, AtomicUpdateRemovalJavabinTest, ScriptEngineTest, TestComplexPhraseQParserPlugin, DistributedIntervalFacetingTest, SOLR749Test, CollectionStateFormat2Test, TestStressCloudBlindAtomicUpdates, TestLeaderElectionZkExpiry, CoreAdminOperationTest, ZkShardTermsTest, SolrRequestParserTest, TestJsonFacetsStatsParsing, DateRangeFieldTest, JWTAuthPluginIntegrationTest, OverseerStatusTest, TestStressUserVersions, AddBlockUpdateTest, OrderedExecutorTest, AnalyticsQueryTest, ClassificationUpdateProcessorIntegrationTest, DirectSolrConnectionTest, TestSolrIndexConfig, HdfsDirectoryFactoryTest, TestSerializedLuceneMatchVersion, TestPolicyCloud, SuggestComponentContextFilterQueryTest, TestLegacyBM25SimilarityFactory, TestConfigReload, TestPayloadCheckQParserPlugin, Tagger2Test, TestHashQParserPlugin, UpdateParamsTest, CreateRoutedAliasTest, TestFieldTypeCollectionResource, SliceStateTest, TestJsonRequest, TestDynamicLoading, TestHighlightDedupGrouping, TestTolerantUpdateProcessorCloud, TestPrepRecovery, TestSchemaField, JavaBinAtomicUpdateMultivalueTest, SystemInfoHandlerTest, AddReplicaTest, NumericFieldsTest, TestChildDocTransformerHierarchy, TestManagedStopFilterFactory, RegexBoostProcessorTest, SortByFunctionTest, HttpTriggerListenerTest, FastVectorHighlighterTest, TestSolr4Spatial, TestGeoJSONResponseWriter, TestSolrQueryResponse, MigrateRouteKeyTest, TestSearchPerf, DirectSolrSpellCheckerTest, SolrCoreTest, TestDefaultStatsCache, CustomTermsComponentTest, TestSurroundQueryParser, TestCoreDiscovery, DirectoryFactoryTest, SmileWriterTest, SolrLogAuditLoggerPluginTest, CategoryRoutedAliasUpdateProcessorTest, TestConfigSets, BasicDistributedZkTest, PeerSyncWithBufferUpdatesTest, CdcrRequestHandlerTest, DimensionalRoutedAliasUpdateProcessorTest]
[junit4] Completed [341/889 (2!)] on J1 in 142.90s, 2 tests, 2 failures <<< FAILURES!
[...truncated 54864 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.4-Windows/237/consoleText
[repro] Revision: 832bf13dd9187095831caf69783179d41059d013
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseConcMarkSweepGC"
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr\core
[repro] DimensionalRoutedAliasUpdateProcessorTest
[repro] TestRestoreCore
[repro] ant compile-test
[...truncated 3621 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=10 -Dtests.class="*.DimensionalRoutedAliasUpdateProcessorTest|*.TestRestoreCore" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseConcMarkSweepGC" -Dtests.seed=786E2189D008B9D9 -Dtests.slow=true -Dtests.locale=es-NI -Dtests.timezone=Etc/GMT+5 -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[...truncated 107 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.handler.TestRestoreCore
[repro] 0/5 failed: org.apache.solr.update.processor.DimensionalRoutedAliasUpdateProcessorTest
[repro] Exiting with code 0
[...truncated 78 lines...]
[JENKINS] Lucene-Solr-8.4-Windows (64bit/jdk-12.0.1) - Build # 239
- Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.4-Windows/239/
Java: 64bit/jdk-12.0.1 -XX:-UseCompressedOops -XX:+UseSerialGC
2 tests failed.
FAILED: org.apache.solr.cloud.LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud
Error Message:
IOException occurred when talking to server at: https://127.0.0.1:57528/solr
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: IOException occurred when talking to server at: https://127.0.0.1:57528/solr
at __randomizedtesting.SeedInfo.seed([63D6FAA614B50A2D:B2D10823B0BA811F]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:679)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:265)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:368)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:296)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1143)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:906)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:838)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:228)
at org.apache.solr.cloud.LegacyCloudClusterPropTest.createAndTest(LegacyCloudClusterPropTest.java:87)
at org.apache.solr.cloud.LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud(LegacyCloudClusterPropTest.java:79)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:567)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:835)
Caused by: javax.net.ssl.SSLException: Software caused connection abort: recv failed
at java.base/sun.security.ssl.Alert.createSSLException(Alert.java:127)
at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:320)
at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:263)
at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:258)
at java.base/sun.security.ssl.SSLSocketImpl.handleException(SSLSocketImpl.java:1342)
at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:844)
at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:282)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:165)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185)
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:564)
... 49 more
Suppressed: java.net.SocketException: Software caused connection abort: socket write error
at java.base/java.net.SocketOutputStream.socketWrite0(Native Method)
at java.base/java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:110)
at java.base/java.net.SocketOutputStream.write(SocketOutputStream.java:150)
at java.base/sun.security.ssl.SSLSocketOutputRecord.encodeAlert(SSLSocketOutputRecord.java:81)
at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:351)
... 71 more
Caused by: java.net.SocketException: Software caused connection abort: recv failed
at java.base/java.net.SocketInputStream.socketRead0(Native Method)
at java.base/java.net.SocketInputStream.socketRead(SocketInputStream.java:115)
at java.base/java.net.SocketInputStream.read(SocketInputStream.java:168)
at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
at java.base/sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:448)
at java.base/sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:68)
at java.base/sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1132)
at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:828)
... 67 more
FAILED: org.apache.solr.cloud.LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud
Error Message:
IOException occurred when talking to server at: https://127.0.0.1:55578/solr
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: IOException occurred when talking to server at: https://127.0.0.1:55578/solr
at __randomizedtesting.SeedInfo.seed([63D6FAA614B50A2D:B2D10823B0BA811F]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:679)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:265)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:368)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:296)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1143)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:906)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:838)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:228)
at org.apache.solr.cloud.LegacyCloudClusterPropTest.createAndTest(LegacyCloudClusterPropTest.java:87)
at org.apache.solr.cloud.LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud(LegacyCloudClusterPropTest.java:79)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:567)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:835)
Caused by: javax.net.ssl.SSLException: Software caused connection abort: recv failed
at java.base/sun.security.ssl.Alert.createSSLException(Alert.java:127)
at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:320)
at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:263)
at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:258)
at java.base/sun.security.ssl.SSLSocketImpl.handleException(SSLSocketImpl.java:1342)
at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:844)
at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:282)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:165)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185)
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:564)
... 49 more
Suppressed: java.net.SocketException: Software caused connection abort: socket write error
at java.base/java.net.SocketOutputStream.socketWrite0(Native Method)
at java.base/java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:110)
at java.base/java.net.SocketOutputStream.write(SocketOutputStream.java:150)
at java.base/sun.security.ssl.SSLSocketOutputRecord.encodeAlert(SSLSocketOutputRecord.java:81)
at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:351)
... 71 more
Caused by: java.net.SocketException: Software caused connection abort: recv failed
at java.base/java.net.SocketInputStream.socketRead0(Native Method)
at java.base/java.net.SocketInputStream.socketRead(SocketInputStream.java:115)
at java.base/java.net.SocketInputStream.read(SocketInputStream.java:168)
at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
at java.base/sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:448)
at java.base/sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:68)
at java.base/sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1132)
at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:828)
... 67 more
Build Log:
[...truncated 13841 lines...]
[junit4] Suite: org.apache.solr.cloud.LegacyCloudClusterPropTest
[junit4] 2> 198722 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 198725 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J2\temp\solr.cloud.LegacyCloudClusterPropTest_63D6FAA614B50A2D-001\data-dir-21-001
[junit4] 2> 198725 WARN (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=5 numCloses=5
[junit4] 2> 198725 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 198726 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 198726 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J2\temp\solr.cloud.LegacyCloudClusterPropTest_63D6FAA614B50A2D-001\tempDir-001
[junit4] 2> 198726 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 198726 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 198726 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 198827 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:55565
[junit4] 2> 198827 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:55565
[junit4] 2> 198827 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:55565
[junit4] 2> 198827 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 55565
[junit4] 2> 198829 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 198834 INFO (zkConnectionManagerCallback-689-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 198834 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 198837 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 198838 INFO (zkConnectionManagerCallback-691-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 198838 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 198842 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 198844 INFO (zkConnectionManagerCallback-693-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 198844 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 198981 WARN (jetty-launcher-694-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 198981 INFO (jetty-launcher-694-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 198981 INFO (jetty-launcher-694-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 198981 INFO (jetty-launcher-694-thread-1) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 12.0.1+12
[junit4] 2> 198982 INFO (jetty-launcher-694-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 198982 INFO (jetty-launcher-694-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 198982 INFO (jetty-launcher-694-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 198982 INFO (jetty-launcher-694-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@153d41da{/solr,null,AVAILABLE}
[junit4] 2> 198994 INFO (jetty-launcher-694-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1017f4df{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:55578}
[junit4] 2> 198994 INFO (jetty-launcher-694-thread-1) [ ] o.e.j.s.Server Started @199038ms
[junit4] 2> 198994 INFO (jetty-launcher-694-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=55578}
[junit4] 2> 198998 ERROR (jetty-launcher-694-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 198998 INFO (jetty-launcher-694-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 198998 INFO (jetty-launcher-694-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.4.1
[junit4] 2> 198998 INFO (jetty-launcher-694-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 198998 INFO (jetty-launcher-694-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 198998 INFO (jetty-launcher-694-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-14T02:00:31.071917600Z
[junit4] 2> 199000 INFO (jetty-launcher-694-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 199006 INFO (zkConnectionManagerCallback-696-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 199006 INFO (jetty-launcher-694-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 199007 INFO (jetty-launcher-694-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 199562 INFO (jetty-launcher-694-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 199564 WARN (jetty-launcher-694-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@b067d8f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 199564 WARN (jetty-launcher-694-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@b067d8f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 199568 WARN (jetty-launcher-694-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4d41faef[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 199568 WARN (jetty-launcher-694-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4d41faef[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 199569 INFO (jetty-launcher-694-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55565/solr
[junit4] 2> 199570 INFO (jetty-launcher-694-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 199572 INFO (zkConnectionManagerCallback-703-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 199572 INFO (jetty-launcher-694-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 199675 INFO (jetty-launcher-694-thread-1) [n:127.0.0.1:55578_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 199683 INFO (zkConnectionManagerCallback-705-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 199684 INFO (jetty-launcher-694-thread-1) [n:127.0.0.1:55578_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 199752 INFO (jetty-launcher-694-thread-1) [n:127.0.0.1:55578_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:55578_solr
[junit4] 2> 199752 INFO (jetty-launcher-694-thread-1) [n:127.0.0.1:55578_solr ] o.a.s.c.Overseer Overseer (id=72059230145740805-127.0.0.1:55578_solr-n_0000000000) starting
[junit4] 2> 199757 INFO (OverseerStateUpdate-72059230145740805-127.0.0.1:55578_solr-n_0000000000) [n:127.0.0.1:55578_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:55578_solr
[junit4] 2> 199757 INFO (jetty-launcher-694-thread-1) [n:127.0.0.1:55578_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55578_solr
[junit4] 2> 199758 INFO (OverseerStateUpdate-72059230145740805-127.0.0.1:55578_solr-n_0000000000) [n:127.0.0.1:55578_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 199760 INFO (jetty-launcher-694-thread-1) [n:127.0.0.1:55578_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 199761 WARN (jetty-launcher-694-thread-1) [n:127.0.0.1:55578_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 199781 INFO (jetty-launcher-694-thread-1) [n:127.0.0.1:55578_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 199808 INFO (jetty-launcher-694-thread-1) [n:127.0.0.1:55578_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55578.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@34c33d67
[junit4] 2> 199811 INFO (jetty-launcher-694-thread-1) [n:127.0.0.1:55578_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55578.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@34c33d67
[junit4] 2> 199811 INFO (jetty-launcher-694-thread-1) [n:127.0.0.1:55578_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55578.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@34c33d67
[junit4] 2> 199816 INFO (jetty-launcher-694-thread-1) [n:127.0.0.1:55578_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J2\temp\solr.cloud.LegacyCloudClusterPropTest_63D6FAA614B50A2D-001\tempDir-001\node1\.
[junit4] 2> 199837 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=1
[junit4] 2> 199838 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 199841 INFO (zkConnectionManagerCallback-714-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 199841 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 199842 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 199843 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55565/solr ready
[junit4] 2> 199853 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCreateCollectionSwitchLegacyCloud
[junit4] 2> 199960 INFO (qtp1837376479-2272) [n:127.0.0.1:55578_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterprop with params val=true&name=legacyCloud&action=CLUSTERPROP&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 199962 INFO (qtp1837376479-2272) [n:127.0.0.1:55578_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={val=true&name=legacyCloud&action=CLUSTERPROP&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 199964 INFO (qtp1837376479-2274) [n:127.0.0.1:55578_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&maxShardsPerNode=1&name=legacyTrue&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 199967 INFO (OverseerThreadFactory-588-thread-1-processing-n:127.0.0.1:55578_solr) [n:127.0.0.1:55578_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection legacyTrue
[junit4] 2> 200105 INFO (qtp1837376479-2273) [n:127.0.0.1:55578_solr x:legacyTrue_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=legacyTrue_shard1_replica_n1&action=CREATE&numShards=1&collection=legacyTrue&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 200106 INFO (qtp1837376479-2273) [n:127.0.0.1:55578_solr x:legacyTrue_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 201136 INFO (qtp1837376479-2273) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.4.1
[junit4] 2> 201155 INFO (qtp1837376479-2273) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.s.IndexSchema [legacyTrue_shard1_replica_n1] Schema name=minimal
[junit4] 2> 201157 INFO (qtp1837376479-2273) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 201157 INFO (qtp1837376479-2273) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'legacyTrue_shard1_replica_n1' using configuration from collection legacyTrue, trusted=true
[junit4] 2> 201158 INFO (qtp1837376479-2273) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55578.solr.core.legacyTrue.shard1.replica_n1' (registry 'solr.core.legacyTrue.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@34c33d67
[junit4] 2> 201158 INFO (qtp1837376479-2273) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.SolrCore [[legacyTrue_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J2\temp\solr.cloud.LegacyCloudClusterPropTest_63D6FAA614B50A2D-001\tempDir-001\node1\legacyTrue_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J2\temp\solr.cloud.LegacyCloudClusterPropTest_63D6FAA614B50A2D-001\tempDir-001\node1\.\legacyTrue_shard1_replica_n1\data\]
[junit4] 2> 201218 INFO (qtp1837376479-2273) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 201218 INFO (qtp1837376479-2273) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 201220 INFO (qtp1837376479-2273) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 201220 INFO (qtp1837376479-2273) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 201222 INFO (qtp1837376479-2273) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6e4ba0c1[legacyTrue_shard1_replica_n1] main]
[junit4] 2> 201223 INFO (qtp1837376479-2273) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 201224 INFO (qtp1837376479-2273) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 201224 INFO (qtp1837376479-2273) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 201224 INFO (qtp1837376479-2273) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1655667145621635072
[junit4] 2> 201226 INFO (searcherExecutor-593-thread-1-processing-n:127.0.0.1:55578_solr x:legacyTrue_shard1_replica_n1 c:legacyTrue s:shard1) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.SolrCore [legacyTrue_shard1_replica_n1] Registered new searcher Searcher@6e4ba0c1[legacyTrue_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 201229 INFO (qtp1837376479-2273) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/legacyTrue/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 201229 INFO (qtp1837376479-2273) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/legacyTrue/leaders/shard1
[junit4] 2> 201232 INFO (qtp1837376479-2273) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 201232 INFO (qtp1837376479-2273) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 201232 INFO (qtp1837376479-2273) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:55578/solr/legacyTrue_shard1_replica_n1/
[junit4] 2> 201232 INFO (qtp1837376479-2273) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 201232 INFO (qtp1837376479-2273) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:55578/solr/legacyTrue_shard1_replica_n1/ has no replicas
[junit4] 2> 201232 INFO (qtp1837376479-2273) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/legacyTrue/leaders/shard1/leader after winning as /collections/legacyTrue/leader_elect/shard1/election/72059230145740805-core_node2-n_0000000000
[junit4] 2> 201234 INFO (qtp1837376479-2273) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:55578/solr/legacyTrue_shard1_replica_n1/ shard1
[junit4] 2> 201336 INFO (zkCallback-704-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/legacyTrue/state.json] for collection [legacyTrue] has occurred - updating... (live nodes size: [1])
[junit4] 2> 201336 INFO (zkCallback-704-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/legacyTrue/state.json] for collection [legacyTrue] has occurred - updating... (live nodes size: [1])
[junit4] 2> 201337 INFO (qtp1837376479-2273) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 201341 INFO (qtp1837376479-2273) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=legacyTrue_shard1_replica_n1&action=CREATE&numShards=1&collection=legacyTrue&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1235
[junit4] 2> 201344 INFO (qtp1837376479-2274) [n:127.0.0.1:55578_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 201440 INFO (zkCallback-704-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/legacyTrue/state.json] for collection [legacyTrue] has occurred - updating... (live nodes size: [1])
[junit4] 2> 201440 INFO (zkCallback-704-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/legacyTrue/state.json] for collection [legacyTrue] has occurred - updating... (live nodes size: [1])
[junit4] 2> 201440 INFO (zkCallback-704-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/legacyTrue/state.json] for collection [legacyTrue] has occurred - updating... (live nodes size: [1])
[junit4] 2> 201441 INFO (qtp1837376479-2274) [n:127.0.0.1:55578_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&maxShardsPerNode=1&name=legacyTrue&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=1477
[junit4] 2> 201442 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: legacyTrue
[junit4] 2> 201452 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1592681253
[junit4] 2> 201452 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:55578_solr
[junit4] 2> 201453 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 201453 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:55578_solr as DOWN
[junit4] 2> 201455 INFO (coreCloseExecutor-598-thread-1) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.SolrCore [legacyTrue_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@7ade85bd
[junit4] 2> 201455 INFO (coreCloseExecutor-598-thread-1) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.legacyTrue.shard1.replica_n1, tag=SolrCore@7ade85bd
[junit4] 2> 201455 INFO (coreCloseExecutor-598-thread-1) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@148ce6b3: rootName = solr_55578, domain = solr.core.legacyTrue.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.legacyTrue.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@4966e1
[junit4] 2> 201461 INFO (coreCloseExecutor-598-thread-1) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.legacyTrue.shard1.leader, tag=SolrCore@7ade85bd
[junit4] 2> 201465 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 201465 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@22b331f6: rootName = solr_55578, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@458eb54f
[junit4] 2> 201466 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 201466 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4cfd95a0: rootName = solr_55578, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@552046a9
[junit4] 2> 201478 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 201478 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@38934362: rootName = solr_55578, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@1e916b6b
[junit4] 2> 201479 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 201480 INFO (closeThreadPool-719-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72059230145740805-127.0.0.1:55578_solr-n_0000000000) closing
[junit4] 2> 201480 INFO (OverseerStateUpdate-72059230145740805-127.0.0.1:55578_solr-n_0000000000) [n:127.0.0.1:55578_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:55578_solr
[junit4] 2> 201481 INFO (OverseerAutoScalingTriggerThread-72059230145740805-127.0.0.1:55578_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 201482 INFO (closeThreadPool-719-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72059230145740805-127.0.0.1:55578_solr-n_0000000000) closing
[junit4] 2> 201483 INFO (zkCallback-713-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 201583 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.c.Overseer Overseer (id=72059230145740805-127.0.0.1:55578_solr-n_0000000000) closing
[junit4] 2> 201587 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1017f4df{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:0}
[junit4] 2> 201587 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@153d41da{/solr,null,UNAVAILABLE}
[junit4] 2> 201587 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 201588 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.c.MiniSolrCloudCluster waitForJettyToStop: 55578
[junit4] 2> 201588 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=55578)
[junit4] 2> 201593 WARN (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 201594 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 55578 try number 1 ...
[junit4] 2> 201594 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 12.0.1+12
[junit4] 2> 201594 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 201594 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 201595 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 201595 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@31cbbecd{/solr,null,AVAILABLE}
[junit4] 2> 201598 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@25b4ab23{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:55578}
[junit4] 2> 201598 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.e.j.s.Server Started @201639ms
[junit4] 2> 201598 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=55578}
[junit4] 2> 201599 ERROR (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 201599 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 201599 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.4.1
[junit4] 2> 201599 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 201599 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 201599 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-14T02:00:33.672912100Z
[junit4] 2> 201602 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 201607 INFO (zkConnectionManagerCallback-721-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 201607 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 201609 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 201846 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 201852 WARN (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3f5917b5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 201852 WARN (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3f5917b5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 201858 WARN (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4c54b7dd[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 201858 WARN (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4c54b7dd[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 201859 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55565/solr
[junit4] 2> 201859 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 201862 INFO (zkConnectionManagerCallback-728-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 201862 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 201966 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [n:127.0.0.1:55578_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 201966 INFO (zkConnectionManagerCallback-730-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 201966 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [n:127.0.0.1:55578_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 201978 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [n:127.0.0.1:55578_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:55578_solr
[junit4] 2> 201978 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [n:127.0.0.1:55578_solr ] o.a.s.c.Overseer Overseer (id=72059230145740809-127.0.0.1:55578_solr-n_0000000001) starting
[junit4] 2> 201983 INFO (OverseerStateUpdate-72059230145740809-127.0.0.1:55578_solr-n_0000000001) [n:127.0.0.1:55578_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:55578_solr
[junit4] 2> 201983 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [n:127.0.0.1:55578_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55578_solr
[junit4] 2> 201985 INFO (OverseerStateUpdate-72059230145740809-127.0.0.1:55578_solr-n_0000000001) [n:127.0.0.1:55578_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 201985 INFO (zkCallback-713-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 201989 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [n:127.0.0.1:55578_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 201990 WARN (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [n:127.0.0.1:55578_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 202024 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [n:127.0.0.1:55578_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 202056 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [n:127.0.0.1:55578_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55578.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@34c33d67
[junit4] 2> 202066 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [n:127.0.0.1:55578_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55578.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@34c33d67
[junit4] 2> 202066 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [n:127.0.0.1:55578_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55578.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@34c33d67
[junit4] 2> 202070 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [n:127.0.0.1:55578_solr ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J2\temp\solr.cloud.LegacyCloudClusterPropTest_63D6FAA614B50A2D-001\tempDir-001\node1\.
[junit4] 2> 202070 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [n:127.0.0.1:55578_solr ] o.a.s.c.CorePropertiesLocator Cores are: [legacyTrue_shard1_replica_n1]
[junit4] 2> 202072 INFO (coreLoadExecutor-612-thread-1-processing-n:127.0.0.1:55578_solr) [n:127.0.0.1:55578_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 202082 INFO (coreLoadExecutor-612-thread-1-processing-n:127.0.0.1:55578_solr) [n:127.0.0.1:55578_solr ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.4.1
[junit4] 2> 202102 INFO (coreLoadExecutor-612-thread-1-processing-n:127.0.0.1:55578_solr) [n:127.0.0.1:55578_solr ] o.a.s.s.IndexSchema [legacyTrue_shard1_replica_n1] Schema name=minimal
[junit4] 2> 202105 INFO (coreLoadExecutor-612-thread-1-processing-n:127.0.0.1:55578_solr) [n:127.0.0.1:55578_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 202119 INFO (coreLoadExecutor-612-thread-1-processing-n:127.0.0.1:55578_solr) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.4.1
[junit4] 2> 202134 INFO (coreLoadExecutor-612-thread-1-processing-n:127.0.0.1:55578_solr) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.s.IndexSchema [legacyTrue_shard1_replica_n1] Schema name=minimal
[junit4] 2> 202138 INFO (coreLoadExecutor-612-thread-1-processing-n:127.0.0.1:55578_solr) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 202138 INFO (coreLoadExecutor-612-thread-1-processing-n:127.0.0.1:55578_solr) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'legacyTrue_shard1_replica_n1' using configuration from collection legacyTrue, trusted=true
[junit4] 2> 202138 INFO (coreLoadExecutor-612-thread-1-processing-n:127.0.0.1:55578_solr) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55578.solr.core.legacyTrue.shard1.replica_n1' (registry 'solr.core.legacyTrue.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@34c33d67
[junit4] 2> 202138 INFO (coreLoadExecutor-612-thread-1-processing-n:127.0.0.1:55578_solr) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.SolrCore [[legacyTrue_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J2\temp\solr.cloud.LegacyCloudClusterPropTest_63D6FAA614B50A2D-001\tempDir-001\node1\legacyTrue_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J2\temp\solr.cloud.LegacyCloudClusterPropTest_63D6FAA614B50A2D-001\tempDir-001\node1\.\legacyTrue_shard1_replica_n1\data\]
[junit4] 2> 202194 INFO (coreLoadExecutor-612-thread-1-processing-n:127.0.0.1:55578_solr) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 202194 INFO (coreLoadExecutor-612-thread-1-processing-n:127.0.0.1:55578_solr) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 202194 INFO (coreLoadExecutor-612-thread-1-processing-n:127.0.0.1:55578_solr) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 202194 INFO (coreLoadExecutor-612-thread-1-processing-n:127.0.0.1:55578_solr) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 202198 INFO (coreLoadExecutor-612-thread-1-processing-n:127.0.0.1:55578_solr) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3a9156ed[legacyTrue_shard1_replica_n1] main]
[junit4] 2> 202198 INFO (coreLoadExecutor-612-thread-1-processing-n:127.0.0.1:55578_solr) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 202198 INFO (coreLoadExecutor-612-thread-1-processing-n:127.0.0.1:55578_solr) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 202198 INFO (coreLoadExecutor-612-thread-1-processing-n:127.0.0.1:55578_solr) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 202198 INFO (coreLoadExecutor-612-thread-1-processing-n:127.0.0.1:55578_solr) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1655667146642948096
[junit4] 2> 202202 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=1
[junit4] 2> 202202 INFO (searcherExecutor-613-thread-1-processing-n:127.0.0.1:55578_solr x:legacyTrue_shard1_replica_n1 c:legacyTrue s:shard1) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.SolrCore [legacyTrue_shard1_replica_n1] Registered new searcher Searcher@3a9156ed[legacyTrue_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 202206 INFO (coreZkRegister-600-thread-1-processing-n:127.0.0.1:55578_solr x:legacyTrue_shard1_replica_n1 c:legacyTrue s:shard1) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/legacyTrue/leaders/shard1
[junit4] 2> 202206 INFO (coreZkRegister-600-thread-1-processing-n:127.0.0.1:55578_solr x:legacyTrue_shard1_replica_n1 c:legacyTrue s:shard1) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 202206 INFO (coreZkRegister-600-thread-1-processing-n:127.0.0.1:55578_solr x:legacyTrue_shard1_replica_n1 c:legacyTrue s:shard1) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 202206 INFO (coreZkRegister-600-thread-1-processing-n:127.0.0.1:55578_solr x:legacyTrue_shard1_replica_n1 c:legacyTrue s:shard1) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:55578/solr/legacyTrue_shard1_replica_n1/
[junit4] 2> 202206 INFO (coreZkRegister-600-thread-1-processing-n:127.0.0.1:55578_solr x:legacyTrue_shard1_replica_n1 c:legacyTrue s:shard1) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 202206 INFO (coreZkRegister-600-thread-1-processing-n:127.0.0.1:55578_solr x:legacyTrue_shard1_replica_n1 c:legacyTrue s:shard1) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:55578/solr/legacyTrue_shard1_replica_n1/ has no replicas
[junit4] 2> 202206 INFO (coreZkRegister-600-thread-1-processing-n:127.0.0.1:55578_solr x:legacyTrue_shard1_replica_n1 c:legacyTrue s:shard1) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/legacyTrue/leaders/shard1/leader after winning as /collections/legacyTrue/leader_elect/shard1/election/72059230145740809-core_node2-n_0000000001
[junit4] 2> 202211 INFO (coreZkRegister-600-thread-1-processing-n:127.0.0.1:55578_solr x:legacyTrue_shard1_replica_n1 c:legacyTrue s:shard1) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:55578/solr/legacyTrue_shard1_replica_n1/ shard1
[junit4] 2> 202211 INFO (zkCallback-729-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/legacyTrue/state.json] for collection [legacyTrue] has occurred - updating... (live nodes size: [1])
[junit4] 2> 202211 INFO (coreZkRegister-600-thread-1-processing-n:127.0.0.1:55578_solr x:legacyTrue_shard1_replica_n1 c:legacyTrue s:shard1) [n:127.0.0.1:55578_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 202317 INFO (zkCallback-729-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/legacyTrue/state.json] for collection [legacyTrue] has occurred - updating... (live nodes size: [1])
[junit4] 2> 202317 INFO (zkCallback-729-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/legacyTrue/state.json] for collection [legacyTrue] has occurred - updating... (live nodes size: [1])
[junit4] 2> 202317 INFO (zkCallback-729-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/legacyTrue/state.json] for collection [legacyTrue] has occurred - updating... (live nodes size: [1])
[junit4] 2> 203208 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 203229 INFO (qtp1631125152-2338) [n:127.0.0.1:55578_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=legacyTrue&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 203233 INFO (OverseerThreadFactory-608-thread-1-processing-n:127.0.0.1:55578_solr) [n:127.0.0.1:55578_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true&deleteMetricsHistory=true, asyncId=null
[junit4] 2> 203250 INFO (qtp1631125152-2335) [n:127.0.0.1:55578_solr x:legacyTrue_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.legacyTrue.shard1.replica_n1, tag=null
[junit4] 2> 203250 INFO (qtp1631125152-2335) [n:127.0.0.1:55578_solr x:legacyTrue_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@232bcea: rootName = solr_55578, domain = solr.core.legacyTrue.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.legacyTrue.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@714e37ba
[junit4] 2> 203262 INFO (qtp1631125152-2335) [n:127.0.0.1:55578_solr x:legacyTrue_shard1_replica_n1 ] o.a.s.c.SolrCore [legacyTrue_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@76492ba8
[junit4] 2> 203262 INFO (qtp1631125152-2335) [n:127.0.0.1:55578_solr x:legacyTrue_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.legacyTrue.shard1.replica_n1, tag=SolrCore@76492ba8
[junit4] 2> 203262 INFO (qtp1631125152-2335) [n:127.0.0.1:55578_solr x:legacyTrue_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.legacyTrue.shard1.leader, tag=SolrCore@76492ba8
[junit4] 2> 203269 INFO (qtp1631125152-2335) [n:127.0.0.1:55578_solr x:legacyTrue_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/legacyTrue/terms/shard1 to Terms{values={}, version=1}
[junit4] 2> 203271 INFO (qtp1631125152-2335) [n:127.0.0.1:55578_solr x:legacyTrue_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&deleteMetricsHistory=true&core=legacyTrue_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=21
[junit4] 2> 203376 INFO (zkCallback-729-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/legacyTrue/state.json] for collection [legacyTrue] has occurred - updating... (live nodes size: [1])
[junit4] 2> 203377 INFO (zkCallback-729-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/legacyTrue/state.json] for collection [legacyTrue] has occurred - updating... (live nodes size: [1])
[junit4] 2> 203377 INFO (zkCallback-729-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/legacyTrue/state.json] for collection [legacyTrue] has occurred - updating... (live nodes size: [1])
[junit4] 2> 203386 INFO (qtp1631125152-2338) [n:127.0.0.1:55578_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=legacyTrue&action=DELETE&wt=javabin&version=2} status=0 QTime=157
[junit4] 2> 203386 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[63D6FAA614B50A2D]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCreateCollectionSwitchLegacyCloud
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=LegacyCloudClusterPropTest -Dtests.method=testCreateCollectionSwitchLegacyCloud -Dtests.seed=63D6FAA614B50A2D -Dtests.slow=true -Dtests.locale=ha-NG -Dtests.timezone=America/Jamaica -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 3.54s J2 | LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: IOException occurred when talking to server at: https://127.0.0.1:55578/solr
[junit4] > at __randomizedtesting.SeedInfo.seed([63D6FAA614B50A2D:B2D10823B0BA811F]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:679)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:265)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
[junit4] > at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:368)
[junit4] > at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:296)
[junit4] > at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1143)
[junit4] > at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:906)
[junit4] > at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:838)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:228)
[junit4] > at org.apache.solr.cloud.LegacyCloudClusterPropTest.createAndTest(LegacyCloudClusterPropTest.java:87)
[junit4] > at org.apache.solr.cloud.LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud(LegacyCloudClusterPropTest.java:79)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:567)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:835)
[junit4] > Caused by: javax.net.ssl.SSLException: Software caused connection abort: recv failed
[junit4] > at java.base/sun.security.ssl.Alert.createSSLException(Alert.java:127)
[junit4] > at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:320)
[junit4] > at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:263)
[junit4] > at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:258)
[junit4] > at java.base/sun.security.ssl.SSLSocketImpl.handleException(SSLSocketImpl.java:1342)
[junit4] > at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:844)
[junit4] > at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
[junit4] > at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
[junit4] > at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:282)
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
[junit4] > at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
[junit4] > at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
[junit4] > at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:165)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
[junit4] > at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
[junit4] > at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185)
[junit4] > at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
[junit4] > at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
[junit4] > at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:564)
[junit4] > ... 49 more
[junit4] > Suppressed: java.net.SocketException: Software caused connection abort: socket write error
[junit4] > at java.base/java.net.SocketOutputStream.socketWrite0(Native Method)
[junit4] > at java.base/java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:110)
[junit4] > at java.base/java.net.SocketOutputStream.write(SocketOutputStream.java:150)
[junit4] > at java.base/sun.security.ssl.SSLSocketOutputRecord.encodeAlert(SSLSocketOutputRecord.java:81)
[junit4] > at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:351)
[junit4] > ... 71 more
[junit4] > Caused by: java.net.SocketException: Software caused connection abort: recv failed
[junit4] > at java.base/java.net.SocketInputStream.socketRead0(Native Method)
[junit4] > at java.base/java.net.SocketInputStream.socketRead(SocketInputStream.java:115)
[junit4] > at java.base/java.net.SocketInputStream.read(SocketInputStream.java:168)
[junit4] > at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
[junit4] > at java.base/sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:448)
[junit4] > at java.base/sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:68)
[junit4] > at java.base/sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1132)
[junit4] > at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:828)
[junit4] > ... 67 more
[junit4] 2> 203494 INFO (jetty-closer-738-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=581121549
[junit4] 2> 203494 INFO (jetty-closer-738-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:55578_solr
[junit4] 2> 203494 INFO (jetty-closer-738-thread-1) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 203494 INFO (jetty-closer-738-thread-1) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:55578_solr as DOWN
[junit4] 2> 203497 INFO (jetty-closer-738-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 203497 INFO (jetty-closer-738-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5bc23f89: rootName = solr_55578, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@6709273
[junit4] 2> 203502 INFO (jetty-closer-738-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 203502 INFO (jetty-closer-738-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3115a286: rootName = solr_55578, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@552046a9
[junit4] 2> 203504 INFO (jetty-closer-738-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 203504 INFO (jetty-closer-738-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@26532801: rootName = solr_55578, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@1e916b6b
[junit4] 2> 203504 INFO (jetty-closer-738-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 203506 INFO (closeThreadPool-742-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72059230145740809-127.0.0.1:55578_solr-n_0000000001) closing
[junit4] 2> 203506 INFO (OverseerStateUpdate-72059230145740809-127.0.0.1:55578_solr-n_0000000001) [n:127.0.0.1:55578_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:55578_solr
[junit4] 2> 203508 INFO (OverseerAutoScalingTriggerThread-72059230145740809-127.0.0.1:55578_solr-n_0000000001) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 203510 INFO (closeThreadPool-742-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72059230145740809-127.0.0.1:55578_solr-n_0000000001) closing
[junit4] 2> 203611 INFO (jetty-closer-738-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72059230145740809-127.0.0.1:55578_solr-n_0000000001) closing
[junit4] 2> 203613 INFO (jetty-closer-738-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@25b4ab23{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:55578}
[junit4] 2> 203614 INFO (jetty-closer-738-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@31cbbecd{/solr,null,UNAVAILABLE}
[junit4] 2> 203614 INFO (jetty-closer-738-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 203625 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 203838 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/aliases.json
[junit4] 2> 3 /solr/collections/legacyTrue/terms/shard1
[junit4] 2> 3 /solr/collections/legacyTrue/state.json
[junit4] 2> 2 /solr/packages.json
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/configs/conf
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 16 /solr/collections/legacyTrue/state.json
[junit4] 2> 6 /solr/clusterprops.json
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2> 3 /solr/aliases.json
[junit4] 2> 2 /solr/autoscaling.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 20 /solr/overseer/queue
[junit4] 2> 9 /solr/collections
[junit4] 2> 8 /solr/live_nodes
[junit4] 2> 8 /solr/overseer/collection-queue-work
[junit4] 2> 2 /solr/autoscaling/events/.scheduled_maintenance
[junit4] 2> 2 /solr/autoscaling/events/.auto_add_replicas
[junit4] 2> 2 /solr/overseer/queue-work
[junit4] 2>
[junit4] 2> 203843 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:55565
[junit4] 2> 203843 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:55565
[junit4] 2> 203843 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 55565
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J2\temp\solr.cloud.LegacyCloudClusterPropTest_63D6FAA614B50A2D-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {}, docValues:{}, maxPointsInLeafNode=1041, maxMBSortInHeap=7.353578990881287, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@7e79797b), locale=ha-NG, timezone=America/Jamaica
[junit4] 2> NOTE: Windows 10 10.0 amd64/AdoptOpenJDK 12.0.1 (64-bit)/cpus=6,threads=1,free=130030224,total=270811136
[junit4] 2> NOTE: All tests run in this JVM: [ConfigSetsAPITest, BadCopyFieldTest, HighlighterConfigTest, SolrShardReporterTest, FacetPivotSmallTest, DistributedDebugComponentTest, DeleteLastCustomShardedReplicaTest, TestSlowCompositeReaderWrapper, RAMDirectoryFactoryTest, HdfsCollectionsAPIDistributedZkTest, HttpPartitionOnCommitTest, HLLSerializationTest, DataDrivenBlockJoinTest, TestSolrDeletionPolicy1, TestRandomCollapseQParserPlugin, NoCacheHeaderTest, PackageManagerCLITest, NestedShardedAtomicUpdateTest, TestTrackingShardHandlerFactory, BJQFilterAccessibleTest, SystemInfoHandlerTest, LegacyCloudClusterPropTest]
[junit4] Completed [65/889 (1!)] on J2 in 7.31s, 1 test, 1 error <<< FAILURES!
[...truncated 47742 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.4-Windows/239/consoleText
[repro] Revision: 832bf13dd9187095831caf69783179d41059d013
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseSerialGC"
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr\core
[repro] LegacyCloudClusterPropTest
[repro] ant compile-test
[...truncated 3626 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.LegacyCloudClusterPropTest" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=63D6FAA614B50A2D -Dtests.slow=true -Dtests.locale=ha-NG -Dtests.timezone=America/Jamaica -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[...truncated 35 lines...]
[junit4] Suite: org.apache.solr.cloud.LegacyCloudClusterPropTest
[junit4] 2> 11437 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 11450 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.LegacyCloudClusterPropTest_63D6FAA614B50A2D-001\data-dir-1-001
[junit4] 2> 11616 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 11670 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.e.j.u.log Logging initialized @11701ms to org.eclipse.jetty.util.log.Slf4jLog
[junit4] 2> 11680 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 11860 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.LegacyCloudClusterPropTest_63D6FAA614B50A2D-001\tempDir-001
[junit4] 2> 11871 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 11873 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 11873 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 12091 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:57454
[junit4] 2> 12091 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:57454
[junit4] 2> 12098 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:57454
[junit4] 2> 12099 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 57454
[junit4] 2> 12336 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 12387 INFO (zkConnectionManagerCallback-3-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 12388 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 12440 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 12461 INFO (zkConnectionManagerCallback-5-thread-1) [ ] o.a.s.c.c.Connecti
[...truncated too long message...]
te(SocketOutputStream.java:150)
[junit4] > at java.base/sun.security.ssl.SSLSocketOutputRecord.encodeAlert(SSLSocketOutputRecord.java:81)
[junit4] > at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:351)
[junit4] > ... 71 more
[junit4] > Caused by: java.net.SocketException: Software caused connection abort: recv failed
[junit4] > at java.base/java.net.SocketInputStream.socketRead0(Native Method)
[junit4] > at java.base/java.net.SocketInputStream.socketRead(SocketInputStream.java:115)
[junit4] > at java.base/java.net.SocketInputStream.read(SocketInputStream.java:168)
[junit4] > at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
[junit4] > at java.base/sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:448)
[junit4] > at java.base/sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:68)
[junit4] > at java.base/sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1132)
[junit4] > at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:828)
[junit4] > ... 67 more
[junit4] 2> 25003 INFO (jetty-closer-52-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2143874791
[junit4] 2> 25003 INFO (jetty-closer-52-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:57551_solr
[junit4] 2> 25004 INFO (jetty-closer-52-thread-1) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 25004 INFO (jetty-closer-52-thread-1) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:57551_solr as DOWN
[junit4] 2> 25007 INFO (jetty-closer-52-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 25007 INFO (jetty-closer-52-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5d12627e: rootName = solr_57551, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@3a4e0681
[junit4] 2> 25014 INFO (jetty-closer-52-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 25019 INFO (jetty-closer-52-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@74b0eb48: rootName = solr_57551, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@15e16c7f
[junit4] 2> 25022 INFO (jetty-closer-52-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 25022 INFO (jetty-closer-52-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@141c79de: rootName = solr_57551, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@7f181988
[junit4] 2> 25022 INFO (jetty-closer-52-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 25022 INFO (closeThreadPool-56-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72059437139886089-127.0.0.1:57551_solr-n_0000000001) closing
[junit4] 2> 25022 INFO (OverseerAutoScalingTriggerThread-72059437139886089-127.0.0.1:57551_solr-n_0000000001) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 25022 INFO (OverseerStateUpdate-72059437139886089-127.0.0.1:57551_solr-n_0000000001) [n:127.0.0.1:57551_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:57551_solr
[junit4] 2> 25026 INFO (closeThreadPool-56-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72059437139886089-127.0.0.1:57551_solr-n_0000000001) closing
[junit4] 2> 25126 INFO (jetty-closer-52-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72059437139886089-127.0.0.1:57551_solr-n_0000000001) closing
[junit4] 2> 25129 INFO (jetty-closer-52-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@8136cdd{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:57551}
[junit4] 2> 25129 INFO (jetty-closer-52-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@b7b2f8b{/solr,null,UNAVAILABLE}
[junit4] 2> 25129 INFO (jetty-closer-52-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 25130 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 25335 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/aliases.json
[junit4] 2> 4 /solr/collections/legacyTrue/terms/shard1
[junit4] 2> 3 /solr/collections/legacyTrue/state.json
[junit4] 2> 2 /solr/packages.json
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/configs/conf
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 15 /solr/collections/legacyTrue/state.json
[junit4] 2> 6 /solr/clusterprops.json
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2> 3 /solr/aliases.json
[junit4] 2> 2 /solr/autoscaling.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 20 /solr/overseer/queue
[junit4] 2> 9 /solr/collections
[junit4] 2> 9 /solr/overseer/collection-queue-work
[junit4] 2> 8 /solr/live_nodes
[junit4] 2> 2 /solr/autoscaling/events/.scheduled_maintenance
[junit4] 2> 2 /solr/autoscaling/events/.auto_add_replicas
[junit4] 2> 2 /solr/overseer/queue-work
[junit4] 2>
[junit4] 2> 25339 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:57477
[junit4] 2> 25339 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:57477
[junit4] 2> 25339 INFO (SUITE-LegacyCloudClusterPropTest-seed#[63D6FAA614B50A2D]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 57477
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.LegacyCloudClusterPropTest_63D6FAA614B50A2D-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {}, docValues:{}, maxPointsInLeafNode=1041, maxMBSortInHeap=7.353578990881287, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@529af38c), locale=ha-NG, timezone=America/Jamaica
[junit4] 2> NOTE: Windows 10 10.0 amd64/AdoptOpenJDK 12.0.1 (64-bit)/cpus=6,threads=2,free=12789544,total=97452032
[junit4] 2> NOTE: All tests run in this JVM: [LegacyCloudClusterPropTest]
[junit4] Completed [5/5 (5!)] on J3 in 25.85s, 1 test, 1 error <<< FAILURES!
[...truncated 17 lines...]
BUILD FAILED
C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\lucene\common-build.xml:1591: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows\lucene\common-build.xml:1117: There were test failures: 5 suites, 5 tests, 5 errors [seed: 63D6FAA614B50A2D]
Total time: 30 seconds
[repro] Setting last failure code to 1
[repro] Failures w/original seeds:
[repro] 5/5 failed: org.apache.solr.cloud.LegacyCloudClusterPropTest
[repro] Exiting with code 1
C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows>IF EXIST lucene\build move /y lucene\build lucene\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows>IF EXIST solr\build move /y solr\build solr\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows>IF EXIST lucene\build.orig move /y lucene\build.orig lucene\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows>IF EXIST solr\build.orig move /y solr\build.orig solr\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows>exit 0
Archiving artifacts
Setting ANT_1_8_2_HOME=C:\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=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
No credentials specified
Setting ANT_1_8_2_HOME=C:\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=832bf13dd9187095831caf69783179d41059d013, workspace=C:\Users\jenkins\workspace\Lucene-Solr-8.4-Windows
[WARNINGS] Computing warning deltas based on reference build #237
Recording test results
Setting ANT_1_8_2_HOME=C:\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=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2