Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/5774/ Java: 64bit/jdk-12.0.2 -XX:-UseCompressedOops -XX:+UseParallelGC
1 tests failed. FAILED: junit.framework.TestSuite.org.apache.solr.cloud.autoscaling.sim.TestSnapshotCloudManager Error Message: 1 thread leaked from SUITE scope at org.apache.solr.cloud.autoscaling.sim.TestSnapshotCloudManager: 1) Thread[id=23692, name=SessionTracker, state=TIMED_WAITING, group=TGRP-TestSnapshotCloudManager] at [email protected]/java.lang.Thread.sleep(Native Method) at app//org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.cloud.autoscaling.sim.TestSnapshotCloudManager: 1) Thread[id=23692, name=SessionTracker, state=TIMED_WAITING, group=TGRP-TestSnapshotCloudManager] at [email protected]/java.lang.Thread.sleep(Native Method) at app//org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151) at __randomizedtesting.SeedInfo.seed([C1255F999A045813]:0) Build Log: [...truncated 14790 lines...] [junit4] Suite: org.apache.solr.cloud.autoscaling.sim.TestSnapshotCloudManager [junit4] 2> 1758054 INFO (SUITE-TestSnapshotCloudManager-seed#[C1255F999A045813]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/server/solr/configsets/_default/conf' [junit4] 2> 1758054 INFO (SUITE-TestSnapshotCloudManager-seed#[C1255F999A045813]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 1758056 INFO (SUITE-TestSnapshotCloudManager-seed#[C1255F999A045813]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.sim.TestSnapshotCloudManager_C1255F999A045813-001/data-dir-149-001 [junit4] 2> 1758065 INFO (SUITE-TestSnapshotCloudManager-seed#[C1255F999A045813]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 1758076 INFO (SUITE-TestSnapshotCloudManager-seed#[C1255F999A045813]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0) w/ MAC_OS_X supressed clientAuth [junit4] 2> 1758078 INFO (SUITE-TestSnapshotCloudManager-seed#[C1255F999A045813]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 3 servers in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.sim.TestSnapshotCloudManager_C1255F999A045813-001/tempDir-001 [junit4] 2> 1758080 INFO (SUITE-TestSnapshotCloudManager-seed#[C1255F999A045813]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1758081 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1758081 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1758181 INFO (SUITE-TestSnapshotCloudManager-seed#[C1255F999A045813]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:58079 [junit4] 2> 1758181 INFO (SUITE-TestSnapshotCloudManager-seed#[C1255F999A045813]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:58079 [junit4] 2> 1758181 INFO (SUITE-TestSnapshotCloudManager-seed#[C1255F999A045813]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:58079 [junit4] 2> 1758181 INFO (SUITE-TestSnapshotCloudManager-seed#[C1255F999A045813]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 58079 [junit4] 2> 1758184 INFO (SUITE-TestSnapshotCloudManager-seed#[C1255F999A045813]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1758190 INFO (zkConnectionManagerCallback-8329-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1758191 INFO (SUITE-TestSnapshotCloudManager-seed#[C1255F999A045813]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1758199 INFO (SUITE-TestSnapshotCloudManager-seed#[C1255F999A045813]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1758204 INFO (zkConnectionManagerCallback-8331-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1758204 INFO (SUITE-TestSnapshotCloudManager-seed#[C1255F999A045813]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1758207 INFO (SUITE-TestSnapshotCloudManager-seed#[C1255F999A045813]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1758213 INFO (zkConnectionManagerCallback-8333-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1758213 INFO (SUITE-TestSnapshotCloudManager-seed#[C1255F999A045813]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1758381 INFO (jetty-launcher-8334-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 1758381 INFO (jetty-launcher-8334-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 1758381 INFO (jetty-launcher-8334-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 1758381 INFO (jetty-launcher-8334-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 1758381 INFO (jetty-launcher-8334-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 1758381 INFO (jetty-launcher-8334-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 1758381 INFO (jetty-launcher-8334-thread-2) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 12.0.2+10 [junit4] 2> 1758381 INFO (jetty-launcher-8334-thread-1) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 12.0.2+10 [junit4] 2> 1758381 INFO (jetty-launcher-8334-thread-3) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 12.0.2+10 [junit4] 2> 1758394 INFO (jetty-launcher-8334-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1758394 INFO (jetty-launcher-8334-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1758394 INFO (jetty-launcher-8334-thread-3) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 1758396 INFO (jetty-launcher-8334-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1758396 INFO (jetty-launcher-8334-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1758396 INFO (jetty-launcher-8334-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 1758397 INFO (jetty-launcher-8334-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@fe05218{/solr,null,AVAILABLE} [junit4] 2> 1758399 INFO (jetty-launcher-8334-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@8adb3dd{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:58084} [junit4] 2> 1758400 INFO (jetty-launcher-8334-thread-3) [ ] o.e.j.s.Server Started @1758473ms [junit4] 2> 1758400 INFO (jetty-launcher-8334-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=58084} [junit4] 2> 1758401 INFO (jetty-launcher-8334-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1758401 ERROR (jetty-launcher-8334-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1758401 INFO (jetty-launcher-8334-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1758401 INFO (jetty-launcher-8334-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1758401 INFO (jetty-launcher-8334-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 1758401 INFO (jetty-launcher-8334-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0 [junit4] 2> 1758401 INFO (jetty-launcher-8334-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1758401 INFO (jetty-launcher-8334-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr [junit4] 2> 1758401 INFO (jetty-launcher-8334-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-15T16:02:05.355347Z [junit4] 2> 1758402 INFO (jetty-launcher-8334-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@777f3938{/solr,null,AVAILABLE} [junit4] 2> 1758402 INFO (jetty-launcher-8334-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2bc9c64c{/solr,null,AVAILABLE} [junit4] 2> 1758403 INFO (jetty-launcher-8334-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1758405 INFO (jetty-launcher-8334-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1886d07f{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:58086} [junit4] 2> 1758405 INFO (jetty-launcher-8334-thread-2) [ ] o.e.j.s.Server Started @1758478ms [junit4] 2> 1758405 INFO (jetty-launcher-8334-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=58086} [junit4] 2> 1758405 ERROR (jetty-launcher-8334-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1758405 INFO (jetty-launcher-8334-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@51cae495{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:58085} [junit4] 2> 1758405 INFO (jetty-launcher-8334-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1758405 INFO (jetty-launcher-8334-thread-1) [ ] o.e.j.s.Server Started @1758478ms [junit4] 2> 1758405 INFO (jetty-launcher-8334-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0 [junit4] 2> 1758405 INFO (jetty-launcher-8334-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=58085} [junit4] 2> 1758405 INFO (jetty-launcher-8334-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1758405 INFO (jetty-launcher-8334-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr [junit4] 2> 1758405 INFO (jetty-launcher-8334-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-15T16:02:05.359959Z [junit4] 2> 1758406 ERROR (jetty-launcher-8334-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1758406 INFO (jetty-launcher-8334-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1758406 INFO (jetty-launcher-8334-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0 [junit4] 2> 1758406 INFO (jetty-launcher-8334-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1758406 INFO (jetty-launcher-8334-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr [junit4] 2> 1758406 INFO (jetty-launcher-8334-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-15T16:02:05.360732Z [junit4] 2> 1758409 INFO (jetty-launcher-8334-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1758411 INFO (zkConnectionManagerCallback-8336-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1758411 INFO (jetty-launcher-8334-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1758412 INFO (jetty-launcher-8334-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1758413 INFO (jetty-launcher-8334-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1758416 INFO (zkConnectionManagerCallback-8340-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1758416 INFO (jetty-launcher-8334-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1758416 INFO (zkConnectionManagerCallback-8338-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1758416 INFO (jetty-launcher-8334-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1758418 INFO (jetty-launcher-8334-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1758419 INFO (jetty-launcher-8334-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1758529 INFO (jetty-launcher-8334-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] [junit4] 2> 1758531 WARN (jetty-launcher-8334-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@19f00f61[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1758531 WARN (jetty-launcher-8334-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@19f00f61[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1758538 WARN (jetty-launcher-8334-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@774a07c6[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1758538 WARN (jetty-launcher-8334-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@774a07c6[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1758541 INFO (jetty-launcher-8334-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58079/solr [junit4] 2> 1758545 INFO (jetty-launcher-8334-thread-3) [n:127.0.0.1:58084_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1758550 INFO (zkConnectionManagerCallback-8347-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1758550 INFO (jetty-launcher-8334-thread-3) [n:127.0.0.1:58084_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1758638 INFO (jetty-launcher-8334-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] [junit4] 2> 1758640 WARN (jetty-launcher-8334-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@af47e6a[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1758640 WARN (jetty-launcher-8334-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@af47e6a[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1758644 WARN (jetty-launcher-8334-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5dc06ebb[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1758644 WARN (jetty-launcher-8334-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5dc06ebb[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1758645 INFO (jetty-launcher-8334-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58079/solr [junit4] 2> 1758647 INFO (jetty-launcher-8334-thread-2) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1758650 INFO (zkConnectionManagerCallback-8356-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1758651 INFO (jetty-launcher-8334-thread-2) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1758683 INFO (jetty-launcher-8334-thread-2) [n:127.0.0.1:58086_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:58086_solr [junit4] 2> 1758685 INFO (jetty-launcher-8334-thread-2) [n:127.0.0.1:58086_solr ] o.a.s.c.Overseer Overseer (id=72140998918078471-127.0.0.1:58086_solr-n_0000000000) starting [junit4] 2> 1758713 INFO (OverseerStateUpdate-72140998918078471-127.0.0.1:58086_solr-n_0000000000) [n:127.0.0.1:58086_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:58086_solr [junit4] 2> 1758714 INFO (jetty-launcher-8334-thread-2) [n:127.0.0.1:58086_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58086_solr [junit4] 2> 1758719 INFO (OverseerStateUpdate-72140998918078471-127.0.0.1:58086_solr-n_0000000000) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1758729 INFO (jetty-launcher-8334-thread-2) [n:127.0.0.1:58086_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1 [junit4] 2> 1758730 WARN (jetty-launcher-8334-thread-2) [n:127.0.0.1:58086_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> 1758744 INFO (jetty-launcher-8334-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] [junit4] 2> 1758746 WARN (jetty-launcher-8334-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@286a5652[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1758746 WARN (jetty-launcher-8334-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@286a5652[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1758751 WARN (jetty-launcher-8334-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@50559676[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1758751 WARN (jetty-launcher-8334-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@50559676[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1758753 INFO (jetty-launcher-8334-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58079/solr [junit4] 2> 1758754 INFO (jetty-launcher-8334-thread-1) [n:127.0.0.1:58085_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1758759 INFO (zkConnectionManagerCallback-8366-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1758759 INFO (jetty-launcher-8334-thread-1) [n:127.0.0.1:58085_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1758759 INFO (jetty-launcher-8334-thread-2) [n:127.0.0.1:58086_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 1758775 INFO (jetty-launcher-8334-thread-1) [n:127.0.0.1:58085_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1758787 INFO (jetty-launcher-8334-thread-2) [n:127.0.0.1:58086_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58086.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7fc6c4ea [junit4] 2> 1758789 INFO (jetty-launcher-8334-thread-1) [n:127.0.0.1:58085_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:58085_solr as DOWN [junit4] 2> 1758792 INFO (jetty-launcher-8334-thread-1) [n:127.0.0.1:58085_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores [junit4] 2> 1758792 INFO (jetty-launcher-8334-thread-1) [n:127.0.0.1:58085_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58085_solr [junit4] 2> 1758795 INFO (zkCallback-8355-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1758795 INFO (zkCallback-8365-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1758796 INFO (jetty-launcher-8334-thread-2) [n:127.0.0.1:58086_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58086.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7fc6c4ea [junit4] 2> 1758796 INFO (jetty-launcher-8334-thread-2) [n:127.0.0.1:58086_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58086.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7fc6c4ea [junit4] 2> 1758798 INFO (jetty-launcher-8334-thread-1) [n:127.0.0.1:58085_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1 [junit4] 2> 1758798 INFO (jetty-launcher-8334-thread-2) [n:127.0.0.1:58086_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.sim.TestSnapshotCloudManager_C1255F999A045813-001/tempDir-001/node2/. [junit4] 2> 1758798 WARN (jetty-launcher-8334-thread-1) [n:127.0.0.1:58085_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> 1758815 INFO (jetty-launcher-8334-thread-3) [n:127.0.0.1:58084_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1758824 INFO (jetty-launcher-8334-thread-3) [n:127.0.0.1:58084_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:58084_solr as DOWN [junit4] 2> 1758828 INFO (jetty-launcher-8334-thread-3) [n:127.0.0.1:58084_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores [junit4] 2> 1758828 INFO (jetty-launcher-8334-thread-3) [n:127.0.0.1:58084_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58084_solr [junit4] 2> 1758829 INFO (jetty-launcher-8334-thread-1) [n:127.0.0.1:58085_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 1758832 INFO (zkCallback-8355-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1758832 INFO (zkCallback-8365-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1758832 INFO (zkCallback-8346-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1758835 INFO (jetty-launcher-8334-thread-3) [n:127.0.0.1:58084_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1 [junit4] 2> 1758836 WARN (jetty-launcher-8334-thread-3) [n:127.0.0.1:58084_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> 1758852 INFO (jetty-launcher-8334-thread-1) [n:127.0.0.1:58085_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58085.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7fc6c4ea [junit4] 2> 1758856 INFO (jetty-launcher-8334-thread-3) [n:127.0.0.1:58084_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 1758861 INFO (jetty-launcher-8334-thread-1) [n:127.0.0.1:58085_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58085.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7fc6c4ea [junit4] 2> 1758861 INFO (jetty-launcher-8334-thread-1) [n:127.0.0.1:58085_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58085.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7fc6c4ea [junit4] 2> 1758862 INFO (jetty-launcher-8334-thread-1) [n:127.0.0.1:58085_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.sim.TestSnapshotCloudManager_C1255F999A045813-001/tempDir-001/node1/. [junit4] 2> 1758884 INFO (jetty-launcher-8334-thread-3) [n:127.0.0.1:58084_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58084.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7fc6c4ea [junit4] 2> 1758893 INFO (jetty-launcher-8334-thread-3) [n:127.0.0.1:58084_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58084.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7fc6c4ea [junit4] 2> 1758893 INFO (jetty-launcher-8334-thread-3) [n:127.0.0.1:58084_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58084.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7fc6c4ea [junit4] 2> 1758895 INFO (jetty-launcher-8334-thread-3) [n:127.0.0.1:58084_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.sim.TestSnapshotCloudManager_C1255F999A045813-001/tempDir-001/node3/. [junit4] 2> 1758928 INFO (SUITE-TestSnapshotCloudManager-seed#[C1255F999A045813]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=3 [junit4] 2> 1758929 INFO (SUITE-TestSnapshotCloudManager-seed#[C1255F999A045813]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1758933 INFO (zkConnectionManagerCallback-8378-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1758933 INFO (SUITE-TestSnapshotCloudManager-seed#[C1255F999A045813]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1758936 INFO (SUITE-TestSnapshotCloudManager-seed#[C1255F999A045813]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 1758939 INFO (SUITE-TestSnapshotCloudManager-seed#[C1255F999A045813]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:58079/solr ready [junit4] 2> 1758960 INFO (qtp310295311-23744) [n:127.0.0.1:58086_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=1&name=.system&nrtReplicas=2&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1758977 INFO (OverseerThreadFactory-8333-thread-1-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection .system [junit4] 2> 1759095 INFO (OverseerStateUpdate-72140998918078471-127.0.0.1:58086_solr-n_0000000000) [n:127.0.0.1:58086_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":".system", [junit4] 2> "shard":"shard1", [junit4] 2> "core":".system_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:58085/solr";, [junit4] 2> "node_name":"127.0.0.1:58085_solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1759101 INFO (OverseerStateUpdate-72140998918078471-127.0.0.1:58086_solr-n_0000000000) [n:127.0.0.1:58086_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":".system", [junit4] 2> "shard":"shard1", [junit4] 2> "core":".system_shard1_replica_n2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:58084/solr";, [junit4] 2> "node_name":"127.0.0.1:58084_solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1759107 INFO (OverseerStateUpdate-72140998918078471-127.0.0.1:58086_solr-n_0000000000) [n:127.0.0.1:58086_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":".system", [junit4] 2> "shard":"shard1", [junit4] 2> "core":".system_shard1_replica_p4", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:58086/solr";, [junit4] 2> "node_name":"127.0.0.1:58086_solr", [junit4] 2> "type":"PULL", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1759332 INFO (qtp310295311-23741) [n:127.0.0.1:58086_solr x:.system_shard1_replica_p4 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node6&collection.configName=.system&newCollection=true&name=.system_shard1_replica_p4&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=PULL [junit4] 2> 1759333 INFO (qtp310295311-23741) [n:127.0.0.1:58086_solr x:.system_shard1_replica_p4 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores [junit4] 2> 1759335 INFO (qtp1530040293-23738) [n:127.0.0.1:58085_solr x:.system_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&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> 1759336 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr x:.system_shard1_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n2&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 1759359 INFO (qtp310295311-23741) [n:127.0.0.1:58086_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0 [junit4] 2> 1759360 INFO (qtp1530040293-23738) [n:127.0.0.1:58085_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0 [junit4] 2> 1759362 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0 [junit4] 2> 1759384 INFO (qtp310295311-23741) [n:127.0.0.1:58086_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] 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> 1759386 INFO (qtp1530040293-23738) [n:127.0.0.1:58085_solr c:.system s:shard1 r:core_node3 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> 1759389 INFO (qtp310295311-23741) [n:127.0.0.1:58086_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.s.IndexSchema Schema name=_system collection or core [junit4] 2> 1759390 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] 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> 1759390 INFO (qtp1530040293-23738) [n:127.0.0.1:58085_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=_system collection or core [junit4] 2> 1759393 INFO (qtp310295311-23741) [n:127.0.0.1:58086_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id [junit4] 2> 1759394 INFO (qtp1530040293-23738) [n:127.0.0.1:58085_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id [junit4] 2> 1759394 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema name=_system collection or core [junit4] 2> 1759395 INFO (qtp310295311-23741) [n:127.0.0.1:58086_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.s.ManagedIndexSchema Created and persisted managed schema znode at /configs/.system/managed-schema [junit4] 2> 1759395 INFO (qtp1530040293-23738) [n:127.0.0.1:58085_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Managed schema znode at /configs/.system/managed-schema already exists - no need to create it [junit4] 2> 1759398 INFO (qtp310295311-23741) [n:127.0.0.1:58086_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.c.CoreContainer Creating SolrCore '.system_shard1_replica_p4' using configuration from configset .system, trusted=true [junit4] 2> 1759399 INFO (qtp310295311-23741) [n:127.0.0.1:58086_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58086.solr.core..system.shard1.replica_p4' (registry 'solr.core..system.shard1.replica_p4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7fc6c4ea [junit4] 2> 1759399 INFO (qtp310295311-23741) [n:127.0.0.1:58086_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.c.SolrCore [[.system_shard1_replica_p4] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.sim.TestSnapshotCloudManager_C1255F999A045813-001/tempDir-001/node2/./.system_shard1_replica_p4], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.sim.TestSnapshotCloudManager_C1255F999A045813-001/tempDir-001/node2/./.system_shard1_replica_p4/data/] [junit4] 2> 1759399 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id [junit4] 2> 1759401 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Managed schema znode at /configs/.system/managed-schema already exists - no need to create it [junit4] 2> 1759403 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore '.system_shard1_replica_n2' using configuration from configset .system, trusted=true [junit4] 2> 1759404 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58084.solr.core..system.shard1.replica_n2' (registry 'solr.core..system.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7fc6c4ea [junit4] 2> 1759404 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.SolrCore [[.system_shard1_replica_n2] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.sim.TestSnapshotCloudManager_C1255F999A045813-001/tempDir-001/node3/./.system_shard1_replica_n2], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.sim.TestSnapshotCloudManager_C1255F999A045813-001/tempDir-001/node3/./.system_shard1_replica_n2/data/] [junit4] 2> 1759406 INFO (qtp1530040293-23738) [n:127.0.0.1:58085_solr c:.system s:shard1 r:core_node3 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> 1759406 INFO (qtp1530040293-23738) [n:127.0.0.1:58085_solr c:.system s:shard1 r:core_node3 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> 1759407 INFO (qtp1530040293-23738) [n:127.0.0.1:58085_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58085.solr.core..system.shard1.replica_n1' (registry 'solr.core..system.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7fc6c4ea [junit4] 2> 1759407 INFO (qtp1530040293-23738) [n:127.0.0.1:58085_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [[.system_shard1_replica_n1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.sim.TestSnapshotCloudManager_C1255F999A045813-001/tempDir-001/node1/./.system_shard1_replica_n1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.sim.TestSnapshotCloudManager_C1255F999A045813-001/tempDir-001/node1/./.system_shard1_replica_n1/data/] [junit4] 2> 1759881 INFO (qtp310295311-23741) [n:127.0.0.1:58086_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.u.CommitTracker Hard AutoCommit: if 1 uncommitted docs; [junit4] 2> 1759881 INFO (qtp310295311-23741) [n:127.0.0.1:58086_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1759883 INFO (qtp310295311-23741) [n:127.0.0.1:58086_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1b617bcc[.system_shard1_replica_p4] main] [junit4] 2> 1759886 INFO (qtp310295311-23741) [n:127.0.0.1:58086_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/.system [junit4] 2> 1759886 INFO (qtp310295311-23741) [n:127.0.0.1:58086_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/.system [junit4] 2> 1759886 INFO (qtp310295311-23741) [n:127.0.0.1:58086_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/.system/managed-schema [junit4] 2> 1759888 INFO (qtp310295311-23741) [n:127.0.0.1:58086_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest [junit4] 2> 1759888 INFO (qtp310295311-23741) [n:127.0.0.1:58086_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1759891 INFO (searcherExecutor-8351-thread-1-processing-n:127.0.0.1:58086_solr x:.system_shard1_replica_p4 c:.system s:shard1 r:core_node6) [n:127.0.0.1:58086_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.c.SolrCore [.system_shard1_replica_p4] Registered new searcher Searcher@1b617bcc[.system_shard1_replica_p4] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1759898 INFO (qtp310295311-23741) [n:127.0.0.1:58086_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.c.ZkController .system_shard1_replica_p4 starting background replication from leader [junit4] 2> 1759898 INFO (qtp310295311-23741) [n:127.0.0.1:58086_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.c.ReplicateFromLeader Will start replication from leader with poll interval: 00:00:01 [junit4] 2> 1759907 INFO (qtp310295311-23741) [n:127.0.0.1:58086_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms [junit4] 2> 1759907 INFO (qtp310295311-23741) [n:127.0.0.1:58086_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1759915 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1759915 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1759917 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: if 1 uncommitted docs; [junit4] 2> 1759917 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1759919 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@14afc736[.system_shard1_replica_n2] main] [junit4] 2> 1759922 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/.system [junit4] 2> 1759923 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/.system [junit4] 2> 1759923 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/.system/managed-schema [junit4] 2> 1759924 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 0 from ZooKeeper [junit4] 2> 1759928 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema name=_system collection or core [junit4] 2> 1759932 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id [junit4] 2> 1759932 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 7 ms [junit4] 2> 1759932 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1759933 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1661246506938662912 [junit4] 2> 1759937 INFO (searcherExecutor-8352-thread-1-processing-n:127.0.0.1:58084_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node5) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.SolrCore [.system_shard1_replica_n2] Registered new searcher Searcher@14afc736[.system_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1759939 INFO (qtp1530040293-23738) [n:127.0.0.1:58085_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1759939 INFO (qtp1530040293-23738) [n:127.0.0.1:58085_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1759943 INFO (qtp1530040293-23738) [n:127.0.0.1:58085_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: if 1 uncommitted docs; [junit4] 2> 1759943 INFO (qtp1530040293-23738) [n:127.0.0.1:58085_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1759945 INFO (qtp1530040293-23738) [n:127.0.0.1:58085_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@57a92936[.system_shard1_replica_n1] main] [junit4] 2> 1759946 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/.system/terms/shard1 to Terms{values={core_node5=0}, version=0} [junit4] 2> 1759946 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/.system/leaders/shard1 [junit4] 2> 1759950 INFO (qtp1530040293-23738) [n:127.0.0.1:58085_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/.system [junit4] 2> 1759951 INFO (qtp1530040293-23738) [n:127.0.0.1:58085_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/.system [junit4] 2> 1759952 INFO (qtp1530040293-23738) [n:127.0.0.1:58085_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/.system/managed-schema [junit4] 2> 1759953 INFO (qtp1530040293-23738) [n:127.0.0.1:58085_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 0 from ZooKeeper [junit4] 2> 1759956 INFO (qtp1530040293-23738) [n:127.0.0.1:58085_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=_system collection or core [junit4] 2> 1759962 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=2 found=1 timeoutin=9997ms [junit4] 2> 1759962 INFO (qtp1530040293-23738) [n:127.0.0.1:58085_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id [junit4] 2> 1759962 INFO (qtp1530040293-23738) [n:127.0.0.1:58085_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 8 ms [junit4] 2> 1759963 INFO (qtp1530040293-23738) [n:127.0.0.1:58085_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1759963 INFO (qtp1530040293-23738) [n:127.0.0.1:58085_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1661246506970120192 [junit4] 2> 1759969 INFO (searcherExecutor-8353-thread-1-processing-n:127.0.0.1:58085_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:58085_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [.system_shard1_replica_n1] Registered new searcher Searcher@57a92936[.system_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1759979 INFO (qtp1530040293-23738) [n:127.0.0.1:58085_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/.system/terms/shard1 to Terms{values={core_node3=0, core_node5=0}, version=1} [junit4] 2> 1759980 INFO (qtp1530040293-23738) [n:127.0.0.1:58085_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/.system/leaders/shard1 [junit4] 2> 1760065 INFO (zkCallback-8355-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: [3]) [junit4] 2> 1760065 INFO (zkCallback-8355-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: [3]) [junit4] 2> 1760468 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1760468 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1760468 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:58084/solr/.system_shard1_replica_n2/ [junit4] 2> 1760471 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.u.PeerSync PeerSync: core=.system_shard1_replica_n2 url=https://127.0.0.1:58084/solr START replicas=[https://127.0.0.1:58085/solr/.system_shard1_replica_n1/] nUpdates=100 [junit4] 2> 1760471 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.u.PeerSync PeerSync: core=.system_shard1_replica_n2 url=https://127.0.0.1:58084/solr DONE. We have no versions. sync failed. [junit4] 2> 1760485 INFO (qtp1530040293-23729) [n:127.0.0.1:58085_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1760486 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate [junit4] 2> 1760486 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway [junit4] 2> 1760486 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/.system/leaders/shard1/leader after winning as /collections/.system/leader_elect/shard1/election/72140998918078470-core_node5-n_0000000000 [junit4] 2> 1760492 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:58084/solr/.system_shard1_replica_n2/ shard1 [junit4] 2> 1760601 INFO (zkCallback-8346-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: [3]) [junit4] 2> 1760601 INFO (zkCallback-8355-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: [3]) [junit4] 2> 1760601 INFO (zkCallback-8355-thread-3) [ ] 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: [3]) [junit4] 2> 1760607 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1760608 INFO (indexFetcher-8360-thread-1) [ ] o.a.s.h.IndexFetcher Replica core_node5 is leader but it's state is down, skipping replication [junit4] 2> 1760613 INFO (qtp39180461-23733) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node5&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n2&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1277 [junit4] 2> 1760730 INFO (zkCallback-8346-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: [3]) [junit4] 2> 1760730 INFO (zkCallback-8346-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: [3]) [junit4] 2> 1760996 INFO (qtp310295311-23741) [n:127.0.0.1:58086_solr c:.system s:shard1 r:core_node6 x:.system_shard1_replica_p4 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node6&collection.configName=.system&newCollection=true&name=.system_shard1_replica_p4&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=PULL} status=0 QTime=1664 [junit4] 2> 1760996 INFO (qtp1530040293-23738) [n:127.0.0.1:58085_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&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=1662 [junit4] 2> 1761001 INFO (qtp310295311-23744) [n:127.0.0.1:58086_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> 1761051 INFO (indexFetcher-8360-thread-1) [ ] o.a.s.h.IndexFetcher Last replication failed, so I'll force replication [junit4] 2> 1761051 INFO (indexFetcher-8360-thread-1) [ ] o.a.s.h.IndexFetcher Updated masterUrl to https://127.0.0.1:58084/solr/.system_shard1_replica_n2/ [junit4] 2> 1761063 INFO (qtp39180461-23735) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request [.system_shard1_replica_n2] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 1761063 INFO (indexFetcher-8360-thread-1) [ ] o.a.s.h.IndexFetcher Master's generation: 1 [junit4] 2> 1761063 INFO (indexFetcher-8360-thread-1) [ ] o.a.s.h.IndexFetcher Master's version: 0 [junit4] 2> 1761063 INFO (indexFetcher-8360-thread-1) [ ] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 1761063 INFO (indexFetcher-8360-thread-1) [ ] o.a.s.h.IndexFetcher Slave's version: 0 [junit4] 2> 1761063 INFO (indexFetcher-8360-thread-1) [ ] o.a.s.h.IndexFetcher New index in Master. Deleting mine... [junit4] 2> 1761064 INFO (indexFetcher-8360-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1761064 INFO (indexFetcher-8360-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@12e27efa commitCommandVersion:0 [junit4] 2> 1761065 INFO (indexFetcher-8360-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1f4b1174[.system_shard1_replica_p4] main] [junit4] 2> 1761065 INFO (indexFetcher-8360-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1761066 INFO (searcherExecutor-8351-thread-1) [ ] o.a.s.c.SolrCore [.system_shard1_replica_p4] Registered new searcher Searcher@1f4b1174[.system_shard1_replica_p4] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1761109 INFO (zkCallback-8355-thread-3) [ ] 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: [3]) [junit4] 2> 1761109 INFO (zkCallback-8355-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: [3]) [junit4] 2> 1761109 INFO (zkCallback-8346-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: [3]) [junit4] 2> 1761109 INFO (zkCallback-8346-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: [3]) [junit4] 2> 1761109 INFO (zkCallback-8365-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: [3]) [junit4] 2> 1761109 INFO (zkCallback-8365-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: [3]) [junit4] 2> 1761111 INFO (qtp310295311-23744) [n:127.0.0.1:58086_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={pullReplicas=1&name=.system&nrtReplicas=2&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2} status=0 QTime=2150 [junit4] 2> 1761112 INFO (qtp310295311-23743) [n:127.0.0.1:58086_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params name=coll1&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1761119 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection coll1 [junit4] 2> 1761120 INFO (OverseerCollectionConfigSetProcessor-72140998918078471-127.0.0.1:58086_solr-n_0000000000) [n:127.0.0.1:58086_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> 1761121 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/coll1.AUTOCREATED/managed-schema [junit4] 2> 1761124 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/coll1.AUTOCREATED/protwords.txt [junit4] 2> 1761129 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/coll1.AUTOCREATED/lang/contractions_it.txt [junit4] 2> 1761132 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/coll1.AUTOCREATED/lang/contractions_ca.txt [junit4] 2> 1761135 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/coll1.AUTOCREATED/lang/stemdict_nl.txt [junit4] 2> 1761139 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/coll1.AUTOCREATED/lang/stopwords_no.txt [junit4] 2> 1761143 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/coll1.AUTOCREATED/lang/stopwords_hy.txt [junit4] 2> 1761147 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/coll1.AUTOCREATED/lang/stopwords_id.txt [junit4] 2> 1761151 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_et.txt to /configs/coll1.AUTOCREATED/lang/stopwords_et.txt [junit4] 2> 1761155 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/coll1.AUTOCREATED/lang/stopwords_da.txt [junit4] 2> 1761159 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/coll1.AUTOCREATED/lang/stopwords_hi.txt [junit4] 2> 1761163 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/coll1.AUTOCREATED/lang/stopwords_ga.txt [junit4] 2> 1761166 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/coll1.AUTOCREATED/lang/stopwords_pt.txt [junit4] 2> 1761170 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/coll1.AUTOCREATED/lang/stopwords_ja.txt [junit4] 2> 1761173 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/coll1.AUTOCREATED/lang/stopwords_hu.txt [junit4] 2> 1761177 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/coll1.AUTOCREATED/lang/stopwords_el.txt [junit4] 2> 1761181 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/coll1.AUTOCREATED/lang/stopwords_ru.txt [junit4] 2> 1761186 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/coll1.AUTOCREATED/lang/stopwords_tr.txt [junit4] 2> 1761190 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/coll1.AUTOCREATED/lang/stopwords_ar.txt [junit4] 2> 1761194 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/coll1.AUTOCREATED/lang/userdict_ja.txt [junit4] 2> 1761199 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/coll1.AUTOCREATED/lang/stopwords_eu.txt [junit4] 2> 1761204 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/coll1.AUTOCREATED/lang/stopwords_it.txt [junit4] 2> 1761208 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/coll1.AUTOCREATED/lang/stopwords_cz.txt [junit4] 2> 1761212 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/coll1.AUTOCREATED/lang/stoptags_ja.txt [junit4] 2> 1761217 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/coll1.AUTOCREATED/lang/contractions_fr.txt [junit4] 2> 1761222 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/coll1.AUTOCREATED/lang/stopwords_de.txt [junit4] 2> 1761228 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/coll1.AUTOCREATED/lang/stopwords_fa.txt [junit4] 2> 1761233 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/coll1.AUTOCREATED/lang/contractions_ga.txt [junit4] 2> 1761238 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/coll1.AUTOCREATED/lang/stopwords_ca.txt [junit4] 2> 1761242 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/coll1.AUTOCREATED/lang/stopwords_nl.txt [junit4] 2> 1761247 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/coll1.AUTOCREATED/lang/stopwords_bg.txt [junit4] 2> 1761252 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/coll1.AUTOCREATED/lang/stopwords_es.txt [junit4] 2> 1761256 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/coll1.AUTOCREATED/lang/stopwords_lv.txt [junit4] 2> 1761260 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/coll1.AUTOCREATED/lang/stopwords_en.txt [junit4] 2> 1761265 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/coll1.AUTOCREATED/lang/stopwords_ro.txt [junit4] 2> 1761269 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/coll1.AUTOCREATED/lang/stopwords_th.txt [junit4] 2> 1761272 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/coll1.AUTOCREATED/lang/stopwords_fr.txt [junit4] 2> 1761280 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/coll1.AUTOCREATED/lang/stopwords_sv.txt [junit4] 2> 1761284 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/coll1.AUTOCREATED/lang/stopwords_fi.txt [junit4] 2> 1761288 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/coll1.AUTOCREATED/lang/hyphenations_ga.txt [junit4] 2> 1761293 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/coll1.AUTOCREATED/lang/stopwords_gl.txt [junit4] 2> 1761297 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/coll1.AUTOCREATED/solrconfig.xml [junit4] 2> 1761301 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/coll1.AUTOCREATED/synonyms.txt [junit4] 2> 1761305 INFO (OverseerThreadFactory-8333-thread-2-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/coll1.AUTOCREATED/stopwords.txt [junit4] 2> 1761534 INFO (OverseerStateUpdate-72140998918078471-127.0.0.1:58086_solr-n_0000000000) [n:127.0.0.1:58086_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"coll1", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"coll1_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:58085/solr";, [junit4] 2> "node_name":"127.0.0.1:58085_solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1761742 INFO (qtp1530040293-23737) [n:127.0.0.1:58085_solr x:coll1_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=coll1.AUTOCREATED&newCollection=true&name=coll1_shard1_replica_n1&action=CREATE&numShards=1&collection=coll1&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 1761765 INFO (qtp1530040293-23737) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0 [junit4] 2> 1761793 INFO (qtp1530040293-23737) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=default-config [junit4] 2> 1762016 INFO (qtp1530040293-23737) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id [junit4] 2> 1762016 INFO (qtp1530040293-23737) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'coll1_shard1_replica_n1' using configuration from configset coll1.AUTOCREATED, trusted=true [junit4] 2> 1762017 INFO (qtp1530040293-23737) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58085.solr.core.coll1.shard1.replica_n1' (registry 'solr.core.coll1.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7fc6c4ea [junit4] 2> 1762017 INFO (qtp1530040293-23737) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.c.SolrCore [[coll1_shard1_replica_n1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.sim.TestSnapshotCloudManager_C1255F999A045813-001/tempDir-001/node1/./coll1_shard1_replica_n1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.sim.TestSnapshotCloudManager_C1255F999A045813-001/tempDir-001/node1/./coll1_shard1_replica_n1/data/] [junit4] 2> 1762059 INFO (qtp39180461-23731) [n:127.0.0.1:58084_solr c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request [.system_shard1_replica_n2] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 1762059 INFO (indexFetcher-8360-thread-1) [ ] o.a.s.h.IndexFetcher Master's generation: 1 [junit4] 2> 1762059 INFO (indexFetcher-8360-thread-1) [ ] o.a.s.h.IndexFetcher Master's version: 0 [junit4] 2> 1762059 INFO (indexFetcher-8360-thread-1) [ ] o.a.s.h.IndexFetcher Slave's generation: 2 [junit4] 2> 1762059 INFO (indexFetcher-8360-thread-1) [ ] o.a.s.h.IndexFetcher Slave's version: 1584288128018 [junit4] 2> 1762059 INFO (indexFetcher-8360-thread-1) [ ] o.a.s.h.IndexFetcher New index in Master. Deleting mine... [junit4] 2> 1762059 INFO (indexFetcher-8360-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1762059 INFO (indexFetcher-8360-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@12e27efa commitCommandVersion:0 [junit4] 2> 1762060 INFO (indexFetcher-8360-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@a724c54[.system_shard1_replica_p4] main] [junit4] 2> 1762060 INFO (indexFetcher-8360-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1762062 INFO (searcherExecutor-8351-thread-1) [ ] o.a.s.c.SolrCore [.system_shard1_replica_p4] Registered new searcher Searcher@a724c54[.system_shard1_replica_p4] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1762106 INFO (qtp1530040293-23737) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1762106 INFO (qtp1530040293-23737) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1762109 INFO (qtp1530040293-23737) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms; [junit4] 2> 1762109 INFO (qtp1530040293-23737) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1762111 INFO (qtp1530040293-23737) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6447e1aa[coll1_shard1_replica_n1] main] [junit4] 2> 1762116 INFO (qtp1530040293-23737) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/coll1.AUTOCREATED [junit4] 2> 1762117 INFO (qtp1530040293-23737) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/coll1.AUTOCREATED [junit4] 2> 1762117 INFO (qtp1530040293-23737) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/coll1.AUTOCREATED/managed-schema [junit4] 2> 1762119 INFO (qtp1530040293-23737) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest [junit4] 2> 1762120 INFO (qtp1530040293-23737) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 1762121 INFO (qtp1530040293-23737) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.s.DirectSolrSpellChecker init: {name=default, field=_text_, classname=solr.DirectSolrSpellChecker, distanceMeasure=internal, accuracy=0.5, maxEdits=2, minPrefix=1, maxInspections=5, minQueryLength=4, maxQueryFrequency=0.01} [junit4] 2> 1762122 INFO (qtp1530040293-23737) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1762122 INFO (qtp1530040293-23737) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1661246509233995776 [junit4] 2> 1762126 INFO (searcherExecutor-8367-thread-1-processing-n:127.0.0.1:58085_solr x:coll1_shard1_replica_n1 c:coll1 s:shard1 r:core_node2) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@6447e1aa[coll1_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1762126 INFO (searcherExecutor-8367-thread-1-processing-n:127.0.0.1:58085_solr x:coll1_shard1_replica_n1 c:coll1 s:shard1 r:core_node2) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done. [junit4] 2> 1762127 INFO (searcherExecutor-8367-thread-1-processing-n:127.0.0.1:58085_solr x:coll1_shard1_replica_n1 c:coll1 s:shard1 r:core_node2) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 1762128 INFO (searcherExecutor-8367-thread-1-processing-n:127.0.0.1:58085_solr x:coll1_shard1_replica_n1 c:coll1 s:shard1 r:core_node2) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.c.SolrCore [coll1_shard1_replica_n1] Registered new searcher Searcher@6447e1aa[coll1_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1762136 INFO (qtp1530040293-23737) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/coll1/terms/shard1 to Terms{values={core_node2=0}, version=0} [junit4] 2> 1762136 INFO (qtp1530040293-23737) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/coll1/leaders/shard1 [junit4] 2> 1762154 INFO (qtp1530040293-23737) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1762154 INFO (qtp1530040293-23737) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1762154 INFO (qtp1530040293-23737) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:58085/solr/coll1_shard1_replica_n1/ [junit4] 2> 1762155 INFO (qtp1530040293-23737) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1762156 INFO (qtp1530040293-23737) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:58085/solr/coll1_shard1_replica_n1/ has no replicas [junit4] 2> 1762156 INFO (qtp1530040293-23737) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/coll1/leaders/shard1/leader after winning as /collections/coll1/leader_elect/shard1/election/72140998918078472-core_node2-n_0000000000 [junit4] 2> 1762167 INFO (qtp1530040293-23737) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:58085/solr/coll1_shard1_replica_n1/ shard1 [junit4] 2> 1762273 INFO (zkCallback-8365-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [3]) [junit4] 2> 1762273 INFO (zkCallback-8365-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [3]) [junit4] 2> 1762276 INFO (qtp1530040293-23737) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1762280 INFO (qtp1530040293-23737) [n:127.0.0.1:58085_solr c:coll1 s:shard1 r:core_node2 x:coll1_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=coll1.AUTOCREATED&newCollection=true&name=coll1_shard1_replica_n1&action=CREATE&numShards=1&collection=coll1&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=538 [junit4] 2> 1762283 INFO (qtp310295311-23743) [n:127.0.0.1:58086_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> 1762389 INFO (zkCallback-8355-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [3]) [junit4] 2> 1762389 INFO (zkCallback-8365-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [3]) [junit4] 2> 1762389 INFO (zkCallback-8365-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [3]) [junit4] 2> 1762390 INFO (zkCallback-8365-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [3]) [junit4] 2> 1762392 INFO (qtp310295311-23743) [n:127.0.0.1:58086_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=coll1&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=1280 [junit4] 2> 1762396 INFO (qtp310295311-23742) [n:127.0.0.1:58086_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params name=coll10&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1762409 INFO (OverseerThreadFactory-8333-thread-3-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection coll10 [junit4] 2> 1762410 INFO (OverseerCollectionConfigSetProcessor-72140998918078471-127.0.0.1:58086_solr-n_0000000000) [n:127.0.0.1:58086_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> 1762414 INFO (OverseerThreadFactory-8333-thread-3-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/coll10.AUTOCREATED/managed-schema [junit4] 2> 1762424 INFO (OverseerThreadFactory-8333-thread-3-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/coll10.AUTOCREATED/protwords.txt [junit4] 2> 1762438 INFO (OverseerThreadFactory-8333-thread-3-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/coll10.AUTOCREATED/lang/contractions_it.txt [junit4] 2> 1762449 INFO (OverseerThreadFactory-8333-thread-3-processing-n:127.0.0.1:58086_solr) [n:127.0.0.1:58086_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/coll10.AUTOCREATED/lang/contractions_ca.txt [junit4] 2> 1762461 INFO (OverseerThreadFactory-8333-thread-3-processi [...truncated too long message...] 0} [junit4] 2> 1767793 INFO (jetty-closer-8384-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@777f3938{/solr,null,UNAVAILABLE} [junit4] 2> 1767793 INFO (jetty-closer-8384-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 1767795 INFO (SUITE-TestSnapshotCloudManager-seed#[C1255F999A045813]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer. [junit4] 2> 1768019 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> 8 /solr/collections/.system/terms/shard1 [junit4] 2> 4 /solr/aliases.json [junit4] 2> 3 /solr/configs/.system [junit4] 2> 3 /solr/packages.json [junit4] 2> 3 /solr/security.json [junit4] 2> 3 /solr/configs/.system/managed-schema [junit4] 2> 2 /solr/collections/coll1/terms/shard1 [junit4] 2> 2 /solr/collections/coll10/terms/shard1 [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 38 /solr/collections/.system/state.json [junit4] 2> 14 /solr/collections/coll10/state.json [junit4] 2> 14 /solr/collections/coll1/state.json [junit4] 2> 4 /solr/clusterstate.json [junit4] 2> 4 /solr/clusterprops.json [junit4] 2> 2 /solr/autoscaling.json [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 43 /solr/overseer/queue [junit4] 2> 16 /solr/collections [junit4] 2> 12 /solr/live_nodes [junit4] 2> 12 /solr/overseer/collection-queue-work [junit4] 2> [junit4] 2> 1768022 INFO (SUITE-TestSnapshotCloudManager-seed#[C1255F999A045813]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:58079 [junit4] 2> 1768022 INFO (SUITE-TestSnapshotCloudManager-seed#[C1255F999A045813]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:58079 [junit4] 2> 1768022 INFO (SUITE-TestSnapshotCloudManager-seed#[C1255F999A045813]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 58079 [junit4] 2> 1768022 INFO (SUITE-TestSnapshotCloudManager-seed#[C1255F999A045813]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released [junit4] 2> Mar 15, 2020 4:02:14 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> Mar 15, 2020 4:02:25 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> SEVERE: 1 thread leaked from SUITE scope at org.apache.solr.cloud.autoscaling.sim.TestSnapshotCloudManager: [junit4] 2> 1) Thread[id=23692, name=SessionTracker, state=TIMED_WAITING, group=TGRP-TestSnapshotCloudManager] [junit4] 2> at [email protected]/java.lang.Thread.sleep(Native Method) [junit4] 2> at app//org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151) [junit4] 2> Mar 15, 2020 4:02:25 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll [junit4] 2> INFO: Starting to interrupt leaked threads: [junit4] 2> 1) Thread[id=23692, name=SessionTracker, state=TIMED_WAITING, group=TGRP-TestSnapshotCloudManager] [junit4] 2> 1778097 ERROR (SessionTracker) [ ] o.a.z.s.ZooKeeperCriticalThread Severe unrecoverable error, from thread : SessionTracker [junit4] 2> => java.lang.InterruptedException: sleep interrupted [junit4] 2> at java.base/java.lang.Thread.sleep(Native Method) [junit4] 2> java.lang.InterruptedException: sleep interrupted [junit4] 2> at java.lang.Thread.sleep(Native Method) [?:?] [junit4] 2> at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151) ~[zookeeper-3.5.5.jar:3.5.5] [junit4] 2> Mar 15, 2020 4:02:25 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll [junit4] 2> INFO: All leaked threads terminated. [junit4] 2> NOTE: test params are: codec=Asserting(Lucene84), sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@1bbaf5e7), locale=en-MY, timezone=Asia/Irkutsk [junit4] 2> NOTE: Mac OS X 10.14.6 x86_64/AdoptOpenJDK 12.0.2 (64-bit)/cpus=6,threads=1,free=240780888,total=514850816 [junit4] 2> NOTE: All tests run in this JVM: [FuzzySearchTest, TestDocTermOrds, TestSearcherReuse, NodeAddedTriggerTest, HdfsDirectoryFactoryTest, TestSimNodeLostTrigger, TemplateUpdateProcessorTest, ComputePlanActionTest, PeerSyncWithBufferUpdatesTest, OverseerSolrResponseUnsafeSerializationTest, ScheduledMaintenanceTriggerTest, TestManagedSynonymGraphFilterFactory, DeleteShardTest, TestNumericTerms64, RulesTest, TestSweetSpotSimilarityFactory, LeaderTragicEventTest, TestCopyFieldCollectionResource, TestFastOutputStream, TestPushWriter, SimpleFacetsTest, TestDistribDocBasedVersion, NoCacheHeaderTest, JWTVerificationkeyResolverTest, LeaderElectionContextKeyTest, DistributedQueryElevationComponentTest, JvmMetricsTest, CopyFieldTest, TestSort, CollectionsAPISolrJTest, PeerSyncReplicationTest, TestLeaderElectionZkExpiry, SystemLogListenerTest, TestSubQueryTransformerDistrib, ZkNodePropsTest, TestMergePolicyConfig, TestHdfsBackupRestoreCore, TaggerTest, BlockCacheTest, MetricsHistoryHandlerTest, SimplePostToolTest, TestLMJelinekMercerSimilarityFactory, TestBadConfig, TestPKIAuthenticationPlugin, TestRestoreCore, MigrateRouteKeyTest, TestTlogReplayVsRecovery, TestExactStatsCacheCloud, TestStressUserVersions, SolrCloudReportersTest, TestManagedSchemaThreadSafety, DocValuesMultiTest, TestDistributedStatsComponentCardinality, TestFileDictionaryLookup, TestComponentsName, IndexSchemaTest, UUIDFieldTest, TestClassicSimilarityFactory, TestFieldTypeCollectionResource, TestMacros, TestCollationField, TestSolrCLIRunExample, TriggerSetPropertiesIntegrationTest, TestShardHandlerFactory, TestRandomFlRTGCloud, TestSchemaVersionResource, TolerantUpdateProcessorTest, TestUnifiedSolrHighlighterWithoutStoredId, SpatialHeatmapFacetsTest, TestMultiValuedNumericRangeQuery, TestRTGBase, SearchHandlerTest, TestConfigSetsAPI, TestCollectionAPIs, TestSimExecutePlanAction, TimeZoneUtilsTest, CreateRoutedAliasTest, SynonymTokenizerTest, XMLAtomicUpdateMultivalueTest, ConnectionReuseTest, MultiSolrCloudTestCaseTest, CloudReplicaSourceTest, CoreAdminOperationTest, TestMinHashQParser, TestRecovery, ResponseLogComponentTest, DistributedQueueTest, TestPayloadCheckQParserPlugin, DistributedExpandComponentTest, BasicAuthOnSingleNodeTest, TestSimpleTrackingShardHandler, TestSolrCoreProperties, TestSolrTestCaseJ4, TestTolerantSearch, TestTrie, ProtectedTermFilterFactoryTest, TestCharFilters, TestLuceneMatchVersion, TestWordDelimiterFilterFactory, TestEmbeddedSolrServerAdminHandler, TestEmbeddedSolrServerConstructors, TestEmbeddedSolrServerSchemaAPI, AssignBackwardCompatibilityTest, BasicZkTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, CloudExitableDirectoryReaderTest, CollectionPropsTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverseerCollectionConfigSetProcessorTest, OverseerRolesTest, ReplaceNodeNoTargetTest, ReplicationFactorTest, SystemCollectionCompatTest, TestCloudConsistency, TestShortCircuitedRequests, TestSolrCloudWithKerberosAlt, TestStressInPlaceUpdates, AsyncCallRequestStatusResponseTest, CollectionTooManyReplicasTest, ConcurrentDeleteAndCreateCollectionTest, CustomCollectionTest, TestCollectionAPI, TestRequestStatusCollectionAPI, IndexSizeTriggerSizeEstimationTest, TestSimUtils, TestSnapshotCloudManager] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSnapshotCloudManager -Dtests.seed=C1255F999A045813 -Dtests.slow=true -Dtests.locale=en-MY -Dtests.timezone=Asia/Irkutsk -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [junit4] ERROR 0.00s J1 | TestSnapshotCloudManager (suite) <<< [junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.cloud.autoscaling.sim.TestSnapshotCloudManager: [junit4] > 1) Thread[id=23692, name=SessionTracker, state=TIMED_WAITING, group=TGRP-TestSnapshotCloudManager] [junit4] > at [email protected]/java.lang.Thread.sleep(Native Method) [junit4] > at app//org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151) [junit4] > at __randomizedtesting.SeedInfo.seed([C1255F999A045813]:0) [junit4] Completed [598/905 (1!)] on J1 in 20.33s, 5 tests, 1 error <<< FAILURES! [...truncated 53623 lines...] [repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/5774/consoleText [repro] Revision: bd166207063881267855ab00f1cfaa6668c4ff7f [repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseParallelGC" [repro] Repro line: ant test -Dtestcase=TestSnapshotCloudManager -Dtests.seed=C1255F999A045813 -Dtests.slow=true -Dtests.locale=en-MY -Dtests.timezone=Asia/Irkutsk -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [repro] JUnit rest result XML files will be moved to: ./repro-reports [repro] ant clean [...truncated 6 lines...] [repro] Test suites by module: [repro] solr/core [repro] TestSnapshotCloudManager [repro] ant compile-test [...truncated 3150 lines...] [repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestSnapshotCloudManager" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseParallelGC" -Dtests.seed=C1255F999A045813 -Dtests.slow=true -Dtests.locale=en-MY -Dtests.timezone=Asia/Irkutsk -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [...truncated 101 lines...] [repro] Failures w/original seeds: [repro] 0/5 failed: org.apache.solr.cloud.autoscaling.sim.TestSnapshotCloudManager [repro] Exiting with code 0 [...truncated 73 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
