Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.11/3/

1 tests failed.
FAILED:  
org.apache.solr.cloud.TestCloudSearcherWarming.testPeersyncFailureReplicationSuccess

Error Message:
No registered leader was found after waiting for 1000ms , collection: 
testPeersyncFailureReplicationSuccess slice: shard1 saw 
state=DocCollection(testPeersyncFailureReplicationSuccess//collections/testPeersyncFailureReplicationSuccess/state.json/10
 )={   "pullReplicas":"0",   "replicationFactor":"1",   "shards":{"shard1":{    
   "range":"80000000-7fffffff",       "state":"active",       "replicas":{      
   "core_node2":{           
"core":"testPeersyncFailureReplicationSuccess_shard1_replica_n1",           
"node_name":"127.0.0.1:44531_solr",           
"base_url":"http://127.0.0.1:44531/solr";,           "state":"down",           
"type":"NRT",           "force_set_state":"false"},         "core_node4":{      
     "core":"testPeersyncFailureReplicationSuccess_shard1_replica_n3",          
 "node_name":"127.0.0.1:39403_solr",           
"base_url":"http://127.0.0.1:39403/solr";,           "state":"active",           
"type":"NRT",           "force_set_state":"false"}}}},   
"router":{"name":"compositeId"},   "maxShardsPerNode":"2",   
"autoAddReplicas":"false",   "nrtReplicas":"1",   "tlogReplicas":"0"} with 
live_nodes=[127.0.0.1:39403_solr]

Stack Trace:
org.apache.solr.common.SolrException: No registered leader was found after 
waiting for 1000ms , collection: testPeersyncFailureReplicationSuccess slice: 
shard1 saw 
state=DocCollection(testPeersyncFailureReplicationSuccess//collections/testPeersyncFailureReplicationSuccess/state.json/10
 )={
  "pullReplicas":"0",
  "replicationFactor":"1",
  "shards":{"shard1":{
      "range":"80000000-7fffffff",
      "state":"active",
      "replicas":{
        "core_node2":{
          "core":"testPeersyncFailureReplicationSuccess_shard1_replica_n1",
          "node_name":"127.0.0.1:44531_solr",
          "base_url":"http://127.0.0.1:44531/solr";,
          "state":"down",
          "type":"NRT",
          "force_set_state":"false"},
        "core_node4":{
          "core":"testPeersyncFailureReplicationSuccess_shard1_replica_n3",
          "node_name":"127.0.0.1:39403_solr",
          "base_url":"http://127.0.0.1:39403/solr";,
          "state":"active",
          "type":"NRT",
          "force_set_state":"false"}}}},
  "router":{"name":"compositeId"},
  "maxShardsPerNode":"2",
  "autoAddReplicas":"false",
  "nrtReplicas":"1",
  "tlogReplicas":"0"} with live_nodes=[127.0.0.1:39403_solr]
        at 
__randomizedtesting.SeedInfo.seed([35667417295C44F8:E1371758F8E2739B]:0)
        at 
org.apache.solr.common.cloud.ZkStateReader.getLeaderRetry(ZkStateReader.java:993)
        at 
org.apache.solr.common.cloud.ZkStateReader.getLeaderRetry(ZkStateReader.java:968)
        at 
org.apache.solr.cloud.TestCloudSearcherWarming.testPeersyncFailureReplicationSuccess(TestCloudSearcherWarming.java:205)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at org.junit.rules.RunRules.evaluate(RunRules.java:20)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at org.junit.rules.RunRules.evaluate(RunRules.java:20)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at org.junit.rules.RunRules.evaluate(RunRules.java:20)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at org.junit.rules.RunRules.evaluate(RunRules.java:20)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 14217 lines...]
   [junit4] Suite: org.apache.solr.cloud.TestCloudSearcherWarming
   [junit4]   2> 577874 INFO  
(SUITE-TestCloudSearcherWarming-seed#[35667417295C44F8]-worker) [     ] 
o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to 
test-framework derived value of 
'/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/server/solr/configsets/_default/conf'
   [junit4]   2> 577874 INFO  
(SUITE-TestCloudSearcherWarming-seed#[35667417295C44F8]-worker) [     ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 577875 INFO  
(SUITE-TestCloudSearcherWarming-seed#[35667417295C44F8]-worker) [     ] 
o.a.s.u.ErrorLogMuter Closing ErrorLogMuter-regex-186 after mutting 0 log 
messages
   [junit4]   2> 577875 INFO  
(SUITE-TestCloudSearcherWarming-seed#[35667417295C44F8]-worker) [     ] 
o.a.s.u.ErrorLogMuter Creating ErrorLogMuter-regex-187 for ERROR logs matching 
regex: ignore_exception
   [junit4]   2> 577876 INFO  
(SUITE-TestCloudSearcherWarming-seed#[35667417295C44F8]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Created dataDir: 
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_35667417295C44F8-001/data-dir-57-001
   [junit4]   2> 577876 WARN  
(SUITE-TestCloudSearcherWarming-seed#[35667417295C44F8]-worker) [     ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=15 numCloses=15
   [junit4]   2> 577876 INFO  
(SUITE-TestCloudSearcherWarming-seed#[35667417295C44F8]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 577878 INFO  
(SUITE-TestCloudSearcherWarming-seed#[35667417295C44F8]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 577878 INFO  
(SUITE-TestCloudSearcherWarming-seed#[35667417295C44F8]-worker) [     ] 
o.a.s.c.SolrCloudTestCase Using per-replica state
   [junit4]   2> 577880 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.SolrTestCaseJ4 ###Starting testRepFactor1LeaderStartup
   [junit4]   2> 577881 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in 
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_35667417295C44F8-001/tempDir-001
   [junit4]   2> 577881 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 577881 INFO  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
   [junit4]   2> 577881 INFO  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 577882 WARN  (ZkTestServer Run Thread) [     ] 
o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
   [junit4]   2> 577981 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.ZkTestServer start zk server on port: 34027
   [junit4]   2> 577982 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:34027
   [junit4]   2> 577982 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:34027
   [junit4]   2> 577982 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 34027
   [junit4]   2> 577984 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 577986 INFO  (zkConnectionManagerCallback-4027-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 577986 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 577988 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 577989 INFO  (zkConnectionManagerCallback-4029-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 577989 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 577990 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 577991 INFO  (zkConnectionManagerCallback-4031-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 577991 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 578094 INFO  (jetty-launcher-4032-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 578094 INFO  (jetty-launcher-4032-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 578094 INFO  (jetty-launcher-4032-thread-1) [     ] 
o.e.j.s.Server jetty-9.4.44.v20210927; built: 2021-09-27T23:02:44.612Z; git: 
8da83308eeca865e495e53ef315a249d63ba9332; jvm 1.8.0_291-b10
   [junit4]   2> 578101 INFO  (jetty-launcher-4032-thread-1) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 578101 INFO  (jetty-launcher-4032-thread-1) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 578101 INFO  (jetty-launcher-4032-thread-1) [     ] 
o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 578102 INFO  (jetty-launcher-4032-thread-1) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@2e64ea35{/solr,null,AVAILABLE}
   [junit4]   2> 578102 INFO  (jetty-launcher-4032-thread-1) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@5a14d816{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:36257}
   [junit4]   2> 578102 INFO  (jetty-launcher-4032-thread-1) [     ] 
o.e.j.s.Server Started @578133ms
   [junit4]   2> 578102 INFO  (jetty-launcher-4032-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
zkHost=127.0.0.1:34027/solr, hostPort=36257}
   [junit4]   2> 578102 ERROR (jetty-launcher-4032-thread-1) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 578102 INFO  (jetty-launcher-4032-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 578102 INFO  (jetty-launcher-4032-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.11.0
   [junit4]   2> 578102 INFO  (jetty-launcher-4032-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 578102 INFO  (jetty-launcher-4032-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr
   [junit4]   2> 578102 INFO  (jetty-launcher-4032-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2021-11-02T21:18:31.947Z
   [junit4]   2> 578103 INFO  (jetty-launcher-4032-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 578104 INFO  (zkConnectionManagerCallback-4034-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 578104 INFO  (jetty-launcher-4032-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 578105 INFO  (jetty-launcher-4032-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 578108 INFO  (jetty-launcher-4032-thread-1) [     ] 
o.a.s.c.SolrXmlConfig Metrics collection is disabled.
   [junit4]   2> 578404 INFO  (jetty-launcher-4032-thread-1) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 578405 WARN  (jetty-launcher-4032-thread-1) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@7e889139[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 578405 WARN  (jetty-launcher-4032-thread-1) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@7e889139[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 578407 WARN  (jetty-launcher-4032-thread-1) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@25fa64a9[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 578407 WARN  (jetty-launcher-4032-thread-1) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@25fa64a9[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 578408 INFO  (jetty-launcher-4032-thread-1) [     ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34027/solr
   [junit4]   2> 578408 INFO  (jetty-launcher-4032-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 578412 INFO  (zkConnectionManagerCallback-4045-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 578412 INFO  (jetty-launcher-4032-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 578414 WARN  
(jetty-launcher-4032-thread-1-SendThread(127.0.0.1:34027)) [     ] 
o.a.z.ClientCnxn An exception was thrown while closing send thread for session 
0x1089971b8670004.
   [junit4]   2>           => EndOfStreamException: Unable to read additional 
data from server sessionid 0x1089971b8670004, likely server has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
   [junit4]   2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable 
to read additional data from server sessionid 0x1089971b8670004, likely server 
has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) 
~[zookeeper-3.6.2.jar:3.6.2]
   [junit4]   2>        at 
org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350)
 ~[zookeeper-3.6.2.jar:3.6.2]
   [junit4]   2>        at 
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) 
[zookeeper-3.6.2.jar:3.6.2]
   [junit4]   2> 578515 INFO  (jetty-launcher-4032-thread-1) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 578517 INFO  (zkConnectionManagerCallback-4047-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 578517 INFO  (jetty-launcher-4032-thread-1) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 578595 WARN  (jetty-launcher-4032-thread-1) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.ZkController Contents of zookeeper 
/security.json are world-readable; consider setting up ACLs as described in 
https://solr.apache.org/guide/zookeeper-access-control.html
   [junit4]   2> 578600 INFO  (jetty-launcher-4032-thread-1) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:36257_solr
   [junit4]   2> 578601 INFO  (jetty-launcher-4032-thread-1) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.Overseer Overseer 
(id=74478107555725317-127.0.0.1:36257_solr-n_0000000000) starting
   [junit4]   2> 578610 INFO  (jetty-launcher-4032-thread-1) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:36257_solr
   [junit4]   2> 578612 INFO  (zkCallback-4046-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 578613 INFO  (jetty-launcher-4032-thread-1) 
[n:127.0.0.1:36257_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 578613 INFO  
(OverseerStateUpdate-74478107555725317-127.0.0.1:36257_solr-n_0000000000) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.Overseer Starting to work on the main 
queue : 127.0.0.1:36257_solr
   [junit4]   2> 578613 WARN  (jetty-launcher-4032-thread-1) 
[n:127.0.0.1:36257_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> 578639 INFO  (jetty-launcher-4032-thread-1) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_35667417295C44F8-001/tempDir-001/node1
   [junit4]   2> 578654 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=1
   [junit4]   2> 578655 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 578655 INFO  (zkConnectionManagerCallback-4062-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 578655 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 578656 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 578659 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 
127.0.0.1:34027/solr ready
   [junit4]   2> 578670 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.a.c.CreateCollectionCmd Create collection 
testRepFactor1LeaderStartup
   [junit4]   2> 578670 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default to /configs/testRepFactor1LeaderStartup.AUTOCREATED
   [junit4]   2> 578671 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/protwords.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/protwords.txt
   [junit4]   2> 578672 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/managed-schema to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/managed-schema
   [junit4]   2> 578674 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/solrconfig.xml to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/solrconfig.xml
   [junit4]   2> 578676 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/contractions_it.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/contractions_it.txt
   [junit4]   2> 578677 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/contractions_ca.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/contractions_ca.txt
   [junit4]   2> 578678 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_no.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_no.txt
   [junit4]   2> 578680 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stemdict_nl.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stemdict_nl.txt
   [junit4]   2> 578682 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_hy.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_hy.txt
   [junit4]   2> 578684 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_id.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_id.txt
   [junit4]   2> 578685 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_et.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_et.txt
   [junit4]   2> 578687 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_hi.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_hi.txt
   [junit4]   2> 578688 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_da.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_da.txt
   [junit4]   2> 578690 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_ga.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_ga.txt
   [junit4]   2> 578691 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_ja.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_ja.txt
   [junit4]   2> 578693 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_pt.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_pt.txt
   [junit4]   2> 578694 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_hu.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_hu.txt
   [junit4]   2> 578699 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_el.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_el.txt
   [junit4]   2> 578701 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_ru.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_ru.txt
   [junit4]   2> 578702 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_tr.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_tr.txt
   [junit4]   2> 578704 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_ar.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_ar.txt
   [junit4]   2> 578709 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/userdict_ja.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/userdict_ja.txt
   [junit4]   2> 578711 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_it.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_it.txt
   [junit4]   2> 578712 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_eu.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_eu.txt
   [junit4]   2> 578714 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_cz.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_cz.txt
   [junit4]   2> 578715 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stoptags_ja.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stoptags_ja.txt
   [junit4]   2> 578716 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/contractions_fr.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/contractions_fr.txt
   [junit4]   2> 578718 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_fa.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_fa.txt
   [junit4]   2> 578719 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_de.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_de.txt
   [junit4]   2> 578721 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/contractions_ga.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/contractions_ga.txt
   [junit4]   2> 578722 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_ca.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_ca.txt
   [junit4]   2> 578723 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_nl.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_nl.txt
   [junit4]   2> 578725 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_bg.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_bg.txt
   [junit4]   2> 578726 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_es.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_es.txt
   [junit4]   2> 578727 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_en.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_en.txt
   [junit4]   2> 578729 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_lv.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_lv.txt
   [junit4]   2> 578730 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_th.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_th.txt
   [junit4]   2> 578732 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_ro.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_ro.txt
   [junit4]   2> 578742 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_fr.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_fr.txt
   [junit4]   2> 578743 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_sv.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_sv.txt
   [junit4]   2> 578752 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_fi.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_fi.txt
   [junit4]   2> 578753 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/hyphenations_ga.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/hyphenations_ga.txt
   [junit4]   2> 578754 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_gl.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_gl.txt
   [junit4]   2> 578756 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/synonyms.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/synonyms.txt
   [junit4]   2> 578757 INFO  
(OverseerThreadFactory-4054-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/stopwords.txt to 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/stopwords.txt
   [junit4]   2> 578865 INFO  
(OverseerStateUpdate-74478107555725317-127.0.0.1:36257_solr-n_0000000000) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "core":"testRepFactor1LeaderStartup_shard1_replica_n1",
   [junit4]   2>   "node_name":"127.0.0.1:36257_solr",
   [junit4]   2>   "base_url":"http://127.0.0.1:36257/solr";,
   [junit4]   2>   "collection":"testRepFactor1LeaderStartup",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 579068 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr    
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation 
core create command 
qt=/admin/cores&coreNodeName=core_node2&collection.configName=testRepFactor1LeaderStartup.AUTOCREATED&newCollection=true&name=testRepFactor1LeaderStartup_shard1_replica_n1&action=CREATE&numShards=1&collection=testRepFactor1LeaderStartup&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 579069 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr    
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 
2147483647 cores with initial capacity of 1024
   [junit4]   2> 579083 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 8.11.0
   [junit4]   2> 579083 WARN  (qtp1423125756-6430) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.c.SolrConfig 
solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter 
section instead
   [junit4]   2> 579086 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema 
name=default-config
   [junit4]   2> 579135 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded 
schema default-config/1.6 with uniqueid field id
   [junit4]   2> 579173 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.c.CoreContainer 
Creating SolrCore 'testRepFactor1LeaderStartup_shard1_replica_n1' using 
configuration from configset testRepFactor1LeaderStartup.AUTOCREATED, 
trusted=true
   [junit4]   2> 579181 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.c.SolrCore 
[[testRepFactor1LeaderStartup_shard1_replica_n1] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_35667417295C44F8-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_35667417295C44F8-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/data/]
   [junit4]   2> 579229 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 579229 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 579230 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard 
AutoCommit: if uncommitted for 15000ms; 
   [junit4]   2> 579230 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 579233 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/testRepFactor1LeaderStartup.AUTOCREATED
   [junit4]   2> 579234 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/testRepFactor1LeaderStartup.AUTOCREATED
   [junit4]   2> 579234 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader 
Creating ZooKeeper watch for the managed schema at 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/managed-schema
   [junit4]   2> 579234 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader 
Current schema version 0 is already the latest
   [junit4]   2> 579234 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent 
Initializing spell checkers
   [junit4]   2> 579234 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] 
o.a.s.s.DirectSolrSpellChecker init: 
{name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxQueryFrequency=0.01,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4}
   [junit4]   2> 579235 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.h.ReplicationHandler 
Commits will be reserved for 10000 ms
   [junit4]   2> 579235 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not 
find max version in index or recent updates, using new clock 1715352804345774080
   [junit4]   2> 579237 INFO  
(searcherExecutor-4064-thread-1-processing-n:127.0.0.1:36257_solr 
x:testRepFactor1LeaderStartup_shard1_replica_n1 c:testRepFactor1LeaderStartup 
s:shard1 r:core_node2) [n:127.0.0.1:36257_solr c:testRepFactor1LeaderStartup 
s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1 ] 
o.a.s.c.QuerySenderListener QuerySenderListener done.
   [junit4]   2> 579237 INFO  
(searcherExecutor-4064-thread-1-processing-n:127.0.0.1:36257_solr 
x:testRepFactor1LeaderStartup_shard1_replica_n1 c:testRepFactor1LeaderStartup 
s:shard1 r:core_node2) [n:127.0.0.1:36257_solr c:testRepFactor1LeaderStartup 
s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 579237 INFO  
(searcherExecutor-4064-thread-1-processing-n:127.0.0.1:36257_solr 
x:testRepFactor1LeaderStartup_shard1_replica_n1 c:testRepFactor1LeaderStartup 
s:shard1 r:core_node2) [n:127.0.0.1:36257_solr c:testRepFactor1LeaderStartup 
s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1 ] 
o.a.s.c.SolrCore [testRepFactor1LeaderStartup_shard1_replica_n1]  Registered 
new searcher autowarm time: 0 ms
   [junit4]   2> 579241 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.c.ZkShardTerms 
Successful update of terms at 
/collections/testRepFactor1LeaderStartup/terms/shard1 to 
Terms{values={core_node2=0}, version=0}
   [junit4]   2> 579241 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created 
/collections/testRepFactor1LeaderStartup/leaders/shard1
   [junit4]   2> 579244 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 579244 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 579244 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync 
replicas to 
http://127.0.0.1:36257/solr/testRepFactor1LeaderStartup_shard1_replica_n1/
   [junit4]   2> 579244 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync 
Success - now sync replicas to me
   [junit4]   2> 579245 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.c.SyncStrategy 
http://127.0.0.1:36257/solr/testRepFactor1LeaderStartup_shard1_replica_n1/ has 
no replicas
   [junit4]   2> 579245 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node 
/collections/testRepFactor1LeaderStartup/leaders/shard1/leader after winning as 
/collections/testRepFactor1LeaderStartup/leader_elect/shard1/election/74478107555725317-core_node2-n_0000000000
   [junit4]   2> 579247 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:36257/solr/testRepFactor1LeaderStartup_shard1_replica_n1/ 
shard1
   [junit4]   2> 579348 INFO  (zkCallback-4046-thread-1) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/testRepFactor1LeaderStartup/state.json] for collection 
[testRepFactor1LeaderStartup] has occurred - updating... (live nodes size: [1])
   [junit4]   2> 579349 INFO  (zkCallback-4046-thread-2) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/testRepFactor1LeaderStartup/state.json] for collection 
[testRepFactor1LeaderStartup] has occurred - updating... (live nodes size: [1])
   [junit4]   2> 579349 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.c.ZkController I am the 
leader, no recovery necessary
   [junit4]   2> 579353 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=testRepFactor1LeaderStartup.AUTOCREATED&newCollection=true&name=testRepFactor1LeaderStartup_shard1_replica_n1&action=CREATE&numShards=1&collection=testRepFactor1LeaderStartup&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=284
   [junit4]   2> 579355 INFO  (qtp1423125756-6428) [n:127.0.0.1:36257_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> 579452 INFO  (zkCallback-4046-thread-2) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/testRepFactor1LeaderStartup/state.json] for collection 
[testRepFactor1LeaderStartup] has occurred - updating... (live nodes size: [1])
   [junit4]   2> 579452 INFO  (zkCallback-4046-thread-1) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/testRepFactor1LeaderStartup/state.json] for collection 
[testRepFactor1LeaderStartup] has occurred - updating... (live nodes size: [1])
   [junit4]   2> 579453 INFO  (zkCallback-4046-thread-3) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/testRepFactor1LeaderStartup/state.json] for collection 
[testRepFactor1LeaderStartup] has occurred - updating... (live nodes size: [1])
   [junit4]   2> 579453 INFO  (qtp1423125756-6428) [n:127.0.0.1:36257_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={pullReplicas=0&name=testRepFactor1LeaderStartup&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&createNodeSet=127.0.0.1:36257_solr&wt=javabin&version=2}
 status=0 QTime=790
   [junit4]   2> 579453 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: 
testRepFactor1LeaderStartup
   [junit4]   2> 579465 INFO  (Thread-614) [     ] o.a.s.c.SolrCore config 
update listener called for core testRepFactor1LeaderStartup_shard1_replica_n1
   [junit4]   2> 579466 INFO  (qtp1423125756-6431) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler 
Waiting up to 30 secs for 1 replicas to set the property overlay to be of 
version 0 for collection testRepFactor1LeaderStartup
   [junit4]   2> 579468 INFO  (Thread-614) [n:127.0.0.1:36257_solr     ] 
o.a.s.c.SolrCore core reload testRepFactor1LeaderStartup_shard1_replica_n1
   [junit4]   2> 579469 INFO  
(solrHandlerExecutor-4069-thread-1-processing-n:127.0.0.1:36257_solr 
x:testRepFactor1LeaderStartup_shard1_replica_n1 c:testRepFactor1LeaderStartup 
s:shard1 r:core_node2) [n:127.0.0.1:36257_solr c:testRepFactor1LeaderStartup 
s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1 ] 
o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
   [junit4]   2> 579474 INFO  (Thread-614) [n:127.0.0.1:36257_solr     ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0
   [junit4]   2> 579474 WARN  (Thread-614) [n:127.0.0.1:36257_solr     ] 
o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer supported, use 
solr.xml:/metrics/reporter section instead
   [junit4]   2> 579475 INFO  (Thread-614) [n:127.0.0.1:36257_solr     ] 
o.a.s.s.IndexSchema Schema name=default-config
   [junit4]   2> 579519 INFO  (Thread-614) [n:127.0.0.1:36257_solr     ] 
o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
   [junit4]   2> 579549 INFO  (Thread-614) [n:127.0.0.1:36257_solr     ] 
o.a.s.c.CoreContainer Reloading SolrCore 
'testRepFactor1LeaderStartup_shard1_replica_n1' using configuration from 
configset testRepFactor1LeaderStartup.AUTOCREATED
   [junit4]   2> 579550 INFO  (Thread-614) [n:127.0.0.1:36257_solr     ] 
o.a.s.c.SolrCore [[testRepFactor1LeaderStartup_shard1_replica_n1] ] Opening new 
SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_35667417295C44F8-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_35667417295C44F8-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/data/]
   [junit4]   2> 579570 INFO  (qtp1423125756-6429) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler 
expecting overlay version 0 but my version is -1
   [junit4]   2> 579570 INFO  (qtp1423125756-6429) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.c.S.Request 
[testRepFactor1LeaderStartup_shard1_replica_n1]  webapp=/solr 
path=/config/znodeVersion params={overlay=0&wt=javabin&version=2} status=0 
QTime=0
   [junit4]   2> 579571 INFO  
(solrHandlerExecutor-4069-thread-1-processing-n:127.0.0.1:36257_solr 
x:testRepFactor1LeaderStartup_shard1_replica_n1 c:testRepFactor1LeaderStartup 
s:shard1 r:core_node2) [n:127.0.0.1:36257_solr c:testRepFactor1LeaderStartup 
s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1 ] 
o.a.s.h.SolrConfigHandler Could not get expectedVersion 0 from 
http://127.0.0.1:36257/solr/testRepFactor1LeaderStartup_shard1_replica_n1/ for 
prop overlay   after 1 attempts
   [junit4]   2> 579571 INFO  
(solrHandlerExecutor-4069-thread-1-processing-n:127.0.0.1:36257_solr 
x:testRepFactor1LeaderStartup_shard1_replica_n1 c:testRepFactor1LeaderStartup 
s:shard1 r:core_node2) [n:127.0.0.1:36257_solr c:testRepFactor1LeaderStartup 
s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1 ] 
o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
   [junit4]   2> 579571 INFO  (SolrConfigHandler-refreshconf) [     ] 
o.a.s.h.SolrConfigHandler Another reload is in progress . Not doing anything
   [junit4]   2> 579589 INFO  (Thread-614) [n:127.0.0.1:36257_solr     ] 
o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms; 
   [junit4]   2> 579589 INFO  (Thread-614) [n:127.0.0.1:36257_solr     ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 579590 INFO  (Thread-614) [n:127.0.0.1:36257_solr     ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/testRepFactor1LeaderStartup.AUTOCREATED
   [junit4]   2> 579590 INFO  (Thread-614) [n:127.0.0.1:36257_solr     ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/testRepFactor1LeaderStartup.AUTOCREATED
   [junit4]   2> 579590 INFO  (Thread-614) [n:127.0.0.1:36257_solr     ] 
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/managed-schema
   [junit4]   2> 579591 INFO  (Thread-614) [n:127.0.0.1:36257_solr     ] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 579591 INFO  (Thread-614) [n:127.0.0.1:36257_solr     ] 
o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 579591 INFO  (Thread-614) [n:127.0.0.1:36257_solr     ] 
o.a.s.s.DirectSolrSpellChecker init: 
{name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxQueryFrequency=0.01,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4}
   [junit4]   2> 579592 INFO  (Thread-614) [n:127.0.0.1:36257_solr     ] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
   [junit4]   2> 579592 INFO  (Thread-614) [n:127.0.0.1:36257_solr     ] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1715352804720115712
   [junit4]   2> 579593 INFO  
(searcherExecutor-4071-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.QuerySenderListener QuerySenderListener 
done.
   [junit4]   2> 579593 INFO  
(searcherExecutor-4071-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.h.c.SpellCheckComponent Loading spell index 
for spellchecker: default
   [junit4]   2> 579594 INFO  
(searcherExecutor-4071-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.SolrCore 
[testRepFactor1LeaderStartup_shard1_replica_n1]  Registered new searcher 
autowarm time: 0 ms
   [junit4]   2> 579596 INFO  (Thread-614) [n:127.0.0.1:36257_solr     ] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 579596 INFO  
(searcherExecutor-4071-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.QuerySenderListener QuerySenderListener 
done.
   [junit4]   2> 579596 INFO  
(searcherExecutor-4071-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.SolrCore 
[testRepFactor1LeaderStartup_shard1_replica_n1]  Registered new searcher 
autowarm time: 0 ms
   [junit4]   2> 579672 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler I 
already have the expected version 0 of config
   [junit4]   2> 579672 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler 
isStale false , resourceloader org.apache.solr.cloud.ZkSolrResourceLoader
   [junit4]   2> 579672 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.c.S.Request 
[testRepFactor1LeaderStartup_shard1_replica_n1]  webapp=/solr 
path=/config/znodeVersion params={overlay=0&wt=javabin&version=2} status=0 
QTime=0
   [junit4]   2> 579673 INFO  (qtp1423125756-6431) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler 
Took 209.0ms to set the property overlay to be of version 0 for collection 
testRepFactor1LeaderStartup
   [junit4]   2> 579673 INFO  (qtp1423125756-6431) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.c.S.Request 
[testRepFactor1LeaderStartup_shard1_replica_n1]  webapp=/solr path=/config 
params={wt=javabin&version=2} status=0 QTime=216
   [junit4]   2> 579673 INFO  (qtp1423125756-6431) [n:127.0.0.1:36257_solr     
] o.a.s.c.SolrCore [testRepFactor1LeaderStartup_shard1_replica_n1]  CLOSING 
SolrCore org.apache.solr.core.SolrCore@64d9c6c4
   [junit4]   2> 579673 INFO  (qtp1423125756-6431) [n:127.0.0.1:36257_solr     
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.testRepFactor1LeaderStartup.shard1.replica_n1 
tag=SolrCore@64d9c6c4
   [junit4]   2> 579673 INFO  (qtp1423125756-6431) [n:127.0.0.1:36257_solr     
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.collection.testRepFactor1LeaderStartup.shard1.leader 
tag=SolrCore@64d9c6c4
   [junit4]   2> 579683 INFO  (qtp1423125756-6429) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.c.ZkShardTerms 
Successful update of terms at 
/collections/testRepFactor1LeaderStartup/terms/shard1 to 
Terms{values={core_node2=1}, version=1}
   [junit4]   2> 579683 INFO  (qtp1423125756-6429) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] 
o.a.s.u.p.LogUpdateProcessorFactory 
[testRepFactor1LeaderStartup_shard1_replica_n1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[1 (1715352804813438976)]} 0 8
   [junit4]   2> 579702 INFO  
(searcherExecutor-4071-thread-1-processing-n:127.0.0.1:36257_solr 
x:testRepFactor1LeaderStartup_shard1_replica_n1 c:testRepFactor1LeaderStartup 
s:shard1 r:core_node2) [n:127.0.0.1:36257_solr c:testRepFactor1LeaderStartup 
s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1 ] 
o.a.s.c.QuerySenderListener QuerySenderListener done.
   [junit4]   2> 579702 INFO  
(searcherExecutor-4071-thread-1-processing-n:127.0.0.1:36257_solr 
x:testRepFactor1LeaderStartup_shard1_replica_n1 c:testRepFactor1LeaderStartup 
s:shard1 r:core_node2) [n:127.0.0.1:36257_solr c:testRepFactor1LeaderStartup 
s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1 ] 
o.a.s.c.SolrCore [testRepFactor1LeaderStartup_shard1_replica_n1]  Registered 
new searcher autowarm time: 0 ms
   [junit4]   2> 579703 INFO  (qtp1423125756-6430) [n:127.0.0.1:36257_solr 
c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] 
o.a.s.u.p.LogUpdateProcessorFactory 
[testRepFactor1LeaderStartup_shard1_replica_n1]  webapp=/solr path=/update 
params={_stateVer_=testRepFactor1LeaderStartup:4&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 18
   [junit4]   2> 579704 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1011091157
   [junit4]   2> 579704 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.ZkController Remove node as live in 
ZooKeeper:/live_nodes/127.0.0.1:36257_solr
   [junit4]   2> 579704 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.ZkController Publish this node as DOWN...
   [junit4]   2> 579704 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.ZkController Publish node=127.0.0.1:36257_solr as DOWN
   [junit4]   2> 579706 INFO  (coreCloseExecutor-4080-thread-1) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.SolrCore 
[testRepFactor1LeaderStartup_shard1_replica_n1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@157f2366
   [junit4]   2> 579706 INFO  (coreCloseExecutor-4080-thread-1) 
[n:127.0.0.1:36257_solr     ] o.a.s.m.SolrMetricManager Closing metric 
reporters for registry=solr.core.testRepFactor1LeaderStartup.shard1.replica_n1 
tag=SolrCore@157f2366
   [junit4]   2> 579706 INFO  (coreCloseExecutor-4080-thread-1) 
[n:127.0.0.1:36257_solr     ] o.a.s.m.SolrMetricManager Closing metric 
reporters for 
registry=solr.collection.testRepFactor1LeaderStartup.shard1.leader 
tag=SolrCore@157f2366
   [junit4]   2> 579707 INFO  (coreCloseExecutor-4080-thread-1) 
[n:127.0.0.1:36257_solr     ] o.a.s.u.DirectUpdateHandler2 Committing on 
IndexWriter.close()  ... SKIPPED (unnecessary).
   [junit4]   2> 579709 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.node tag=null
   [junit4]   2> 579709 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.jvm tag=null
   [junit4]   2> 579709 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.jetty tag=null
   [junit4]   2> 579709 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.cluster tag=null
   [junit4]   2> 579710 INFO  (closeThreadPool-4082-thread-1) [     ] 
o.a.s.c.Overseer Overseer 
(id=74478107555725317-127.0.0.1:36257_solr-n_0000000000) closing
   [junit4]   2> 579710 INFO  
(OverseerStateUpdate-74478107555725317-127.0.0.1:36257_solr-n_0000000000) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.Overseer Overseer Loop exiting : 
127.0.0.1:36257_solr
   [junit4]   2> 579711 INFO  
(OverseerAutoScalingTriggerThread-74478107555725317-127.0.0.1:36257_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we 
are closed, exiting.
   [junit4]   2> 579726 INFO  (closeThreadPool-4082-thread-2) [     ] 
o.a.s.c.Overseer Overseer 
(id=74478107555725317-127.0.0.1:36257_solr-n_0000000000) closing
   [junit4]   2> 579727 INFO  (zkCallback-4061-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
   [junit4]   2> 579826 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.Overseer Overseer 
(id=74478107555725317-127.0.0.1:36257_solr-n_0000000000) closing
   [junit4]   2> 579827 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.e.j.s.AbstractConnector Stopped ServerConnector@5a14d816{HTTP/1.1, 
(http/1.1, h2c)}{127.0.0.1:0}
   [junit4]   2> 579827 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@2e64ea35{/solr,null,STOPPED}
   [junit4]   2> 579827 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 579828 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.MiniSolrCloudCluster waitForJettyToStop: 36257
   [junit4]   2> 579828 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.SolrCloudTestCase waitForState (testRepFactor1LeaderStartup): 
jetty count:1
   [junit4]   2> 579830 INFO  (watches-4059-thread-2) [     ] 
o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
   [junit4]   2> 579831 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica 
count: 0
   [junit4]   2> 579832 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica 
count: 0
   [junit4]   2> 579833 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica 
count: 0
   [junit4]   2> 579833 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding 
port=36257)
   [junit4]   2> 579833 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 36257 try 
number 2 ...
   [junit4]   2> 579833 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.e.j.s.Server jetty-9.4.44.v20210927; built: 
2021-09-27T23:02:44.612Z; git: 8da83308eeca865e495e53ef315a249d63ba9332; jvm 
1.8.0_291-b10
   [junit4]   2> 579841 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 579841 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 579841 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 579841 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@7ed56697{/solr,null,AVAILABLE}
   [junit4]   2> 579842 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.e.j.s.AbstractConnector Started ServerConnector@49c54a78{HTTP/1.1, 
(http/1.1, h2c)}{127.0.0.1:36257}
   [junit4]   2> 579842 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.e.j.s.Server Started @579872ms
   [junit4]   2> 579842 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
zkHost=127.0.0.1:34027/solr, hostPort=36257}
   [junit4]   2> 579842 ERROR 
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging 
may be missing or incomplete.
   [junit4]   2> 579842 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 579842 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? 
version 8.11.0
   [junit4]   2> 579842 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on 
port null
   [junit4]   2> 579842 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr
   [junit4]   2> 579842 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2021-11-02T21:18:33.687Z
   [junit4]   2> 579845 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 579849 INFO  (zkConnectionManagerCallback-4084-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 579849 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 579850 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 579853 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
   [junit4]   2> 580494 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 580495 WARN  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.e.j.u.s.S.config Trusting all certificates configured for 
Client@497a5928[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 580495 WARN  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm 
configured for Client@497a5928[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 580498 WARN  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.e.j.u.s.S.config Trusting all certificates configured for 
Client@188a254[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 580498 WARN  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm 
configured for Client@188a254[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 580499 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34027/solr
   [junit4]   2> 580500 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 580501 INFO  (zkConnectionManagerCallback-4095-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 580501 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 580603 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [n:127.0.0.1:36257_solr     ] o.a.s.c.c.ConnectionManager Waiting for client 
to connect to ZooKeeper
   [junit4]   2> 580604 INFO  (zkConnectionManagerCallback-4097-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 580604 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [n:127.0.0.1:36257_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 580608 WARN  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [n:127.0.0.1:36257_solr     ] o.a.s.c.ZkController Contents of zookeeper 
/security.json are world-readable; consider setting up ACLs as described in 
https://solr.apache.org/guide/zookeeper-access-control.html
   [junit4]   2> 580612 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [n:127.0.0.1:36257_solr     ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:36257_solr
   [junit4]   2> 580612 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [n:127.0.0.1:36257_solr     ] o.a.s.c.Overseer Overseer 
(id=74478107555725321-127.0.0.1:36257_solr-n_0000000001) starting
   [junit4]   2> 580617 INFO  
(OverseerStateUpdate-74478107555725321-127.0.0.1:36257_solr-n_0000000001) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.Overseer Starting to work on the main 
queue : 127.0.0.1:36257_solr
   [junit4]   2> 580618 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [n:127.0.0.1:36257_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:36257_solr
   [junit4]   2> 580620 INFO  (zkCallback-4061-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 580621 INFO  
(OverseerStateUpdate-74478107555725321-127.0.0.1:36257_solr-n_0000000001) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 580624 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [n:127.0.0.1:36257_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 580625 WARN  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [n:127.0.0.1:36257_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> 580658 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [n:127.0.0.1:36257_solr     ] o.a.s.c.CorePropertiesLocator Found 1 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_35667417295C44F8-001/tempDir-001/node1
   [junit4]   2> 580659 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [n:127.0.0.1:36257_solr     ] o.a.s.c.CorePropertiesLocator Cores are: 
[testRepFactor1LeaderStartup_shard1_replica_n1]
   [junit4]   2> 580660 INFO  
(coreLoadExecutor-4107-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient core cache for max 2147483647 cores with initial capacity of 1024
   [junit4]   2> 580676 INFO  
(coreLoadExecutor-4107-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 
8.11.0
   [junit4]   2> 580677 WARN  
(coreLoadExecutor-4107-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no 
longer supported, use solr.xml:/metrics/reporter section instead
   [junit4]   2> 580689 INFO  
(coreLoadExecutor-4107-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.s.IndexSchema Schema name=default-config
   [junit4]   2> 580743 INFO  
(coreLoadExecutor-4107-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr     ] o.a.s.s.IndexSchema Loaded schema 
default-config/1.6 with uniqueid field id
   [junit4]   2> 580790 INFO  
(coreLoadExecutor-4107-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 8.11.0
   [junit4]   2> 580790 WARN  
(coreLoadExecutor-4107-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.c.SolrConfig 
solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter 
section instead
   [junit4]   2> 580791 INFO  
(coreLoadExecutor-4107-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema 
name=default-config
   [junit4]   2> 580832 INFO  
(coreLoadExecutor-4107-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded 
schema default-config/1.6 with uniqueid field id
   [junit4]   2> 580883 INFO  
(coreLoadExecutor-4107-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.c.CoreContainer 
Creating SolrCore 'testRepFactor1LeaderStartup_shard1_replica_n1' using 
configuration from configset testRepFactor1LeaderStartup.AUTOCREATED, 
trusted=true
   [junit4]   2> 580884 INFO  
(coreLoadExecutor-4107-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.c.SolrCore 
[[testRepFactor1LeaderStartup_shard1_replica_n1] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_35667417295C44F8-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_35667417295C44F8-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/data/]
   [junit4]   2> 580923 INFO  
(coreLoadExecutor-4107-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 580923 INFO  
(coreLoadExecutor-4107-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 580924 INFO  
(coreLoadExecutor-4107-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard 
AutoCommit: if uncommitted for 15000ms; 
   [junit4]   2> 580924 INFO  
(coreLoadExecutor-4107-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 580928 INFO  
(coreLoadExecutor-4107-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/testRepFactor1LeaderStartup.AUTOCREATED
   [junit4]   2> 580929 INFO  
(coreLoadExecutor-4107-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/testRepFactor1LeaderStartup.AUTOCREATED
   [junit4]   2> 580929 INFO  
(coreLoadExecutor-4107-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader 
Creating ZooKeeper watch for the managed schema at 
/configs/testRepFactor1LeaderStartup.AUTOCREATED/managed-schema
   [junit4]   2> 580929 INFO  
(coreLoadExecutor-4107-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader 
Current schema version 0 is already the latest
   [junit4]   2> 580929 INFO  
(coreLoadExecutor-4107-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent 
Initializing spell checkers
   [junit4]   2> 580930 INFO  
(coreLoadExecutor-4107-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] 
o.a.s.s.DirectSolrSpellChecker init: 
{name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxQueryFrequency=0.01,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4}
   [junit4]   2> 580930 INFO  
(coreLoadExecutor-4107-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.h.ReplicationHandler 
Commits will be reserved for 10000 ms
   [junit4]   2> 580933 INFO  
(searcherExecutor-4109-thread-1-processing-n:127.0.0.1:36257_solr 
x:testRepFactor1LeaderStartup_shard1_replica_n1 c:testRepFactor1LeaderStartup 
s:shard1 r:core_node2) [n:127.0.0.1:36257_solr c:testRepFactor1LeaderStartup 
s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1 ] 
o.a.s.c.QuerySenderListener QuerySenderListener done.
   [junit4]   2> 580933 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=1
   [junit4]   2> 580933 INFO  
(searcherExecutor-4109-thread-1-processing-n:127.0.0.1:36257_solr 
x:testRepFactor1LeaderStartup_shard1_replica_n1 c:testRepFactor1LeaderStartup 
s:shard1 r:core_node2) [n:127.0.0.1:36257_solr c:testRepFactor1LeaderStartup 
s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1 ] 
o.a.s.c.TestCloudSearcherWarming Sleeping for 1000 on newSearcher: 
Searcher@6c05fe21[testRepFactor1LeaderStartup_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.11.0):C1:[diagnostics={java.vendor=Oracle
 Corporation, os=Linux, java.version=1.8.0_291, java.vm.version=25.291-b10, 
lucene.version=8.11.0, os.arch=amd64, java.runtime.version=1.8.0_291-b10, 
source=flush, os.version=5.4.0-45-generic, 
timestamp=1635887913532}]:[attributes={Lucene87StoredFieldsFormat.mode=BEST_SPEED}]
 :id=a7pidtxmm1nrm0qeb38l4iznu)))}, currentSearcher: null belonging to (newest) 
core: testRepFactor1LeaderStartup_shard1_replica_n1, id: 
org.apache.solr.core.SolrCore@4cd0b2ae
   [junit4]   2> 580934 INFO  (watches-4059-thread-2) [     ] 
o.a.s.c.TestCloudSearcherWarming Collection state: 
DocCollection(testRepFactor1LeaderStartup//collections/testRepFactor1LeaderStartup/state.json/5
 )={
   [junit4]   2>   "pullReplicas":"0",
   [junit4]   2>   "replicationFactor":"1",
   [junit4]   2>   "shards":{"shard1":{
   [junit4]   2>       "range":"80000000-7fffffff",
   [junit4]   2>       "state":"active",
   [junit4]   2>       "replicas":{"core_node2":{
   [junit4]   2>           
"core":"testRepFactor1LeaderStartup_shard1_replica_n1",
   [junit4]   2>           "node_name":"127.0.0.1:36257_solr",
   [junit4]   2>           "base_url":"http://127.0.0.1:36257/solr";,
   [junit4]   2>           "state":"down",
   [junit4]   2>           "type":"NRT",
   [junit4]   2>           "force_set_state":"false",
   [junit4]   2>           "leader":"true"}}}},
   [junit4]   2>   "router":{"name":"compositeId"},
   [junit4]   2>   "maxShardsPerNode":"1",
   [junit4]   2>   "autoAddReplicas":"false",
   [junit4]   2>   "nrtReplicas":"1",
   [junit4]   2>   "tlogReplicas":"0"}
   [junit4]   2> 580934 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.TestCloudSearcherWarming Collection state: 
DocCollection(testRepFactor1LeaderStartup//collections/testRepFactor1LeaderStartup/state.json/5
 )={
   [junit4]   2>   "pullReplicas":"0",
   [junit4]   2>   "replicationFactor":"1",
   [junit4]   2>   "shards":{"shard1":{
   [junit4]   2>       "range":"80000000-7fffffff",
   [junit4]   2>       "state":"active",
   [junit4]   2>       "replicas":{"core_node2":{
   [junit4]   2>           
"core":"testRepFactor1LeaderStartup_shard1_replica_n1",
   [junit4]   2>           "node_name":"127.0.0.1:36257_solr",
   [junit4]   2>           "base_url":"http://127.0.0.1:36257/solr";,
   [junit4]   2>           "state":"down",
   [junit4]   2>           "type":"NRT",
   [junit4]   2>           "force_set_state":"false",
   [junit4]   2>           "leader":"true"}}}},
   [junit4]   2>   "router":{"name":"compositeId"},
   [junit4]   2>   "maxShardsPerNode":"1",
   [junit4]   2>   "autoAddReplicas":"false",
   [junit4]   2>   "nrtReplicas":"1",
   [junit4]   2>   "tlogReplicas":"0"}
   [junit4]   2> 580934 INFO  (watches-4059-thread-2) [     ] 
o.a.s.c.TestCloudSearcherWarming Collection state: 
DocCollection(testRepFactor1LeaderStartup//collections/testRepFactor1LeaderStartup/state.json/5
 )={
   [junit4]   2>   "pullReplicas":"0",
   [junit4]   2>   "replicationFactor":"1",
   [junit4]   2>   "shards":{"shard1":{
   [junit4]   2>       "range":"80000000-7fffffff",
   [junit4]   2>       "state":"active",
   [junit4]   2>       "replicas":{"core_node2":{
   [junit4]   2>           
"core":"testRepFactor1LeaderStartup_shard1_replica_n1",
   [junit4]   2>           "node_name":"127.0.0.1:36257_solr",
   [junit4]   2>           "base_url":"http://127.0.0.1:36257/solr";,
   [junit4]   2>           "state":"down",
   [junit4]   2>           "type":"NRT",
   [junit4]   2>           "force_set_state":"false",
   [junit4]   2>           "leader":"true"}}}},
   [junit4]   2>   "router":{"name":"compositeId"},
   [junit4]   2>   "maxShardsPerNode":"1",
   [junit4]   2>   "autoAddReplicas":"false",
   [junit4]   2>   "nrtReplicas":"1",
   [junit4]   2>   "tlogReplicas":"0"}
   [junit4]   2> 580934 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.TestCloudSearcherWarming Collection state: 
DocCollection(testRepFactor1LeaderStartup//collections/testRepFactor1LeaderStartup/state.json/5
 )={
   [junit4]   2>   "pullReplicas":"0",
   [junit4]   2>   "replicationFactor":"1",
   [junit4]   2>   "shards":{"shard1":{
   [junit4]   2>       "range":"80000000-7fffffff",
   [junit4]   2>       "state":"active",
   [junit4]   2>       "replicas":{"core_node2":{
   [junit4]   2>           
"core":"testRepFactor1LeaderStartup_shard1_replica_n1",
   [junit4]   2>           "node_name":"127.0.0.1:36257_solr",
   [junit4]   2>           "base_url":"http://127.0.0.1:36257/solr";,
   [junit4]   2>           "state":"down",
   [junit4]   2>           "type":"NRT",
   [junit4]   2>           "force_set_state":"false",
   [junit4]   2>           "leader":"true"}}}},
   [junit4]   2>   "router":{"name":"compositeId"},
   [junit4]   2>   "maxShardsPerNode":"1",
   [junit4]   2>   "autoAddReplicas":"false",
   [junit4]   2>   "nrtReplicas":"1",
   [junit4]   2>   "tlogReplicas":"0"}
   [junit4]   2> 580934 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.TestCloudSearcherWarming Collection state: 
DocCollection(testRepFactor1LeaderStartup//collections/testRepFactor1LeaderStartup/state.json/5
 )={
   [junit4]   2>   "pullReplicas":"0",
   [junit4]   2>   "replicationFactor":"1",
   [junit4]   2>   "shards":{"shard1":{
   [junit4]   2>       "range":"80000000-7fffffff",
   [junit4]   2>       "state":"active",
   [junit4]   2>       "replicas":{"core_node2":{
   [junit4]   2>           
"core":"testRepFactor1LeaderStartup_shard1_replica_n1",
   [junit4]   2>           "node_name":"127.0.0.1:36257_solr",
   [junit4]   2>           "base_url":"http://127.0.0.1:36257/solr";,
   [junit4]   2>           "state":"down",
   [junit4]   2>           "type":"NRT",
   [junit4]   2>           "force_set_state":"false",
   [junit4]   2>           "leader":"true"}}}},
   [junit4]   2>   "router":{"name":"compositeId"},
   [junit4]   2>   "maxShardsPerNode":"1",
   [junit4]   2>   "autoAddReplicas":"false",
   [junit4]   2>   "nrtReplicas":"1",
   [junit4]   2>   "tlogReplicas":"0"}
   [junit4]   2> 580934 INFO  
(TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[35667417295C44F8])
 [     ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: 
testRepFactor1LeaderStartup
   [junit4]   2> 580937 INFO  
(coreZkRegister-4085-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created 
/collections/testRepFactor1LeaderStartup/leaders/shard1
   [junit4]   2> 580939 INFO  
(coreZkRegister-4085-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 580939 INFO  
(coreZkRegister-4085-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 580939 INFO  
(coreZkRegister-4085-thread-1-processing-n:127.0.0.1:36257_solr) 
[n:127.0.0.1:36257_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 
x:testRepFactor1LeaderStartup_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync r

[...truncated too long message...]

19-thread-2) [     ] o.a.s.c.Overseer Overseer 
(id=74478107868266505-127.0.0.1:39403_solr-n_0000000001) closing
   [junit4]   2> 599773 INFO  
(OverseerStateUpdate-74478107868266505-127.0.0.1:39403_solr-n_0000000001) 
[n:127.0.0.1:39403_solr     ] o.a.s.c.Overseer Overseer Loop exiting : 
127.0.0.1:39403_solr
   [junit4]   2> 599774 INFO  
(OverseerAutoScalingTriggerThread-74478107868266505-127.0.0.1:39403_solr-n_0000000001)
 [     ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we 
are closed, exiting.
   [junit4]   2> 599777 WARN  
(TEST-TestCloudSearcherWarming.testPeersyncFailureReplicationSuccess-seed#[35667417295C44F8]-SendThread(127.0.0.1:45067))
 [     ] o.a.z.ClientCnxn An exception was thrown while closing send thread for 
session 0x1089971cb080009.
   [junit4]   2>           => EndOfStreamException: Unable to read additional 
data from server sessionid 0x1089971cb080009, likely server has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
   [junit4]   2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable 
to read additional data from server sessionid 0x1089971cb080009, likely server 
has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) 
~[zookeeper-3.6.2.jar:3.6.2]
   [junit4]   2>        at 
org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350)
 ~[zookeeper-3.6.2.jar:3.6.2]
   [junit4]   2>        at 
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) 
[zookeeper-3.6.2.jar:3.6.2]
   [junit4]   2> 599776 INFO  (closeThreadPool-4219-thread-1) [     ] 
o.a.s.c.Overseer Overseer 
(id=74478107868266505-127.0.0.1:39403_solr-n_0000000001) closing
   [junit4]   2> 599878 INFO  (jetty-closer-4214-thread-1) [     ] 
o.a.s.c.Overseer Overseer 
(id=74478107868266505-127.0.0.1:39403_solr-n_0000000001) closing
   [junit4]   2> 599882 INFO  (jetty-closer-4214-thread-1) [     ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@4190c9cd{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:0}
   [junit4]   2> 599882 INFO  (jetty-closer-4214-thread-1) [     ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@528fb5df{/solr,null,STOPPED}
   [junit4]   2> 599882 INFO  (jetty-closer-4214-thread-1) [     ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 599883 INFO  
(TEST-TestCloudSearcherWarming.testPeersyncFailureReplicationSuccess-seed#[35667417295C44F8])
 [     ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
   [junit4]   2> 600086 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>        11      
/solr/collections/testPeersyncFailureReplicationSuccess/terms/shard1
   [junit4]   2>        7       
/solr/collections/testPeersyncFailureReplicationSuccess/state.json
   [junit4]   2>        4       /solr/clusterprops.json
   [junit4]   2>        4       /solr/aliases.json
   [junit4]   2>        3       
/solr/configs/testPeersyncFailureReplicationSuccess.AUTOCREATED
   [junit4]   2>        3       
/solr/configs/testPeersyncFailureReplicationSuccess.AUTOCREATED/managed-schema
   [junit4]   2>        2       /solr/packages.json
   [junit4]   2>        2       /solr/security.json
   [junit4]   2>        2       
/solr/collections/testPeersyncFailureReplicationSuccess/collectionprops.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        57      
/solr/collections/testPeersyncFailureReplicationSuccess/state.json
   [junit4]   2>        4       /solr/clusterstate.json
   [junit4]   2>        3       /solr/aliases.json
   [junit4]   2>        2       /solr/autoscaling.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        30      /solr/overseer/queue
   [junit4]   2>        18      /solr/overseer/collection-queue-work
   [junit4]   2>        11      
/solr/collections/testPeersyncFailureReplicationSuccess/state.json
   [junit4]   2>        10      /solr/live_nodes
   [junit4]   2>        9       /solr/collections
   [junit4]   2>        2       /solr/autoscaling/events/.scheduled_maintenance
   [junit4]   2>        2       /solr/autoscaling/events/.auto_add_replicas
   [junit4]   2>        2       /solr/overseer/queue-work
   [junit4]   2> 
   [junit4]   2> 600089 INFO  
(TEST-TestCloudSearcherWarming.testPeersyncFailureReplicationSuccess-seed#[35667417295C44F8])
 [     ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:45067
   [junit4]   2> 600089 INFO  
(TEST-TestCloudSearcherWarming.testPeersyncFailureReplicationSuccess-seed#[35667417295C44F8])
 [     ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:45067
   [junit4]   2> 600089 INFO  
(TEST-TestCloudSearcherWarming.testPeersyncFailureReplicationSuccess-seed#[35667417295C44F8])
 [     ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 45067
   [junit4]   2> 600089 INFO  
(TEST-TestCloudSearcherWarming.testPeersyncFailureReplicationSuccess-seed#[35667417295C44F8])
 [     ] o.a.s.SolrTestCaseJ4 ###Ending testPeersyncFailureReplicationSuccess
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestCloudSearcherWarming 
-Dtests.method=testPeersyncFailureReplicationSuccess 
-Dtests.seed=35667417295C44F8 -Dtests.multiplier=2 -Dtests.slow=true 
-Dtests.locale=de -Dtests.timezone=Europe/Lisbon -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1
   [junit4] ERROR   17.5s J0 | 
TestCloudSearcherWarming.testPeersyncFailureReplicationSuccess <<<
   [junit4]    > Throwable #1: org.apache.solr.common.SolrException: No 
registered leader was found after waiting for 1000ms , collection: 
testPeersyncFailureReplicationSuccess slice: shard1 saw 
state=DocCollection(testPeersyncFailureReplicationSuccess//collections/testPeersyncFailureReplicationSuccess/state.json/10
 )={
   [junit4]    >   "pullReplicas":"0",
   [junit4]    >   "replicationFactor":"1",
   [junit4]    >   "shards":{"shard1":{
   [junit4]    >       "range":"80000000-7fffffff",
   [junit4]    >       "state":"active",
   [junit4]    >       "replicas":{
   [junit4]    >         "core_node2":{
   [junit4]    >           
"core":"testPeersyncFailureReplicationSuccess_shard1_replica_n1",
   [junit4]    >           "node_name":"127.0.0.1:44531_solr",
   [junit4]    >           "base_url":"http://127.0.0.1:44531/solr";,
   [junit4]    >           "state":"down",
   [junit4]    >           "type":"NRT",
   [junit4]    >           "force_set_state":"false"},
   [junit4]    >         "core_node4":{
   [junit4]    >           
"core":"testPeersyncFailureReplicationSuccess_shard1_replica_n3",
   [junit4]    >           "node_name":"127.0.0.1:39403_solr",
   [junit4]    >           "base_url":"http://127.0.0.1:39403/solr";,
   [junit4]    >           "state":"active",
   [junit4]    >           "type":"NRT",
   [junit4]    >           "force_set_state":"false"}}}},
   [junit4]    >   "router":{"name":"compositeId"},
   [junit4]    >   "maxShardsPerNode":"2",
   [junit4]    >   "autoAddReplicas":"false",
   [junit4]    >   "nrtReplicas":"1",
   [junit4]    >   "tlogReplicas":"0"} with live_nodes=[127.0.0.1:39403_solr]
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([35667417295C44F8:E1371758F8E2739B]:0)
   [junit4]    >        at 
org.apache.solr.common.cloud.ZkStateReader.getLeaderRetry(ZkStateReader.java:993)
   [junit4]    >        at 
org.apache.solr.common.cloud.ZkStateReader.getLeaderRetry(ZkStateReader.java:968)
   [junit4]    >        at 
org.apache.solr.cloud.TestCloudSearcherWarming.testPeersyncFailureReplicationSuccess(TestCloudSearcherWarming.java:205)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 600095 INFO  
(SUITE-TestCloudSearcherWarming-seed#[35667417295C44F8]-worker) [     ] 
o.a.s.u.ErrorLogMuter Closing ErrorLogMuter-regex-187 after mutting 0 log 
messages
   [junit4]   2> 600095 INFO  
(SUITE-TestCloudSearcherWarming-seed#[35667417295C44F8]-worker) [     ] 
o.a.s.u.ErrorLogMuter Creating ErrorLogMuter-regex-188 for ERROR logs matching 
regex: ignore_exception
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_35667417295C44F8-001
   [junit4]   2> Nov 02, 2021 9:18:53 PM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene87): {}, 
docValues:{}, maxPointsInLeafNode=1123, maxMBSortInHeap=5.706848405945731, 
sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=de, 
timezone=Europe/Lisbon
   [junit4]   2> NOTE: Linux 5.4.0-45-generic amd64/Oracle Corporation 
1.8.0_291 (64-bit)/cpus=4,threads=1,free=136197648,total=519045120
   [junit4]   2> NOTE: All tests run in this JVM: [TestInitQParser, 
RuleEngineTest, BigEndianAscendingWordSerializerTest, 
TestDistributedStatsComponentCardinality, 
OverriddenZkACLAndCredentialsProvidersTest, TestPullReplica, 
TestNumericTokenStream, ScheduledTriggerTest, TestPostingsSolrHighlighter, 
TestChildDocTransformer, BasicAuthStandaloneTest, TestBinaryField, 
DocValuesTest, CloneFieldUpdateProcessorFactoryTest, 
TolerantUpdateProcessorTest, LeaderElectionContextKeyTest, PeerSyncTest, 
SimpleCollectionCreateDeleteTest, TestFieldTypeResource, 
TestReloadAndDeleteDocs, MetricsHistoryIntegrationTest, 
BaseCdcrDistributedZkTest, HdfsRestartWhileUpdatingTest, TestRecovery, 
TestSimLargeCluster, TestDynamicFieldResource, TestRestoreCore, 
XmlUpdateRequestHandlerTest, TestSolr4Spatial, 
DistributedSpellCheckComponentTest, AutoAddReplicasIntegrationTest, 
ClusterStateMockUtilTest, TestInitParams, GraphQueryTest, 
UpdateRequestProcessorFactoryTest, HdfsCollectionsAPIDistributedZkTest, 
AliasIntegrationTest, FileUtilsTest, TestOrdValues, ZkControllerTest, 
TestLegacyTerms, TestCloudJSONFacetSKG, ExitableDirectoryReaderTest, 
DeleteByIdWithRouterFieldTest, TestCloudSearcherWarming]
   [junit4] Completed [257/951 (1!)] on J0 in 23.03s, 2 tests, 1 error <<< 
FAILURES!

[...truncated 56616 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to