You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2021/03/03 00:02:55 UTC
[JENKINS] Lucene » Lucene-Solr-Tests-8.8 - Build # 368 - Unstable!
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.8/368/
1 tests failed.
FAILED: org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest.testHistory
Error Message:
action did not execute
Stack Trace:
java.lang.AssertionError: action did not execute
at __randomizedtesting.SeedInfo.seed([A68BD0FA1DD2A881:CB777407A79A5786]:0)
at org.junit.Assert.fail(Assert.java:89)
at org.junit.Assert.assertTrue(Assert.java:42)
at org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest.testHistory(AutoscalingHistoryHandlerTest.java:283)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 15531 lines...]
[junit4] Suite: org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest
[junit4] 2> 1894377 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A68BD0FA1DD2A881]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/server/solr/configsets/_default/conf'
[junit4] 2> 1894377 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A68BD0FA1DD2A881]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1894378 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A68BD0FA1DD2A881]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J3/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_A68BD0FA1DD2A881-001/data-dir-189-001
[junit4] 2> 1894378 WARN (SUITE-AutoscalingHistoryHandlerTest-seed#[A68BD0FA1DD2A881]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=6 numCloses=6
[junit4] 2> 1894378 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A68BD0FA1DD2A881]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1894380 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A68BD0FA1DD2A881]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 1894380 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A68BD0FA1DD2A881]-worker) [ ] o.a.s.c.SolrCloudTestCase Using per-replica state
[junit4] 2> 1894380 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A68BD0FA1DD2A881]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J3/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_A68BD0FA1DD2A881-001/tempDir-001
[junit4] 2> 1894380 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A68BD0FA1DD2A881]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1894382 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 1894382 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1894383 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 1894651 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A68BD0FA1DD2A881]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 36069
[junit4] 2> 1894651 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A68BD0FA1DD2A881]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:36069
[junit4] 2> 1894651 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A68BD0FA1DD2A881]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:36069
[junit4] 2> 1894651 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A68BD0FA1DD2A881]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 36069
[junit4] 2> 1894655 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A68BD0FA1DD2A881]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1894656 INFO (zkConnectionManagerCallback-15769-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1894656 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A68BD0FA1DD2A881]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1894701 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A68BD0FA1DD2A881]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1894714 INFO (zkConnectionManagerCallback-15771-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1894714 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A68BD0FA1DD2A881]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1894719 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A68BD0FA1DD2A881]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1894719 INFO (zkConnectionManagerCallback-15773-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1894719 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A68BD0FA1DD2A881]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1894830 INFO (jetty-launcher-15774-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1894830 INFO (jetty-launcher-15774-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1894830 INFO (jetty-launcher-15774-thread-1) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_281-b09
[junit4] 2> 1894840 INFO (jetty-launcher-15774-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1894840 INFO (jetty-launcher-15774-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1894840 INFO (jetty-launcher-15774-thread-2) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_281-b09
[junit4] 2> 1894854 INFO (jetty-launcher-15774-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1894854 INFO (jetty-launcher-15774-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1894854 INFO (jetty-launcher-15774-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1894861 INFO (jetty-launcher-15774-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@68439714{/solr,null,AVAILABLE}
[junit4] 2> 1894869 INFO (jetty-launcher-15774-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@377fcf74{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:46731}
[junit4] 2> 1894869 INFO (jetty-launcher-15774-thread-1) [ ] o.e.j.s.Server Started @1894898ms
[junit4] 2> 1894869 INFO (jetty-launcher-15774-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:36069/solr, hostPort=46731}
[junit4] 2> 1894869 ERROR (jetty-launcher-15774-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1894869 INFO (jetty-launcher-15774-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1894869 INFO (jetty-launcher-15774-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.8.2
[junit4] 2> 1894869 INFO (jetty-launcher-15774-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1894869 INFO (jetty-launcher-15774-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr
[junit4] 2> 1894869 INFO (jetty-launcher-15774-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-03-02T23:36:36.914Z
[junit4] 2> 1894890 INFO (jetty-launcher-15774-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1894890 INFO (jetty-launcher-15774-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1894890 INFO (jetty-launcher-15774-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1894890 INFO (jetty-launcher-15774-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5ca4b80e{/solr,null,AVAILABLE}
[junit4] 2> 1894891 INFO (jetty-launcher-15774-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1894891 INFO (jetty-launcher-15774-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@72f2856a{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:33751}
[junit4] 2> 1894891 INFO (jetty-launcher-15774-thread-2) [ ] o.e.j.s.Server Started @1894920ms
[junit4] 2> 1894891 INFO (jetty-launcher-15774-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:36069/solr, hostPort=33751}
[junit4] 2> 1894891 ERROR (jetty-launcher-15774-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1894891 INFO (jetty-launcher-15774-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1894892 INFO (jetty-launcher-15774-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.8.2
[junit4] 2> 1894892 INFO (jetty-launcher-15774-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1894892 INFO (jetty-launcher-15774-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr
[junit4] 2> 1894892 INFO (jetty-launcher-15774-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-03-02T23:36:36.937Z
[junit4] 2> 1894892 INFO (zkConnectionManagerCallback-15776-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1894892 INFO (jetty-launcher-15774-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1894892 INFO (jetty-launcher-15774-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1894893 INFO (jetty-launcher-15774-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1894893 INFO (zkConnectionManagerCallback-15778-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1894893 INFO (jetty-launcher-15774-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1894901 INFO (jetty-launcher-15774-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1895658 INFO (jetty-launcher-15774-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1895660 WARN (jetty-launcher-15774-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@41184e78[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1895661 WARN (jetty-launcher-15774-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@41184e78[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1895686 WARN (jetty-launcher-15774-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@44cc631a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1895686 WARN (jetty-launcher-15774-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@44cc631a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1895687 INFO (jetty-launcher-15774-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36069/solr
[junit4] 2> 1895700 INFO (jetty-launcher-15774-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1895870 INFO (zkConnectionManagerCallback-15792-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1895870 INFO (jetty-launcher-15774-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1895977 INFO (jetty-launcher-15774-thread-1) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1895987 INFO (zkConnectionManagerCallback-15794-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1895988 INFO (jetty-launcher-15774-thread-1) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1896090 INFO (jetty-launcher-15774-thread-1) [n:127.0.0.1:46731_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:46731_solr
[junit4] 2> 1896090 INFO (jetty-launcher-15774-thread-1) [n:127.0.0.1:46731_solr ] o.a.s.c.Overseer Overseer (id=73091384455921670-127.0.0.1:46731_solr-n_0000000000) starting
[junit4] 2> 1896095 INFO (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:46731_solr
[junit4] 2> 1896106 INFO (jetty-launcher-15774-thread-1) [n:127.0.0.1:46731_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46731_solr
[junit4] 2> 1896107 INFO (jetty-launcher-15774-thread-1) [n:127.0.0.1:46731_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1896108 WARN (jetty-launcher-15774-thread-1) [n:127.0.0.1:46731_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1896114 INFO (zkCallback-15793-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1896119 DEBUG (OverseerAutoScalingTriggerThread-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 1896120 DEBUG (OverseerAutoScalingTriggerThread-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 1896121 DEBUG (OverseerAutoScalingTriggerThread-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 1896121 DEBUG (OverseerAutoScalingTriggerThread-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 1896125 INFO (jetty-launcher-15774-thread-1) [n:127.0.0.1:46731_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1896249 DEBUG (OverseerAutoScalingTriggerThread-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:46731_solr]
[junit4] 2> 1896261 DEBUG (OverseerAutoScalingTriggerThread-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1896262 DEBUG (OverseerAutoScalingTriggerThread-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 1896262 DEBUG (ScheduledTrigger-15799-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> 1896283 INFO (jetty-launcher-15774-thread-1) [n:127.0.0.1:46731_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46731.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3700b025
[junit4] 2> 1896294 INFO (jetty-launcher-15774-thread-1) [n:127.0.0.1:46731_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46731.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3700b025
[junit4] 2> 1896294 INFO (jetty-launcher-15774-thread-1) [n:127.0.0.1:46731_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46731.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3700b025
[junit4] 2> 1896296 INFO (jetty-launcher-15774-thread-1) [n:127.0.0.1:46731_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J3/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_A68BD0FA1DD2A881-001/tempDir-001/node1
[junit4] 2> 1896730 INFO (jetty-launcher-15774-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1896731 WARN (jetty-launcher-15774-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2f4a580b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1896731 WARN (jetty-launcher-15774-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2f4a580b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1896734 WARN (jetty-launcher-15774-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@56a46873[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1896734 WARN (jetty-launcher-15774-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@56a46873[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1896735 INFO (jetty-launcher-15774-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36069/solr
[junit4] 2> 1896737 INFO (jetty-launcher-15774-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1896772 INFO (zkConnectionManagerCallback-15814-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1896772 INFO (jetty-launcher-15774-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1896778 WARN (jetty-launcher-15774-thread-2-SendThread(127.0.0.1:36069)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x103ac3a16a30007.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x103ac3a16a30007, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x103ac3a16a30007, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 1896879 INFO (jetty-launcher-15774-thread-2) [n:127.0.0.1:33751_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1896881 INFO (zkConnectionManagerCallback-15816-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1896881 INFO (jetty-launcher-15774-thread-2) [n:127.0.0.1:33751_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1896890 INFO (jetty-launcher-15774-thread-2) [n:127.0.0.1:33751_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1896896 INFO (jetty-launcher-15774-thread-2) [n:127.0.0.1:33751_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:33751_solr as DOWN
[junit4] 2> 1896897 INFO (jetty-launcher-15774-thread-2) [n:127.0.0.1:33751_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1896897 INFO (jetty-launcher-15774-thread-2) [n:127.0.0.1:33751_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33751_solr
[junit4] 2> 1896898 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr",
[junit4] 2> "operation":"downnode"} current state version: 0
[junit4] 2> 1896898 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:33751_solr
[junit4] 2> 1896898 INFO (zkCallback-15793-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1896899 INFO (zkCallback-15815-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1896901 INFO (jetty-launcher-15774-thread-2) [n:127.0.0.1:33751_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1896901 WARN (jetty-launcher-15774-thread-2) [n:127.0.0.1:33751_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1896913 INFO (jetty-launcher-15774-thread-2) [n:127.0.0.1:33751_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1896931 INFO (jetty-launcher-15774-thread-2) [n:127.0.0.1:33751_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33751.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3700b025
[junit4] 2> 1896971 INFO (jetty-launcher-15774-thread-2) [n:127.0.0.1:33751_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33751.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3700b025
[junit4] 2> 1896971 INFO (jetty-launcher-15774-thread-2) [n:127.0.0.1:33751_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33751.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3700b025
[junit4] 2> 1896972 INFO (jetty-launcher-15774-thread-2) [n:127.0.0.1:33751_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J3/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_A68BD0FA1DD2A881-001/tempDir-001/node2
[junit4] 2> 1896991 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A68BD0FA1DD2A881]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 1896992 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A68BD0FA1DD2A881]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1896993 INFO (zkConnectionManagerCallback-15829-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1896993 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A68BD0FA1DD2A881]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1896997 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A68BD0FA1DD2A881]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1896998 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A68BD0FA1DD2A881]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36069/solr ready
[junit4] 2> 1897009 INFO (OverseerThreadFactory-15801-thread-1-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection .system
[junit4] 2> 1897011 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "name":".system",
[junit4] 2> "fromApi":"true",
[junit4] 2> "numShards":1,
[junit4] 2> "createNodeSet":"127.0.0.1:46731_solr",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 1897011 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: .system
[junit4] 2> 1897012 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/.system/state.json
[junit4] 2> 1897163 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:46731_solr",
[junit4] 2> "base_url":"http://127.0.0.1:46731/solr",
[junit4] 2> "collection":".system",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 1897163 INFO (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:46731_solr",
[junit4] 2> "base_url":"http://127.0.0.1:46731/solr",
[junit4] 2> "collection":".system",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1897164 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 1897164 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_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":".system_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:46731_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:46731/solr"}}}
[junit4] 2> 1897263 DEBUG (ScheduledTrigger-15799-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 1
[junit4] 2> 1897265 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 0
[junit4] 2> 1897369 INFO (qtp968373811-29711) [n:127.0.0.1:46731_solr x:.system_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n1&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1897370 INFO (qtp968373811-29711) [n:127.0.0.1:46731_solr x:.system_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1897386 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:46731_solr",
[junit4] 2> "base_url":"http://127.0.0.1:46731/solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":".system",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1897386 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:46731_solr",
[junit4] 2> "base_url":"http://127.0.0.1:46731/solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":".system",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 1897386 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:46731_solr",
[junit4] 2> "base_url":"http://127.0.0.1:46731/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}
[junit4] 2> 1897386 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(.system//collections/.system/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":".system_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:46731_solr",
[junit4] 2> "base_url":"http://127.0.0.1:46731/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}}}}
[junit4] 2> 1897392 INFO (qtp968373811-29711) [n:127.0.0.1:46731_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.2
[junit4] 2> 1897398 INFO (qtp968373811-29711) [n:127.0.0.1:46731_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 1897400 INFO (qtp968373811-29711) [n:127.0.0.1:46731_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=_system collection or core
[junit4] 2> 1897400 INFO (qtp968373811-29711) [n:127.0.0.1:46731_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id
[junit4] 2> 1897402 INFO (qtp968373811-29711) [n:127.0.0.1:46731_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Created and persisted managed schema znode at /configs/.system/managed-schema
[junit4] 2> 1897404 INFO (qtp968373811-29711) [n:127.0.0.1:46731_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in ZooKeeper, renamed the non-managed schema /configs/.system/schema.xml to /configs/.system/schema.xml.bak
[junit4] 2> 1897405 INFO (qtp968373811-29711) [n:127.0.0.1:46731_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore '.system_shard1_replica_n1' using configuration from configset .system, trusted=true
[junit4] 2> 1897405 INFO (qtp968373811-29711) [n:127.0.0.1:46731_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46731.solr.core..system.shard1.replica_n1' (registry 'solr.core..system.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3700b025
[junit4] 2> 1897405 INFO (qtp968373811-29711) [n:127.0.0.1:46731_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [[.system_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J3/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_A68BD0FA1DD2A881-001/tempDir-001/node1/.system_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J3/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_A68BD0FA1DD2A881-001/tempDir-001/node1/.system_shard1_replica_n1/data/]
[junit4] 2> 1897449 INFO (qtp968373811-29711) [n:127.0.0.1:46731_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1897449 INFO (qtp968373811-29711) [n:127.0.0.1:46731_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1897450 INFO (qtp968373811-29711) [n:127.0.0.1:46731_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: if 1 uncommitted docs;
[junit4] 2> 1897450 INFO (qtp968373811-29711) [n:127.0.0.1:46731_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1897452 INFO (qtp968373811-29711) [n:127.0.0.1:46731_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/.system
[junit4] 2> 1897452 INFO (qtp968373811-29711) [n:127.0.0.1:46731_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/.system
[junit4] 2> 1897452 INFO (qtp968373811-29711) [n:127.0.0.1:46731_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/.system/managed-schema
[junit4] 2> 1897453 INFO (qtp968373811-29711) [n:127.0.0.1:46731_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 1897453 INFO (qtp968373811-29711) [n:127.0.0.1:46731_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1897453 INFO (qtp968373811-29711) [n:127.0.0.1:46731_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1693165236516814848
[junit4] 2> 1897458 INFO (searcherExecutor-15831-thread-1-processing-n:127.0.0.1:46731_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:46731_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [.system_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1897460 INFO (qtp968373811-29711) [n:127.0.0.1:46731_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/.system/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 1897460 INFO (qtp968373811-29711) [n:127.0.0.1:46731_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/.system/leaders/shard1
[junit4] 2> 1897560 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 1
[junit4] 2> 1897564 INFO (qtp968373811-29711) [n:127.0.0.1:46731_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1897564 INFO (qtp968373811-29711) [n:127.0.0.1:46731_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1897564 INFO (qtp968373811-29711) [n:127.0.0.1:46731_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:46731/solr/.system_shard1_replica_n1/
[junit4] 2> 1897565 INFO (qtp968373811-29711) [n:127.0.0.1:46731_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1897565 INFO (qtp968373811-29711) [n:127.0.0.1:46731_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:46731/solr/.system_shard1_replica_n1/ has no replicas
[junit4] 2> 1897565 INFO (qtp968373811-29711) [n:127.0.0.1:46731_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/.system/leaders/shard1/leader after winning as /collections/.system/leader_elect/shard1/election/73091384455921670-core_node2-n_0000000000
[junit4] 2> 1897567 INFO (qtp968373811-29711) [n:127.0.0.1:46731_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:46731/solr/.system_shard1_replica_n1/ shard1
[junit4] 2> 1897567 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:46731_solr",
[junit4] 2> "base_url":"http://127.0.0.1:46731/solr",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":".system",
[junit4] 2> "state":"active",
[junit4] 2> "operation":"leader"} current state version: 0
[junit4] 2> 1897673 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 2
[junit4] 2> 1897673 INFO (zkCallback-15793-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1897674 INFO (qtp968373811-29711) [n:127.0.0.1:46731_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1897675 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:46731_solr",
[junit4] 2> "base_url":"http://127.0.0.1:46731/solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":".system",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1897675 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:46731_solr",
[junit4] 2> "base_url":"http://127.0.0.1:46731/solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":".system",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 1897675 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:46731_solr",
[junit4] 2> "base_url":"http://127.0.0.1:46731/solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "leader":"true"}
[junit4] 2> 1897675 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(.system//collections/.system/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":".system_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:46731_solr",
[junit4] 2> "base_url":"http://127.0.0.1:46731/solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "leader":"true"}}}}}
[junit4] 2> 1897677 INFO (qtp968373811-29711) [n:127.0.0.1:46731_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n1&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=307
[junit4] 2> 1897678 INFO (qtp968373811-29708) [n:127.0.0.1:46731_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> 1897776 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 3
[junit4] 2> 1897777 INFO (zkCallback-15793-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1897777 INFO (zkCallback-15793-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1897777 INFO (qtp968373811-29708) [n:127.0.0.1:46731_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=.system&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:46731_solr&wt=javabin&version=2} status=0 QTime=774
[junit4] 2> 1897778 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A68BD0FA1DD2A881]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: .system
[junit4] 2> 1898023 INFO (OverseerCollectionConfigSetProcessor-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_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> 1898024 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection AutoscalingHistoryHandlerTest_collection
[junit4] 2> 1898025 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED
[junit4] 2> 1898027 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/protwords.txt
[junit4] 2> 1898029 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/managed-schema
[junit4] 2> 1898032 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/solrconfig.xml
[junit4] 2> 1898046 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 1898048 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 1898049 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 1898051 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 1898052 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 1898053 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 1898054 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_et.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_et.txt
[junit4] 2> 1898059 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 1898061 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 1898063 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 1898064 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 1898067 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 1898068 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 1898070 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 1898072 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 1898074 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 1898076 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 1898077 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 1898078 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 1898081 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 1898082 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 1898086 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 1898089 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 1898105 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 1898107 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 1898113 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 1898116 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 1898118 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 1898120 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 1898122 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 1898123 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 1898124 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 1898126 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 1898127 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 1898128 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 1898130 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 1898132 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 1898133 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 1898135 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_gl.txt
[junit4] 2> 1898137 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/synonyms.txt
[junit4] 2> 1898138 INFO (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/stopwords.txt
[junit4] 2> 1898142 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "name":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "numShards":"1",
[junit4] 2> "maxShardsPerNode":"3",
[junit4] 2> "createNodeSet":"127.0.0.1:33751_solr",
[junit4] 2> "nrtReplicas":"3",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"3",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 1898142 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: AutoscalingHistoryHandlerTest_collection
[junit4] 2> 1898314 DEBUG (ScheduledTrigger-15799-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1898314 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/AutoscalingHistoryHandlerTest_collection/state.json
[junit4] 2> 1898416 WARN (OverseerThreadFactory-15801-thread-2-processing-n:127.0.0.1:46731_solr) [n:127.0.0.1:46731_solr ] o.a.s.c.a.c.CreateCollectionCmd Specified number of replicas of 3 on collection AutoscalingHistoryHandlerTest_collection is higher than the number of Solr instances currently live or live and part of your createNodeSet(1). It's unusual to run two replica of the same slice on the same Solr-instance.
[junit4] 2> 1898418 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 1898419 INFO (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1898420 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 1898420 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node3":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr"}}}
[junit4] 2> 1898420 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 1898421 INFO (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1898422 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node3":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr"}}}
[junit4] 2> 1898422 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_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_node3":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr"},
[junit4] 2> "core_node5":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr"}}}
[junit4] 2> 1898423 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 1898423 INFO (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1898423 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr"},
[junit4] 2> "core_node5":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr"}}}
[junit4] 2> 1898423 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_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_node3":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr"},
[junit4] 2> "core_node5":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr"},
[junit4] 2> "core_node6":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr"}}}
[junit4] 2> 1898524 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/AutoscalingHistoryHandlerTest_collection/state.json version: 0
[junit4] 2> 1898642 INFO (qtp2083873948-29716) [n:127.0.0.1:33751_solr x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=AutoscalingHistoryHandlerTest_collection.AUTOCREATED&newCollection=true&name=AutoscalingHistoryHandlerTest_collection_shard1_replica_n2&action=CREATE&numShards=1&collection=AutoscalingHistoryHandlerTest_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1898642 INFO (qtp2083873948-29719) [n:127.0.0.1:33751_solr x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n4 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node6&collection.configName=AutoscalingHistoryHandlerTest_collection.AUTOCREATED&newCollection=true&name=AutoscalingHistoryHandlerTest_collection_shard1_replica_n4&action=CREATE&numShards=1&collection=AutoscalingHistoryHandlerTest_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1898643 INFO (qtp2083873948-29721) [n:127.0.0.1:33751_solr x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=AutoscalingHistoryHandlerTest_collection.AUTOCREATED&newCollection=true&name=AutoscalingHistoryHandlerTest_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=AutoscalingHistoryHandlerTest_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1898649 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "core_node_name":"core_node5",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1898649 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "core_node_name":"core_node5",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 1898649 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node5:{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}
[junit4] 2> 1898649 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(AutoscalingHistoryHandlerTest_collection//collections/AutoscalingHistoryHandlerTest_collection/state.json/1 )={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"3",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"3",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"3",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr"},
[junit4] 2> "core_node5":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"},
[junit4] 2> "core_node6":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr"}}}}}
[junit4] 2> 1898656 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "core_node_name":"core_node6",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1898656 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "core_node_name":"core_node6",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 1898656 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node6:{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}
[junit4] 2> 1898656 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(AutoscalingHistoryHandlerTest_collection//collections/AutoscalingHistoryHandlerTest_collection/state.json/1 )={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"3",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"3",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"3",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr"},
[junit4] 2> "core_node5":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"},
[junit4] 2> "core_node6":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}}}}
[junit4] 2> 1898660 INFO (qtp2083873948-29716) [n:127.0.0.1:33751_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node5 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.2
[junit4] 2> 1898666 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node3",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1898666 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node3",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 1898666 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node3:{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}
[junit4] 2> 1898666 DEBUG (OverseerStateUpdate-73091384455921670-127.0.0.1:46731_solr-n_0000000000) [n:127.0.0.1:46731_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(AutoscalingHistoryHandlerTest_collection//collections/AutoscalingHistoryHandlerTest_collection/state.json/1 )={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"3",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"3",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"3",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"},
[junit4] 2> "core_node5":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"},
[junit4] 2> "core_node6":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "node_name":"127.0.0.1:33751_solr",
[junit4] 2> "base_url":"http://127.0.0.1:33751/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}}}}
[junit4] 2> 1898684 INFO (qtp2083873948-29719) [n:127.0.0.1:33751_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node6 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n4 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.2
[junit4] 2> 1898691 INFO (qtp2083873948-29721) [n:127.0.0.1:33751_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node3 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.2
[junit4] 2> 1898707 INFO (qtp2083873948-29719) [n:127.0.0.1:33751_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node6 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n4 ] o.a.s.
[...truncated too long message...]
.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 1962459 INFO (jetty-closer-15881-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@72f2856a{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1962459 INFO (jetty-closer-15881-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5ca4b80e{/solr,null,STOPPED}
[junit4] 2> 1962459 INFO (jetty-closer-15881-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1962574 INFO (jetty-closer-15881-thread-1) [ ] o.a.s.c.Overseer Overseer (id=73091384455921670-127.0.0.1:46731_solr-n_0000000000) closing
[junit4] 2> 1962676 INFO (jetty-closer-15881-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@377fcf74{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1962676 INFO (jetty-closer-15881-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@68439714{/solr,null,STOPPED}
[junit4] 2> 1962676 INFO (jetty-closer-15881-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1962677 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A68BD0FA1DD2A881]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1962779 WARN (SUITE-AutoscalingHistoryHandlerTest-seed#[A68BD0FA1DD2A881]-worker-SendThread(127.0.0.1:36069)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x103ac3a16a30001.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x103ac3a16a30001, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x103ac3a16a30001, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 1962880 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/clusterprops.json
[junit4] 2> 5 /solr/aliases.json
[junit4] 2> 4 /solr/collections/AutoscalingHistoryHandlerTest_collection/terms/shard1
[junit4] 2> 3 /solr/packages.json
[junit4] 2> 3 /solr/security.json
[junit4] 2> 3 /solr/collections/.system/terms/shard1
[junit4] 2> 3 /solr/configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/managed-schema
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 19 /solr/collections/AutoscalingHistoryHandlerTest_collection/state.json
[junit4] 2> 12 /solr/autoscaling.json
[junit4] 2> 11 /solr/collections/.system/state.json
[junit4] 2> 5 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 34 /solr/overseer/queue
[junit4] 2> 17 /solr/live_nodes
[junit4] 2> 11 /solr/collections
[junit4] 2> 10 /solr/collections/AutoscalingHistoryHandlerTest_collection/state.json
[junit4] 2> 8 /solr/overseer/collection-queue-work
[junit4] 2> 6 /solr/collections/.system/state.json
[junit4] 2>
[junit4] 2> 1962884 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A68BD0FA1DD2A881]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:36069
[junit4] 2> 1962884 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A68BD0FA1DD2A881]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:36069
[junit4] 2> 1962884 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A68BD0FA1DD2A881]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 36069
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J3/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_A68BD0FA1DD2A881-001
[junit4] 2> Mar 02, 2021 11:37:44 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=CheapBastard, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=es-CL, timezone=Asia/Chongqing
[junit4] 2> NOTE: Linux 5.4.0-45-generic amd64/Oracle Corporation 1.8.0_281 (64-bit)/cpus=4,threads=1,free=214695376,total=519569408
[junit4] 2> NOTE: All tests run in this JVM: [TestLegacyField, TestMaxTokenLenTokenizer, TaggingAttributeTest, PKIAuthenticationIntegrationTest, TestSolrDeletionPolicy2, CollectionTooManyReplicasTest, TestWaitForStateWithJettyShutdowns, TestBooleanSimilarityFactory, TestSchemaManager, TestHdfsUpdateLog, MetricsHandlerTest, TermsComponentTest, EnumFieldTest, HdfsUnloadDistributedZkTest, OrderedExecutorTest, TestFunctionQuery, HttpPartitionWithTlogReplicasTest, NestedAtomicUpdateTest, ZkSolrClientTest, TestCorePropertiesReload, ScheduledTriggerTest, TestAnalyzedSuggestions, TestManagedSchemaThreadSafety, SparseHLLTest, TestReplicationHandlerBackup, TestChildDocTransformerHierarchy, SolrIndexMetricsTest, QueryParsingTest, TestHashQParserPlugin, SuggestComponentContextFilterQueryTest, ChangedSchemaMergeTest, TestInPlaceUpdatesStandalone, BaseCdcrDistributedZkTest, TestCSVResponseWriter, SegmentsInfoRequestHandlerTest, TestSimDistributedQueue, BlockDirectoryTest, TestFieldCacheSort, SolrXmlInZkTest, AuthToolTest, TokenizerChainTest, MissingSegmentRecoveryTest, TestDocTermOrds, TestLRUCache, RollingRestartTest, TestBulkSchemaConcurrent, TriLevelCompositeIdRoutingTest, ConfigureRecoveryStrategyTest, BasicDistributedZkTest, ZkCollectionPropsCachingTest, TestAddFieldRealTimeGet, TestAuthenticationFramework, TestStreamBody, CursorPagingTest, TestFieldCollectionResource, UUIDUpdateProcessorFallbackTest, TestUtils, SolrCoreCheckLockOnStartupTest, TestLegacyFieldCache, TestCloudRecovery2, TestSimLargeCluster, DistanceFunctionTest, DeleteStatusTest, TestMacros, ZookeeperStatusHandlerTest, BlobRepositoryCloudTest, DefaultValueUpdateProcessorTest, TestRandomFlRTGCloud, TestTrie, TestSimScenario, LargeFieldTest, MinimalSchemaTest, TestConfigsApi, DistributedFacetPivotSmallTest, LegacyCloudClusterPropTest, TestFuzzyAnalyzedSuggestions, CollectionPropsTest, SpellingQueryConverterTest, TestElisionMultitermQuery, NodeAddedTriggerIntegrationTest, TestFacetMethods, CustomTermsComponentTest, BasicAuthIntegrationTest, TestMissingGroups, DateRangeFieldTest, DeleteLastCustomShardedReplicaTest, RankFieldTest, SolrCloudReportersTest, MultiSolrCloudTestCaseTest, TestSerializedLuceneMatchVersion, SolrGraphiteReporterTest, RuleEngineTest, TestConfigSetsAPIExclusivity, HttpSolrCallGetCoreTest, BigEndianAscendingWordDeserializerTest, ChaosMonkeySafeLeaderTest, StatelessScriptUpdateProcessorFactoryTest, TestPivotHelperCode, TestStressThreadBackup, JsonLoaderTest, TestReRankQParserPlugin, RootFieldTest, CloudExitableDirectoryReaderTest, TestDistributedGrouping, AddReplicaTest, LeaderFailoverAfterPartitionTest, TestLogWatcher, TestQueryUtils, TestJsonFacetsWithNestedObjects, TriggerSetPropertiesIntegrationTest, AnalyticsQueryTest, SolrPluginUtilsTest, AnalysisAfterCoreReloadTest, BasicFunctionalityTest, ConvertedLegacyTest, SolrTestCaseJ4DeleteCoreTest, SolrTestCaseJ4Test, TestCrossCoreJoin, TestCursorMarkWithoutUniqueKey, TestGroupingSearch, TestRandomFaceting, TestDeprecatedFilters, TestLuceneMatchVersion, TestReversedWildcardFilterFactory, TestWordDelimiterFilterFactory, TestEmbeddedSolrServerSchemaAPI, TestJettySolrRunner, ConnectionReuseTest, ActionThrottleTest, AliasIntegrationTest, AssignBackwardCompatibilityTest, BasicDistributedZk2Test, BasicZkTest, ChaosMonkeyNothingIsSafeTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, ConnectionManagerTest, CreateCollectionCleanupTest, CreateRoutedAliasTest, DeleteInactiveReplicaTest, DistributedQueueTest, DistributedVersionInfoTest, LeaderElectionTest, LeaderFailureAfterFreshStartTest, LeaderVoteWaitTimeoutTest, RecoveryZkTest, RemoteQueryErrorTest, ReplaceNodeNoTargetTest, SaslZkACLProviderTest, SharedFSAutoReplicaFailoverTest, SliceStateTest, SolrCLIZkUtilsTest, SolrCloudExampleTest, SplitShardTest, SyncSliceTest, TestConfigSetsAPIShareSchema, TestLeaderElectionWithEmptyReplica, TestPrepRecovery, TestSSLRandomization, TestShortCircuitedRequests, TestSkipOverseerOperations, ConcurrentDeleteAndCreateCollectionTest, CustomCollectionTest, SimpleCollectionCreateDeleteTest, TestHdfsCloudBackupRestore, IndexSizeTriggerSizeEstimationTest, MetricTriggerTest, RestoreTriggerStateTest, TriggerCooldownIntegrationTest, TestSimExtremeIndexing, TestSimNodeAddedTrigger, TestSimNodeLostTrigger, TestSimPolicyCloud, CdcrReplicationHandlerTest, CdcrRequestHandlerTest, CdcrVersionReplicationTest, ZkDynamicConfigTest, AlternateDirectoryTest, ByteBuffersDirectoryFactoryTest, CachingDirectoryFactoryTest, RAMDirectoryFactoryTest, TestCodecSupport, TestConfigSetProperties, TestCustomStream, TestMergePolicyConfig, TestQuerySenderNoQuery, TestReloadAndDeleteDocs, TestXIncludeConfig, TestSolrCoreSnapshots, BinaryUpdateRequestHandlerTest, CSVRequestHandlerTest, FieldAnalysisRequestHandlerTest, PingRequestHandlerTest, TestCoreBackup, TestSQLHandler, TestSystemCollAutoCreate, XsltUpdateRequestHandlerTest, AutoscalingHistoryHandlerTest]
[junit4] Completed [675/923 (1!)] on J3 in 69.33s, 1 test, 1 failure <<< FAILURES!
[...truncated 51608 lines...]
[JENKINS] Lucene » Lucene-Solr-Tests-8.8 - Build # 369 - Still Unstable!
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.8/369/
1 tests failed.
FAILED: org.apache.solr.cloud.AliasIntegrationTest.testDeleteAliasWithExistingCollectionName
Error Message:
collection_old should be gone
Stack Trace:
java.lang.AssertionError: collection_old should be gone
at __randomizedtesting.SeedInfo.seed([ADC29D7A6924617:7CAEFF26E2F9902A]:0)
at org.junit.Assert.fail(Assert.java:89)
at org.junit.Assert.assertTrue(Assert.java:42)
at org.junit.Assert.assertFalse(Assert.java:65)
at org.apache.solr.cloud.AliasIntegrationTest.testDeleteAliasWithExistingCollectionName(AliasIntegrationTest.java:515)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 14761 lines...]
[junit4] Suite: org.apache.solr.cloud.AliasIntegrationTest
[junit4] 2> 1110034 INFO (SUITE-AliasIntegrationTest-seed#[ADC29D7A6924617]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/server/solr/configsets/_default/conf'
[junit4] 2> 1110034 INFO (SUITE-AliasIntegrationTest-seed#[ADC29D7A6924617]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.cloud.AliasIntegrationTest_ADC29D7A6924617-001/data-dir-116-001
[junit4] 2> 1110034 INFO (SUITE-AliasIntegrationTest-seed#[ADC29D7A6924617]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1110035 INFO (SUITE-AliasIntegrationTest-seed#[ADC29D7A6924617]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 1110035 INFO (SUITE-AliasIntegrationTest-seed#[ADC29D7A6924617]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1110035 INFO (SUITE-AliasIntegrationTest-seed#[ADC29D7A6924617]-worker) [ ] o.a.s.c.SolrCloudTestCase Using per-replica state
[junit4] 2> 1110036 INFO (SUITE-AliasIntegrationTest-seed#[ADC29D7A6924617]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.cloud.AliasIntegrationTest_ADC29D7A6924617-001/tempDir-001
[junit4] 2> 1110036 INFO (SUITE-AliasIntegrationTest-seed#[ADC29D7A6924617]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1110036 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 1110036 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1110037 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 1110136 INFO (SUITE-AliasIntegrationTest-seed#[ADC29D7A6924617]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 38221
[junit4] 2> 1110136 INFO (SUITE-AliasIntegrationTest-seed#[ADC29D7A6924617]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:38221
[junit4] 2> 1110136 INFO (SUITE-AliasIntegrationTest-seed#[ADC29D7A6924617]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:38221
[junit4] 2> 1110136 INFO (SUITE-AliasIntegrationTest-seed#[ADC29D7A6924617]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 38221
[junit4] 2> 1110138 INFO (SUITE-AliasIntegrationTest-seed#[ADC29D7A6924617]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1110141 INFO (zkConnectionManagerCallback-8309-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1110141 INFO (SUITE-AliasIntegrationTest-seed#[ADC29D7A6924617]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1110147 INFO (SUITE-AliasIntegrationTest-seed#[ADC29D7A6924617]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1110153 INFO (zkConnectionManagerCallback-8311-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1110153 INFO (SUITE-AliasIntegrationTest-seed#[ADC29D7A6924617]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1110158 INFO (SUITE-AliasIntegrationTest-seed#[ADC29D7A6924617]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1110162 INFO (zkConnectionManagerCallback-8313-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1110162 INFO (SUITE-AliasIntegrationTest-seed#[ADC29D7A6924617]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1110163 WARN (SUITE-AliasIntegrationTest-seed#[ADC29D7A6924617]-worker-SendThread(127.0.0.1:38221)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x103acf13bff0002.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x103acf13bff0002, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x103acf13bff0002, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 1110265 INFO (jetty-launcher-8314-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1110265 INFO (jetty-launcher-8314-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1110265 INFO (jetty-launcher-8314-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1110265 INFO (jetty-launcher-8314-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1110265 INFO (jetty-launcher-8314-thread-2) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_281-b09
[junit4] 2> 1110265 INFO (jetty-launcher-8314-thread-1) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_281-b09
[junit4] 2> 1110266 INFO (jetty-launcher-8314-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1110266 INFO (jetty-launcher-8314-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1110266 INFO (jetty-launcher-8314-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1110267 INFO (jetty-launcher-8314-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1110267 INFO (jetty-launcher-8314-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1110267 INFO (jetty-launcher-8314-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1110270 INFO (jetty-launcher-8314-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4dd648eb{/solr,null,AVAILABLE}
[junit4] 2> 1110274 INFO (jetty-launcher-8314-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2ffb230f{/solr,null,AVAILABLE}
[junit4] 2> 1110274 INFO (jetty-launcher-8314-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2a0878cb{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:33543}
[junit4] 2> 1110274 INFO (jetty-launcher-8314-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@62b05726{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:44675}
[junit4] 2> 1110274 INFO (jetty-launcher-8314-thread-2) [ ] o.e.j.s.Server Started @1110314ms
[junit4] 2> 1110274 INFO (jetty-launcher-8314-thread-1) [ ] o.e.j.s.Server Started @1110314ms
[junit4] 2> 1110274 INFO (jetty-launcher-8314-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:38221/solr, hostPort=33543}
[junit4] 2> 1110274 INFO (jetty-launcher-8314-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:38221/solr, hostPort=44675}
[junit4] 2> 1110275 ERROR (jetty-launcher-8314-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1110275 ERROR (jetty-launcher-8314-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1110275 INFO (jetty-launcher-8314-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1110275 INFO (jetty-launcher-8314-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1110275 INFO (jetty-launcher-8314-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.2
[junit4] 2> 1110275 INFO (jetty-launcher-8314-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.2
[junit4] 2> 1110275 INFO (jetty-launcher-8314-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1110275 INFO (jetty-launcher-8314-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1110275 INFO (jetty-launcher-8314-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr
[junit4] 2> 1110275 INFO (jetty-launcher-8314-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr
[junit4] 2> 1110275 INFO (jetty-launcher-8314-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-03-03T02:59:03.469Z
[junit4] 2> 1110275 INFO (jetty-launcher-8314-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-03-03T02:59:03.469Z
[junit4] 2> 1110276 INFO (jetty-launcher-8314-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1110278 INFO (jetty-launcher-8314-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1110281 INFO (zkConnectionManagerCallback-8318-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1110281 INFO (jetty-launcher-8314-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1110281 INFO (zkConnectionManagerCallback-8317-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1110281 INFO (jetty-launcher-8314-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1110281 INFO (jetty-launcher-8314-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1110281 INFO (jetty-launcher-8314-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1110285 INFO (jetty-launcher-8314-thread-2) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 1110285 INFO (jetty-launcher-8314-thread-1) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 1110285 WARN (jetty-launcher-8314-thread-1-SendThread(127.0.0.1:38221)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x103acf13bff0004.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x103acf13bff0004, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x103acf13bff0004, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 1111323 INFO (jetty-launcher-8314-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1111324 WARN (jetty-launcher-8314-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@454d9c1a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1111324 WARN (jetty-launcher-8314-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@454d9c1a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1111326 WARN (jetty-launcher-8314-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@36abf161[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1111326 WARN (jetty-launcher-8314-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@36abf161[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1111327 INFO (jetty-launcher-8314-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38221/solr
[junit4] 2> 1111327 INFO (jetty-launcher-8314-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1111328 INFO (zkConnectionManagerCallback-8332-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1111329 INFO (jetty-launcher-8314-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1111434 INFO (jetty-launcher-8314-thread-2) [n:127.0.0.1:33543_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1111434 INFO (zkConnectionManagerCallback-8334-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1111434 INFO (jetty-launcher-8314-thread-2) [n:127.0.0.1:33543_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1111487 INFO (jetty-launcher-8314-thread-2) [n:127.0.0.1:33543_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:33543_solr
[junit4] 2> 1111488 INFO (jetty-launcher-8314-thread-2) [n:127.0.0.1:33543_solr ] o.a.s.c.Overseer Overseer (id=73092171061723142-127.0.0.1:33543_solr-n_0000000000) starting
[junit4] 2> 1111491 INFO (OverseerStateUpdate-73092171061723142-127.0.0.1:33543_solr-n_0000000000) [n:127.0.0.1:33543_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:33543_solr
[junit4] 2> 1111498 INFO (jetty-launcher-8314-thread-2) [n:127.0.0.1:33543_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33543_solr
[junit4] 2> 1111506 INFO (jetty-launcher-8314-thread-2) [n:127.0.0.1:33543_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1111506 WARN (jetty-launcher-8314-thread-2) [n:127.0.0.1:33543_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1111511 INFO (zkCallback-8333-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1111523 INFO (jetty-launcher-8314-thread-2) [n:127.0.0.1:33543_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.cloud.AliasIntegrationTest_ADC29D7A6924617-001/tempDir-001/node2
[junit4] 2> 1111575 INFO (jetty-launcher-8314-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1111576 WARN (jetty-launcher-8314-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@44f26e86[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1111576 WARN (jetty-launcher-8314-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@44f26e86[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1111581 WARN (jetty-launcher-8314-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@60c942a2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1111582 WARN (jetty-launcher-8314-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@60c942a2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1111583 INFO (jetty-launcher-8314-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38221/solr
[junit4] 2> 1111583 INFO (jetty-launcher-8314-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1111586 INFO (zkConnectionManagerCallback-8352-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1111586 INFO (jetty-launcher-8314-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1111587 WARN (jetty-launcher-8314-thread-1-SendThread(127.0.0.1:38221)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x103acf13bff0007.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x103acf13bff0007, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x103acf13bff0007, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 1111692 INFO (jetty-launcher-8314-thread-1) [n:127.0.0.1:44675_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1111692 INFO (zkConnectionManagerCallback-8354-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1111692 INFO (jetty-launcher-8314-thread-1) [n:127.0.0.1:44675_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1111696 INFO (jetty-launcher-8314-thread-1) [n:127.0.0.1:44675_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1111699 INFO (jetty-launcher-8314-thread-1) [n:127.0.0.1:44675_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:44675_solr as DOWN
[junit4] 2> 1111700 INFO (jetty-launcher-8314-thread-1) [n:127.0.0.1:44675_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1111700 INFO (jetty-launcher-8314-thread-1) [n:127.0.0.1:44675_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44675_solr
[junit4] 2> 1111701 INFO (zkCallback-8333-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1111701 INFO (jetty-launcher-8314-thread-1) [n:127.0.0.1:44675_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1111702 WARN (jetty-launcher-8314-thread-1) [n:127.0.0.1:44675_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1111715 INFO (zkCallback-8353-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1111720 INFO (jetty-launcher-8314-thread-1) [n:127.0.0.1:44675_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.cloud.AliasIntegrationTest_ADC29D7A6924617-001/tempDir-001/node1
[junit4] 2> 1111734 INFO (SUITE-AliasIntegrationTest-seed#[ADC29D7A6924617]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 1111738 INFO (SUITE-AliasIntegrationTest-seed#[ADC29D7A6924617]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1111739 INFO (zkConnectionManagerCallback-8365-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1111739 INFO (SUITE-AliasIntegrationTest-seed#[ADC29D7A6924617]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1111740 INFO (SUITE-AliasIntegrationTest-seed#[ADC29D7A6924617]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1111741 INFO (SUITE-AliasIntegrationTest-seed#[ADC29D7A6924617]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:38221/solr ready
[junit4] 2> 1111757 INFO (TEST-AliasIntegrationTest.testDeleteAliasWithExistingCollectionName-seed#[ADC29D7A6924617]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDeleteAliasWithExistingCollectionName
[junit4] 2> 1111781 INFO (qtp617583138-12796) [n:127.0.0.1:33543_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 1111790 INFO (OverseerThreadFactory-8341-thread-1-processing-n:127.0.0.1:33543_solr) [n:127.0.0.1:33543_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection_old
[junit4] 2> 1111896 INFO (OverseerStateUpdate-73092171061723142-127.0.0.1:33543_solr-n_0000000000) [n:127.0.0.1:33543_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"collection_old_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:33543_solr",
[junit4] 2> "base_url":"http://127.0.0.1:33543/solr",
[junit4] 2> "collection":"collection_old",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1111898 INFO (OverseerStateUpdate-73092171061723142-127.0.0.1:33543_solr-n_0000000000) [n:127.0.0.1:33543_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"collection_old_shard2_replica_n2",
[junit4] 2> "node_name":"127.0.0.1:44675_solr",
[junit4] 2> "base_url":"http://127.0.0.1:44675/solr",
[junit4] 2> "collection":"collection_old",
[junit4] 2> "shard":"shard2",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1112101 INFO (qtp617583138-12798) [n:127.0.0.1:33543_solr x:collection_old_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection_old_shard1_replica_n1&action=CREATE&numShards=2&collection=collection_old&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1112101 INFO (qtp617583138-12798) [n:127.0.0.1:33543_solr x:collection_old_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1112102 INFO (qtp1819784408-12805) [n:127.0.0.1:44675_solr x:collection_old_shard2_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection_old_shard2_replica_n2&action=CREATE&numShards=2&collection=collection_old&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1112121 INFO (qtp1819784408-12805) [n:127.0.0.1:44675_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.2
[junit4] 2> 1112129 INFO (qtp617583138-12798) [n:127.0.0.1:33543_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.2
[junit4] 2> 1112132 INFO (qtp1819784408-12805) [n:127.0.0.1:44675_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 1112132 INFO (qtp1819784408-12805) [n:127.0.0.1:44675_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1112132 INFO (qtp1819784408-12805) [n:127.0.0.1:44675_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'collection_old_shard2_replica_n2' using configuration from configset conf, trusted=true
[junit4] 2> 1112133 INFO (qtp1819784408-12805) [n:127.0.0.1:44675_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2 ] o.a.s.c.SolrCore [[collection_old_shard2_replica_n2] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.cloud.AliasIntegrationTest_ADC29D7A6924617-001/tempDir-001/node1/collection_old_shard2_replica_n2], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.cloud.AliasIntegrationTest_ADC29D7A6924617-001/tempDir-001/node1/collection_old_shard2_replica_n2/data/]
[junit4] 2> 1112136 INFO (qtp617583138-12798) [n:127.0.0.1:33543_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 1112136 INFO (qtp617583138-12798) [n:127.0.0.1:33543_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1112136 INFO (qtp617583138-12798) [n:127.0.0.1:33543_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection_old_shard1_replica_n1' using configuration from configset conf, trusted=true
[junit4] 2> 1112136 INFO (qtp617583138-12798) [n:127.0.0.1:33543_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1 ] o.a.s.c.SolrCore [[collection_old_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.cloud.AliasIntegrationTest_ADC29D7A6924617-001/tempDir-001/node2/collection_old_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.cloud.AliasIntegrationTest_ADC29D7A6924617-001/tempDir-001/node2/collection_old_shard1_replica_n1/data/]
[junit4] 2> 1112170 INFO (qtp1819784408-12805) [n:127.0.0.1:44675_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1112170 INFO (qtp1819784408-12805) [n:127.0.0.1:44675_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1112181 INFO (qtp617583138-12798) [n:127.0.0.1:33543_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1112181 INFO (qtp617583138-12798) [n:127.0.0.1:33543_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1112198 INFO (qtp1819784408-12805) [n:127.0.0.1:44675_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1112198 INFO (qtp1819784408-12805) [n:127.0.0.1:44675_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1112199 INFO (qtp617583138-12798) [n:127.0.0.1:33543_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1112199 INFO (qtp617583138-12798) [n:127.0.0.1:33543_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1112205 INFO (qtp1819784408-12805) [n:127.0.0.1:44675_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 1112205 INFO (qtp617583138-12798) [n:127.0.0.1:33543_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 1112206 INFO (qtp617583138-12798) [n:127.0.0.1:33543_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1112206 INFO (qtp617583138-12798) [n:127.0.0.1:33543_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1112206 INFO (qtp617583138-12798) [n:127.0.0.1:33543_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1693177972418150400
[junit4] 2> 1112207 INFO (qtp1819784408-12805) [n:127.0.0.1:44675_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1112207 INFO (qtp1819784408-12805) [n:127.0.0.1:44675_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1112207 INFO (qtp1819784408-12805) [n:127.0.0.1:44675_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1693177972419198976
[junit4] 2> 1112209 INFO (searcherExecutor-8370-thread-1-processing-n:127.0.0.1:33543_solr x:collection_old_shard1_replica_n1 c:collection_old s:shard1 r:core_node3) [n:127.0.0.1:33543_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1 ] o.a.s.c.SolrCore [collection_old_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1112210 INFO (searcherExecutor-8368-thread-1-processing-n:127.0.0.1:44675_solr x:collection_old_shard2_replica_n2 c:collection_old s:shard2 r:core_node4) [n:127.0.0.1:44675_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2 ] o.a.s.c.SolrCore [collection_old_shard2_replica_n2] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1112213 INFO (qtp617583138-12798) [n:127.0.0.1:33543_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection_old/terms/shard1 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 1112213 INFO (qtp617583138-12798) [n:127.0.0.1:33543_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection_old/leaders/shard1
[junit4] 2> 1112218 INFO (qtp1819784408-12805) [n:127.0.0.1:44675_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection_old/terms/shard2 to Terms{values={core_node4=0}, version=0}
[junit4] 2> 1112218 INFO (qtp1819784408-12805) [n:127.0.0.1:44675_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection_old/leaders/shard2
[junit4] 2> 1112218 INFO (qtp617583138-12798) [n:127.0.0.1:33543_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1112218 INFO (qtp617583138-12798) [n:127.0.0.1:33543_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1112219 INFO (qtp617583138-12798) [n:127.0.0.1:33543_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:33543/solr/collection_old_shard1_replica_n1/
[junit4] 2> 1112219 INFO (qtp617583138-12798) [n:127.0.0.1:33543_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1112220 INFO (qtp617583138-12798) [n:127.0.0.1:33543_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:33543/solr/collection_old_shard1_replica_n1/ has no replicas
[junit4] 2> 1112220 INFO (qtp617583138-12798) [n:127.0.0.1:33543_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection_old/leaders/shard1/leader after winning as /collections/collection_old/leader_elect/shard1/election/73092171061723142-core_node3-n_0000000000
[junit4] 2> 1112223 INFO (qtp617583138-12798) [n:127.0.0.1:33543_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:33543/solr/collection_old_shard1_replica_n1/ shard1
[junit4] 2> 1112223 INFO (qtp1819784408-12805) [n:127.0.0.1:44675_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1112223 INFO (qtp1819784408-12805) [n:127.0.0.1:44675_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1112223 INFO (qtp1819784408-12805) [n:127.0.0.1:44675_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:44675/solr/collection_old_shard2_replica_n2/
[junit4] 2> 1112224 INFO (qtp1819784408-12805) [n:127.0.0.1:44675_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1112224 INFO (qtp1819784408-12805) [n:127.0.0.1:44675_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2 ] o.a.s.c.SyncStrategy http://127.0.0.1:44675/solr/collection_old_shard2_replica_n2/ has no replicas
[junit4] 2> 1112224 INFO (qtp1819784408-12805) [n:127.0.0.1:44675_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection_old/leaders/shard2/leader after winning as /collections/collection_old/leader_elect/shard2/election/73092171061723144-core_node4-n_0000000000
[junit4] 2> 1112226 INFO (qtp1819784408-12805) [n:127.0.0.1:44675_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:44675/solr/collection_old_shard2_replica_n2/ shard2
[junit4] 2> 1112229 INFO (zkCallback-8353-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_old/state.json] for collection [collection_old] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1112333 INFO (zkCallback-8353-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_old/state.json] for collection [collection_old] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1112333 INFO (zkCallback-8333-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_old/state.json] for collection [collection_old] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1112333 INFO (zkCallback-8353-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_old/state.json] for collection [collection_old] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1112333 INFO (qtp617583138-12798) [n:127.0.0.1:33543_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1112335 INFO (qtp1819784408-12805) [n:127.0.0.1:44675_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1112338 INFO (qtp617583138-12798) [n:127.0.0.1:33543_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=collection_old_shard1_replica_n1&action=CREATE&numShards=2&collection=collection_old&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=238
[junit4] 2> 1112339 INFO (qtp1819784408-12805) [n:127.0.0.1:44675_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=collection_old_shard2_replica_n2&action=CREATE&numShards=2&collection=collection_old&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=237
[junit4] 2> 1112341 INFO (qtp1819784408-12802) [n:127.0.0.1:44675_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> 1112443 INFO (zkCallback-8353-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_old/state.json] for collection [collection_old] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1112443 INFO (zkCallback-8353-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_old/state.json] for collection [collection_old] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1112443 INFO (zkCallback-8333-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_old/state.json] for collection [collection_old] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1112443 INFO (zkCallback-8333-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_old/state.json] for collection [collection_old] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1112443 INFO (qtp1819784408-12802) [n:127.0.0.1:44675_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&name=collection_old&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=657
[junit4] 2> 1112444 INFO (zkCallback-8353-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_old/state.json] for collection [collection_old] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1112450 INFO (OverseerCollectionConfigSetProcessor-73092171061723142-127.0.0.1:33543_solr-n_0000000000) [n:127.0.0.1:33543_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> 1112450 INFO (OverseerThreadFactory-8341-thread-2-processing-n:127.0.0.1:33543_solr) [n:127.0.0.1:33543_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection_new
[junit4] 2> 1112657 INFO (OverseerStateUpdate-73092171061723142-127.0.0.1:33543_solr-n_0000000000) [n:127.0.0.1:33543_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"collection_new_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:33543_solr",
[junit4] 2> "base_url":"http://127.0.0.1:33543/solr",
[junit4] 2> "collection":"collection_new",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1112859 INFO (qtp617583138-12801) [n:127.0.0.1:33543_solr x:collection_new_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=collection_new_shard1_replica_n1&action=CREATE&numShards=1&collection=collection_new&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1112869 INFO (qtp617583138-12801) [n:127.0.0.1:33543_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.2
[junit4] 2> 1112875 INFO (qtp617583138-12801) [n:127.0.0.1:33543_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 1112876 INFO (qtp617583138-12801) [n:127.0.0.1:33543_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1112876 INFO (qtp617583138-12801) [n:127.0.0.1:33543_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection_new_shard1_replica_n1' using configuration from configset conf, trusted=true
[junit4] 2> 1112876 INFO (qtp617583138-12801) [n:127.0.0.1:33543_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1 ] o.a.s.c.SolrCore [[collection_new_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.cloud.AliasIntegrationTest_ADC29D7A6924617-001/tempDir-001/node2/collection_new_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.cloud.AliasIntegrationTest_ADC29D7A6924617-001/tempDir-001/node2/collection_new_shard1_replica_n1/data/]
[junit4] 2> 1112904 INFO (qtp617583138-12801) [n:127.0.0.1:33543_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1112904 INFO (qtp617583138-12801) [n:127.0.0.1:33543_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1112905 INFO (qtp617583138-12801) [n:127.0.0.1:33543_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1112905 INFO (qtp617583138-12801) [n:127.0.0.1:33543_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1112906 INFO (qtp617583138-12801) [n:127.0.0.1:33543_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 1112907 INFO (qtp617583138-12801) [n:127.0.0.1:33543_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1112907 INFO (qtp617583138-12801) [n:127.0.0.1:33543_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1112907 INFO (qtp617583138-12801) [n:127.0.0.1:33543_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1693177973153202176
[junit4] 2> 1112909 INFO (searcherExecutor-8380-thread-1-processing-n:127.0.0.1:33543_solr x:collection_new_shard1_replica_n1 c:collection_new s:shard1 r:core_node2) [n:127.0.0.1:33543_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1 ] o.a.s.c.SolrCore [collection_new_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1112913 INFO (qtp617583138-12801) [n:127.0.0.1:33543_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection_new/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 1112913 INFO (qtp617583138-12801) [n:127.0.0.1:33543_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection_new/leaders/shard1
[junit4] 2> 1112918 INFO (qtp617583138-12801) [n:127.0.0.1:33543_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1112918 INFO (qtp617583138-12801) [n:127.0.0.1:33543_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1112918 INFO (qtp617583138-12801) [n:127.0.0.1:33543_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:33543/solr/collection_new_shard1_replica_n1/
[junit4] 2> 1112918 INFO (qtp617583138-12801) [n:127.0.0.1:33543_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1112919 INFO (qtp617583138-12801) [n:127.0.0.1:33543_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:33543/solr/collection_new_shard1_replica_n1/ has no replicas
[junit4] 2> 1112919 INFO (qtp617583138-12801) [n:127.0.0.1:33543_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection_new/leaders/shard1/leader after winning as /collections/collection_new/leader_elect/shard1/election/73092171061723142-core_node2-n_0000000000
[junit4] 2> 1112921 INFO (qtp617583138-12801) [n:127.0.0.1:33543_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:33543/solr/collection_new_shard1_replica_n1/ shard1
[junit4] 2> 1113022 INFO (zkCallback-8333-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_new/state.json] for collection [collection_new] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1113022 INFO (zkCallback-8333-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_new/state.json] for collection [collection_new] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1113022 INFO (zkCallback-8333-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_new/state.json] for collection [collection_new] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1113024 INFO (qtp617583138-12801) [n:127.0.0.1:33543_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1113027 INFO (qtp617583138-12801) [n:127.0.0.1:33543_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=collection_new_shard1_replica_n1&action=CREATE&numShards=1&collection=collection_new&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=168
[junit4] 2> 1113028 INFO (qtp1819784408-12806) [n:127.0.0.1:44675_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> 1113126 INFO (zkCallback-8353-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_new/state.json] for collection [collection_new] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1113126 INFO (zkCallback-8333-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_new/state.json] for collection [collection_new] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1113126 INFO (zkCallback-8333-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_new/state.json] for collection [collection_new] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1113126 INFO (zkCallback-8333-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_new/state.json] for collection [collection_new] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1113126 INFO (zkCallback-8333-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_new/state.json] for collection [collection_new] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1113127 INFO (qtp1819784408-12806) [n:127.0.0.1:44675_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&name=collection_new&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=682
[junit4] 2> 1113127 INFO (TEST-AliasIntegrationTest.testDeleteAliasWithExistingCollectionName-seed#[ADC29D7A6924617]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: collection_old
[junit4] 2> 1113132 INFO (TEST-AliasIntegrationTest.testDeleteAliasWithExistingCollectionName-seed#[ADC29D7A6924617]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: collection_new
[junit4] 2> 1113134 INFO (TEST-AliasIntegrationTest.testDeleteAliasWithExistingCollectionName-seed#[ADC29D7A6924617]) [ ] o.a.s.c.SolrCloudTestCase waitForState (collection_old): Expected collection_old to be created with 2 shards and 1 replica
[junit4] 2> 1113134 INFO (watches-8362-thread-1) [ ] o.a.s.c.SolrCloudTestCase active replica count: 2 expected replica count: 2
[junit4] 2> 1113138 INFO (TEST-AliasIntegrationTest.testDeleteAliasWithExistingCollectionName-seed#[ADC29D7A6924617]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 2 expected replica count: 2
[junit4] 2> 1113138 INFO (TEST-AliasIntegrationTest.testDeleteAliasWithExistingCollectionName-seed#[ADC29D7A6924617]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 2 expected replica count: 2
[junit4] 2> 1113138 INFO (TEST-AliasIntegrationTest.testDeleteAliasWithExistingCollectionName-seed#[ADC29D7A6924617]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 2 expected replica count: 2
[junit4] 2> 1113138 INFO (TEST-AliasIntegrationTest.testDeleteAliasWithExistingCollectionName-seed#[ADC29D7A6924617]) [ ] o.a.s.c.SolrCloudTestCase waitForState (collection_new): Expected collection_new to be created with 1 shard and 1 replica
[junit4] 2> 1113138 INFO (watches-8362-thread-2) [ ] o.a.s.c.SolrCloudTestCase active replica count: 1 expected replica count: 1
[junit4] 2> 1113139 INFO (watches-8362-thread-1) [ ] o.a.s.c.SolrCloudTestCase active replica count: 1 expected replica count: 1
[junit4] 2> 1113139 INFO (TEST-AliasIntegrationTest.testDeleteAliasWithExistingCollectionName-seed#[ADC29D7A6924617]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 1 expected replica count: 1
[junit4] 2> 1113139 INFO (TEST-AliasIntegrationTest.testDeleteAliasWithExistingCollectionName-seed#[ADC29D7A6924617]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 1 expected replica count: 1
[junit4] 2> 1113139 INFO (TEST-AliasIntegrationTest.testDeleteAliasWithExistingCollectionName-seed#[ADC29D7A6924617]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 1 expected replica count: 1
[junit4] 2> 1113154 INFO (qtp617583138-12796) [n:127.0.0.1:33543_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection_old/terms/shard1 to Terms{values={core_node3=1}, version=1}
[junit4] 2> 1113154 INFO (qtp617583138-12796) [n:127.0.0.1:33543_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection_old_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection_old:4&wt=javabin&version=2}{add=[8 (1693177973409054720)]} 0 3
[junit4] 2> 1113154 INFO (qtp1819784408-12800) [n:127.0.0.1:44675_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection_old/terms/shard2 to Terms{values={core_node4=1}, version=1}
[junit4] 2> 1113154 INFO (qtp1819784408-12800) [n:127.0.0.1:44675_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2 ] o.a.s.u.p.LogUpdateProcessorFactory [collection_old_shard2_replica_n2] webapp=/solr path=/update params={_stateVer_=collection_old:4&wt=javabin&version=2}{add=[6 (1693177973409054720), 7 (1693177973411151872)]} 0 3
[junit4] 2> 1113170 INFO (searcherExecutor-8368-thread-1-processing-n:127.0.0.1:44675_solr x:collection_old_shard2_replica_n2 c:collection_old s:shard2 r:core_node4) [n:127.0.0.1:44675_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2 ] o.a.s.c.SolrCore [collection_old_shard2_replica_n2] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1113170 INFO (qtp1819784408-12802) [n:127.0.0.1:44675_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2 ] o.a.s.u.p.LogUpdateProcessorFactory [collection_old_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:33543/solr/collection_old_shard1_replica_n1/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 3
[junit4] 2> 1113174 INFO (searcherExecutor-8370-thread-1-processing-n:127.0.0.1:33543_solr x:collection_old_shard1_replica_n1 c:collection_old s:shard1 r:core_node3) [n:127.0.0.1:33543_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1 ] o.a.s.c.SolrCore [collection_old_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1113174 INFO (qtp617583138-12797) [n:127.0.0.1:33543_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection_old_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection_old:4&commit=true&wt=javabin&version=2}{commit=} 0 19
[junit4] 2> 1113177 INFO (qtp617583138-12798) [n:127.0.0.1:33543_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection_new/terms/shard1 to Terms{values={core_node2=1}, version=1}
[junit4] 2> 1113177 INFO (qtp617583138-12798) [n:127.0.0.1:33543_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection_new_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection_new:3&wt=javabin&version=2}{add=[1 (1693177973435269120)]} 0 1
[junit4] 2> 1113180 INFO (searcherExecutor-8380-thread-1-processing-n:127.0.0.1:33543_solr x:collection_new_shard1_replica_n1 c:collection_new s:shard1 r:core_node2) [n:127.0.0.1:33543_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1 ] o.a.s.c.SolrCore [collection_new_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1113180 INFO (qtp617583138-12801) [n:127.0.0.1:33543_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection_new_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection_new:3&commit=true&wt=javabin&version=2}{commit=} 0 2
[junit4] 2> 1113197 INFO (qtp617583138-12798) [n:127.0.0.1:33543_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1 ] o.a.s.c.S.Request [collection_old_shard1_replica_n1] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection_old:4&fl=id&fl=score&shards.purpose=16388&start=0&fsv=true&shard.url=http://127.0.0.1:33543/solr/collection_old_shard1_replica_n1/&rid=127.0.0.1-57667&rows=10&version=2&q=*:*&omitHeader=false&NOW=1614740346374&isShard=true&wt=javabin} hits=1 status=0 QTime=0
[junit4] 2> 1113204 INFO (qtp1819784408-12803) [n:127.0.0.1:44675_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2 ] o.a.s.c.S.Request [collection_old_shard2_replica_n2] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection_old:4&fl=id&fl=score&shards.purpose=16388&start=0&fsv=true&shard.url=http://127.0.0.1:44675/solr/collection_old_shard2_replica_n2/&rid=127.0.0.1-57667&rows=10&version=2&q=*:*&omitHeader=false&NOW=1614740346374&isShard=true&wt=javabin} hits=2 status=0 QTime=0
[junit4] 2> 1113209 INFO (qtp1819784408-12805) [n:127.0.0.1:44675_solr c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2 ] o.a.s.c.S.Request [collection_old_shard2_replica_n2] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection_old:4&shards.purpose=64&shard.url=http://127.0.0.1:44675/solr/collection_old_shard2_replica_n2/&rid=127.0.0.1-57667&version=2&q=*:*&omitHeader=false&NOW=1614740346374&ids=6,7&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1113218 INFO (qtp617583138-12801) [n:127.0.0.1:33543_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1 ] o.a.s.c.S.Request [collection_old_shard1_replica_n1] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection_old:4&shards.purpose=64&shard.url=http://127.0.0.1:33543/solr/collection_old_shard1_replica_n1/&rid=127.0.0.1-57667&version=2&q=*:*&omitHeader=false&NOW=1614740346374&ids=8&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1113218 INFO (qtp617583138-12794) [n:127.0.0.1:33543_solr c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1 ] o.a.s.c.S.Request [collection_old_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=collection_old:4&wt=javabin&version=2} rid=127.0.0.1-57667 hits=3 status=0 QTime=38
[junit4] 2> 1113234 INFO (OverseerCollectionConfigSetProcessor-73092171061723142-127.0.0.1:33543_solr-n_0000000000) [n:127.0.0.1:33543_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 1113338 INFO (qtp1819784408-12802) [n:127.0.0.1:44675_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collections=collection_old&name=collection_old_reserve&action=CREATEALIAS&wt=javabin&version=2} status=0 QTime=118
[junit4] 2> 1113342 INFO (OverseerCollectionConfigSetProcessor-73092171061723142-127.0.0.1:33543_solr-n_0000000000) [n:127.0.0.1:33543_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000004 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 1113445 INFO (qtp1819784408-12806) [n:127.0.0.1:44675_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collections=collection_new&name=collection_old&action=CREATEALIAS&wt=javabin&version=2} status=0 QTime=106
[junit4] 2> 1113447 INFO (qtp617583138-12796) [n:127.0.0.1:33543_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1 ] o.a.s.c.S.Request [collection_new_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=collection_old:4&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 2> 1113448 INFO (qtp617583138-12797) [n:127.0.0.1:33543_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1 ] o.a.s.c.S.Request [collection_new_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=collection_new:3&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 2> 1113451 INFO (qtp1819784408-12800) [n:127.0.0.1:44675_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=273e154d-a12e-46e9-8c66-c9a646c76ac7&name=collection_old&action=DELETE&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 1113452 INFO (qtp1819784408-12803) [n:127.0.0.1:44675_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=273e154d-a12e-46e9-8c66-c9a646c76ac7&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1113453 INFO (OverseerCollectionConfigSetProcessor-73092171061723142-127.0.0.1:33543_solr-n_0000000000) [n:127.0.0.1:33543_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000006 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 1113454 ERROR (OverseerThreadFactory-8341-thread-5-processing-n:127.0.0.1:33543_solr) [n:127.0.0.1:33543_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Collection: collection_old operation: delete failed:org.apache.solr.common.SolrException: Collection : collection_old is part of aliases: [collection_old_reserve], remove or modify the aliases before removing this collection.
[junit4] 2> at org.apache.solr.cloud.api.collections.DeleteCollectionCmd.checkAliasReference(DeleteCollectionCmd.java:244)
[junit4] 2> at org.apache.solr.cloud.api.collections.DeleteCollectionCmd.call(DeleteCollectionCmd.java:86)
[junit4] 2> at org.apache.solr.cloud.api.collections.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:266)
[junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:524)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:218)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 1113456 INFO (OverseerThreadFactory-8341-thread-5-processing-n:127.0.0.1:33543_solr) [n:127.0.0.1:33543_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000008 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 1114454 INFO (qtp1819784408-12805) [n:127.0.0.1:44675_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=273e154d-a12e-46e9-8c66-c9a646c76ac7&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1114456 INFO (qtp1819784408-12802) [n:127.0.0.1:44675_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=273e154d-a12e-46e9-8c66-c9a646c76ac7&action=DELETESTATUS&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1114458 INFO (qtp617583138-12801) [n:127.0.0.1:33543_solr c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1 ] o.a.s.c.S.Request [collection_new_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=collection_old:4&_forwardedCount=1&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 2> 1114463 INFO (qtp1819784408-12800) [n:127.0.0.1:44675_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=b18ba812-1570-4a9b-8066-f650dadcafe5&name=collection_old_reserve&action=DELETEALIAS&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 1114467 INFO (qtp1819784408-12803) [n:127.0.0.1:44675_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=b18ba812-1570-4a9b-8066-f650dadcafe5&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 1114467 INFO (OverseerCollectionConfigSetProcessor-73092171061723142-127.0.0.1:33543_solr-n_0000000000) [n:127.0.0.1:33543_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000008 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 1114468 INFO (OverseerThreadFactory-8341-thread-5-processing-n:127.0.0.1:33543_solr) [n:127.0.0.1:33543_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000009 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 1115468 INFO (qtp1819784408-12805) [n:127.0.0.1:44675_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=b18ba812-1570-4a9b-8066-f650dadcafe5&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1115469 INFO (qtp1819784408-12802) [n:127.0.0.1:44675_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=b18ba812-1570-4a9b-8066-f650dadcafe5&action=DELETESTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1115472 INFO (qtp1819784408-12806) [n:127.0.0.1:44675_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=defa71f3-b238-4585-950e-75216b7e6100&name=collection_old&action=DELETEALIAS&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1115474 INFO (qtp1819784408-12800) [n:127.0.0.1:44675_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=defa71f3-b238-4585-950e-75216b7e6100&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1115476 INFO (OverseerCollectionConfigSetProcessor-73092171061723142-127.0.0.1:33543_solr-n_0000000000) [n:127.0.0.1:33543_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000009 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 1115477 INFO (OverseerThreadFactory-8341-thread-5-processing-n:127.0.0.1:33543_solr) [n:127.0.0.1:33543_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000010 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 1116476 INFO (qtp1819784408-12803) [n:127.0.0.1:44675_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=defa71f3-b238-4585-950e-75216b7e6100&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1116477 INFO (qtp1819784408-12805) [n:127.0.0.1:44675_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=defa71f3-b238-4585-950e-75216b7e6100&action=DELETESTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1116480 INFO (qtp1819784408-12802) [n:127.0.0.1:44675_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=98bd82f8-acba-481e-a406-584ba2ae5ebd&name=collection_new&action=DELETE&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 1116481 INFO (qtp1819784408-12806) [n:127.0.0.1:44675_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=98bd82f8-acba-481e-a406-584ba2ae5ebd&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1116492 INFO (OverseerCollectionConfigSetProcessor-73092171061723142-127.0.0.1:33543_solr-n_0000000000) [n:127.0.0.1:33543_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000010 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 1116492 INFO (OverseerThreadFactory-8341-thread-5-processing-n:127.0.0.1:33543_solr) [n:127.0.0.1:33543_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true&deleteMetricsHistory=true, asyncId=98bd82f8-acba-481e-a406-584ba2ae5ebd
[junit4] 2> 1116496 INFO (qtp617583138-12794) [n:127.0.0.1:33543_solr x:collection_new_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&deleteMetricsHistory=true&core=collection_new_shard1_replica_n1&async=98bd82f8-acba-481e-a406-584ba2ae5ebd15786399030608790&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 1116497 INFO (qtp617583138-12796) [n:127.0.0.1:33543_solr ] o.a.s.h.a.CoreAdminOperation Checking request status for : 98bd82f8-acba-481e-a406-584ba2ae5ebd15786399030608790
[junit4] 2> 1116497 INFO (qtp617583138-12796) [n:127.0.0.1:33543_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=98bd82f8-acba-481e-a406-584ba2ae5ebd15786399030608790&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1116498 INFO (parallelCoreAdminExecutor-8342-thread-1-processing-n:127.0.0.1:33543_solr x:collection_new_shard1_replica_n1 98bd82f8-acba-481e-a406-584ba2ae5ebd15786399030608790 UNLOAD) [n:127.0.0.1:33543_solr x:collection_new_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection_new.shard1.replica_n1 tag=null
[junit4] 2> 1116498 INFO (parallelCoreAdminExecutor-8342-thread-1-processing-n:127.0.0.1:33543_solr x:collection_new_shard1_replica_n1 98bd82f8-acba-481e-a406-584ba2ae5ebd15786399030608790 UNLOAD) [n:127.0.0.1:33543_solr ] o.a.s.c.SolrCore [collection_new_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@59d034b7
[junit4] 2> 1116498 INFO (parallelCoreAdminExecutor-8342-thread-1-processing-n:127.0.0.1:33543_solr x:collection_new_shard1_replica_n1 98bd82f8-acba-481e-a406-584ba2ae5ebd15786399030608790 UNLOAD) [n:127.0.0.1:33543_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection_new.shard1.replica_n1 tag=SolrCore@59d034b7
[junit4] 2> 1116498 INFO (parallelCoreAdminExecutor-8342-thread-1-processing-n:127.0.0.1:33543_solr x:collection_new_shard1_replica_n1 98bd82f8-acba-481e-a406-584ba2ae5ebd15786399030608790 UNLOAD) [n:127.0.0.1:33543_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection_new.shard1.leader tag=SolrCore@59d034b7
[junit4] 2> 1116499 INFO (parallelCoreAdminExecutor-8342-thread-1-processing-n:127.0.0.1:33543_solr x:collection_new_shard1_replica_n1 98bd82f8-acba-481e-a406-584ba2ae5ebd15786399030608790 UNLOAD) [n:127.0.0.1:33543_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 1116501 INFO (parallelCoreAdminExecutor-8342-thread-1-processing-n:127.0.0.1:33543_solr x:collection_new_shard1_replica_n1 98bd82f8-acba-481e-a406-584ba2ae5ebd15786399030608790 UNLOAD) [n:127.0.0.1:33543_solr ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection_new/terms/shard1 to Terms{values={}, version=2}
[junit4] 2> 1116505 INFO (zkCallback-8333-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_new/state.json] for collection [collection_new] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1116505 INFO (zkCallback-8333-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_new/state.json] for collection [collection_new] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1116505 INFO (zkCallback-8333-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_new/state.json] for collection [collection_new] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1116505 INFO (zkCallback-8333-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection_new/state.json] for collection [collection_new] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1117483 INFO (qtp1819784408-12800) [n:127.0.0.1:44675_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=98bd82f8-acba-481e-a406-584ba2ae5ebd&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1117499 INFO (qtp617583138-12797) [n:127.0.0.1:33543_solr ] o.a.s.h.a.CoreAdminOperation Checking request status for : 98bd82f8-acba-481e-a406-584ba2ae5ebd15786399030608790
[junit4] 2> 1117499 INFO (qtp617583138-12797) [n:127.0.0.1:33543_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=98bd82f8-acba-481e-a406-584ba2ae5ebd15786399030608790&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1117603 INFO (zkCallback-8333-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection_new/state.json] for collection [collection_new] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1117603 INFO (zkCallback-8333-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection_new/state.json] for collection [collection_new] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1117603 INFO (zkCallback-8333-thread-5) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection_new/state.json] for collection [collection_new] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1117603 INFO (zkCallback-8333-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection_new/state.json] for collection [collection_new] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1117603 INFO (zkCallback-8333-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection_new/state.json] for collection [collection_new] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1117610 INFO (OverseerThreadFactory-8341-thread-5-processing-n:127.0.0.1:33543_solr) [n:127.0.0.1:33543_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000011 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 1118485 INFO (qtp1819784408-12803) [n:127.0.0.1:44675_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=98bd82f8-acba-481e-a406-584ba2ae5ebd&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1118500 INFO (OverseerCollectionConfigSetProcessor-73092171061723142-127.0.0.1:33543_solr-n_0000000000) [n:127.0.0.1:33543_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000011 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 1118501 INFO (qtp1819784408-12805) [n:127.0.0.1:44675_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=98bd82f8-acba-481e-a406-584ba2ae5ebd&action=DELETESTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1118504 INFO (qtp1819784408-12802) [n:127.0.0.1:44675_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=8ecbb99f-ba5f-4ea0-9b33-a1762a64cdc2&name=collection_old&action=DELETE&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 1118505 INFO (qtp1819784408-12806) [n:127.0.0.1:44675_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=8ecbb99f-ba5f-4ea0-9b33-a1762a64cdc2&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1118506 INFO (OverseerThreadFactory-8341-thread-5-processing-n:127.0.0.1:33543_solr) [n:127.0.0.1:33543_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true&deleteMetricsHistory=true, asyncId=8ecbb99f-ba5f-4ea0-9b33-a1762a64cdc2
[junit4] 2> 1118507 INFO (qtp617583138-12798) [n:127.0.0.1:33543_solr x:collection_old_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&deleteMetricsHistory=true&core=collection_old_shard1_replica_n1&async=8ecbb99f-ba5f-4ea0-9b33-a1762a64cdc215786401044727644&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1118508 INFO (parallelCoreAdminExecutor-8342-thread-2-processing-n:127.0.0.1:33543_solr x:collection_old_shard1_replica_n1 8ecbb99f-ba5f-4ea0-9b33-a1762a64cdc215786401044727644 UNLOAD) [n:127.0.0.1:33543_solr x:collection_old_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection_old.shard1.replica_n1 tag=null
[junit4] 2> 1118508 INFO (parallelCoreAdminExecutor-8342-thread-2-processing-n:127.0.0.1:33543_solr x:collection_old_shard1_replica_n1 8ecbb99f-ba5f-4ea0-9b33-a1762a64cdc215786401044727644 UNLOAD) [n:127.0.0.1:33543_solr ] o.a.s.c.SolrCore [collection_old_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@32d00374
[junit4] 2> 1118508 INFO (parallelCoreAdminExecutor-8342-thread-2-processing-n:127.0.0.1:33543_solr x:collection_old_shard1_replica_n1 8ecbb99f-ba5f-4ea0-9b33-a1762a64cdc215786401044727644 UNLOAD) [n:127.0.0.1:33543_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection_old.shard1.replica_n1 tag=SolrCore@32d00374
[junit4] 2> 1118508 INFO (parallelCoreAdminExecutor-8342-thread-2-processing-n:127.0.0.1:33543_solr x:collection_old_shard1_replica_n1 8ecbb99f-ba5f-4ea0-9b33-a1762a64cdc215786401044727644 UNLOAD) [n:127.0.0.1:33543_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection_old.shard1.leader tag=SolrCore@32d00374
[junit4] 2> 1118508 INFO (qtp1819784408-12800) [n:127.0.0.1:44675_solr x:collection_old_shard2_replica_n2 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&deleteMetricsHistory=true&core=collection_old_shard2_replica_n2&async=8ecbb99f-ba5f-4ea0-9b33-a1762a64cdc215786401044766285&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1118514 INFO (parallelCoreAdminExecutor-8342-thread-2-processing-n:127.0.0.1:33543_solr x:collection_old_shard1_replica_n1 8ecbb99f-ba5f-4ea0-9b33-a1762a64cdc215786401044727644 UNLOAD) [n:127.0.0.1:33543_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 1118514 INFO (parallelCoreAdminExecutor-8358-thread-1-processing-n:127.0.0.1:44675_solr x:collection_old_shard2_replica_n2 8ecbb99f-ba5f-4ea0-9b33-a1762a64cdc215786401044766285 UNLOAD) [n:127.0.0.1:44675_solr x:collection_old_shard2_replica_n2 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection_old.shard2.replica_n2 tag=null
[junit4] 2> 1118515 INFO (parallelCoreAdminExecutor-8358-thread-1-processing-n:127.0.0.1:44675_solr x:collection_old_shard2_replica_n2 8ecbb99f-ba5f-4ea0-9b33-a1762a64cdc215786401044766285 UNLOAD) [n:127.0.0.1:44675_solr ] o.a.s.c.SolrCore [collection_old_shard2_replica_n2] CLOSING SolrCore org.apache.solr.core.SolrCore@2cdaf909
[junit4] 2> 1118515 INFO (qtp617583138-12801) [n:127.0.0.1:33543_solr ] o.a.s.h.a.CoreAdminOperation Checking request status for : 8ecbb99f-ba5f-4ea0-9b33-a1762a64cdc215786401044727644
[junit4] 2> 1118515 INFO (parallelCoreAdminExecutor-8358-thread-1-processing-n:127.0.0.1:44675_solr x:collection_old_shard2_replica_n2 8ecbb99f-ba5f-4ea0-9b33-a1762a64cdc215786401044766285 UNLOAD) [n:127.0.0.1:44675_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection_old.shard2.replica_n2 tag=SolrCore@2cdaf909
[junit4] 2> 1118515 INFO (qtp617583138-12801) [n:127.0.0.1:33543_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=8ecbb99f-ba5f-4ea0-9b33-a1762a64cdc215786401044727644&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1118515 INFO (parallelCoreAdminExecutor-8358-thread-1-processing-n:127.0.0.1:44675_solr x:collection_old_shard2_replica_n2 8ecbb99f-ba5f-4ea0-9b33-a1762a64cdc215786401044766285 UNLOAD) [n:127.0.0.1:44675_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection_old.shard2.leader tag=SolrCore@2cdaf909
[junit4] 2> 1118518 INFO (parallelCoreAdminExecutor-8358-thread-1-processing-n:127.0.0.1:44675_solr x:collection_old_shard2_replica_n2 8ecbb99f-ba5f-4ea0-9b33-a1762a64cdc215786401044766285 UNLOAD) [n:127.0.0.1:44675_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 1118519 INFO (parallelCoreAdminExecutor-8342-thread-2-processing-n:127.0.0.1:33543_solr x:collection_old_shard1_replica_n1 8ecbb99f-ba5f-4ea0-9b33-a1762a64cdc215786401044727644 UNLOAD) [n:127.0.0.1:33543_solr ] o.a.s.c.Z
[...truncated too long message...]
as closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x103acf13bff0006, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 1147494 INFO (jetty-closer-8580-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@62b05726{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1147494 INFO (jetty-closer-8580-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2ffb230f{/solr,null,STOPPED}
[junit4] 2> 1147494 INFO (jetty-closer-8580-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1147593 INFO (jetty-closer-8580-thread-1) [ ] o.a.s.c.Overseer Overseer (id=73092171061723142-127.0.0.1:33543_solr-n_0000000000) closing
[junit4] 2> 1147595 INFO (jetty-closer-8580-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@2a0878cb{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1147595 INFO (jetty-closer-8580-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4dd648eb{/solr,null,STOPPED}
[junit4] 2> 1147595 INFO (jetty-closer-8580-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1147596 INFO (SUITE-AliasIntegrationTest-seed#[ADC29D7A6924617]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1147799 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 40 /solr/collections/collection1meta/state.json
[junit4] 2> 22 /solr/collections/collection2meta/state.json
[junit4] 2> 22 /solr/aliases.json
[junit4] 2> 22 /solr/clusterprops.json
[junit4] 2> 18 /solr/configs/conf
[junit4] 2> 15 /solr/collections/collection2meta/terms/shard1
[junit4] 2> 15 /solr/collections/collection1meta/terms/shard1
[junit4] 2> 14 /solr/collections/collection1meta/terms/shard2
[junit4] 2> 8 /solr/collections/collection1/state.json
[junit4] 2> 8 /solr/collections/testErrorChecks-collection/state.json
[junit4] 2> 5 /solr/collections/collection_two/state.json
[junit4] 2> 5 /solr/collections/collection_new/state.json
[junit4] 2> 5 /solr/collections/collection_old/state.json
[junit4] 2> 5 /solr/collections/collection_one/state.json
[junit4] 2> 4 /solr/collections/collection_one/terms/shard1
[junit4] 2> 4 /solr/collections/collection2/terms/shard1
[junit4] 2> 4 /solr/collections/collection_two/terms/shard1
[junit4] 2> 4 /solr/collections/collection2/state.json
[junit4] 2> 4 /solr/collections/collection_old/terms/shard2
[junit4] 2> 4 /solr/collections/collection_old/terms/shard1
[junit4] 2> 4 /solr/collections/collection1/terms/shard1
[junit4] 2> 4 /solr/collections/collection1/terms/shard2
[junit4] 2> 3 /solr/collections/collection_one/terms/shard2
[junit4] 2> 3 /solr/collections/collection_new/terms/shard1
[junit4] 2> 3 /solr/collections/testErrorChecks-collection/terms/shard1
[junit4] 2> 3 /solr/collections/testErrorChecks-collection/terms/shard2
[junit4] 2> 2 /solr/collections/collection_old/collectionprops.json
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/collections/collection_one/collectionprops.json
[junit4] 2> 2 /solr/collections/collection1/collectionprops.json
[junit4] 2> 2 /solr/packages.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 358 /solr/aliases.json
[junit4] 2> 114 /solr/collections/collection1meta/state.json
[junit4] 2> 73 /solr/collections/collection2meta/state.json
[junit4] 2> 33 /solr/collections/collection_one/state.json
[junit4] 2> 25 /solr/collections/collection_old/state.json
[junit4] 2> 24 /solr/collections/collection1/state.json
[junit4] 2> 24 /solr/collections/testErrorChecks-collection/state.json
[junit4] 2> 22 /solr/clusterstate.json
[junit4] 2> 20 /solr/collections/collection_new/state.json
[junit4] 2> 20 /solr/collections/collection_two/state.json
[junit4] 2> 15 /solr/collections/collection2/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 253 /solr/overseer/queue
[junit4] 2> 241 /solr/overseer/collection-queue-work
[junit4] 2> 205 /solr/collections
[junit4] 2> 50 /solr/collections/collection1meta/state.json
[junit4] 2> 35 /solr/collections/collection2meta/state.json
[junit4] 2> 26 /solr/live_nodes
[junit4] 2> 11 /solr/collections/collection_one/state.json
[junit4] 2> 11 /solr/collections/collection_old/state.json
[junit4] 2> 10 /solr/collections/collection1/state.json
[junit4] 2> 10 /solr/collections/testErrorChecks-collection/state.json
[junit4] 2> 8 /solr/collections/collection_new/state.json
[junit4] 2> 8 /solr/collections/collection_two/state.json
[junit4] 2> 7 /solr/collections/collection2/state.json
[junit4] 2>
[junit4] 2> 1147804 INFO (SUITE-AliasIntegrationTest-seed#[ADC29D7A6924617]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:38221
[junit4] 2> 1147804 INFO (SUITE-AliasIntegrationTest-seed#[ADC29D7A6924617]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:38221
[junit4] 2> 1147804 INFO (SUITE-AliasIntegrationTest-seed#[ADC29D7A6924617]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 38221
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.cloud.AliasIntegrationTest_ADC29D7A6924617-001
[junit4] 2> Mar 03, 2021 2:59:41 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene87), sim=Asserting(RandomSimilarity(queryNorm=true): {}), locale=sk-SK, timezone=Africa/Niamey
[junit4] 2> NOTE: Linux 4.15.0-112-generic amd64/Oracle Corporation 1.8.0_281 (64-bit)/cpus=4,threads=1,free=254431160,total=515375104
[junit4] 2> NOTE: All tests run in this JVM: [TestPostingsSolrHighlighter, SpatialFilterTest, DocValuesTest, DistributedQueryComponentCustomSortTest, NodeMutatorTest, SolrIndexConfigTest, RemoteQueryErrorTest, TestRequestForwarding, TestOnReconnectListenerSupport, TestHttpServletCarrier, PrimitiveFieldTypeTest, TermVectorComponentTest, MoreLikeThisComponentTest, DataDrivenBlockJoinTest, TestFreeTextSuggestions, QueryEqualityTest, SolrInfoBeanTest, EchoParamsTest, HdfsTlogReplayBufferedWhileIndexingTest, TestManagedSchemaAPI, BJQFilterAccessibleTest, TestUtilizeNode, TestConfigSetsAPI, IndexSizeTriggerMixedBoundsTest, ImplicitSnitchTest, MBeansHandlerTest, AlternateDirectoryTest, TestStressLucene, SubstringBytesRefFilterTest, DistributedSuggestComponentTest, TestLFUCache, MoveReplicaTest, TestDownShardTolerantSearch, MultiDestinationAuditLoggerTest, TestSimPolicyCloud, IndexSchemaTest, TestExternalRoleRuleBasedAuthorizationPlugin, TestReloadAndDeleteDocs, CoreAdminRequestStatusTest, SOLR749Test, PeerSyncTest, TestLeaderElectionWithEmptyReplica, IndexSizeEstimatorTest, SystemLogListenerTest, SolrMetricsIntegrationTest, TestValueSourceCache, OverseerStatusTest, MetricsDisabledCloudTest, SchemaVersionSpecificBehaviorTest, ClusterStateUpdateTest, LeaderElectionTest, TestUninvertingReader, TestDynamicFieldResource, MetricTriggerTest, TestCloudNestedDocsSort, TestRestManager, TestLegacyBM25SimilarityFactory, TestOverriddenPrefixQueryForCustomFieldType, TlogReplayBufferedWhileIndexingTest, HttpPartitionTest, TestSearcherReuse, JavaBinAtomicUpdateMultivalueTest, TestWordDelimiterFilterFactory, TestExceedMaxTermLength, ConcurrentCreateRoutedAliasTest, FieldAnalysisRequestHandlerTest, CleanupOldIndexTest, UninvertDocValuesMergePolicyTest, ExplicitHLLTest, TestDocSet, TestFieldSortValues, TestCoreDiscovery, TestJsonFacetsStatsParsing, TestHttpShardHandlerFactory, OverseerModifyCollectionTest, PeerSyncReplicationTest, ConvertedLegacyTest, TestSolr4Spatial2, JavabinLoaderTest, CloneFieldUpdateProcessorFactoryTest, TestFieldCacheReopen, TestMinHashQParser, TestDistribPackageStore, SplitHandlerTest, TestMinimalConfig, ChaosMonkeyNothingIsSafeWithPullReplicasTest, TestSimGenericDistributedQueue, CdcrOpsAndBoundariesTest, SpellPossibilityIteratorTest, TestRemoteStreaming, TestDFRSimilarityFactory, TestSubQueryTransformerCrossCore, TestSolrDeletionPolicy1, TestXIncludeConfig, TestMacroExpander, AutoscalingHistoryHandlerTest, CachingDirectoryFactoryTest, TestFieldCacheWithThreads, TestCharFilters, LeaderVoteWaitTimeoutTest, BlockJoinFacetRandomTest, XMLAtomicUpdateMultivalueTest, BlockCacheTest, RegexBoostProcessorTest, BooleanFieldTest, CollectionReloadTest, TestGraphMLResponseWriter, TestCloudJSONFacetSKGEquiv, TestDocumentBuilder, TestSolrCloudWithSecureImpersonation, TestSolrJ, ComputePlanActionTest, TestRandomDVFaceting, TestEmbeddedSolrServerAdminHandler, TestEmbeddedSolrServerConstructors, TestEmbeddedSolrServerSchemaAPI, TestJettySolrRunner, ConnectionReuseTest, ActionThrottleTest, AliasIntegrationTest]
[junit4] Completed [461/923 (1!)] on J2 in 38.49s, 9 tests, 1 failure <<< FAILURES!
[...truncated 52400 lines...]