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/04/26 00:01:59 UTC
[JENKINS] Lucene-Solr-8.x-MacOSX (64bit/jdk1.8.0) - Build # 896 -
Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/896/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
3 tests failed.
FAILED: org.apache.solr.cloud.MultiThreadedOCPTest.test
Error Message:
acoll: 1587856850953 bcoll: 1587856853773
Stack Trace:
java.lang.AssertionError: acoll: 1587856850953 bcoll: 1587856853773
at __randomizedtesting.SeedInfo.seed([2C43E0ABEDB33B30:A417DF71434F56C8]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.cloud.MultiThreadedOCPTest.testFillWorkQueue(MultiThreadedOCPTest.java:113)
at org.apache.solr.cloud.MultiThreadedOCPTest.test(MultiThreadedOCPTest.java:68)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1081)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1053)
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.cloud.autoscaling.ExecutePlanActionTest.testTaskTimeout
Error Message:
did not finish processing event in time
Stack Trace:
java.lang.AssertionError: did not finish processing event in time
at __randomizedtesting.SeedInfo.seed([2C43E0ABEDB33B30:291ED84170DBF9A7]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.cloud.autoscaling.ExecutePlanActionTest.testTaskTimeout(ExecutePlanActionTest.java:313)
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.cloud.autoscaling.SearchRateTriggerTest.testWaitForElapsed
Error Message:
[]
Stack Trace:
java.lang.AssertionError: []
at __randomizedtesting.SeedInfo.seed([2C43E0ABEDB33B30:C44FD2BA7CCF0932]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.cloud.autoscaling.SearchRateTriggerTest.testWaitForElapsed(SearchRateTriggerTest.java:263)
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 13491 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.ExecutePlanActionTest
[junit4] 2> 222345 INFO (SUITE-ExecutePlanActionTest-seed#[2C43E0ABEDB33B30]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/server/solr/configsets/_default/conf'
[junit4] 2> 222346 INFO (SUITE-ExecutePlanActionTest-seed#[2C43E0ABEDB33B30]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ExecutePlanActionTest_2C43E0ABEDB33B30-001/data-dir-17-001
[junit4] 2> 222346 INFO (SUITE-ExecutePlanActionTest-seed#[2C43E0ABEDB33B30]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 222350 INFO (SUITE-ExecutePlanActionTest-seed#[2C43E0ABEDB33B30]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 222350 INFO (SUITE-ExecutePlanActionTest-seed#[2C43E0ABEDB33B30]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 222352 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testTaskFail
[junit4] 2> 222353 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ExecutePlanActionTest_2C43E0ABEDB33B30-001/tempDir-001
[junit4] 2> 222353 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 222354 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 222354 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 222456 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.c.ZkTestServer start zk server on port:61660
[junit4] 2> 222456 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:61660
[junit4] 2> 222456 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:61660
[junit4] 2> 222456 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 61660
[junit4] 2> 222458 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 222463 INFO (zkConnectionManagerCallback-1077-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 222463 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 222467 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 222470 INFO (zkConnectionManagerCallback-1079-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 222470 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 222471 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 222473 INFO (zkConnectionManagerCallback-1081-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 222473 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 222593 WARN (jetty-launcher-1082-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 222593 WARN (jetty-launcher-1082-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 222593 INFO (jetty-launcher-1082-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 222593 INFO (jetty-launcher-1082-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 222593 INFO (jetty-launcher-1082-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 222593 INFO (jetty-launcher-1082-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 222593 INFO (jetty-launcher-1082-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 1.8.0_201-b09
[junit4] 2> 222593 INFO (jetty-launcher-1082-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 1.8.0_201-b09
[junit4] 2> 222601 INFO (jetty-launcher-1082-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 222601 INFO (jetty-launcher-1082-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 222601 INFO (jetty-launcher-1082-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 222601 INFO (jetty-launcher-1082-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 222601 INFO (jetty-launcher-1082-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 222601 INFO (jetty-launcher-1082-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 222602 INFO (jetty-launcher-1082-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7c8a5a19{/solr,null,AVAILABLE}
[junit4] 2> 222602 INFO (jetty-launcher-1082-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@227a4672{/solr,null,AVAILABLE}
[junit4] 2> 222602 INFO (jetty-launcher-1082-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2e0a51fa{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:61668}
[junit4] 2> 222602 INFO (jetty-launcher-1082-thread-2) [ ] o.e.j.s.Server Started @222689ms
[junit4] 2> 222603 INFO (jetty-launcher-1082-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=61668}
[junit4] 2> 222603 ERROR (jetty-launcher-1082-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 222603 INFO (jetty-launcher-1082-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 222603 INFO (jetty-launcher-1082-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 222603 INFO (jetty-launcher-1082-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 222603 INFO (jetty-launcher-1082-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 222603 INFO (jetty-launcher-1082-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-04-25T22:58:51.621Z
[junit4] 2> 222604 INFO (jetty-launcher-1082-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 222604 INFO (jetty-launcher-1082-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@30560b4a{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:61667}
[junit4] 2> 222604 INFO (jetty-launcher-1082-thread-1) [ ] o.e.j.s.Server Started @222690ms
[junit4] 2> 222604 INFO (jetty-launcher-1082-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=61667}
[junit4] 2> 222604 ERROR (jetty-launcher-1082-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 222604 INFO (jetty-launcher-1082-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 222604 INFO (jetty-launcher-1082-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 222604 INFO (jetty-launcher-1082-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 222604 INFO (jetty-launcher-1082-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 222604 INFO (jetty-launcher-1082-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-04-25T22:58:51.622Z
[junit4] 2> 222605 INFO (jetty-launcher-1082-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 222609 INFO (zkConnectionManagerCallback-1086-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 222609 INFO (jetty-launcher-1082-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 222609 INFO (zkConnectionManagerCallback-1084-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 222609 INFO (jetty-launcher-1082-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 222611 INFO (jetty-launcher-1082-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 222611 INFO (jetty-launcher-1082-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 222918 INFO (jetty-launcher-1082-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 222920 WARN (jetty-launcher-1082-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@24a6befa[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 222920 WARN (jetty-launcher-1082-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@24a6befa[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 222927 WARN (jetty-launcher-1082-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@68af1a3f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 222927 WARN (jetty-launcher-1082-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@68af1a3f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 222928 INFO (jetty-launcher-1082-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61660/solr
[junit4] 2> 222928 INFO (jetty-launcher-1082-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 222929 INFO (jetty-launcher-1082-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 222930 WARN (jetty-launcher-1082-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@442e1a5c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 222930 WARN (jetty-launcher-1082-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@442e1a5c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 222932 INFO (zkConnectionManagerCallback-1101-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 222932 INFO (jetty-launcher-1082-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 222934 WARN (jetty-launcher-1082-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@39515d3c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 222934 WARN (jetty-launcher-1082-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@39515d3c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 222935 INFO (jetty-launcher-1082-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61660/solr
[junit4] 2> 222937 INFO (jetty-launcher-1082-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 222940 INFO (zkConnectionManagerCallback-1108-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 222941 INFO (jetty-launcher-1082-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 223044 INFO (jetty-launcher-1082-thread-2) [n:127.0.0.1:61668_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 223047 INFO (zkConnectionManagerCallback-1110-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 223047 INFO (jetty-launcher-1082-thread-2) [n:127.0.0.1:61668_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 223147 INFO (jetty-launcher-1082-thread-1) [n:127.0.0.1:61667_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 223150 INFO (zkConnectionManagerCallback-1114-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 223150 INFO (jetty-launcher-1082-thread-1) [n:127.0.0.1:61667_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 223186 INFO (jetty-launcher-1082-thread-1) [n:127.0.0.1:61667_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:61667_solr
[junit4] 2> 223188 INFO (jetty-launcher-1082-thread-1) [n:127.0.0.1:61667_solr ] o.a.s.c.Overseer Overseer (id=72110765771718664-127.0.0.1:61667_solr-n_0000000000) starting
[junit4] 2> 223212 INFO (OverseerStateUpdate-72110765771718664-127.0.0.1:61667_solr-n_0000000000) [n:127.0.0.1:61667_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:61667_solr
[junit4] 2> 223215 INFO (jetty-launcher-1082-thread-1) [n:127.0.0.1:61667_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61667_solr
[junit4] 2> 223216 DEBUG (OverseerAutoScalingTriggerThread-72110765771718664-127.0.0.1:61667_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 223224 DEBUG (OverseerAutoScalingTriggerThread-72110765771718664-127.0.0.1:61667_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 223225 DEBUG (OverseerAutoScalingTriggerThread-72110765771718664-127.0.0.1:61667_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 223225 DEBUG (OverseerAutoScalingTriggerThread-72110765771718664-127.0.0.1:61667_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 223226 INFO (OverseerStateUpdate-72110765771718664-127.0.0.1:61667_solr-n_0000000000) [n:127.0.0.1:61667_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 223237 INFO (jetty-launcher-1082-thread-1) [n:127.0.0.1:61667_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 223240 DEBUG (OverseerAutoScalingTriggerThread-72110765771718664-127.0.0.1:61667_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:61667_solr]
[junit4] 2> 223241 WARN (jetty-launcher-1082-thread-1) [n:127.0.0.1:61667_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=you make it. Consider configuring authentication/authorization before exposing Solr to users internal or authorization=external. See https://s.apache.org/solrsecurity for more info. Solr is only as secure as disableddisabled
[junit4] 2> 223241 DEBUG (OverseerAutoScalingTriggerThread-72110765771718664-127.0.0.1:61667_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 223243 DEBUG (OverseerAutoScalingTriggerThread-72110765771718664-127.0.0.1:61667_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 223243 DEBUG (ScheduledTrigger-1119-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 223272 INFO (jetty-launcher-1082-thread-2) [n:127.0.0.1:61668_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 223287 INFO (jetty-launcher-1082-thread-2) [n:127.0.0.1:61668_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:61668_solr as DOWN
[junit4] 2> 223289 INFO (jetty-launcher-1082-thread-2) [n:127.0.0.1:61668_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 223289 INFO (jetty-launcher-1082-thread-2) [n:127.0.0.1:61668_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61668_solr
[junit4] 2> 223295 INFO (zkCallback-1113-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 223296 INFO (zkCallback-1109-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 223300 INFO (jetty-launcher-1082-thread-2) [n:127.0.0.1:61668_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 223300 WARN (jetty-launcher-1082-thread-2) [n:127.0.0.1:61668_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=you make it. Consider configuring authentication/authorization before exposing Solr to users internal or authorization=external. See https://s.apache.org/solrsecurity for more info. Solr is only as secure as disableddisabled
[junit4] 2> 223311 INFO (jetty-launcher-1082-thread-1) [n:127.0.0.1:61667_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 223322 INFO (jetty-launcher-1082-thread-2) [n:127.0.0.1:61668_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 223388 INFO (jetty-launcher-1082-thread-1) [n:127.0.0.1:61667_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61667.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@304423bb
[junit4] 2> 223395 INFO (jetty-launcher-1082-thread-2) [n:127.0.0.1:61668_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61668.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@304423bb
[junit4] 2> 223400 INFO (jetty-launcher-1082-thread-1) [n:127.0.0.1:61667_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61667.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@304423bb
[junit4] 2> 223400 INFO (jetty-launcher-1082-thread-1) [n:127.0.0.1:61667_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61667.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@304423bb
[junit4] 2> 223402 INFO (jetty-launcher-1082-thread-1) [n:127.0.0.1:61667_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ExecutePlanActionTest_2C43E0ABEDB33B30-001/tempDir-001/node1/.
[junit4] 2> 223405 INFO (jetty-launcher-1082-thread-2) [n:127.0.0.1:61668_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61668.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@304423bb
[junit4] 2> 223405 INFO (jetty-launcher-1082-thread-2) [n:127.0.0.1:61668_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61668.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@304423bb
[junit4] 2> 223406 INFO (jetty-launcher-1082-thread-2) [n:127.0.0.1:61668_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ExecutePlanActionTest_2C43E0ABEDB33B30-001/tempDir-001/node2/.
[junit4] 2> 223435 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 223437 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 223442 INFO (zkConnectionManagerCallback-1137-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 223442 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 223444 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 223447 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:61660/solr ready
[junit4] 2> 223459 DEBUG (zkCallback-1113-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 223459 DEBUG (OverseerAutoScalingTriggerThread-72110765771718664-127.0.0.1:61667_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 223466 DEBUG (OverseerAutoScalingTriggerThread-72110765771718664-127.0.0.1:61667_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 223467 DEBUG (OverseerAutoScalingTriggerThread-72110765771718664-127.0.0.1:61667_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 223540 INFO (qtp1049748200-1709) [n:127.0.0.1:61667_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 223543 INFO (qtp550349715-1712) [n:127.0.0.1:61668_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 223543 DEBUG (qtp1049748200-1706) [n:127.0.0.1:61667_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 223544 INFO (qtp1049748200-1706) [n:127.0.0.1:61667_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=79
[junit4] 2> 223545 DEBUG (zkCallback-1113-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3
[junit4] 2> 223546 DEBUG (OverseerAutoScalingTriggerThread-72110765771718664-127.0.0.1:61667_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 223546 INFO (qtp1049748200-1705) [n:127.0.0.1:61667_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&maxShardsPerNode=1&name=testTaskFail&nrtReplicas=2&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 223550 DEBUG (OverseerAutoScalingTriggerThread-72110765771718664-127.0.0.1:61667_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger node_lost_trigger3 - Initial livenodes: [127.0.0.1:61667_solr, 127.0.0.1:61668_solr]
[junit4] 2> 223551 DEBUG (OverseerAutoScalingTriggerThread-72110765771718664-127.0.0.1:61667_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 223553 DEBUG (OverseerAutoScalingTriggerThread-72110765771718664-127.0.0.1:61667_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 223553 DEBUG (ScheduledTrigger-1119-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger3 with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 223554 INFO (OverseerThreadFactory-1121-thread-1-processing-n:127.0.0.1:61667_solr) [n:127.0.0.1:61667_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testTaskFail
[junit4] 2> 223734 INFO (OverseerStateUpdate-72110765771718664-127.0.0.1:61667_solr-n_0000000000) [n:127.0.0.1:61667_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testTaskFail",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testTaskFail_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:61667/solr",
[junit4] 2> "node_name":"127.0.0.1:61667_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 223745 INFO (OverseerStateUpdate-72110765771718664-127.0.0.1:61667_solr-n_0000000000) [n:127.0.0.1:61667_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testTaskFail",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testTaskFail_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:61668/solr",
[junit4] 2> "node_name":"127.0.0.1:61668_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 223951 INFO (qtp1049748200-1707) [n:127.0.0.1:61667_solr x:testTaskFail_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=testTaskFail_shard1_replica_n1&action=CREATE&numShards=1&collection=testTaskFail&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 223951 INFO (qtp550349715-1714) [n:127.0.0.1:61668_solr x:testTaskFail_shard1_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=testTaskFail_shard1_replica_n2&action=CREATE&numShards=1&collection=testTaskFail&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 223952 INFO (qtp1049748200-1707) [n:127.0.0.1:61667_solr x:testTaskFail_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 223984 INFO (qtp1049748200-1707) [n:127.0.0.1:61667_solr c:testTaskFail s:shard1 r:core_node3 x:testTaskFail_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 223985 INFO (qtp550349715-1714) [n:127.0.0.1:61668_solr c:testTaskFail s:shard1 r:core_node4 x:testTaskFail_shard1_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 224003 INFO (qtp550349715-1714) [n:127.0.0.1:61668_solr c:testTaskFail s:shard1 r:core_node4 x:testTaskFail_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 224004 INFO (qtp1049748200-1707) [n:127.0.0.1:61667_solr c:testTaskFail s:shard1 r:core_node3 x:testTaskFail_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 224008 INFO (qtp550349715-1714) [n:127.0.0.1:61668_solr c:testTaskFail s:shard1 r:core_node4 x:testTaskFail_shard1_replica_n2 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 224008 INFO (qtp1049748200-1707) [n:127.0.0.1:61667_solr c:testTaskFail s:shard1 r:core_node3 x:testTaskFail_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 224008 INFO (qtp1049748200-1707) [n:127.0.0.1:61667_solr c:testTaskFail s:shard1 r:core_node3 x:testTaskFail_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'testTaskFail_shard1_replica_n1' using configuration from configset conf, trusted=true
[junit4] 2> 224008 INFO (qtp550349715-1714) [n:127.0.0.1:61668_solr c:testTaskFail s:shard1 r:core_node4 x:testTaskFail_shard1_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'testTaskFail_shard1_replica_n2' using configuration from configset conf, trusted=true
[junit4] 2> 224008 INFO (qtp550349715-1714) [n:127.0.0.1:61668_solr c:testTaskFail s:shard1 r:core_node4 x:testTaskFail_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61668.solr.core.testTaskFail.shard1.replica_n2' (registry 'solr.core.testTaskFail.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@304423bb
[junit4] 2> 224008 INFO (qtp1049748200-1707) [n:127.0.0.1:61667_solr c:testTaskFail s:shard1 r:core_node3 x:testTaskFail_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61667.solr.core.testTaskFail.shard1.replica_n1' (registry 'solr.core.testTaskFail.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@304423bb
[junit4] 2> 224008 INFO (qtp550349715-1714) [n:127.0.0.1:61668_solr c:testTaskFail s:shard1 r:core_node4 x:testTaskFail_shard1_replica_n2 ] o.a.s.c.SolrCore [[testTaskFail_shard1_replica_n2] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ExecutePlanActionTest_2C43E0ABEDB33B30-001/tempDir-001/node2/./testTaskFail_shard1_replica_n2], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ExecutePlanActionTest_2C43E0ABEDB33B30-001/tempDir-001/node2/./testTaskFail_shard1_replica_n2/data/]
[junit4] 2> 224008 INFO (qtp1049748200-1707) [n:127.0.0.1:61667_solr c:testTaskFail s:shard1 r:core_node3 x:testTaskFail_shard1_replica_n1 ] o.a.s.c.SolrCore [[testTaskFail_shard1_replica_n1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ExecutePlanActionTest_2C43E0ABEDB33B30-001/tempDir-001/node1/./testTaskFail_shard1_replica_n1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ExecutePlanActionTest_2C43E0ABEDB33B30-001/tempDir-001/node1/./testTaskFail_shard1_replica_n1/data/]
[junit4] 2> 224104 INFO (qtp1049748200-1707) [n:127.0.0.1:61667_solr c:testTaskFail s:shard1 r:core_node3 x:testTaskFail_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 224104 INFO (qtp1049748200-1707) [n:127.0.0.1:61667_solr c:testTaskFail s:shard1 r:core_node3 x:testTaskFail_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 224105 INFO (qtp1049748200-1707) [n:127.0.0.1:61667_solr c:testTaskFail s:shard1 r:core_node3 x:testTaskFail_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 224105 INFO (qtp1049748200-1707) [n:127.0.0.1:61667_solr c:testTaskFail s:shard1 r:core_node3 x:testTaskFail_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 224106 INFO (qtp1049748200-1707) [n:127.0.0.1:61667_solr c:testTaskFail s:shard1 r:core_node3 x:testTaskFail_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@a8a5836[testTaskFail_shard1_replica_n1] main]
[junit4] 2> 224107 INFO (qtp550349715-1714) [n:127.0.0.1:61668_solr c:testTaskFail s:shard1 r:core_node4 x:testTaskFail_shard1_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 224107 INFO (qtp550349715-1714) [n:127.0.0.1:61668_solr c:testTaskFail s:shard1 r:core_node4 x:testTaskFail_shard1_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 224108 INFO (qtp1049748200-1707) [n:127.0.0.1:61667_solr c:testTaskFail s:shard1 r:core_node3 x:testTaskFail_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 224109 INFO (qtp1049748200-1707) [n:127.0.0.1:61667_solr c:testTaskFail s:shard1 r:core_node3 x:testTaskFail_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 224110 INFO (qtp550349715-1714) [n:127.0.0.1:61668_solr c:testTaskFail s:shard1 r:core_node4 x:testTaskFail_shard1_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 224110 INFO (qtp1049748200-1707) [n:127.0.0.1:61667_solr c:testTaskFail s:shard1 r:core_node3 x:testTaskFail_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 224110 INFO (qtp1049748200-1707) [n:127.0.0.1:61667_solr c:testTaskFail s:shard1 r:core_node3 x:testTaskFail_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1664987203505225728
[junit4] 2> 224110 INFO (qtp550349715-1714) [n:127.0.0.1:61668_solr c:testTaskFail s:shard1 r:core_node4 x:testTaskFail_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 224111 INFO (qtp550349715-1714) [n:127.0.0.1:61668_solr c:testTaskFail s:shard1 r:core_node4 x:testTaskFail_shard1_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2de244aa[testTaskFail_shard1_replica_n2] main]
[junit4] 2> 224113 INFO (qtp550349715-1714) [n:127.0.0.1:61668_solr c:testTaskFail s:shard1 r:core_node4 x:testTaskFail_shard1_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 224116 INFO (searcherExecutor-1141-thread-1-processing-n:127.0.0.1:61667_solr x:testTaskFail_shard1_replica_n1 c:testTaskFail s:shard1 r:core_node3) [n:127.0.0.1:61667_solr c:testTaskFail s:shard1 r:core_node3 x:testTaskFail_shard1_replica_n1 ] o.a.s.c.SolrCore [testTaskFail_shard1_replica_n1] Registered new searcher Searcher@a8a5836[testTaskFail_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 224116 INFO (qtp550349715-1714) [n:127.0.0.1:61668_solr c:testTaskFail s:shard1 r:core_node4 x:testTaskFail_shard1_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 224116 INFO (qtp550349715-1714) [n:127.0.0.1:61668_solr c:testTaskFail s:shard1 r:core_node4 x:testTaskFail_shard1_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 224117 INFO (qtp550349715-1714) [n:127.0.0.1:61668_solr c:testTaskFail s:shard1 r:core_node4 x:testTaskFail_shard1_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1664987203512565760
[junit4] 2> 224123 INFO (searcherExecutor-1140-thread-1-processing-n:127.0.0.1:61668_solr x:testTaskFail_shard1_replica_n2 c:testTaskFail s:shard1 r:core_node4) [n:127.0.0.1:61668_solr c:testTaskFail s:shard1 r:core_node4 x:testTaskFail_shard1_replica_n2 ] o.a.s.c.SolrCore [testTaskFail_shard1_replica_n2] Registered new searcher Searcher@2de244aa[testTaskFail_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 224128 INFO (qtp1049748200-1707) [n:127.0.0.1:61667_solr c:testTaskFail s:shard1 r:core_node3 x:testTaskFail_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testTaskFail/terms/shard1 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 224128 INFO (qtp1049748200-1707) [n:127.0.0.1:61667_solr c:testTaskFail s:shard1 r:core_node3 x:testTaskFail_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testTaskFail/leaders/shard1
[junit4] 2> 224148 INFO (qtp550349715-1714) [n:127.0.0.1:61668_solr c:testTaskFail s:shard1 r:core_node4 x:testTaskFail_shard1_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testTaskFail/terms/shard1 to Terms{values={core_node3=0, core_node4=0}, version=1}
[junit4] 2> 224148 INFO (qtp550349715-1714) [n:127.0.0.1:61668_solr c:testTaskFail s:shard1 r:core_node4 x:testTaskFail_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testTaskFail/leaders/shard1
[junit4] 2> 224161 INFO (qtp1049748200-1707) [n:127.0.0.1:61667_solr c:testTaskFail s:shard1 r:core_node3 x:testTaskFail_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 224161 INFO (qtp1049748200-1707) [n:127.0.0.1:61667_solr c:testTaskFail s:shard1 r:core_node3 x:testTaskFail_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 224161 INFO (qtp1049748200-1707) [n:127.0.0.1:61667_solr c:testTaskFail s:shard1 r:core_node3 x:testTaskFail_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:61667/solr/testTaskFail_shard1_replica_n1/
[junit4] 2> 224163 INFO (qtp1049748200-1707) [n:127.0.0.1:61667_solr c:testTaskFail s:shard1 r:core_node3 x:testTaskFail_shard1_replica_n1 ] o.a.s.u.PeerSync PeerSync: core=testTaskFail_shard1_replica_n1 url=http://127.0.0.1:61667/solr START replicas=[http://127.0.0.1:61668/solr/testTaskFail_shard1_replica_n2/] nUpdates=100
[junit4] 2> 224165 INFO (qtp1049748200-1707) [n:127.0.0.1:61667_solr c:testTaskFail s:shard1 r:core_node3 x:testTaskFail_shard1_replica_n1 ] o.a.s.u.PeerSync PeerSync: core=testTaskFail_shard1_replica_n1 url=http://127.0.0.1:61667/solr DONE. We have no versions. sync failed.
[junit4] 2> 224168 INFO (qtp550349715-1711) [n:127.0.0.1:61668_solr c:testTaskFail s:shard1 r:core_node4 x:testTaskFail_shard1_replica_n2 ] o.a.s.c.S.Request [testTaskFail_shard1_replica_n2] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 224169 INFO (qtp1049748200-1707) [n:127.0.0.1:61667_solr c:testTaskFail s:shard1 r:core_node3 x:testTaskFail_shard1_replica_n1 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 224169 INFO (qtp1049748200-1707) [n:127.0.0.1:61667_solr c:testTaskFail s:shard1 r:core_node3 x:testTaskFail_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 224169 INFO (qtp1049748200-1707) [n:127.0.0.1:61667_solr c:testTaskFail s:shard1 r:core_node3 x:testTaskFail_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testTaskFail/leaders/shard1/leader after winning as /collections/testTaskFail/leader_elect/shard1/election/72110765771718664-core_node3-n_0000000000
[junit4] 2> 224179 INFO (qtp1049748200-1707) [n:127.0.0.1:61667_solr c:testTaskFail s:shard1 r:core_node3 x:testTaskFail_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:61667/solr/testTaskFail_shard1_replica_n1/ shard1
[junit4] 2> 224285 INFO (zkCallback-1113-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testTaskFail/state.json] for collection [testTaskFail] has occurred - updating... (live nodes size: [2])
[junit4] 2> 224286 INFO (zkCallback-1113-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testTaskFail/state.json] for collection [testTaskFail] has occurred - updating... (live nodes size: [2])
[junit4] 2> 224289 INFO (qtp1049748200-1707) [n:127.0.0.1:61667_solr c:testTaskFail s:shard1 r:core_node3 x:testTaskFail_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 224297 INFO (qtp1049748200-1707) [n:127.0.0.1:61667_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=testTaskFail_shard1_replica_n1&action=CREATE&numShards=1&collection=testTaskFail&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=347
[junit4] 2> 224404 INFO (zkCallback-1113-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testTaskFail/state.json] for collection [testTaskFail] has occurred - updating... (live nodes size: [2])
[junit4] 2> 224404 INFO (zkCallback-1113-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testTaskFail/state.json] for collection [testTaskFail] has occurred - updating... (live nodes size: [2])
[junit4] 2> 224405 INFO (zkCallback-1113-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testTaskFail/state.json] for collection [testTaskFail] has occurred - updating... (live nodes size: [2])
[junit4] 2> 224565 DEBUG (ScheduledTrigger-1119-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger3 with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 225177 INFO (qtp550349715-1714) [n:127.0.0.1:61668_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=testTaskFail_shard1_replica_n2&action=CREATE&numShards=1&collection=testTaskFail&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1226
[junit4] 2> 225182 INFO (qtp1049748200-1705) [n:127.0.0.1:61667_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> 225284 INFO (zkCallback-1109-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testTaskFail/state.json] for collection [testTaskFail] has occurred - updating... (live nodes size: [2])
[junit4] 2> 225284 INFO (zkCallback-1113-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testTaskFail/state.json] for collection [testTaskFail] has occurred - updating... (live nodes size: [2])
[junit4] 2> 225284 INFO (zkCallback-1113-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testTaskFail/state.json] for collection [testTaskFail] has occurred - updating... (live nodes size: [2])
[junit4] 2> 225285 INFO (zkCallback-1113-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testTaskFail/state.json] for collection [testTaskFail] has occurred - updating... (live nodes size: [2])
[junit4] 2> 225285 INFO (zkCallback-1109-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testTaskFail/state.json] for collection [testTaskFail] has occurred - updating... (live nodes size: [2])
[junit4] 2> 225287 INFO (qtp1049748200-1705) [n:127.0.0.1:61667_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&maxShardsPerNode=1&name=testTaskFail&nrtReplicas=2&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=1740
[junit4] 2> 225287 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: testTaskFail
[junit4] 2> 225290 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.c.SolrCloudTestCase waitForState (testTaskFail): Timed out waiting for replicas of new collection to be active
[junit4] 2> 225292 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 2 expected replica count: 2
[junit4] 2> 225292 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 2 expected replica count: 2
[junit4] 2> 225293 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 2 expected replica count: 2
[junit4] 2> 225294 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=118598559
[junit4] 2> 225294 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:61667_solr
[junit4] 2> 225295 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 225295 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:61667_solr as DOWN
[junit4] 2> 225304 INFO (coreCloseExecutor-1153-thread-1) [n:127.0.0.1:61667_solr ] o.a.s.c.SolrCore [testTaskFail_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@706a7dde
[junit4] 2> 225304 INFO (coreCloseExecutor-1153-thread-1) [n:127.0.0.1:61667_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.testTaskFail.shard1.replica_n1 tag=SolrCore@706a7dde
[junit4] 2> 225304 INFO (coreCloseExecutor-1153-thread-1) [n:127.0.0.1:61667_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@21ea8357: rootName = solr_61667, domain = solr.core.testTaskFail.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.testTaskFail.shard1.replica_n1/com.codahale.metrics.MetricRegistry@422b0ecf
[junit4] 2> 225313 INFO (coreCloseExecutor-1153-thread-1) [n:127.0.0.1:61667_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.testTaskFail.shard1.leader tag=SolrCore@706a7dde
[junit4] 2> 225314 INFO (coreCloseExecutor-1153-thread-1) [n:127.0.0.1:61667_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 225317 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 225317 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@8c47fb4: rootName = solr_61667, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@69a7934e
[junit4] 2> 225323 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 225323 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5b0dd830: rootName = solr_61667, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@38fc02ec
[junit4] 2> 225326 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 225326 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4b40a077: rootName = solr_61667, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@20d63d45
[junit4] 2> 225326 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 225328 INFO (closeThreadPool-1155-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72110765771718664-127.0.0.1:61667_solr-n_0000000000) closing
[junit4] 2> 225328 INFO (OverseerStateUpdate-72110765771718664-127.0.0.1:61667_solr-n_0000000000) [n:127.0.0.1:61667_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:61667_solr
[junit4] 2> 225332 INFO (OverseerAutoScalingTriggerThread-72110765771718664-127.0.0.1:61667_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 225334 DEBUG (closeThreadPool-1155-thread-2) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 225338 DEBUG (closeThreadPool-1155-thread-2) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 225338 DEBUG (closeThreadPool-1155-thread-2) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 225338 DEBUG (closeThreadPool-1155-thread-2) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 225341 DEBUG (closeThreadPool-1155-thread-2) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 225341 DEBUG (closeThreadPool-1155-thread-2) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 225342 INFO (closeThreadPool-1155-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72110765771718664-127.0.0.1:61667_solr-n_0000000000) closing
[junit4] 2> 225342 INFO (zkCallback-1109-thread-2) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:61668_solr
[junit4] 2> 225444 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.c.Overseer Overseer (id=72110765771718664-127.0.0.1:61667_solr-n_0000000000) closing
[junit4] 2> 225446 INFO (zkCallback-1136-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 225448 INFO (zkCallback-1109-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 225450 INFO (zkCallback-1109-thread-2) [n:127.0.0.1:61668_solr ] o.a.s.c.Overseer Overseer (id=72110765771718663-127.0.0.1:61668_solr-n_0000000001) starting
[junit4] 2> 225450 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@30560b4a{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 225452 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7c8a5a19{/solr,null,UNAVAILABLE}
[junit4] 2> 225453 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 225456 INFO (TEST-ExecutePlanActionTest.testTaskFail-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.c.MiniSolrCloudCluster waitForJettyToStop: 61667
[junit4] 2> 225457 INFO (zkCallback-1109-thread-1) [n:127.0.0.1:61668_solr c:testTaskFail s:shard1 r:core_node4 x:testTaskFail_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 225467 INFO (OverseerStateUpdate-72110765771718663-127.0.0.1:61668_solr-n_0000000001) [n:127.0.0.1:61668_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:61668_solr
[junit4] 2> 225468 DEBUG (OverseerAutoScalingTriggerThread-72110765771718663-127.0.0.1:61668_solr-n_0000000001) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 225471 DEBUG (OverseerAutoScalingTriggerThread-72110765771718663-127.0.0.1:61668_solr-n_0000000001) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 4
[junit4] 2> 225473 DEBUG (OverseerAutoScalingTriggerThread-72110765771718663-127.0.0.1:61668_solr-n_0000000001) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVersion 0
[junit4] 2> 225473 DEBUG (OverseerAutoScalingTriggerThread-72110765771718663-127.0.0.1:61668_solr-n_0000000001) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 4
[junit4] 2> 225485 DEBUG (OverseerAutoScalingTriggerThread-72110765771718663-127.0.0.1:61668_solr-n_0000000001) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger node_lost_trigger3 - Initial livenodes: [127.0.0.1:61668_solr]
[junit4] 2> 225487 INFO (zkCallback-1109-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testTaskFail/state.json] for collection [testTaskFail] has occurred - updating... (live nodes size: [1])
[junit4] 2> 225487 INFO (zkCallback-1109-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testTaskFail/state.json] for collection [testTaskFail] has occurred - updating... (live nodes size: [1])
[junit4] 2> 225488 DEBUG (OverseerAutoScalingTriggerThread-72110765771718663-127.0.0.1:61668_solr-n_0000000001) [ ] o.a.s.c.a.NodeLostTrigger Adding lost node from marker path: 127.0.0.1:61667_solr
[junit4] 2> 225492 DEBUG (ScheduledTrigger-1157-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger3 with currently live nodes: 1 and last live nodes: 2
[junit4] 2> 225492 DEBUG (ScheduledTrigger-1157-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Tracking lost node: 127.0.0.1:61667_solr
[junit4] 2> 225493 DEBUG (OverseerAutoScalingTriggerThread-72110765771718663-127.0.0.1:61668_solr-n_0000000001) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:61668_solr]
[junit4] 2> 225494 DEBUG (OverseerAutoScalingTriggerThread-72110765771718663-127.0.0.1:61668_solr-n_0000000001) [ ] o.a.s.c.a.NodeLostTrigger Adding lost node from marker path: 127.0.0.1:61667_solr
[junit4] 2> 225495 DEBUG (OverseerAutoScalingTriggerThread-72110765771718663-127.0.0.1:61668_solr-n_0000000001) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 225497 DEBUG (ScheduledTrigger-1157-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 225501 DEBUG (OverseerAutoScalingTriggerThread-72110765771718663-127.0.0.1:61668_solr-n_0000000001) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVersion 4
[junit4] 2> 225593 INFO (zkCallback-1109-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testTaskFail/state.json] for collection [testTaskFail] has occurred - updating... (live nodes size: [1])
[junit4] 2> 225593 INFO (zkCallback-1109-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testTaskFail/state.json] for collection [testTaskFail] has occurred - updating... (live nodes size: [1])
[junit4] 2> 226494 DEBUG (ScheduledTrigger-1157-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger3 with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 226494 DEBUG (ScheduledTrigger-1157-thread-2) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger firing registered processor for lost nodes: [127.0.0.1:61667_solr]
[junit4] 2> 226494 DEBUG (ScheduledTrigger-1157-thread-2) [ ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
[junit4] 2> "id":"2e1e8ad03cdf6T4mwlwsuoa4qxjhr5rvyuh0lwq",
[junit4] 2> "source":"node_lost_trigger3",
[junit4] 2> "eventTime":811339404791286,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[811339404791286],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "nodeNames":["127.0.0.1:61667_solr"]}}
[junit4] 2> 226495 DEBUG (ScheduledTrigger-1157-thread-2) [ ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: [node_lost_trigger3, .auto_add_replicas, .scheduled_maintenance]
[junit4] 2> 226497 DEBUG (ScheduledTrigger-1157-thread-2) [ ] o.a.s.c.a.SystemLogListener Collection .system missing, skip sending event {
[junit4] 2> "id":"2e1e8ad03cdf6T4mwlwsuoa4qxjhr5rvyuh0lwq",
[junit4] 2> "source":"node_lost_trigger3",
[junit4] 2> "eventTime":811339404791286,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[811339404791286],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":811340408150957,
[junit4] 2> "nodeNames":["127.0.0.1:61667_solr"]}}
[junit4] 2> 226498 DEBUG (AutoscalingActionExecutor-1158-thread-1) [ ] o.a.s.c.a.ScheduledTriggers -- processing actions for {
[junit4] 2> "id":"2e1e8ad03cdf6T4mwlwsuoa4qxjhr5rvyuh0lwq",
[junit4] 2> "source":"node_lost_trigger3",
[junit4] 2> "eventTime":811339404791286,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[811339404791286],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":811340408150957,
[junit4] 2> "nodeNames":["127.0.0.1:61667_solr"]}}
[junit4] 2> 226499 DEBUG (AutoscalingActionExecutor-1158-thread-1) [ ] o.a.s.c.a.ComputePlanAction -- processing event: {
[junit4] 2> "id":"2e1e8ad03cdf6T4mwlwsuoa4qxjhr5rvyuh0lwq",
[junit4] 2> "source":"node_lost_trigger3",
[junit4] 2> "eventTime":811339404791286,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[811339404791286],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":811340408150957,
[junit4] 2> "nodeNames":["127.0.0.1:61667_solr"]}} with context properties: {AFTER_ACTION=[start], BEFORE_ACTION=[start, compute_plan]}
[junit4] 2> 226506 INFO (qtp550349715-1712) [n:127.0.0.1:61668_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.testTaskFail.shard1.replica_n2:INDEX.sizeInBytes} status=0 QTime=1
[junit4] 2> 226510 INFO (qtp550349715-1714) [n:127.0.0.1:61668_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1
[junit4] 2> 226522 DEBUG (AutoscalingActionExecutor-1158-thread-1) [ ] o.a.s.c.a.ComputePlanAction Computed Plan: action=MOVEREPLICA&collection=testTaskFail&targetNode=127.0.0.1:61668_solr&inPlaceMove=true&replica=core_node3
[junit4] 2> 226523 DEBUG (AutoscalingActionExecutor-1158-thread-1) [ ] o.a.s.c.a.ExecutePlanAction -- processing event: {
[junit4] 2> "id":"2e1e8ad03cdf6T4mwlwsuoa4qxjhr5rvyuh0lwq",
[junit4] 2> "source":"node_lost_trigger3",
[junit4] 2> "eventTime":811339404791286,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[811339404791286],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":811340408150957,
[junit4] 2> "nodeNames":["127.0.0.1:61667_solr"]}} with context properties: {operations=[{
[junit4] 2> "class":"org.apache.solr.client.solrj.request.CollectionAdminRequest$MoveReplica",
[junit4] 2> "method":"GET",
[junit4] 2> "params.action":"MOVEREPLICA",
[junit4] 2> "params.collection":"testTaskFail",
[junit4] 2> "params.targetNode":"127.0.0.1:61668_solr",
[junit4] 2> "params.inPlaceMove":"true",
[junit4] 2> "params.replica":"core_node3"}], AFTER_ACTION=[start, compute_plan], BEFORE_ACTION=[start, compute_plan, execute_plan]}
[junit4] 2> 226523 DEBUG (AutoscalingActionExecutor-1158-thread-1) [ ] o.a.s.c.a.ExecutePlanAction Executing operation: action=MOVEREPLICA&collection=testTaskFail&targetNode=127.0.0.1:61668_solr&inPlaceMove=true&replica=core_node3
[junit4] 2> 226534 DEBUG (AutoscalingActionExecutor-1158-thread-1) [ ] o.a.s.c.a.SystemLogListener Collection .system missing, skip sending event {
[junit4] 2> "id":"2e1e8ad03cdf6T4mwlwsuoa4qxjhr5rvyuh0lwq",
[junit4] 2> "source":"node_lost_trigger3",
[junit4] 2> "eventTime":811339404791286,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[811339404791286],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":811340408150957,
[junit4] 2> "nodeNames":["127.0.0.1:61667_solr"]}}
[junit4] 2> 226534 WARN (AutoscalingActionExecutor-1158-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Exception executing actions
[junit4] 2> => org.apache.solr.cloud.autoscaling.TriggerActionException: Error processing action for trigger event: {
[junit4] 2> "id":"2e1e8ad03cdf6T4mwlwsuoa4qxjhr5rvyuh0lwq",
[junit4] 2> org.apache.solr.cloud.autoscaling.TriggerActionException: Error processing action for trigger event: {
[junit4] 2> "id":"2e1e8ad03cdf6T4mwlwsuoa4qxjhr5rvyuh0lwq",
[junit4] 2> "source":"node_lost_trigger3",
[junit4] 2> "eventTime":811339404791286,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[811339404791286],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":811340408150957,
[junit4] 2> "nodeNames":["127.0.0.1:61667_solr"]}}
[junit4] 2> at org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:329) ~[java/:?]
[junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) ~[?:1.8.0_201]
[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> Caused by: org.apache.solr.common.SolrException: Unexpected exception while processing event: {
[junit4] 2> "id":"2e1e8ad03cdf6T4mwlwsuoa4qxjhr5rvyuh0lwq",
[junit4] 2> "source":"node_lost_trigger3",
[junit4] 2> "eventTime":811339404791286,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[811339404791286],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":811340408150957,
[junit4] 2> "nodeNames":["127.0.0.1:61667_solr"]}}
[junit4] 2> at org.apache.solr.cloud.autoscaling.ExecutePlanAction.process(ExecutePlanAction.java:180) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:326) ~[java/:?]
[junit4] 2> ... 6 more
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Unexpected exception executing operation: action=MOVEREPLICA&async=node_lost_trigger3/2e1e8ad03cdf6T4mwlwsuoa4qxjhr5rvyuh0lwq/0&waitForFinalState=true&collection=testTaskFail&targetNode=127.0.0.1:61668_solr&inPlaceMove=true&replica=core_node3
[junit4] 2> at org.apache.solr.cloud.autoscaling.ExecutePlanAction.process(ExecutePlanAction.java:170) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:326) ~[java/:?]
[junit4] 2> ... 6 more
[junit4] 2> Caused by: java.io.IOException: Task node_lost_trigger3/2e1e8ad03cdf6T4mwlwsuoa4qxjhr5rvyuh0lwq/0 failed: timed out. Operation: {
[junit4] 2> "class":"org.apache.solr.client.solrj.request.CollectionAdminRequest$MoveReplica",
[junit4] 2> "method":"GET",
[junit4] 2> "params.action":"MOVEREPLICA",
[junit4] 2> "params.async":"node_lost_trigger3/2e1e8ad03cdf6T4mwlwsuoa4qxjhr5rvyuh0lwq/0",
[junit4] 2> "params.waitForFinalState":"true",
[junit4] 2> "params.collection":"testTaskFail",
[junit4] 2> "params.targetNode":"127.0.0.1:61668_solr",
[junit4] 2> "params.inPlaceMove":"true",
[junit4] 2> "params.replica":"core_node3"}
[junit4] 2> at org.apache.solr.cloud.autoscaling.ExecutePlanAction.process(ExecutePlanAction.java:154) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:326) ~[java/:?]
[junit4] 2> ... 6 more
[junit4] 2> 226534 DEBUG (AutoscalingActionExecutor-1158-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Resuming trigger: .scheduled_maintenance after 5000ms
[junit4] 2> 226534 DEBUG (AutoscalingActionExecutor-1158-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_lost_trigger3 after 5000ms
[junit4] 2> 226534 DEBUG (AutoscalingActionExecutor-1158-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Resuming trigger: .auto_add_replicas after 5000ms
[junit4] 2> 226534 DEBUG (AutoscalingActionExecutor-1158-thread-1) [ ] o.a.s.c.a.ScheduledTriggers -- processing took 36 ms for event id=2e1e8ad03cdf6T4mwlwsuoa4qxjhr5rvyuh0lwq
[junit4] 2> 228219 INFO (zkCallback-1109-thread-1) [n:127.0.0.1:61668_solr c:testTaskFail s:shard1 r:core_node4 x:testTaskFail_shard1_replica_n2 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:61668/solr/testTaskFail_shard1_replica_n2/
[junit4] 2> 228219 INFO (zkCallback-1109-thread-1) [n:127.0.0.1:61668_solr c:testTaskFail s:shard1 r:core_node4 x:testTaskFail_shard1_replica_n2 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 228219 INFO (zkCallback-1109-thread-1) [n:127.0.0.1:61668_solr c:testTaskFail s:shard1 r:core_node4 x:testTaskFail_shard1_replica_n2 ] o.a.s.c.SyncStrategy http://127.0.0.1:61668/solr/testTaskFail_shard1_replica_n2/ has no replicas
[junit4] 2> 228219 INFO (zkCallback-1109-thread-1) [n:127.0.0.1:61668_solr c:testTaskFail s:shard1 r:core_node4 x:testTaskFail_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testTaskFail/leaders/shard1/leader after winning as /collections/testTaskFail/leader_elect/shard1/election/72110765771718663-core_node4-n_0000000001
[junit4] 2> 228224 INFO (zkCallback-1109-thread-1) [n:127.0.0.1:61668_solr c:testTaskFail s:shard1 r:core_node4 x:testTaskFail_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:61668/solr/testTaskFail_shard1_replica_n2/ shard1
[junit4] 2> 228226 INFO (zkCallback-1109-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testTaskFail/state.json] for collection [testTaskFail] has occurred - updating... (live nodes size: [1])
[junit4] 2> 228226 INFO (zkCallback-1109-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testTaskFail/state.json] for collection [testTaskFail] has occurred - updating... (live nodes size: [1])
[junit4] 2> 231538 DEBUG (ScheduledTrigger-1157-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 231538 DEBUG (ScheduledTrigger-1157-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger3 with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 232538 DEBUG (ScheduledTrigger-1157-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger3 with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 232538 DEBUG (ScheduledTrigger-1157-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 233849 DEBUG (ScheduledTrigger-1157-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 233849 DEBUG (ScheduledTrigger-1157-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger3 with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 233953 INFO (jetty-closer-1160-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=997110484
[junit4] 2> 233953 INFO (jetty-closer-1160-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:61668_solr
[junit4] 2> 233954 INFO (jetty-closer-1160-thread-1) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 233954 INFO (jetty-closer-1160-thread-1) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:61668_solr as DOWN
[junit4] 2> 233967 INFO (coreCloseExecutor-1164-thread-1) [n:127.0.0.1:61668_solr ] o.a.s.c.SolrCore [testTaskFail_shard1_replica_n2] CLOSING SolrCore org.apache.solr.core.SolrCore@1f85f250
[junit4] 2> 233967 INFO (coreCloseExecutor-1164-thread-1) [n:127.0.0.1:61668_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.testTaskFail.shard1.replica_n2 tag=SolrCore@1f85f250
[junit4] 2> 233967 INFO (coreCloseExecutor-1164-thread-1) [n:127.0.0.1:61668_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@259f0ef3: rootName = solr_61668, domain = solr.core.testTaskFail.shard1.replica_n2, service url = null, agent id = null] for registry solr.core.testTaskFail.shard1.replica_n2/com.codahale.metrics.MetricRegistry@473a4a76
[junit4] 2> 233980 INFO (zkCallback-1109-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testTaskFail/state.json] for collection [testTaskFail] has occurred - updating... (live nodes size: [1])
[junit4] 2> 233985 INFO (coreCloseExecutor-1164-thread-1) [n:127.0.0.1:61668_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.testTaskFail.shard1.leader tag=SolrCore@1f85f250
[junit4] 2> 233986 INFO (zkCallback-1109-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testTaskFail/state.json] for collection [testTaskFail] has occurred - updating... (live nodes size: [1])
[junit4] 2> 233986 INFO (coreCloseExecutor-1164-thread-1) [n:127.0.0.1:61668_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 233996 INFO (jetty-closer-1160-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 233996 INFO (jetty-closer-1160-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@9e8ebd0: rootName = solr_61668, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@4d76e6c2
[junit4] 2> 234001 INFO (jetty-closer-1160-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 234001 INFO (jetty-closer-1160-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@35b3e2d5: rootName = solr_61668, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@38fc02ec
[junit4] 2> 234003 INFO (jetty-closer-1160-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 234003 INFO (jetty-closer-1160-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4cb0953c: rootName = solr_61668, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@20d63d45
[junit4] 2> 234003 INFO (jetty-closer-1160-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 234005 INFO (closeThreadPool-1166-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72110765771718663-127.0.0.1:61668_solr-n_0000000001) closing
[junit4] 2> 234005 INFO (OverseerStateUpdate-72110765771718663-127.0.0.1:61668_solr-n_0000000001) [n:127.0.0.1:61668_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:61668_solr
[junit4] 2> 234005 INFO (OverseerAutoScalingTriggerThread-72110765771718663-127.0.0.1:61668_solr-n_0000000001) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 234007 DEBUG (closeThreadPool-1166-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 234010 DEBUG (closeThreadPool-1166-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 234010 DEBUG (closeThreadPool-1166-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 234011 DEBUG (closeThreadPool-1166-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 234011 DEBUG (closeThreadPool-1166-thread-1) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 234011 DEBUG (closeThreadPool-1166-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 234011 INFO (closeThreadPool-1166-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72110765771718663-127.0.0.1:61668_solr-n_0000000001) closing
[junit4] 2> 234118 INFO (jetty-closer-1160-
[...truncated too long message...]
conf1
[junit4] 2> 4 /solr/packages.json
[junit4] 2> 4 /solr/security.json
[junit4] 2> 3 /solr/collections/collection1/collectionprops.json
[junit4] 2> 3 /solr/collections/ocptest_shardsplit2/terms/shard2_0
[junit4] 2> 3 /solr/collections/ocptest_shardsplit2/terms/shard1_1
[junit4] 2> 3 /solr/collections/ocptest_shardsplit2/terms/shard1_0
[junit4] 2> 3 /solr/collections/control_collection/terms/shard1
[junit4] 2> 3 /solr/collections/ocptest_shardsplit2/terms/shard2_1
[junit4] 2> 3 /solr/collections/collection1/terms/shard1
[junit4] 2> 3 /solr/collections/ocptest_shardsplit2/collectionprops.json
[junit4] 2> 2 /solr/collections/ocptest_shardsplit2/terms/shard2
[junit4] 2> 2 /solr/collections/ocptest_shardsplit2/terms/shard3
[junit4] 2> 2 /solr/collections/ocptest_shardsplit2/terms/shard1
[junit4] 2> 2 /solr/collections/ocptest1/terms/shard1
[junit4] 2> 2 /solr/collections/ocptest2/terms/shard1
[junit4] 2> 2 /solr/collections/ocptest2/terms/shard2
[junit4] 2> 2 /solr/collections/ocptest3/terms/shard2
[junit4] 2> 2 /solr/collections/ocptest3/terms/shard3
[junit4] 2> 2 /solr/collections/ocptest3/terms/shard1
[junit4] 2> 2 /solr/collections/collection1/terms/shard1_0
[junit4] 2> 2 /solr/collections/ocptest_shardsplit/terms/shard4
[junit4] 2> 2 /solr/collections/ocptest2/terms/shard3
[junit4] 2> 2 /solr/collections/ocptest_shardsplit/terms/shard3
[junit4] 2> 2 /solr/collections/ocptest_shardsplit/terms/shard2
[junit4] 2> 2 /solr/collections/ocptest1/terms/shard3
[junit4] 2> 2 /solr/collections/ocptest_shardsplit/terms/shard1
[junit4] 2> 2 /solr/collections/ocptest1/terms/shard2
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 189 /solr/collections/ocptest_shardsplit2/state.json
[junit4] 2> 81 /solr/clusterstate.json
[junit4] 2> 54 /solr/collections/ocptest1/state.json
[junit4] 2> 48 /solr/collections/ocptest2/state.json
[junit4] 2> 47 /solr/collections/ocptest_shardsplit/state.json
[junit4] 2> 42 /solr/collections/ocptest3/state.json
[junit4] 2> 12 /solr/collections/control_collection/state.json
[junit4] 2> 6 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 133 /solr/overseer/queue
[junit4] 2> 48 /solr/overseer/collection-queue-work
[junit4] 2> 31 /solr/collections
[junit4] 2> 23 /solr/live_nodes
[junit4] 2>
[junit4] 2> 1564152 INFO (TEST-MultiThreadedOCPTest.test-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:57188
[junit4] 2> 1564153 INFO (TEST-MultiThreadedOCPTest.test-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:57188
[junit4] 2> 1564153 INFO (TEST-MultiThreadedOCPTest.test-seed#[2C43E0ABEDB33B30]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 57188
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=MultiThreadedOCPTest -Dtests.method=test -Dtests.seed=2C43E0ABEDB33B30 -Dtests.slow=true -Dtests.locale=en-ZA -Dtests.timezone=Asia/Dili -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] FAILURE 80.8s J4 | MultiThreadedOCPTest.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: acoll: 1587856850953 bcoll: 1587856853773
[junit4] > at __randomizedtesting.SeedInfo.seed([2C43E0ABEDB33B30:A417DF71434F56C8]:0)
[junit4] > at org.apache.solr.cloud.MultiThreadedOCPTest.testFillWorkQueue(MultiThreadedOCPTest.java:113)
[junit4] > at org.apache.solr.cloud.MultiThreadedOCPTest.test(MultiThreadedOCPTest.java:68)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1081)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1053)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.MultiThreadedOCPTest_2C43E0ABEDB33B30-001
[junit4] 2> Apr 25, 2020 11:21:13 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {multiDefault=FST50, _root_=PostingsFormat(name=Asserting), id=PostingsFormat(name=MockRandom)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene80), _version_=DocValuesFormat(name=Direct), intDefault=DocValuesFormat(name=Direct), id_i1=DocValuesFormat(name=Direct), range_facet_i_dv=DocValuesFormat(name=Lucene80), intDvoDefault=DocValuesFormat(name=Lucene80), range_facet_l=DocValuesFormat(name=Lucene80), timestamp=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=1960, maxMBSortInHeap=7.3344545906421885, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@4cccb98b), locale=en-ZA, timezone=Asia/Dili
[junit4] 2> NOTE: Mac OS X 10.14.6 x86_64/Oracle Corporation 1.8.0_201 (64-bit)/cpus=6,threads=1,free=194040984,total=393625600
[junit4] 2> NOTE: All tests run in this JVM: [MoveReplicaTest, TestDistribDocBasedVersion, SignatureUpdateProcessorFactoryTest, SystemInfoHandlerTest, TestReqParamsAPI, ChaosMonkeyNothingIsSafeTest, TestCoreBackup, PackageManagerCLITest, ComputePlanActionTest, TestSolrFieldCacheBean, AliasIntegrationTest, TestComponentsName, RequestLoggingTest, TestSnapshotCloudManager, CdcrReplicationHandlerTest, TestDynamicURP, DisMaxRequestHandlerTest, MetricsHistoryWithAuthIntegrationTest, IndexSizeTriggerSizeEstimationTest, TestStressLucene, TestWriterPerf, TestSolrCloudSnapshots, DebugComponentTest, TestReplicaProperties, TestDistributedTracing, TestPostingsSolrHighlighter, ConcurrentDeleteAndCreateCollectionTest, TestJavabinTupleStreamParser, TestConfigSetsAPI, HdfsRecoverLeaseTest, TestCloudPseudoReturnFields, AuditLoggerIntegrationTest, UpdateParamsTest, TestSubQueryTransformer, TestDFRSimilarityFactory, WrapperMergePolicyFactoryTest, TestRangeQuery, TestJsonFacetsWithNestedObjects, TestWaitForStateWithJettyShutdowns, TestSystemIdResolver, RandomizedTaggerTest, BooleanFieldTest, SolrXmlInZkTest, TestFieldCacheWithThreads, ZkNodePropsTest, TestSurroundQueryParser, TestReloadAndDeleteDocs, HealthCheckHandlerTest, NodeAddedTriggerTest, TestManagedStopFilterFactory, TestHighlightDedupGrouping, SplitByPrefixTest, CircularListTest, TestFieldCollectionResource, TestFastLRUCache, TestBackupRepositoryFactory, HdfsTlogReplayBufferedWhileIndexingTest, SpellCheckCollatorTest, TestFaceting, TestWordDelimiterFilterFactory, ZookeeperStatusHandlerTest, TestNumericTokenStream, TestSimNodeLostTrigger, TestDistributedGrouping, TestOnReconnectListenerSupport, ParsingFieldUpdateProcessorsTest, ScheduledMaintenanceTriggerTest, SampleTest, TestSha256AuthenticationProvider, ProtectedTermFilterFactoryTest, ZkCollectionPropsCachingTest, TestSolrDeletionPolicy2, SystemLogListenerTest, HdfsBasicDistributedZk2Test, TestCorePropertiesReload, TestCloudSchemaless, SolrRequestParserTest, RulesTest, TestObjectReleaseTracker, SpellCheckComponentTest, TestAnalyzeInfixSuggestions, TestSegmentSorting, DocumentAnalysisRequestHandlerTest, TestXmlQParserPlugin, TestRestManager, TestBinaryResponseWriter, TestOrdValues, TestPackages, TestFuzzyAnalyzedSuggestions, DeleteInactiveReplicaTest, DeleteShardTest, DistribCursorPagingTest, DistribJoinFromCollectionTest, DistributedQueueTest, DocValuesNotIndexedTest, HttpPartitionTest, LeaderFailoverAfterPartitionTest, MultiThreadedOCPTest]
[junit4] Completed [495/906 (3!)] on J4 in 81.81s, 1 test, 1 failure <<< FAILURES!
[...truncated 54084 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/896/consoleText
[repro] Revision: 83f090877b0590a1d99c79cfeec076dfed963076
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseConcMarkSweepGC"
[repro] Repro line: ant test -Dtestcase=ExecutePlanActionTest -Dtests.method=testTaskTimeout -Dtests.seed=2C43E0ABEDB33B30 -Dtests.slow=true -Dtests.locale=sl -Dtests.timezone=Africa/Kampala -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[repro] Repro line: ant test -Dtestcase=SearchRateTriggerTest -Dtests.method=testWaitForElapsed -Dtests.seed=2C43E0ABEDB33B30 -Dtests.slow=true -Dtests.locale=ar-LB -Dtests.timezone=Japan -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[repro] Repro line: ant test -Dtestcase=MultiThreadedOCPTest -Dtests.method=test -Dtests.seed=2C43E0ABEDB33B30 -Dtests.slow=true -Dtests.locale=en-ZA -Dtests.timezone=Asia/Dili -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] MultiThreadedOCPTest
[repro] ExecutePlanActionTest
[repro] SearchRateTriggerTest
[repro] ant compile-test
[...truncated 3479 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=15 -Dtests.class="*.MultiThreadedOCPTest|*.ExecutePlanActionTest|*.SearchRateTriggerTest" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseConcMarkSweepGC" -Dtests.seed=2C43E0ABEDB33B30 -Dtests.slow=true -Dtests.locale=en-ZA -Dtests.timezone=Asia/Dili -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[...truncated 146 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.cloud.MultiThreadedOCPTest
[repro] 0/5 failed: org.apache.solr.cloud.autoscaling.ExecutePlanActionTest
[repro] 0/5 failed: org.apache.solr.cloud.autoscaling.SearchRateTriggerTest
[repro] Exiting with code 0
[...truncated 73 lines...]
[JENKINS] Lucene-Solr-8.x-MacOSX (64bit/jdk-12.0.2) - Build # 897 -
Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/897/
Java: 64bit/jdk-12.0.2 -XX:+UseCompressedOops -XX:+UseSerialGC
3 tests failed.
FAILED: org.apache.solr.cloud.LeaderFailoverAfterPartitionTest.test
Error Message:
Timeout occurred while waiting response from server at: http://127.0.0.1:65002/zob/lx
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: http://127.0.0.1:65002/zob/lx
at __randomizedtesting.SeedInfo.seed([C2194C7330ACFCA9:4A4D73A99E509151]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:676)
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.AbstractFullDistribZkTestBase.createServers(AbstractFullDistribZkTestBase.java:338)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1079)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1053)
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: java.net.SocketTimeoutException: Read timed out
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 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:280)
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:157)
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:186)
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)
... 42 more
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.TestWithCollection
Error Message:
1 thread leaked from SUITE scope at org.apache.solr.cloud.TestWithCollection: 1) Thread[id=10215, name=SessionTracker, state=TIMED_WAITING, group=TGRP-TestWithCollection] at java.base@12.0.2/java.lang.Thread.sleep(Native Method) at app//org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.cloud.TestWithCollection:
1) Thread[id=10215, name=SessionTracker, state=TIMED_WAITING, group=TGRP-TestWithCollection]
at java.base@12.0.2/java.lang.Thread.sleep(Native Method)
at app//org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151)
at __randomizedtesting.SeedInfo.seed([C2194C7330ACFCA9]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.schema.TestManagedSchemaAPI
Error Message:
1 thread leaked from SUITE scope at org.apache.solr.schema.TestManagedSchemaAPI: 1) Thread[id=26054, name=SessionTracker, state=TIMED_WAITING, group=TGRP-TestManagedSchemaAPI] at java.base@12.0.2/java.lang.Thread.sleep(Native Method) at app//org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.schema.TestManagedSchemaAPI:
1) Thread[id=26054, name=SessionTracker, state=TIMED_WAITING, group=TGRP-TestManagedSchemaAPI]
at java.base@12.0.2/java.lang.Thread.sleep(Native Method)
at app//org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151)
at __randomizedtesting.SeedInfo.seed([C2194C7330ACFCA9]:0)
Build Log:
[...truncated 14912 lines...]
[junit4] Suite: org.apache.solr.cloud.TestWithCollection
[junit4] 2> 1018446 INFO (SUITE-TestWithCollection-seed#[C2194C7330ACFCA9]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/server/solr/configsets/_default/conf'
[junit4] 2> 1018446 INFO (SUITE-TestWithCollection-seed#[C2194C7330ACFCA9]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1018448 INFO (SUITE-TestWithCollection-seed#[C2194C7330ACFCA9]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_C2194C7330ACFCA9-001/data-dir-83-001
[junit4] 2> 1018448 WARN (SUITE-TestWithCollection-seed#[C2194C7330ACFCA9]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=10 numCloses=10
[junit4] 2> 1018448 INFO (SUITE-TestWithCollection-seed#[C2194C7330ACFCA9]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1018449 INFO (SUITE-TestWithCollection-seed#[C2194C7330ACFCA9]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 1018453 INFO (TEST-TestWithCollection.testDeleteWithCollection-seed#[C2194C7330ACFCA9]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDeleteWithCollection
[junit4] 2> 1018454 INFO (TEST-TestWithCollection.testDeleteWithCollection-seed#[C2194C7330ACFCA9]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_C2194C7330ACFCA9-001/tempDir-001
[junit4] 2> 1018454 INFO (TEST-TestWithCollection.testDeleteWithCollection-seed#[C2194C7330ACFCA9]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1018455 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1018455 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1018555 INFO (TEST-TestWithCollection.testDeleteWithCollection-seed#[C2194C7330ACFCA9]) [ ] o.a.s.c.ZkTestServer start zk server on port:62662
[junit4] 2> 1018555 INFO (TEST-TestWithCollection.testDeleteWithCollection-seed#[C2194C7330ACFCA9]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:62662
[junit4] 2> 1018555 INFO (TEST-TestWithCollection.testDeleteWithCollection-seed#[C2194C7330ACFCA9]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:62662
[junit4] 2> 1018555 INFO (TEST-TestWithCollection.testDeleteWithCollection-seed#[C2194C7330ACFCA9]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 62662
[junit4] 2> 1018559 INFO (TEST-TestWithCollection.testDeleteWithCollection-seed#[C2194C7330ACFCA9]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1018565 INFO (zkConnectionManagerCallback-4910-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1018565 INFO (TEST-TestWithCollection.testDeleteWithCollection-seed#[C2194C7330ACFCA9]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1018571 INFO (TEST-TestWithCollection.testDeleteWithCollection-seed#[C2194C7330ACFCA9]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1018576 INFO (zkConnectionManagerCallback-4912-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1018577 INFO (TEST-TestWithCollection.testDeleteWithCollection-seed#[C2194C7330ACFCA9]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1018578 INFO (TEST-TestWithCollection.testDeleteWithCollection-seed#[C2194C7330ACFCA9]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1018583 INFO (zkConnectionManagerCallback-4914-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1018583 INFO (TEST-TestWithCollection.testDeleteWithCollection-seed#[C2194C7330ACFCA9]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1018693 WARN (jetty-launcher-4915-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1018693 WARN (jetty-launcher-4915-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1018693 INFO (jetty-launcher-4915-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1018693 INFO (jetty-launcher-4915-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1018693 INFO (jetty-launcher-4915-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1018693 INFO (jetty-launcher-4915-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1018693 INFO (jetty-launcher-4915-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 12.0.2+10
[junit4] 2> 1018693 INFO (jetty-launcher-4915-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 12.0.2+10
[junit4] 2> 1018698 INFO (jetty-launcher-4915-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1018698 INFO (jetty-launcher-4915-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1018699 INFO (jetty-launcher-4915-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1018699 INFO (jetty-launcher-4915-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1018699 INFO (jetty-launcher-4915-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1018699 INFO (jetty-launcher-4915-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1018700 INFO (jetty-launcher-4915-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@729d5458{/solr,null,AVAILABLE}
[junit4] 2> 1018700 INFO (jetty-launcher-4915-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1eeffc71{/solr,null,AVAILABLE}
[junit4] 2> 1018702 INFO (jetty-launcher-4915-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3c932abf{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:62668}
[junit4] 2> 1018702 INFO (jetty-launcher-4915-thread-1) [ ] o.e.j.s.Server Started @1018743ms
[junit4] 2> 1018702 INFO (jetty-launcher-4915-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=62668}
[junit4] 2> 1018702 ERROR (jetty-launcher-4915-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1018702 INFO (jetty-launcher-4915-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1ccb1a45{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:62669}
[junit4] 2> 1018702 INFO (jetty-launcher-4915-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1018702 INFO (jetty-launcher-4915-thread-2) [ ] o.e.j.s.Server Started @1018743ms
[junit4] 2> 1018703 INFO (jetty-launcher-4915-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 1018702 INFO (jetty-launcher-4915-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=62669}
[junit4] 2> 1018703 INFO (jetty-launcher-4915-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1018703 INFO (jetty-launcher-4915-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 1018703 INFO (jetty-launcher-4915-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-04-26T04:00:12.463067Z
[junit4] 2> 1018703 ERROR (jetty-launcher-4915-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1018703 INFO (jetty-launcher-4915-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1018703 INFO (jetty-launcher-4915-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 1018703 INFO (jetty-launcher-4915-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1018703 INFO (jetty-launcher-4915-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 1018703 INFO (jetty-launcher-4915-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-04-26T04:00:12.463615Z
[junit4] 2> 1018704 INFO (jetty-launcher-4915-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1018705 INFO (jetty-launcher-4915-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1018711 INFO (zkConnectionManagerCallback-4917-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1018711 INFO (jetty-launcher-4915-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1018711 INFO (zkConnectionManagerCallback-4919-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1018711 INFO (jetty-launcher-4915-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1018714 INFO (jetty-launcher-4915-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1018714 INFO (jetty-launcher-4915-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1018919 INFO (jetty-launcher-4915-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1018921 WARN (jetty-launcher-4915-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@432247a4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1018921 WARN (jetty-launcher-4915-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@432247a4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1018925 WARN (jetty-launcher-4915-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1d92d19e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1018925 WARN (jetty-launcher-4915-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1d92d19e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1018927 INFO (jetty-launcher-4915-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62662/solr
[junit4] 2> 1018929 INFO (jetty-launcher-4915-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1018935 INFO (zkConnectionManagerCallback-4930-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1018935 INFO (jetty-launcher-4915-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1019044 INFO (jetty-launcher-4915-thread-1) [n:127.0.0.1:62668_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1019052 INFO (zkConnectionManagerCallback-4935-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1019053 INFO (jetty-launcher-4915-thread-1) [n:127.0.0.1:62668_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1019168 INFO (jetty-launcher-4915-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1019170 WARN (jetty-launcher-4915-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@10045e4e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1019170 WARN (jetty-launcher-4915-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@10045e4e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1019174 WARN (jetty-launcher-4915-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@15cef9ef[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1019175 WARN (jetty-launcher-4915-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@15cef9ef[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1019176 INFO (jetty-launcher-4915-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62662/solr
[junit4] 2> 1019179 INFO (jetty-launcher-4915-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1019182 INFO (zkConnectionManagerCallback-4945-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1019182 INFO (jetty-launcher-4915-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1019290 INFO (jetty-launcher-4915-thread-2) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1019294 INFO (zkConnectionManagerCallback-4947-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1019295 INFO (jetty-launcher-4915-thread-2) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1019333 INFO (jetty-launcher-4915-thread-2) [n:127.0.0.1:62669_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:62669_solr
[junit4] 2> 1019336 INFO (jetty-launcher-4915-thread-2) [n:127.0.0.1:62669_solr ] o.a.s.c.Overseer Overseer (id=72111950717911048-127.0.0.1:62669_solr-n_0000000000) starting
[junit4] 2> 1019355 INFO (OverseerStateUpdate-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [n:127.0.0.1:62669_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:62669_solr
[junit4] 2> 1019356 DEBUG (OverseerAutoScalingTriggerThread-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 1019357 INFO (jetty-launcher-4915-thread-2) [n:127.0.0.1:62669_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:62669_solr
[junit4] 2> 1019361 DEBUG (OverseerAutoScalingTriggerThread-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 1019361 DEBUG (OverseerAutoScalingTriggerThread-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 1019361 DEBUG (OverseerAutoScalingTriggerThread-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 1019365 INFO (zkCallback-4946-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1019375 INFO (jetty-launcher-4915-thread-2) [n:127.0.0.1:62669_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1019377 WARN (jetty-launcher-4915-thread-2) [n:127.0.0.1:62669_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=you make it. Consider configuring authentication/authorization before exposing Solr to users internal or authorization=external. See https://s.apache.org/solrsecurity for more info. Solr is only as secure as disableddisabled
[junit4] 2> 1019379 DEBUG (OverseerAutoScalingTriggerThread-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:62669_solr]
[junit4] 2> 1019381 DEBUG (OverseerAutoScalingTriggerThread-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1019383 DEBUG (ScheduledTrigger-4952-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 1019383 TRACE (ScheduledTrigger-4952-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Current Live Nodes for .auto_add_replicas: [127.0.0.1:62669_solr]
[junit4] 2> 1019383 TRACE (ScheduledTrigger-4952-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Last Live Nodes for .auto_add_replicas: [127.0.0.1:62669_solr]
[junit4] 2> 1019384 DEBUG (OverseerAutoScalingTriggerThread-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 1019424 INFO (jetty-launcher-4915-thread-2) [n:127.0.0.1:62669_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1019434 INFO (jetty-launcher-4915-thread-1) [n:127.0.0.1:62668_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1019454 INFO (jetty-launcher-4915-thread-1) [n:127.0.0.1:62668_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:62668_solr as DOWN
[junit4] 2> 1019458 INFO (jetty-launcher-4915-thread-1) [n:127.0.0.1:62668_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1019458 INFO (jetty-launcher-4915-thread-1) [n:127.0.0.1:62668_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:62668_solr
[junit4] 2> 1019461 DEBUG (OverseerStateUpdate-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [n:127.0.0.1:62669_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:62668_solr
[junit4] 2> 1019465 INFO (zkCallback-4946-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1019466 INFO (zkCallback-4934-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1019471 INFO (jetty-launcher-4915-thread-1) [n:127.0.0.1:62668_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1019472 WARN (jetty-launcher-4915-thread-1) [n:127.0.0.1:62668_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=you make it. Consider configuring authentication/authorization before exposing Solr to users internal or authorization=external. See https://s.apache.org/solrsecurity for more info. Solr is only as secure as disableddisabled
[junit4] 2> 1019502 INFO (jetty-launcher-4915-thread-1) [n:127.0.0.1:62668_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1019508 INFO (jetty-launcher-4915-thread-2) [n:127.0.0.1:62669_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62669.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56c4b462
[junit4] 2> 1019561 INFO (jetty-launcher-4915-thread-2) [n:127.0.0.1:62669_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62669.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56c4b462
[junit4] 2> 1019562 INFO (jetty-launcher-4915-thread-2) [n:127.0.0.1:62669_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62669.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56c4b462
[junit4] 2> 1019566 INFO (jetty-launcher-4915-thread-2) [n:127.0.0.1:62669_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_C2194C7330ACFCA9-001/tempDir-001/node2/.
[junit4] 2> 1019583 INFO (jetty-launcher-4915-thread-1) [n:127.0.0.1:62668_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62668.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56c4b462
[junit4] 2> 1019611 INFO (jetty-launcher-4915-thread-1) [n:127.0.0.1:62668_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62668.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56c4b462
[junit4] 2> 1019611 INFO (jetty-launcher-4915-thread-1) [n:127.0.0.1:62668_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62668.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56c4b462
[junit4] 2> 1019619 INFO (jetty-launcher-4915-thread-1) [n:127.0.0.1:62668_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_C2194C7330ACFCA9-001/tempDir-001/node1/.
[junit4] 2> 1019636 INFO (TEST-TestWithCollection.testDeleteWithCollection-seed#[C2194C7330ACFCA9]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 1019638 INFO (TEST-TestWithCollection.testDeleteWithCollection-seed#[C2194C7330ACFCA9]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1019644 INFO (zkConnectionManagerCallback-4970-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1019644 INFO (TEST-TestWithCollection.testDeleteWithCollection-seed#[C2194C7330ACFCA9]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1019649 INFO (TEST-TestWithCollection.testDeleteWithCollection-seed#[C2194C7330ACFCA9]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1019654 INFO (TEST-TestWithCollection.testDeleteWithCollection-seed#[C2194C7330ACFCA9]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:62662/solr ready
[junit4] 2> 1019668 DEBUG (zkCallback-4946-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 1019668 DEBUG (OverseerAutoScalingTriggerThread-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 1019679 DEBUG (OverseerAutoScalingTriggerThread-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1019683 DEBUG (OverseerAutoScalingTriggerThread-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 1019686 INFO (qtp844415621-8975) [n:127.0.0.1:62668_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&name=testDeleteWithCollection_abc&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1019698 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testDeleteWithCollection_abc
[junit4] 2> 1019701 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testDeleteWithCollection_abc.AUTOCREATED/managed-schema
[junit4] 2> 1019709 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/protwords.txt
[junit4] 2> 1019717 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 1019724 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 1019731 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 1019742 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 1019750 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 1019757 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 1019764 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_et.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_et.txt
[junit4] 2> 1019771 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 1019777 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 1019783 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 1019791 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 1019798 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 1019805 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 1019810 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 1019817 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 1019824 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 1019831 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 1019838 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 1019846 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 1019854 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 1019861 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 1019867 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 1019875 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 1019883 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 1019891 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 1019898 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 1019908 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 1019914 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 1019922 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 1019930 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 1019939 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 1019949 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 1019957 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 1019966 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 1019973 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 1019978 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 1019987 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 1019992 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 1019997 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_gl.txt
[junit4] 2> 1020002 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testDeleteWithCollection_abc.AUTOCREATED/solrconfig.xml
[junit4] 2> 1020008 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/synonyms.txt
[junit4] 2> 1020013 INFO (OverseerThreadFactory-4954-thread-1-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/stopwords.txt
[junit4] 2> 1020028 DEBUG (OverseerStateUpdate-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [n:127.0.0.1:62669_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: testDeleteWithCollection_abc
[junit4] 2> 1020029 DEBUG (OverseerStateUpdate-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [n:127.0.0.1:62669_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/testDeleteWithCollection_abc/state.json
[junit4] 2> 1020154 INFO (OverseerStateUpdate-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [n:127.0.0.1:62669_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testDeleteWithCollection_abc",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testDeleteWithCollection_abc_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:62669/solr",
[junit4] 2> "node_name":"127.0.0.1:62669_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1020159 DEBUG (OverseerStateUpdate-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [n:127.0.0.1:62669_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 1020159 DEBUG (OverseerStateUpdate-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [n:127.0.0.1:62669_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":"testDeleteWithCollection_abc_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:62669/solr",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:62669_solr",
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 1020264 DEBUG (OverseerStateUpdate-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [n:127.0.0.1:62669_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testDeleteWithCollection_abc/state.json version: 0
[junit4] 2> 1020365 INFO (qtp302835316-8977) [n:127.0.0.1:62669_solr x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=testDeleteWithCollection_abc.AUTOCREATED&newCollection=true&name=testDeleteWithCollection_abc_shard1_replica_n1&action=CREATE&numShards=1&collection=testDeleteWithCollection_abc&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1020365 INFO (qtp302835316-8977) [n:127.0.0.1:62669_solr x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1020377 DEBUG (OverseerStateUpdate-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [n:127.0.0.1:62669_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"testDeleteWithCollection_abc_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:62669/solr",
[junit4] 2> "node_name":"127.0.0.1:62669_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testDeleteWithCollection_abc",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 1020377 DEBUG (OverseerStateUpdate-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [n:127.0.0.1:62669_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{
[junit4] 2> "core":"testDeleteWithCollection_abc_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:62669/solr",
[junit4] 2> "node_name":"127.0.0.1:62669_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}
[junit4] 2> 1020377 DEBUG (OverseerStateUpdate-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [n:127.0.0.1:62669_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(testDeleteWithCollection_abc//collections/testDeleteWithCollection_abc/state.json/1)={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":"testDeleteWithCollection_abc_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:62669/solr",
[junit4] 2> "node_name":"127.0.0.1:62669_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}}}}
[junit4] 2> 1020395 INFO (qtp302835316-8977) [n:127.0.0.1:62669_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 1020413 INFO (qtp302835316-8977) [n:127.0.0.1:62669_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1020484 DEBUG (OverseerStateUpdate-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [n:127.0.0.1:62669_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testDeleteWithCollection_abc/state.json version: 1
[junit4] 2> 1020621 INFO (qtp302835316-8977) [n:127.0.0.1:62669_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 1020735 INFO (qtp302835316-8977) [n:127.0.0.1:62669_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'testDeleteWithCollection_abc_shard1_replica_n1' using configuration from configset testDeleteWithCollection_abc.AUTOCREATED, trusted=true
[junit4] 2> 1020736 INFO (qtp302835316-8977) [n:127.0.0.1:62669_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62669.solr.core.testDeleteWithCollection_abc.shard1.replica_n1' (registry 'solr.core.testDeleteWithCollection_abc.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56c4b462
[junit4] 2> 1020736 INFO (qtp302835316-8977) [n:127.0.0.1:62669_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.c.SolrCore [[testDeleteWithCollection_abc_shard1_replica_n1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_C2194C7330ACFCA9-001/tempDir-001/node2/./testDeleteWithCollection_abc_shard1_replica_n1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_C2194C7330ACFCA9-001/tempDir-001/node2/./testDeleteWithCollection_abc_shard1_replica_n1/data/]
[junit4] 2> 1020815 INFO (qtp302835316-8977) [n:127.0.0.1:62669_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1020815 INFO (qtp302835316-8977) [n:127.0.0.1:62669_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1020817 INFO (qtp302835316-8977) [n:127.0.0.1:62669_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 1020817 INFO (qtp302835316-8977) [n:127.0.0.1:62669_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1020819 INFO (qtp302835316-8977) [n:127.0.0.1:62669_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5d014c63[testDeleteWithCollection_abc_shard1_replica_n1] main]
[junit4] 2> 1020822 INFO (qtp302835316-8977) [n:127.0.0.1:62669_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/testDeleteWithCollection_abc.AUTOCREATED
[junit4] 2> 1020822 INFO (qtp302835316-8977) [n:127.0.0.1:62669_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/testDeleteWithCollection_abc.AUTOCREATED
[junit4] 2> 1020822 INFO (qtp302835316-8977) [n:127.0.0.1:62669_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/testDeleteWithCollection_abc.AUTOCREATED/managed-schema
[junit4] 2> 1020824 INFO (qtp302835316-8977) [n:127.0.0.1:62669_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 1020825 INFO (qtp302835316-8977) [n:127.0.0.1:62669_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1020825 INFO (qtp302835316-8977) [n:127.0.0.1:62669_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 2> 1020826 INFO (qtp302835316-8977) [n:127.0.0.1:62669_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1020826 INFO (qtp302835316-8977) [n:127.0.0.1:62669_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1665006163288129536
[junit4] 2> 1020830 INFO (searcherExecutor-4972-thread-1-processing-n:127.0.0.1:62669_solr x:testDeleteWithCollection_abc_shard1_replica_n1 c:testDeleteWithCollection_abc s:shard1 r:core_node2) [n:127.0.0.1:62669_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@5d014c63[testDeleteWithCollection_abc_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1020830 INFO (searcherExecutor-4972-thread-1-processing-n:127.0.0.1:62669_solr x:testDeleteWithCollection_abc_shard1_replica_n1 c:testDeleteWithCollection_abc s:shard1 r:core_node2) [n:127.0.0.1:62669_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1020830 INFO (searcherExecutor-4972-thread-1-processing-n:127.0.0.1:62669_solr x:testDeleteWithCollection_abc_shard1_replica_n1 c:testDeleteWithCollection_abc s:shard1 r:core_node2) [n:127.0.0.1:62669_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1020832 INFO (searcherExecutor-4972-thread-1-processing-n:127.0.0.1:62669_solr x:testDeleteWithCollection_abc_shard1_replica_n1 c:testDeleteWithCollection_abc s:shard1 r:core_node2) [n:127.0.0.1:62669_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.c.SolrCore [testDeleteWithCollection_abc_shard1_replica_n1] Registered new searcher Searcher@5d014c63[testDeleteWithCollection_abc_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1020841 INFO (qtp302835316-8977) [n:127.0.0.1:62669_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testDeleteWithCollection_abc/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 1020841 INFO (qtp302835316-8977) [n:127.0.0.1:62669_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testDeleteWithCollection_abc/leaders/shard1
[junit4] 2> 1020854 INFO (qtp302835316-8977) [n:127.0.0.1:62669_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1020854 INFO (qtp302835316-8977) [n:127.0.0.1:62669_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1020854 INFO (qtp302835316-8977) [n:127.0.0.1:62669_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:62669/solr/testDeleteWithCollection_abc_shard1_replica_n1/
[junit4] 2> 1020855 INFO (qtp302835316-8977) [n:127.0.0.1:62669_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1020857 INFO (qtp302835316-8977) [n:127.0.0.1:62669_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:62669/solr/testDeleteWithCollection_abc_shard1_replica_n1/ has no replicas
[junit4] 2> 1020857 INFO (qtp302835316-8977) [n:127.0.0.1:62669_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testDeleteWithCollection_abc/leaders/shard1/leader after winning as /collections/testDeleteWithCollection_abc/leader_elect/shard1/election/72111950717911048-core_node2-n_0000000000
[junit4] 2> 1020866 INFO (qtp302835316-8977) [n:127.0.0.1:62669_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:62669/solr/testDeleteWithCollection_abc_shard1_replica_n1/ shard1
[junit4] 2> 1020971 DEBUG (OverseerStateUpdate-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [n:127.0.0.1:62669_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testDeleteWithCollection_abc/state.json version: 2
[junit4] 2> 1020972 INFO (zkCallback-4946-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testDeleteWithCollection_abc/state.json] for collection [testDeleteWithCollection_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1020972 INFO (zkCallback-4946-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testDeleteWithCollection_abc/state.json] for collection [testDeleteWithCollection_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1020975 INFO (qtp302835316-8977) [n:127.0.0.1:62669_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1020979 DEBUG (OverseerStateUpdate-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [n:127.0.0.1:62669_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"testDeleteWithCollection_abc_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:62669/solr",
[junit4] 2> "node_name":"127.0.0.1:62669_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testDeleteWithCollection_abc",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 1020979 DEBUG (OverseerStateUpdate-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [n:127.0.0.1:62669_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{
[junit4] 2> "core":"testDeleteWithCollection_abc_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:62669/solr",
[junit4] 2> "node_name":"127.0.0.1:62669_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "leader":"true"}
[junit4] 2> 1020980 DEBUG (OverseerStateUpdate-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [n:127.0.0.1:62669_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(testDeleteWithCollection_abc//collections/testDeleteWithCollection_abc/state.json/3)={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":"testDeleteWithCollection_abc_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:62669/solr",
[junit4] 2> "node_name":"127.0.0.1:62669_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "leader":"true"}}}}}
[junit4] 2> 1020980 INFO (qtp302835316-8977) [n:127.0.0.1:62669_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=testDeleteWithCollection_abc.AUTOCREATED&newCollection=true&name=testDeleteWithCollection_abc_shard1_replica_n1&action=CREATE&numShards=1&collection=testDeleteWithCollection_abc&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=616
[junit4] 2> 1020985 INFO (qtp844415621-8975) [n:127.0.0.1:62668_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> 1021085 DEBUG (OverseerStateUpdate-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [n:127.0.0.1:62669_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testDeleteWithCollection_abc/state.json version: 3
[junit4] 2> 1021086 INFO (zkCallback-4934-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testDeleteWithCollection_abc/state.json] for collection [testDeleteWithCollection_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1021086 INFO (zkCallback-4946-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testDeleteWithCollection_abc/state.json] for collection [testDeleteWithCollection_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1021086 INFO (zkCallback-4946-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testDeleteWithCollection_abc/state.json] for collection [testDeleteWithCollection_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1021086 INFO (zkCallback-4946-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testDeleteWithCollection_abc/state.json] for collection [testDeleteWithCollection_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1021088 INFO (qtp844415621-8975) [n:127.0.0.1:62668_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={pullReplicas=0&name=testDeleteWithCollection_abc&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2} status=0 QTime=1403
[junit4] 2> 1021090 INFO (qtp844415621-8976) [n:127.0.0.1:62668_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&withCollection=testDeleteWithCollection_abc&name=testDeleteWithCollection_xyz&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1021098 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testDeleteWithCollection_xyz
[junit4] 2> 1021100 INFO (OverseerCollectionConfigSetProcessor-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [n:127.0.0.1:62669_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 1021102 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testDeleteWithCollection_xyz.AUTOCREATED/managed-schema
[junit4] 2> 1021106 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/protwords.txt
[junit4] 2> 1021112 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 1021118 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 1021126 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 1021132 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 1021138 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 1021148 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 1021156 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_et.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_et.txt
[junit4] 2> 1021204 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 1021210 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 1021216 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 1021222 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 1021227 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 1021232 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 1021236 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 1021242 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 1021248 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 1021254 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 1021259 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 1021264 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 1021270 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 1021275 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 1021280 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 1021285 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 1021293 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 1021298 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 1021304 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 1021309 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 1021314 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 1021319 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 1021324 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 1021329 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 1021333 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 1021341 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 1021346 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 1021351 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 1021357 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 1021362 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 1021367 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 1021372 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_gl.txt
[junit4] 2> 1021377 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testDeleteWithCollection_xyz.AUTOCREATED/solrconfig.xml
[junit4] 2> 1021381 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/synonyms.txt
[junit4] 2> 1021386 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/stopwords.txt
[junit4] 2> 1021400 DEBUG (OverseerStateUpdate-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [n:127.0.0.1:62669_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: testDeleteWithCollection_xyz
[junit4] 2> 1021502 DEBUG (OverseerStateUpdate-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [n:127.0.0.1:62669_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/testDeleteWithCollection_xyz/state.json
[junit4] 2> 1021604 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:62668_solr for creating new replica of shard shard1 for collection testDeleteWithCollection_abc
[junit4] 2> 1021607 INFO (OverseerThreadFactory-4954-thread-2-processing-n:127.0.0.1:62669_solr) [n:127.0.0.1:62669_solr ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 1021611 INFO (OverseerStateUpdate-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [n:127.0.0.1:62669_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"addreplica",
[junit4] 2> "collection":"testDeleteWithCollection_abc",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testDeleteWithCollection_abc_shard1_replica_n3",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:62668/solr",
[junit4] 2> "node_name":"127.0.0.1:62668_solr",
[junit4] 2> "type":"NRT"}
[junit4] 2> 1021614 DEBUG (OverseerStateUpdate-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [n:127.0.0.1:62669_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":"testDeleteWithCollection_abc_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:62669/solr",
[junit4] 2> "node_name":"127.0.0.1:62669_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "leader":"true"}}}
[junit4] 2> 1021614 DEBUG (OverseerStateUpdate-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [n:127.0.0.1:62669_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node2":{
[junit4] 2> "core":"testDeleteWithCollection_abc_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:62669/solr",
[junit4] 2> "node_name":"127.0.0.1:62669_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "leader":"true"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testDeleteWithCollection_abc_shard1_replica_n3",
[junit4] 2> "base_url":"http://127.0.0.1:62668/solr",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:62668_solr",
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 1021718 DEBUG (OverseerStateUpdate-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [n:127.0.0.1:62669_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testDeleteWithCollection_abc/state.json version: 4
[junit4] 2> 1021719 INFO (zkCallback-4946-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testDeleteWithCollection_abc/state.json] for collection [testDeleteWithCollection_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1021719 INFO (zkCallback-4946-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testDeleteWithCollection_abc/state.json] for collection [testDeleteWithCollection_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1021719 INFO (zkCallback-4946-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testDeleteWithCollection_abc/state.json] for collection [testDeleteWithCollection_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1021819 INFO (qtp844415621-8975) [n:127.0.0.1:62668_solr x:testDeleteWithCollection_abc_shard1_replica_n3 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=testDeleteWithCollection_abc.AUTOCREATED&name=testDeleteWithCollection_abc_shard1_replica_n3&action=CREATE&collection=testDeleteWithCollection_abc&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1021830 DEBUG (OverseerStateUpdate-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [n:127.0.0.1:62669_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=null message={
[junit4] 2> "core":"testDeleteWithCollection_abc_shard1_replica_n3",
[junit4] 2> "core_node_name":"core_node4",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:62668/solr",
[junit4] 2> "node_name":"127.0.0.1:62668_solr",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testDeleteWithCollection_abc",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 1021830 DEBUG (OverseerStateUpdate-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [n:127.0.0.1:62669_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node4:{
[junit4] 2> "core":"testDeleteWithCollection_abc_shard1_replica_n3",
[junit4] 2> "base_url":"http://127.0.0.1:62668/solr",
[junit4] 2> "node_name":"127.0.0.1:62668_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}
[junit4] 2> 1021830 DEBUG (OverseerStateUpdate-72111950717911048-127.0.0.1:62669_solr-n_0000000000) [n:127.0.0.1:62669_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(testDeleteWithCollection_abc//collections/testDeleteWithCollection_abc/state.json/5)={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node2":{
[junit4] 2> "core":"testDeleteWithCollection_abc_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:62669/solr",
[junit4] 2> "node_name":"127.0.0.1:62669_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "leader":"true"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testDeleteWithCollection_abc_shard1_replica_n3",
[junit4] 2> "base_url":"http://127.0.0.1:62668/solr",
[junit4] 2> "node_name":"127.0.0.1:62668_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}}}}
[junit4] 2> 1021848 INFO (qtp844415621-8975) [n:127.0.0.1:62668_solr c:testDeleteWithCollection_abc s:shard1 r:core_node4 x:testDeleteWithCollection_abc_shard1_replica_n3 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 1021871 INFO (qtp844415621-8975) [n:127.0.0.1:62668_solr c:testDeleteWithCollection_abc s:shard1 r:core_node4 x:testDeleteWithCollection_abc_shard1_rep
[...truncated too long message...]
/solr/collections/testschemaapi/collectionprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 23 /solr/collections/testschemaapi/state.json
[junit4] 2> 6 /solr/configs/conf1/managed-schema
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 19 /solr/overseer/queue
[junit4] 2> 11 /solr/overseer/collection-queue-work
[junit4] 2> 7 /solr/live_nodes
[junit4] 2> 6 /solr/collections
[junit4] 2>
[junit4] 2> 2000797 INFO (SUITE-TestManagedSchemaAPI-seed#[C2194C7330ACFCA9]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:57306
[junit4] 2> 2000797 INFO (SUITE-TestManagedSchemaAPI-seed#[C2194C7330ACFCA9]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:57306
[junit4] 2> 2000797 INFO (SUITE-TestManagedSchemaAPI-seed#[C2194C7330ACFCA9]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 57306
[junit4] 2> 2000797 INFO (SUITE-TestManagedSchemaAPI-seed#[C2194C7330ACFCA9]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
[junit4] 2> Apr 26, 2020 4:16:34 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> Apr 26, 2020 4:16:45 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> SEVERE: 1 thread leaked from SUITE scope at org.apache.solr.schema.TestManagedSchemaAPI:
[junit4] 2> 1) Thread[id=26054, name=SessionTracker, state=TIMED_WAITING, group=TGRP-TestManagedSchemaAPI]
[junit4] 2> at java.base@12.0.2/java.lang.Thread.sleep(Native Method)
[junit4] 2> at app//org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151)
[junit4] 2> Apr 26, 2020 4:16:45 AM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=26054, name=SessionTracker, state=TIMED_WAITING, group=TGRP-TestManagedSchemaAPI]
[junit4] 2> 2011275 ERROR (SessionTracker) [ ] o.a.z.s.ZooKeeperCriticalThread Severe unrecoverable error, from thread : SessionTracker
[junit4] 2> => java.lang.InterruptedException: sleep interrupted
[junit4] 2> at java.base/java.lang.Thread.sleep(Native Method)
[junit4] 2> java.lang.InterruptedException: sleep interrupted
[junit4] 2> at java.lang.Thread.sleep(Native Method) [?:?]
[junit4] 2> at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151) ~[zookeeper-3.5.7.jar:3.5.7]
[junit4] 2> Apr 26, 2020 4:16:45 AM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: All leaked threads terminated.
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {_root_=PostingsFormat(name=LuceneVarGapDocFreqInterval), id=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), myNewField1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), myNewField=Lucene84}, docValues:{_version_=DocValuesFormat(name=Lucene80), id=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=990, maxMBSortInHeap=6.244142252454687, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@3f73eba5), locale=ce-RU, timezone=Etc/GMT+10
[junit4] 2> NOTE: Mac OS X 10.14.6 x86_64/AdoptOpenJDK 12.0.2 (64-bit)/cpus=6,threads=1,free=213163400,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [UpdateLogTest, ShardRoutingCustomTest, XmlInterpolationTest, SuggesterWFSTTest, NotRequiredUniqueKeyTest, TestSolrJacksonAnnotation, AsyncCallRequestStatusResponseTest, CurrencyRangeFacetCloudTest, UninvertDocValuesMergePolicyTest, TestSimDistributedQueue, DocExpirationUpdateProcessorFactoryTest, DocValuesNotIndexedTest, DaemonStreamApiTest, TestSimNodeAddedTrigger, ConfigSetsAPITest, DynamicMapsTest, TestLegacyBM25SimilarityFactory, TestLFUCache, HDFSCollectionsAPITest, ClassificationUpdateProcessorTest, EnumFieldTest, RuleEngineTest, TestManagedResourceStorage, TestUtilizeNode, AtomicUpdateRemovalJavabinTest, TriLevelCompositeIdRoutingTest, RestoreTriggerStateTest, CoreAdminCreateDiscoverTest, ScriptEngineTest, BadIndexSchemaTest, ResponseHeaderTest, OverseerTest, TestUniqueKeyFieldResource, MoveReplicaHDFSTest, TestQueryingOnDownCollection, OutputWriterTest, MetricsHistoryIntegrationTest, QueryParsingTest, SecurityConfHandlerTest, TestComplexPhraseQParserPlugin, TestDFISimilarityFactory, HdfsThreadLeakTest, TestNumericRangeQuery64, MultiDestinationAuditLoggerTest, LukeRequestHandlerTest, SolrCoreTest, TestCursorMarkWithoutUniqueKey, EmbeddedSolrNoSerializeTest, TestZkAclsWithHadoopAuth, TestCloudJSONFacetJoinDomain, TestAuthenticationFramework, SolrCloudExampleTest, TermVectorComponentDistributedTest, AutoScalingHandlerTest, TestSchemaNameResource, CustomTermsComponentTest, HdfsNNFailoverTest, ReindexCollectionTest, TriggerIntegrationTest, PreAnalyzedFieldTest, BasicZkTest, TestSimpleTrackingShardHandler, TestSchemaField, CSVRequestHandlerTest, SimpleCollectionCreateDeleteTest, TestDocTermOrdsUninvertLimit, TestSolrCloudWithDelegationTokens, OverseerRolesTest, TestDeleteCollectionOnDownNodes, TestRandomCollapseQParserPlugin, TestPayloadScoreQParserPlugin, TestSuggestSpellingConverter, TestRawTransformer, TestEmbeddedSolrServerConstructors, TestFacetMethods, SolrCoreMetricManagerTest, CursorPagingTest, SolrJmxReporterCloudTest, DirectUpdateHandlerTest, AuthToolTest, PathHierarchyTokenizerFactoryTest, TestExceedMaxTermLength, TestJsonFacetsStatsParsing, AddReplicaTest, TestSimUtils, HttpPartitionTest, TestReplicationHandlerDiskOverFlow, TestStressVersions, TestDistributedSearch, TestDocumentBuilder, TestGroupingSearch, TestHighlightDedupGrouping, TestJoin, TestSolrTestCaseJ4, TestCharFilters, TestDeprecatedFilters, TestLuceneMatchVersion, TestReversedWildcardFilterFactory, TestWordDelimiterFilterFactory, TestJettySolrRunner, ChaosMonkeySafeLeaderTest, ClusterStateTest, CollectionPropsTest, DeleteLastCustomShardedReplicaTest, DeleteReplicaTest, MigrateRouteKeyTest, PeerSyncReplicationTest, TestDynamicFieldNamesIndexCorrectly, TestRebalanceLeaders, TestStressLiveNodes, TestCollectionAPI, IndexSizeTriggerMixedBoundsTest, TestDirectoryFactory, TestDynamicLoadingUrl, TestSolrDeletionPolicy2, TestSolrCoreSnapshots, RequestLoggingTest, TestBlobHandler, TestSolrConfigHandlerConcurrent, DistributedExpandComponentTest, DistributedFacetPivotLongTailTest, DistributedMLTComponentTest, DistributedSpellCheckComponentTest, InfixSuggestersTest, ShardsWhitelistTest, TestDistributedStatsComponentCardinality, TestMacros, TestBinaryResponseWriter, TestJavabinTupleStreamParser, TestChildDocTransformerHierarchy, TestBulkSchemaAPI, TestManagedSchemaAPI]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestManagedSchemaAPI -Dtests.seed=C2194C7330ACFCA9 -Dtests.slow=true -Dtests.locale=ce-RU -Dtests.timezone=Etc/GMT+10 -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.00s J3 | TestManagedSchemaAPI (suite) <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.schema.TestManagedSchemaAPI:
[junit4] > 1) Thread[id=26054, name=SessionTracker, state=TIMED_WAITING, group=TGRP-TestManagedSchemaAPI]
[junit4] > at java.base@12.0.2/java.lang.Thread.sleep(Native Method)
[junit4] > at app//org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151)
[junit4] > at __randomizedtesting.SeedInfo.seed([C2194C7330ACFCA9]:0)
[junit4] Completed [745/906 (3!)] on J3 in 16.83s, 1 test, 1 error <<< FAILURES!
[...truncated 48504 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/897/consoleText
[repro] Revision: 83f090877b0590a1d99c79cfeec076dfed963076
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC"
[repro] Repro line: ant test -Dtestcase=TestWithCollection -Dtests.seed=C2194C7330ACFCA9 -Dtests.slow=true -Dtests.locale=en-SC -Dtests.timezone=America/Winnipeg -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[repro] Repro line: ant test -Dtestcase=LeaderFailoverAfterPartitionTest -Dtests.method=test -Dtests.seed=C2194C7330ACFCA9 -Dtests.slow=true -Dtests.locale=ms-MY -Dtests.timezone=Africa/Kigali -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[repro] Repro line: ant test -Dtestcase=TestManagedSchemaAPI -Dtests.seed=C2194C7330ACFCA9 -Dtests.slow=true -Dtests.locale=ce-RU -Dtests.timezone=Etc/GMT+10 -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[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] TestManagedSchemaAPI
[repro] LeaderFailoverAfterPartitionTest
[repro] TestWithCollection
[repro] ant compile-test
[...truncated 3484 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=15 -Dtests.class="*.TestManagedSchemaAPI|*.LeaderFailoverAfterPartitionTest|*.TestWithCollection" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=C2194C7330ACFCA9 -Dtests.slow=true -Dtests.locale=ce-RU -Dtests.timezone=Etc/GMT+10 -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[...truncated 162 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.cloud.LeaderFailoverAfterPartitionTest
[repro] 0/5 failed: org.apache.solr.cloud.TestWithCollection
[repro] 0/5 failed: org.apache.solr.schema.TestManagedSchemaAPI
[repro] Exiting with code 0
[...truncated 73 lines...]