Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/25869/
Java: 64bit/jdk-12.0.2 -XX:-UseCompressedOops -XX:+UnlockExperimentalVMOptions 
-XX:+UseZGC

1 tests failed.
FAILED:  org.apache.solr.cloud.SystemCollectionCompatTest.testBackCompat

Error Message:
No live SolrServers available to handle this 
request:[http://127.0.0.1:33733/solr/.system]

Stack Trace:
org.apache.solr.client.solrj.SolrServerException: No live SolrServers available 
to handle this request:[http://127.0.0.1:33733/solr/.system]
        at 
__randomizedtesting.SeedInfo.seed([15A23661BBE3F8A2:655795C8DB2B51D4]:0)
        at 
org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:345)
        at 
org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1143)
        at 
org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:906)
        at 
org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:838)
        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207)
        at 
org.apache.solr.cloud.SystemCollectionCompatTest.setupSystemCollection(SystemCollectionCompatTest.java:118)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:567)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:976)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
        at java.base/java.lang.Thread.run(Thread.java:835)
Caused by: org.apache.solr.client.solrj.SolrServerException: Timeout occurred 
while waiting response from server at: http://127.0.0.1:33733/solr/.system
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:676)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:265)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
        at 
org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:368)
        at 
org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:296)
        ... 43 more
Caused by: java.net.SocketTimeoutException: Read timed out
        at java.base/java.net.SocketInputStream.socketRead0(Native Method)
        at 
java.base/java.net.SocketInputStream.socketRead(SocketInputStream.java:115)
        at java.base/java.net.SocketInputStream.read(SocketInputStream.java:168)
        at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
        at 
org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
        at 
org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
        at 
org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
        at 
org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
        at 
org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
        at 
org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
        at 
org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
        at 
org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:157)
        at 
org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
        at 
org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
        at 
org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
        at 
org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:186)
        at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
        at 
org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
        at 
org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
        at 
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
        at 
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:564)
        ... 47 more




Build Log:
[...truncated 13939 lines...]
   [junit4] Suite: org.apache.solr.cloud.SystemCollectionCompatTest
   [junit4]   2> 369142 INFO  
(SUITE-SystemCollectionCompatTest-seed#[15A23661BBE3F8A2]-worker) [     ] 
o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to 
test-framework derived value of 
'/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/server/solr/configsets/_default/conf'
   [junit4]   2> 369142 INFO  
(SUITE-SystemCollectionCompatTest-seed#[15A23661BBE3F8A2]-worker) [     ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 369142 INFO  
(SUITE-SystemCollectionCompatTest-seed#[15A23661BBE3F8A2]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Created dataDir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_15A23661BBE3F8A2-001/data-dir-28-001
   [junit4]   2> 369143 WARN  
(SUITE-SystemCollectionCompatTest-seed#[15A23661BBE3F8A2]-worker) [     ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=64 numCloses=64
   [junit4]   2> 369143 INFO  
(SUITE-SystemCollectionCompatTest-seed#[15A23661BBE3F8A2]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 369143 INFO  
(SUITE-SystemCollectionCompatTest-seed#[15A23661BBE3F8A2]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, 
clientAuth=0.0/0.0)
   [junit4]   2> 369143 INFO  
(SUITE-SystemCollectionCompatTest-seed#[15A23661BBE3F8A2]-worker) [     ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_15A23661BBE3F8A2-001/tempDir-001
   [junit4]   2> 369144 INFO  
(SUITE-SystemCollectionCompatTest-seed#[15A23661BBE3F8A2]-worker) [     ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 369144 INFO  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 369144 INFO  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 369244 INFO  
(SUITE-SystemCollectionCompatTest-seed#[15A23661BBE3F8A2]-worker) [     ] 
o.a.s.c.ZkTestServer start zk server on port:38745
   [junit4]   2> 369244 INFO  
(SUITE-SystemCollectionCompatTest-seed#[15A23661BBE3F8A2]-worker) [     ] 
o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:38745
   [junit4]   2> 369244 INFO  
(SUITE-SystemCollectionCompatTest-seed#[15A23661BBE3F8A2]-worker) [     ] 
o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:38745
   [junit4]   2> 369244 INFO  
(SUITE-SystemCollectionCompatTest-seed#[15A23661BBE3F8A2]-worker) [     ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1 38745
   [junit4]   2> 369246 INFO  
(SUITE-SystemCollectionCompatTest-seed#[15A23661BBE3F8A2]-worker) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 369248 INFO  (zkConnectionManagerCallback-1594-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 369248 INFO  
(SUITE-SystemCollectionCompatTest-seed#[15A23661BBE3F8A2]-worker) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 369250 INFO  
(SUITE-SystemCollectionCompatTest-seed#[15A23661BBE3F8A2]-worker) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 369254 INFO  (zkConnectionManagerCallback-1596-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 369254 INFO  
(SUITE-SystemCollectionCompatTest-seed#[15A23661BBE3F8A2]-worker) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 369256 INFO  
(SUITE-SystemCollectionCompatTest-seed#[15A23661BBE3F8A2]-worker) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 369257 INFO  (zkConnectionManagerCallback-1598-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 369257 INFO  
(SUITE-SystemCollectionCompatTest-seed#[15A23661BBE3F8A2]-worker) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 369365 INFO  (jetty-launcher-1599-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 369365 INFO  (jetty-launcher-1599-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 369366 INFO  (jetty-launcher-1599-thread-2) [     ] 
o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 
363d5f2df3a8a28de40604320230664b9c793c16; jvm 12.0.2+10
   [junit4]   2> 369367 INFO  (jetty-launcher-1599-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 369367 INFO  (jetty-launcher-1599-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 369367 INFO  (jetty-launcher-1599-thread-1) [     ] 
o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 
363d5f2df3a8a28de40604320230664b9c793c16; jvm 12.0.2+10
   [junit4]   2> 369377 INFO  (jetty-launcher-1599-thread-2) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 369377 INFO  (jetty-launcher-1599-thread-2) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 369377 INFO  (jetty-launcher-1599-thread-2) [     ] 
o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 369388 INFO  (jetty-launcher-1599-thread-1) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 369388 INFO  (jetty-launcher-1599-thread-1) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 369388 INFO  (jetty-launcher-1599-thread-1) [     ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 369388 INFO  (jetty-launcher-1599-thread-1) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@2792fce1{/solr,null,AVAILABLE}
   [junit4]   2> 369388 INFO  (jetty-launcher-1599-thread-2) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@638fb66{/solr,null,AVAILABLE}
   [junit4]   2> 369389 INFO  (jetty-launcher-1599-thread-1) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@1b90b616{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:39531}
   [junit4]   2> 369389 INFO  (jetty-launcher-1599-thread-1) [     ] 
o.e.j.s.Server Started @369678ms
   [junit4]   2> 369389 INFO  (jetty-launcher-1599-thread-2) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@50f752e4{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:33733}
   [junit4]   2> 369389 INFO  (jetty-launcher-1599-thread-2) [     ] 
o.e.j.s.Server Started @369678ms
   [junit4]   2> 369389 INFO  (jetty-launcher-1599-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=39531}
   [junit4]   2> 369389 INFO  (jetty-launcher-1599-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=33733}
   [junit4]   2> 369390 ERROR (jetty-launcher-1599-thread-1) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 369390 ERROR (jetty-launcher-1599-thread-2) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 369390 INFO  (jetty-launcher-1599-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 369390 INFO  (jetty-launcher-1599-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 369390 INFO  (jetty-launcher-1599-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
9.0.0
   [junit4]   2> 369390 INFO  (jetty-launcher-1599-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
9.0.0
   [junit4]   2> 369390 INFO  (jetty-launcher-1599-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 369390 INFO  (jetty-launcher-1599-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 369390 INFO  (jetty-launcher-1599-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr
   [junit4]   2> 369390 INFO  (jetty-launcher-1599-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr
   [junit4]   2> 369390 INFO  (jetty-launcher-1599-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-02-28T06:48:05.075278Z
   [junit4]   2> 369390 INFO  (jetty-launcher-1599-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-02-28T06:48:05.075279Z
   [junit4]   2> 369392 INFO  (jetty-launcher-1599-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 369392 INFO  (jetty-launcher-1599-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 369394 INFO  (zkConnectionManagerCallback-1602-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 369394 INFO  (zkConnectionManagerCallback-1603-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 369394 INFO  (jetty-launcher-1599-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 369394 INFO  (jetty-launcher-1599-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 369397 INFO  (jetty-launcher-1599-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 369397 INFO  (jetty-launcher-1599-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 369510 INFO  (jetty-launcher-1599-thread-1) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 369512 WARN  (jetty-launcher-1599-thread-1) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@7099ce97[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 369512 WARN  (jetty-launcher-1599-thread-1) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@7099ce97[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 369516 WARN  (jetty-launcher-1599-thread-1) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@689047dc[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 369516 WARN  (jetty-launcher-1599-thread-1) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@689047dc[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 369517 INFO  (jetty-launcher-1599-thread-1) [     ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38745/solr
   [junit4]   2> 369521 INFO  (jetty-launcher-1599-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 369523 INFO  (zkConnectionManagerCallback-1610-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 369523 INFO  (jetty-launcher-1599-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 369610 INFO  (jetty-launcher-1599-thread-2) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 369611 WARN  (jetty-launcher-1599-thread-2) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@66fc5f92[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 369611 WARN  (jetty-launcher-1599-thread-2) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@66fc5f92[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 369613 WARN  (jetty-launcher-1599-thread-2) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@25f0abe1[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 369613 WARN  (jetty-launcher-1599-thread-2) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@25f0abe1[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 369614 INFO  (jetty-launcher-1599-thread-2) [     ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38745/solr
   [junit4]   2> 369615 INFO  (jetty-launcher-1599-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 369617 INFO  (zkConnectionManagerCallback-1617-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 369617 INFO  (jetty-launcher-1599-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 369709 INFO  (jetty-launcher-1599-thread-1) 
[n:127.0.0.1:39531_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 369710 INFO  (zkConnectionManagerCallback-1619-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 369710 INFO  (jetty-launcher-1599-thread-1) 
[n:127.0.0.1:39531_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 369740 INFO  (jetty-launcher-1599-thread-1) 
[n:127.0.0.1:39531_solr     ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:39531_solr
   [junit4]   2> 369741 INFO  (jetty-launcher-1599-thread-1) 
[n:127.0.0.1:39531_solr     ] o.a.s.c.Overseer Overseer 
(id=72089961821896711-127.0.0.1:39531_solr-n_0000000000) starting
   [junit4]   2> 369743 INFO  
(OverseerStateUpdate-72089961821896711-127.0.0.1:39531_solr-n_0000000000) 
[n:127.0.0.1:39531_solr     ] o.a.s.c.Overseer Starting to work on the main 
queue : 127.0.0.1:39531_solr
   [junit4]   2> 369743 INFO  (jetty-launcher-1599-thread-1) 
[n:127.0.0.1:39531_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:39531_solr
   [junit4]   2> 369744 INFO  
(OverseerStateUpdate-72089961821896711-127.0.0.1:39531_solr-n_0000000000) 
[n:127.0.0.1:39531_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 369745 INFO  (jetty-launcher-1599-thread-1) 
[n:127.0.0.1:39531_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 369745 WARN  (jetty-launcher-1599-thread-1) 
[n:127.0.0.1:39531_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> 369758 INFO  (jetty-launcher-1599-thread-1) 
[n:127.0.0.1:39531_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 369775 INFO  (jetty-launcher-1599-thread-1) 
[n:127.0.0.1:39531_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_39531.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@14940231
   [junit4]   2> 369780 INFO  (jetty-launcher-1599-thread-1) 
[n:127.0.0.1:39531_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_39531.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@14940231
   [junit4]   2> 369780 INFO  (jetty-launcher-1599-thread-1) 
[n:127.0.0.1:39531_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_39531.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@14940231
   [junit4]   2> 369782 INFO  (jetty-launcher-1599-thread-1) 
[n:127.0.0.1:39531_solr     ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_15A23661BBE3F8A2-001/tempDir-001/node1/.
   [junit4]   2> 369809 INFO  (jetty-launcher-1599-thread-2) 
[n:127.0.0.1:33733_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 369811 INFO  (zkConnectionManagerCallback-1625-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 369811 INFO  (jetty-launcher-1599-thread-2) 
[n:127.0.0.1:33733_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 369813 INFO  (jetty-launcher-1599-thread-2) 
[n:127.0.0.1:33733_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 369815 INFO  (jetty-launcher-1599-thread-2) 
[n:127.0.0.1:33733_solr     ] o.a.s.c.ZkController Publish 
node=127.0.0.1:33733_solr as DOWN
   [junit4]   2> 369815 INFO  (jetty-launcher-1599-thread-2) 
[n:127.0.0.1:33733_solr     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 369815 INFO  (jetty-launcher-1599-thread-2) 
[n:127.0.0.1:33733_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:33733_solr
   [junit4]   2> 369816 INFO  (zkCallback-1618-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 369817 INFO  (jetty-launcher-1599-thread-2) 
[n:127.0.0.1:33733_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 369818 WARN  (jetty-launcher-1599-thread-2) 
[n:127.0.0.1:33733_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> 369820 INFO  (zkCallback-1624-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 369824 INFO  (jetty-launcher-1599-thread-2) 
[n:127.0.0.1:33733_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 369836 INFO  (jetty-launcher-1599-thread-2) 
[n:127.0.0.1:33733_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_33733.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@14940231
   [junit4]   2> 369842 INFO  (jetty-launcher-1599-thread-2) 
[n:127.0.0.1:33733_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_33733.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@14940231
   [junit4]   2> 369842 INFO  (jetty-launcher-1599-thread-2) 
[n:127.0.0.1:33733_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_33733.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@14940231
   [junit4]   2> 369843 INFO  (jetty-launcher-1599-thread-2) 
[n:127.0.0.1:33733_solr     ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_15A23661BBE3F8A2-001/tempDir-001/node2/.
   [junit4]   2> 369855 INFO  
(SUITE-SystemCollectionCompatTest-seed#[15A23661BBE3F8A2]-worker) [     ] 
o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
   [junit4]   2> 369856 INFO  
(SUITE-SystemCollectionCompatTest-seed#[15A23661BBE3F8A2]-worker) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 369861 INFO  (zkConnectionManagerCallback-1634-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 369861 INFO  
(SUITE-SystemCollectionCompatTest-seed#[15A23661BBE3F8A2]-worker) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 369862 INFO  
(SUITE-SystemCollectionCompatTest-seed#[15A23661BBE3F8A2]-worker) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 369862 INFO  
(SUITE-SystemCollectionCompatTest-seed#[15A23661BBE3F8A2]-worker) [     ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:38745/solr ready
   [junit4]   2> 369875 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[15A23661BBE3F8A2]) [     
] o.a.s.SolrTestCaseJ4 ###Starting testBackCompat
   [junit4]   2> 369878 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[15A23661BBE3F8A2]) [     
] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 369880 INFO  (zkConnectionManagerCallback-1639-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 369880 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[15A23661BBE3F8A2]) [     
] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 369880 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[15A23661BBE3F8A2]) [     
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 369881 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[15A23661BBE3F8A2]) [     
] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:38745/solr ready
   [junit4]   2> 369884 INFO  (qtp1580250962-5732) [n:127.0.0.1:33733_solr     
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with 
params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 369890 INFO  (qtp1580250962-5732) [n:127.0.0.1:33733_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=5
   [junit4]   2> 369891 INFO  (qtp1580250962-5732) [n:127.0.0.1:33733_solr     
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
maxShardsPerNode=2&name=.system&nrtReplicas=2&action=CREATE&numShards=1&createNodeSet=127.0.0.1:33733_solr&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 369894 INFO  
(OverseerCollectionConfigSetProcessor-72089961821896711-127.0.0.1:39531_solr-n_0000000000)
 [n:127.0.0.1:39531_solr     ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000000 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 369894 INFO  
(OverseerThreadFactory-2371-thread-2-processing-n:127.0.0.1:39531_solr) 
[n:127.0.0.1:39531_solr     ] o.a.s.c.a.c.CreateCollectionCmd Create collection 
.system
   [junit4]   2> 369998 WARN  
(OverseerThreadFactory-2371-thread-2-processing-n:127.0.0.1:39531_solr) 
[n:127.0.0.1:39531_solr     ] o.a.s.c.a.c.CreateCollectionCmd Specified number 
of replicas of 2 on collection .system is higher than the number of Solr 
instances currently live or live and part of your createNodeSet(1). It's 
unusual to run two replica of the same slice on the same Solr-instance.
   [junit4]   2> 370000 INFO  
(OverseerStateUpdate-72089961821896711-127.0.0.1:39531_solr-n_0000000000) 
[n:127.0.0.1:39531_solr     ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":".system",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":".system_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:33733/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:33733_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 370001 INFO  
(OverseerStateUpdate-72089961821896711-127.0.0.1:39531_solr-n_0000000000) 
[n:127.0.0.1:39531_solr     ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":".system",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":".system_shard1_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:33733/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:33733_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 370205 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr    
x:.system_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node3&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n1&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 370205 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr    
x:.system_shard1_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node4&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n2&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 370213 WARN  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.c.SolrConfig You should not use LATEST as luceneMatchVersion property: if 
you use this setting, and then Solr upgrades to a newer release of Lucene, 
sizable changes may happen. If precise back compatibility is important then you 
should instead explicitly specify an actual Lucene version.
   [junit4]   2> 370213 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 370213 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 370220 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but 
managed schema resource managed-schema not found - loading non-managed schema 
schema.xml instead
   [junit4]   2> 370220 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but 
managed schema resource managed-schema not found - loading non-managed schema 
schema.xml instead
   [junit4]   2> 370221 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.IndexSchema [.system_shard1_replica_n2] Schema name=_system collection 
or core
   [junit4]   2> 370221 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.IndexSchema [.system_shard1_replica_n1] Schema name=_system collection 
or core
   [junit4]   2> 370224 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid 
field id
   [junit4]   2> 370224 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid 
field id
   [junit4]   2> 370226 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.ManagedIndexSchema Created and persisted managed schema znode at 
/configs/.system/managed-schema
   [junit4]   2> 370226 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ManagedIndexSchema Managed schema znode at 
/configs/.system/managed-schema already exists - no need to create it
   [junit4]   2> 370227 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.c.CoreContainer Creating SolrCore '.system_shard1_replica_n1' using 
configuration from configset .system, trusted=true
   [junit4]   2> 370228 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_33733.solr.core..system.shard1.replica_n1' (registry 
'solr.core..system.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@14940231
   [junit4]   2> 370228 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore 
[[.system_shard1_replica_n1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_15A23661BBE3F8A2-001/tempDir-001/node2/.system_shard1_replica_n1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_15A23661BBE3F8A2-001/tempDir-001/node2/./.system_shard1_replica_n1/data/]
   [junit4]   2> 370228 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in 
ZooKeeper, renamed the non-managed schema /configs/.system/schema.xml to 
/configs/.system/schema.xml.bak
   [junit4]   2> 370228 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.c.CoreContainer Creating SolrCore '.system_shard1_replica_n2' using 
configuration from configset .system, trusted=true
   [junit4]   2> 370229 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_33733.solr.core..system.shard1.replica_n2' (registry 
'solr.core..system.shard1.replica_n2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@14940231
   [junit4]   2> 370229 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.SolrCore 
[[.system_shard1_replica_n2] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_15A23661BBE3F8A2-001/tempDir-001/node2/.system_shard1_replica_n2],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_15A23661BBE3F8A2-001/tempDir-001/node2/./.system_shard1_replica_n2/data/]
   [junit4]   2> 370262 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 370262 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 370263 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 370263 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 370266 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.CommitTracker Hard AutoCommit: if 1 uncommitted docs; 
   [junit4]   2> 370266 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.CommitTracker Hard AutoCommit: if 1 uncommitted docs; 
   [junit4]   2> 370266 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 370266 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 370268 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@5a78f000[.system_shard1_replica_n1] 
main]
   [junit4]   2> 370268 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@639abf14[.system_shard1_replica_n2] 
main]
   [junit4]   2> 370269 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/.system
   [junit4]   2> 370269 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/.system
   [junit4]   2> 370269 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/.system
   [junit4]   2> 370269 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/.system
   [junit4]   2> 370271 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at 
/configs/.system/managed-schema
   [junit4]   2> 370271 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at 
/configs/.system/managed-schema
   [junit4]   2> 370272 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 370272 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ZkIndexSchemaReader Retrieved schema version 0 from ZooKeeper
   [junit4]   2> 370272 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 370272 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1659762099834847232
   [junit4]   2> 370273 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.IndexSchema [.system_shard1_replica_n2] Schema name=_system collection 
or core
   [junit4]   2> 370274 INFO  
(searcherExecutor-2380-thread-1-processing-n:127.0.0.1:33733_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:33733_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [.system_shard1_replica_n1] 
Registered new searcher Searcher@5a78f000[.system_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 370275 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/.system/terms/shard1 to Terms{values={core_node3=0}, version=0}
   [junit4]   2> 370276 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created 
/collections/.system/leaders/shard1
   [junit4]   2> 370277 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid 
field id
   [junit4]   2> 370278 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 5 ms
   [junit4]   2> 370278 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 370278 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1659762099841138688
   [junit4]   2> 370283 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for 
shard shard1: total=2 found=1 timeoutin=9995ms
   [junit4]   2> 370284 INFO  
(searcherExecutor-2381-thread-1-processing-n:127.0.0.1:33733_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:33733_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.c.SolrCore [.system_shard1_replica_n2] 
Registered new searcher Searcher@639abf14[.system_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 370284 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/.system/terms/shard1 to Terms{values={core_node3=0, core_node4=0}, 
version=1}
   [junit4]   2> 370284 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created 
/collections/.system/leaders/shard1
   [junit4]   2> 370784 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 370784 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 370784 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:33733/solr/.system_shard1_replica_n1/
   [junit4]   2> 370785 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.PeerSync 
PeerSync: core=.system_shard1_replica_n1 url=http://127.0.0.1:33733/solr START 
replicas=[http://127.0.0.1:33733/solr/.system_shard1_replica_n2/] nUpdates=100
   [junit4]   2> 370785 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.PeerSync 
PeerSync: core=.system_shard1_replica_n1 url=http://127.0.0.1:33733/solr DONE.  
We have no versions.  sync failed.
   [junit4]   2> 370789 INFO  (qtp1580250962-5749) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 370789 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the 
next candidate
   [junit4]   2> 370789 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we 
can't sync in that case - we were active before, so become leader anyway
   [junit4]   2> 370789 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node 
/collections/.system/leaders/shard1/leader after winning as 
/collections/.system/leader_elect/shard1/election/72089961821896712-core_node3-n_0000000000
   [junit4]   2> 370791 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:33733/solr/.system_shard1_replica_n1/ shard1
   [junit4]   2> 370892 INFO  (zkCallback-1624-thread-2) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] 
for collection [.system] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 370894 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 370896 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n1&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=690
   [junit4]   2> 370996 INFO  (zkCallback-1624-thread-2) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] 
for collection [.system] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 370996 INFO  (zkCallback-1624-thread-1) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] 
for collection [.system] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 371286 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n2&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=1081
   [junit4]   2> 371288 INFO  (qtp1580250962-5732) [n:127.0.0.1:33733_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> 371387 INFO  (zkCallback-1624-thread-1) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] 
for collection [.system] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 371387 INFO  (zkCallback-1624-thread-2) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] 
for collection [.system] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 371388 INFO  (qtp1580250962-5732) [n:127.0.0.1:33733_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={maxShardsPerNode=2&name=.system&nrtReplicas=2&action=CREATE&numShards=1&createNodeSet=127.0.0.1:33733_solr&wt=javabin&version=2}
 status=0 QTime=1496
   [junit4]   2> 371388 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[15A23661BBE3F8A2]) [     
] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: .system
   [junit4]   2> 371395 INFO  (qtp1580250962-5749) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/.system/terms/shard1 to Terms{values={core_node3=1, core_node4=1}, 
version=2}
   [junit4]   2> 371398 INFO  (qtp1580250962-5732) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:33733/solr/.system_shard1_replica_n1/&wt=javabin&version=2}{add=[1708a8d64a2Td08i7k8qpo8zqpjjxuj5qxmvt
 (1659762101008203776)]} 0 1
   [junit4]   2> 371398 INFO  (qtp1580250962-5749) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n1]  webapp=/solr 
path=/update 
params={wt=javabin&version=2}{add=[1708a8d64a2Td08i7k8qpo8zqpjjxuj5qxmvt 
(1659762101008203776)]} 0 8
   [junit4]   2> 371400 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1659762101017640960,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 371400 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@66f3df7a 
commitCommandVersion:1659762101017640960
   [junit4]   2> 371401 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1659762101018689536,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 371401 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@7952103f 
commitCommandVersion:1659762101018689536
   [junit4]   2> 371406 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@4da4b0e1[.system_shard1_replica_n1] 
main]
   [junit4]   2> 371406 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@38290b72[.system_shard1_replica_n2] 
main]
   [junit4]   2> 371407 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 371407 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 371408 INFO  
(searcherExecutor-2381-thread-1-processing-n:127.0.0.1:33733_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:33733_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.c.SolrCore [.system_shard1_replica_n2] 
Registered new searcher Searcher@38290b72[.system_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={timestamp=1582872487088,
 java.vendor=AdoptOpenJDK, os=Linux, os.version=5.3.0-40-generic, 
java.runtime.version=12.0.2+10, os.arch=amd64, source=flush, 
lucene.version=9.0.0, java.vm.version=12.0.2+10, 
java.version=12.0.2}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 371408 INFO  
(searcherExecutor-2380-thread-1-processing-n:127.0.0.1:33733_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:33733_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [.system_shard1_replica_n1] 
Registered new searcher Searcher@4da4b0e1[.system_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={timestamp=1582872487088,
 java.vendor=AdoptOpenJDK, os=Linux, os.version=5.3.0-40-generic, 
java.runtime.version=12.0.2+10, os.arch=amd64, source=flush, 
lucene.version=9.0.0, java.vm.version=12.0.2+10, 
java.version=12.0.2}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 371408 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:33733/solr/.system_shard1_replica_n1/&commit_end_point=replicas&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 7
   [junit4]   2> 371409 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n1]  webapp=/solr 
path=/update 
params={_stateVer_=.system:5&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 9
   [junit4]   2> 371410 INFO  (qtp1580250962-5732) [n:127.0.0.1:33733_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={action=STATUS&indexInfo=true&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 371411 INFO  (qtp1580250962-5749) [n:127.0.0.1:33733_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={action=STATUS&indexInfo=true&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 371413 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema 
params={wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 371417 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.SchemaManager managed schema loaded . version : 1 
   [junit4]   2> 371419 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.IndexSchema [.system_shard1_replica_n1] Schema name=_system collection 
or core
   [junit4]   2> 371423 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid 
field id
   [junit4]   2> 371424 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.c.ZkController Persisted config data to node 
/configs/.system/schema.xml.bak 
   [junit4]   2> 371425 INFO  (Thread-1443) [     ] o.a.s.c.SolrCore config 
update listener called for core .system_shard1_replica_n2
   [junit4]   2> 371425 INFO  (Thread-1443) [     ] o.a.s.c.SolrCore config 
update listener called for core .system_shard1_replica_n1
   [junit4]   2> 371426 INFO  (Thread-1443) [     ] o.a.s.c.SolrCore core 
reload .system_shard1_replica_n1
   [junit4]   2> 371431 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 371439 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.IndexSchema [.system_shard1_replica_n1] Schema name=_system collection 
or core
   [junit4]   2> 371442 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid 
field id
   [junit4]   2> 371443 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.c.CoreContainer Reloading SolrCore '.system_shard1_replica_n1' using 
configuration from configset .system
   [junit4]   2> 371477 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_33733.solr.core..system.shard1.replica_n1' (registry 
'solr.core..system.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@14940231
   [junit4]   2> 371477 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore 
[[.system_shard1_replica_n1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_15A23661BBE3F8A2-001/tempDir-001/node2/.system_shard1_replica_n1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_15A23661BBE3F8A2-001/tempDir-001/node2/./.system_shard1_replica_n1/data/]
   [junit4]   2> 371514 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.CommitTracker Hard AutoCommit: if 1 uncommitted docs; 
   [junit4]   2> 371514 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 371516 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@5f0bc376[.system_shard1_replica_n1] 
main]
   [junit4]   2> 371517 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/.system
   [junit4]   2> 371517 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/.system
   [junit4]   2> 371517 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at 
/configs/.system/managed-schema
   [junit4]   2> 371517 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 371518 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 371521 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 371521 INFO  
(searcherExecutor-2390-thread-1-processing-n:127.0.0.1:33733_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:33733_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [.system_shard1_replica_n1] 
Registered new searcher Searcher@5f0bc376[.system_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={timestamp=1582872487088,
 java.vendor=AdoptOpenJDK, os=Linux, os.version=5.3.0-40-generic, 
java.runtime.version=12.0.2+10, os.arch=amd64, source=flush, 
lucene.version=9.0.0, java.vm.version=12.0.2+10, 
java.version=12.0.2}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 371522 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@cfbc89d[.system_shard1_replica_n1] 
main]
   [junit4]   2> 371524 INFO  
(searcherExecutor-2390-thread-1-processing-n:127.0.0.1:33733_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:33733_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [.system_shard1_replica_n1] 
Registered new searcher Searcher@cfbc89d[.system_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={timestamp=1582872487088,
 os=Linux, java.vendor=AdoptOpenJDK, os.version=5.3.0-40-generic, 
java.runtime.version=12.0.2+10, os.arch=amd64, source=flush, 
lucene.version=9.0.0, java.vm.version=12.0.2+10, 
java.version=12.0.2}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 371525 INFO  (qtp1580250962-5740) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.ManagedIndexSchema Waiting up to 599 secs for 1 replicas to apply 
schema update version 2 for collection .system
   [junit4]   2> 371530 INFO  (qtp1580250962-5749) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 371531 INFO  (qtp1580250962-5749) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 371531 INFO  (qtp1580250962-5749) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=2
   [junit4]   2> 371533 ERROR 
(managedSchemaExecutor-2394-thread-1-processing-n:127.0.0.1:33733_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:33733_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:33733/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 371533 INFO  (Thread-1443) [     ] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 9.0.0
   [junit4]   2> 371540 INFO  (Thread-1443) [     ] o.a.s.s.IndexSchema 
[.system_shard1_replica_n1] Schema name=_system collection or core
   [junit4]   2> 371543 INFO  (Thread-1443) [     ] o.a.s.s.IndexSchema Loaded 
schema _system collection or core/1.1 with uniqueid field id
   [junit4]   2> 371543 INFO  (Thread-1443) [     ] o.a.s.c.CoreContainer 
Reloading SolrCore '.system_shard1_replica_n1' using configuration from 
configset .system
   [junit4]   2> 371552 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 371553 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 371553 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 371553 ERROR 
(managedSchemaExecutor-2394-thread-1-processing-n:127.0.0.1:33733_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:33733_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:33733/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 371560 INFO  (qtp1580250962-5732) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 371561 INFO  (qtp1580250962-5732) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 371561 INFO  (qtp1580250962-5732) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 371561 ERROR 
(managedSchemaExecutor-2394-thread-1-processing-n:127.0.0.1:33733_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:33733_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:33733/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 371572 INFO  (Thread-1443) [n:127.0.0.1:33733_solr c:.system 
s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter 
JMX monitoring for 'solr_33733.solr.core..system.shard1.replica_n1' (registry 
'solr.core..system.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@14940231
   [junit4]   2> 371572 INFO  (Thread-1443) [n:127.0.0.1:33733_solr c:.system 
s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore 
[[.system_shard1_replica_n1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_15A23661BBE3F8A2-001/tempDir-001/node2/.system_shard1_replica_n1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_15A23661BBE3F8A2-001/tempDir-001/node2/./.system_shard1_replica_n1/data/]
   [junit4]   2> 371572 INFO  (qtp1580250962-5749) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 371573 INFO  (qtp1580250962-5749) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 371573 INFO  (qtp1580250962-5749) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 371573 ERROR 
(managedSchemaExecutor-2394-thread-1-processing-n:127.0.0.1:33733_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:33733_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:33733/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 371575 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 371575 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 371575 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 371575 ERROR 
(managedSchemaExecutor-2394-thread-1-processing-n:127.0.0.1:33733_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:33733_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:33733/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 371576 INFO  (qtp1580250962-5732) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 371576 INFO  (qtp1580250962-5732) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 371576 INFO  (qtp1580250962-5732) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 371576 ERROR 
(managedSchemaExecutor-2394-thread-1-processing-n:127.0.0.1:33733_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:33733_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:33733/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 371577 INFO  (qtp1580250962-5749) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 371577 INFO  (qtp1580250962-5749) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 371577 INFO  (qtp1580250962-5749) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 371577 ERROR 
(managedSchemaExecutor-2394-thread-1-processing-n:127.0.0.1:33733_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:33733_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:33733/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 371577 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 371578 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 371578 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 371578 ERROR 
(managedSchemaExecutor-2394-thread-1-processing-n:127.0.0.1:33733_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:33733_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:33733/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 371578 INFO  (qtp1580250962-5732) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 371578 INFO  (qtp1580250962-5732) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 371578 INFO  (qtp1580250962-5732) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 371578 ERROR 
(managedSchemaExecutor-2394-thread-1-processing-n:127.0.0.1:33733_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:33733_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:33733/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 371580 INFO  (qtp1580250962-5749) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 371580 INFO  (qtp1580250962-5749) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 371580 INFO  (qtp1580250962-5749) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 371580 ERROR 
(managedSchemaExecutor-2394-thread-1-processing-n:127.0.0.1:33733_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:33733_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:33733/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 371581 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 371581 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 371581 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 371581 ERROR 
(managedSchemaExecutor-2394-thread-1-processing-n:127.0.0.1:33733_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:33733_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:33733/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 371581 INFO  (qtp1580250962-5732) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 371582 INFO  (qtp1580250962-5732) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 371582 INFO  (qtp1580250962-5732) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 371582 ERROR 
(managedSchemaExecutor-2394-thread-1-processing-n:127.0.0.1:33733_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:33733_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:33733/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 371582 INFO  (qtp1580250962-5749) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 371582 INFO  (qtp1580250962-5749) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 371582 INFO  (qtp1580250962-5749) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 371582 ERROR 
(managedSchemaExecutor-2394-thread-1-processing-n:127.0.0.1:33733_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:33733_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:33733/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 371583 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 371583 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 371583 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 371583 ERROR 
(managedSchemaExecutor-2394-thread-1-processing-n:127.0.0.1:33733_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:33733_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:33733/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 371583 INFO  (qtp1580250962-5732) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 371583 INFO  (qtp1580250962-5732) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 371583 INFO  (qtp1580250962-5732) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 371583 ERROR 
(managedSchemaExecutor-2394-thread-1-processing-n:127.0.0.1:33733_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:33733_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:33733/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 371584 INFO  (qtp1580250962-5749) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 371584 INFO  (qtp1580250962-5749) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 371584 INFO  (qtp1580250962-5749) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 371584 ERROR 
(managedSchemaExecutor-2394-thread-1-processing-n:127.0.0.1:33733_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:33733_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:33733/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 371584 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 371584 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 371584 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 371585 ERROR 
(managedSchemaExecutor-2394-thread-1-processing-n:127.0.0.1:33733_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:33733_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:33733/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 371588 INFO  (qtp1580250962-5732) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 371588 INFO  (qtp1580250962-5732) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 371588 INFO  (qtp1580250962-5732) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 371588 ERROR 
(managedSchemaExecutor-2394-thread-1-processing-n:127.0.0.1:33733_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:33733_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:33733/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 371589 INFO  (qtp1580250962-5749) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 371589 INFO  (qtp1580250962-5749) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 371589 INFO  (qtp1580250962-5749) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 371589 ERROR 
(managedSchemaExecutor-2394-thread-1-processing-n:127.0.0.1:33733_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:33733_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:33733/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 371590 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 371590 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 371590 INFO  (qtp1580250962-5741) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 371590 ERROR 
(managedSchemaExecutor-2394-thread-1-processing-n:127.0.0.1:33733_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:33733_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:33733/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 371590 INFO  (qtp1580250962-5732) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 371591 INFO  (qtp1580250962-5732) [n:127.0.0.1:33733_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 371591 INFO  (qtp1580250962-5732) [n:127.0.0.1:33733_solr 

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

1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:40951_solr c:.system s:shard1 
r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Failed to 
get /schema/zkversion from 
http://127.0.0.1:40951/solr/.system_shard1_replica_n3/ due to: 
java.util.concurrent.ExecutionException: 
org.apache.solr.client.solrj.SolrServerException: Server refused connection at: 
http://127.0.0.1:40951/solr/.system_shard1_replica_n3
   [junit4]   2> 142701 WARN  
(managedSchemaExecutor-37-thread-1-processing-n:127.0.0.1:40951_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) 
[n:127.0.0.1:40951_solr c:.system s:shard1 r:core_node2 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Failed to get 
/schema/zkversion from http://127.0.0.1:40951/solr/.system_shard1_replica_n3/ 
due to: java.util.concurrent.ExecutionException: 
org.apache.solr.client.solrj.SolrServerException: Server refused connection at: 
http://127.0.0.1:40951/solr/.system_shard1_replica_n3
   [junit4]   2> 143725 WARN  
(managedSchemaExecutor-37-thread-1-processing-n:127.0.0.1:40951_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) 
[n:127.0.0.1:40951_solr c:.system s:shard1 r:core_node2 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Failed to get 
/schema/zkversion from http://127.0.0.1:40951/solr/.system_shard1_replica_n3/ 
due to: java.util.concurrent.ExecutionException: 
org.apache.solr.client.solrj.SolrServerException: Server refused connection at: 
http://127.0.0.1:40951/solr/.system_shard1_replica_n3
   [junit4]   2> 144749 WARN  
(managedSchemaExecutor-37-thread-1-processing-n:127.0.0.1:40951_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) 
[n:127.0.0.1:40951_solr c:.system s:shard1 r:core_node2 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Failed to get 
/schema/zkversion from http://127.0.0.1:40951/solr/.system_shard1_replica_n3/ 
due to: java.util.concurrent.ExecutionException: 
org.apache.solr.client.solrj.SolrServerException: Server refused connection at: 
http://127.0.0.1:40951/solr/.system_shard1_replica_n3
   [junit4]   2> 144901 INFO  
(httpUriRequest-419680-thread-1-processing-x:.system_shard1_replica_n1 
r:core_node2 n:127.0.0.1:40951_solr 
http:////127.0.0.1:40951//solr//.system_shard1_replica_n3 c:.system s:shard1) 
[n:127.0.0.1:40951_solr c:.system s:shard1 r:core_node2 
x:.system_shard1_replica_n1 ] o.a.h.i.e.RetryExec I/O exception 
(java.net.SocketException) caught when processing request to 
{}->http://127.0.0.1:40951: Socket closed
   [junit4]   2> 144901 INFO  
(httpUriRequest-419680-thread-1-processing-x:.system_shard1_replica_n1 
r:core_node2 n:127.0.0.1:40951_solr 
http:////127.0.0.1:40951//solr//.system_shard1_replica_n3 c:.system s:shard1) 
[n:127.0.0.1:40951_solr c:.system s:shard1 r:core_node2 
x:.system_shard1_replica_n1 ] o.a.h.i.e.RetryExec Retrying request to 
{}->http://127.0.0.1:40951
   [junit4]   2> 144902 WARN  (qtp797520821-167) [n:127.0.0.1:40951_solr 
c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] 
o.a.s.s.ManagedIndexSchema Core core_node2 was interrupted waiting for schema 
version 2 to propagate to 1 replicas for collection .system
   [junit4]   2> 144902 INFO  (qtp797520821-167) [n:127.0.0.1:40951_solr 
c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] 
o.a.s.s.ManagedIndexSchema Took 134981.0ms for 1 replicas to apply schema 
update version 2 for collection .system
   [junit4]   2> 144902 INFO  (qtp797520821-167) [n:127.0.0.1:40951_solr 
c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema 
params={wt=javabin&version=2} status=0 QTime=135871
   [junit4]   2> 144902 INFO  (qtp797520821-167) [n:127.0.0.1:40951_solr 
c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore 
[.system_shard1_replica_n1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@6d31b11a
   [junit4]   2> 144902 WARN  (qtp797520821-167) [n:127.0.0.1:40951_solr 
c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] 
o.a.s.m.SolrMetricManager Interrupted while trying to obtain lock to modify 
reporters registry: solr.core..system.shard1.replica_n1
   [junit4]   2> 144902 INFO  (qtp797520821-167) [n:127.0.0.1:40951_solr 
c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.collection..system.shard1.leader, tag=SolrCore@6d31b11a
   [junit4]   2> 144903 INFO  (qtp797520821-167) [n:127.0.0.1:40951_solr 
c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] 
o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() .
   [junit4]   2> 144903 INFO  (qtp797520821-167) [n:127.0.0.1:40951_solr 
c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@5177a159 commitCommandVersion:0
   [junit4]   2> 144906 INFO  
(SUITE-SystemCollectionCompatTest-seed#[15A23661BBE3F8A2]-worker) [     ] 
o.a.s.c.ZkTestServer Shutting down ZkTestServer.
   [junit4]   2> 145110 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>        7       /solr/clusterprops.json
   [junit4]   2>        7       /solr/aliases.json
   [junit4]   2>        7       /solr/collections/.system/terms/shard1
   [junit4]   2>        4       /solr/configs/.system/managed-schema
   [junit4]   2>        2       /solr/configs/.system
   [junit4]   2>        2       /solr/packages.json
   [junit4]   2>        2       /solr/security.json
   [junit4]   2>        2       /solr/collections/.system/collectionprops.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        15      /solr/collections/.system/state.json
   [junit4]   2>        7       /solr/clusterstate.json
   [junit4]   2>        6       /solr/aliases.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        24      /solr/overseer/queue
   [junit4]   2>        18      /solr/collections
   [junit4]   2>        12      /solr/live_nodes
   [junit4]   2>        11      /solr/overseer/collection-queue-work
   [junit4]   2> 
   [junit4]   2> 145112 INFO  
(SUITE-SystemCollectionCompatTest-seed#[15A23661BBE3F8A2]-worker) [     ] 
o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:45481
   [junit4]   2> 145112 INFO  
(SUITE-SystemCollectionCompatTest-seed#[15A23661BBE3F8A2]-worker) [     ] 
o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:45481
   [junit4]   2> 145112 INFO  
(SUITE-SystemCollectionCompatTest-seed#[15A23661BBE3F8A2]-worker) [     ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1 45481
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_15A23661BBE3F8A2-001
   [junit4]   2> Feb 28, 2020 7:21:23 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene84): 
{id=PostingsFormat(name=LuceneVarGapFixedInterval), 
type=PostingsFormat(name=LuceneVarGapDocFreqInterval)}, 
docValues:{timestamp_l=DocValuesFormat(name=Asserting), 
_version_=DocValuesFormat(name=Lucene80), 
time_l=DocValuesFormat(name=Lucene80), 
timestamp=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=22, 
maxMBSortInHeap=7.251937586437538, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@7052fdde),
 locale=en-BZ, timezone=Universal
   [junit4]   2> NOTE: Linux 5.3.0-40-generic amd64/AdoptOpenJDK 12.0.2 
(64-bit)/cpus=16,threads=2,free=434110464,total=536870912
   [junit4]   2> NOTE: All tests run in this JVM: [SystemCollectionCompatTest]
   [junit4] Completed [5/5 (1!)] on J0 in 144.74s, 1 test, 1 error <<< FAILURES!

[...truncated 13 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1516: 
The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1043: 
There were test failures: 5 suites, 5 tests, 1 error [seed: 15A23661BBE3F8A2]

Total time: 2 minutes 48 seconds

[repro] Setting last failure code to 256

[repro] Failures w/original seeds:
[repro]   1/5 failed: org.apache.solr.cloud.SystemCollectionCompatTest
[repro] Exiting with code 256
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting 
ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting 
ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
No credentials specified
Setting 
ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all 
warnings.
<Git Blamer> GIT_COMMIT=3ad9915547874e713e6da71b6e3e1cb86dab8158, 
workspace=/home/jenkins/workspace/Lucene-Solr-master-Linux
[WARNINGS] Computing warning deltas based on reference build #25868
Recording test results
Setting 
ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting 
ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting 
ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting 
ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting 
ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting 
ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting 
ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to