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

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

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

Stack Trace:
org.apache.solr.client.solrj.SolrServerException: No live SolrServers available 
to handle this request:[https://127.0.0.1:45313/solr/.system]
        at 
__randomizedtesting.SeedInfo.seed([87F1D41409A21160:F70477BD696AB816]: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: https://127.0.0.1:45313/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 
java.base/sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:448)
        at 
java.base/sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:68)
        at 
java.base/sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1132)
        at 
java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:828)
        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 13210 lines...]
   [junit4] Suite: org.apache.solr.cloud.SystemCollectionCompatTest
   [junit4]   2> 65088 INFO  
(SUITE-SystemCollectionCompatTest-seed#[87F1D41409A21160]-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> 65088 INFO  
(SUITE-SystemCollectionCompatTest-seed#[87F1D41409A21160]-worker) [     ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 65088 INFO  
(SUITE-SystemCollectionCompatTest-seed#[87F1D41409A21160]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Created dataDir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_87F1D41409A21160-001/data-dir-14-001
   [junit4]   2> 65088 INFO  
(SUITE-SystemCollectionCompatTest-seed#[87F1D41409A21160]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 65089 INFO  
(SUITE-SystemCollectionCompatTest-seed#[87F1D41409A21160]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, 
clientAuth=0.0/0.0)
   [junit4]   2> 65089 INFO  
(SUITE-SystemCollectionCompatTest-seed#[87F1D41409A21160]-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_87F1D41409A21160-001/tempDir-001
   [junit4]   2> 65090 INFO  
(SUITE-SystemCollectionCompatTest-seed#[87F1D41409A21160]-worker) [     ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 65090 INFO  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 65090 INFO  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 65190 INFO  
(SUITE-SystemCollectionCompatTest-seed#[87F1D41409A21160]-worker) [     ] 
o.a.s.c.ZkTestServer start zk server on port:39219
   [junit4]   2> 65190 INFO  
(SUITE-SystemCollectionCompatTest-seed#[87F1D41409A21160]-worker) [     ] 
o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:39219
   [junit4]   2> 65190 INFO  
(SUITE-SystemCollectionCompatTest-seed#[87F1D41409A21160]-worker) [     ] 
o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:39219
   [junit4]   2> 65190 INFO  
(SUITE-SystemCollectionCompatTest-seed#[87F1D41409A21160]-worker) [     ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1 39219
   [junit4]   2> 65191 INFO  
(SUITE-SystemCollectionCompatTest-seed#[87F1D41409A21160]-worker) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 65192 INFO  (zkConnectionManagerCallback-528-thread-1) [     ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 65192 INFO  
(SUITE-SystemCollectionCompatTest-seed#[87F1D41409A21160]-worker) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 65193 INFO  
(SUITE-SystemCollectionCompatTest-seed#[87F1D41409A21160]-worker) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 65194 INFO  (zkConnectionManagerCallback-530-thread-1) [     ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 65194 INFO  
(SUITE-SystemCollectionCompatTest-seed#[87F1D41409A21160]-worker) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 65195 INFO  
(SUITE-SystemCollectionCompatTest-seed#[87F1D41409A21160]-worker) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 65195 INFO  (zkConnectionManagerCallback-532-thread-1) [     ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 65195 INFO  
(SUITE-SystemCollectionCompatTest-seed#[87F1D41409A21160]-worker) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 65302 INFO  (jetty-launcher-533-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 65302 INFO  (jetty-launcher-533-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 65302 INFO  (jetty-launcher-533-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 65302 INFO  (jetty-launcher-533-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 65303 INFO  (jetty-launcher-533-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> 65303 INFO  (jetty-launcher-533-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> 65306 INFO  (jetty-launcher-533-thread-1) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 65306 INFO  (jetty-launcher-533-thread-1) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 65306 INFO  (jetty-launcher-533-thread-1) [     ] 
o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 65307 INFO  (jetty-launcher-533-thread-2) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 65307 INFO  (jetty-launcher-533-thread-2) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 65307 INFO  (jetty-launcher-533-thread-2) [     ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 65308 INFO  (jetty-launcher-533-thread-1) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@6d73904c{/solr,null,AVAILABLE}
   [junit4]   2> 65311 INFO  (jetty-launcher-533-thread-1) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@714176fd{ssl,[ssl, alpn, 
http/1.1, h2]}{127.0.0.1:44061}
   [junit4]   2> 65311 INFO  (jetty-launcher-533-thread-1) [     ] 
o.e.j.s.Server Started @65330ms
   [junit4]   2> 65311 INFO  (jetty-launcher-533-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=44061}
   [junit4]   2> 65311 INFO  (jetty-launcher-533-thread-2) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@3af77f8d{/solr,null,AVAILABLE}
   [junit4]   2> 65311 ERROR (jetty-launcher-533-thread-1) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 65311 INFO  (jetty-launcher-533-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 65311 INFO  (jetty-launcher-533-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
9.0.0
   [junit4]   2> 65311 INFO  (jetty-launcher-533-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 65311 INFO  (jetty-launcher-533-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr
   [junit4]   2> 65311 INFO  (jetty-launcher-533-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-03-11T09:41:53.493639Z
   [junit4]   2> 65314 INFO  (jetty-launcher-533-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 65314 INFO  (jetty-launcher-533-thread-2) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@58be4e0{ssl,[ssl, alpn, 
http/1.1, h2]}{127.0.0.1:45313}
   [junit4]   2> 65314 INFO  (jetty-launcher-533-thread-2) [     ] 
o.e.j.s.Server Started @65333ms
   [junit4]   2> 65314 INFO  (jetty-launcher-533-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=45313}
   [junit4]   2> 65314 ERROR (jetty-launcher-533-thread-2) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 65314 INFO  (jetty-launcher-533-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 65314 INFO  (jetty-launcher-533-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
9.0.0
   [junit4]   2> 65314 INFO  (jetty-launcher-533-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 65314 INFO  (jetty-launcher-533-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr
   [junit4]   2> 65315 INFO  (jetty-launcher-533-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-03-11T09:41:53.496983Z
   [junit4]   2> 65315 INFO  (zkConnectionManagerCallback-535-thread-1) [     ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 65315 INFO  (jetty-launcher-533-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 65316 INFO  (jetty-launcher-533-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 65316 INFO  (jetty-launcher-533-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 65318 INFO  (zkConnectionManagerCallback-537-thread-1) [     ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 65318 INFO  (jetty-launcher-533-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 65320 INFO  (jetty-launcher-533-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 65422 INFO  (jetty-launcher-533-thread-1) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 65423 WARN  (jetty-launcher-533-thread-1) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@3263e82d[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 65423 WARN  (jetty-launcher-533-thread-1) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@3263e82d[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 65425 WARN  (jetty-launcher-533-thread-1) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@614d0c3a[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 65425 WARN  (jetty-launcher-533-thread-1) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@614d0c3a[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 65426 INFO  (jetty-launcher-533-thread-1) [     ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39219/solr
   [junit4]   2> 65426 INFO  (jetty-launcher-533-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 65427 INFO  (zkConnectionManagerCallback-544-thread-1) [     ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 65427 INFO  (jetty-launcher-533-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 65522 INFO  (jetty-launcher-533-thread-2) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 65523 WARN  (jetty-launcher-533-thread-2) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@4937f68[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 65523 WARN  (jetty-launcher-533-thread-2) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@4937f68[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 65524 WARN  (jetty-launcher-533-thread-2) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@524e5b6d[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 65524 WARN  (jetty-launcher-533-thread-2) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@524e5b6d[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 65525 INFO  (jetty-launcher-533-thread-2) [     ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39219/solr
   [junit4]   2> 65525 INFO  (jetty-launcher-533-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 65526 INFO  (zkConnectionManagerCallback-551-thread-1) [     ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 65526 INFO  (jetty-launcher-533-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 65622 INFO  (jetty-launcher-533-thread-1) 
[n:127.0.0.1:44061_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 65623 INFO  (zkConnectionManagerCallback-553-thread-1) [     ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 65623 INFO  (jetty-launcher-533-thread-1) 
[n:127.0.0.1:44061_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 65651 INFO  (jetty-launcher-533-thread-1) 
[n:127.0.0.1:44061_solr     ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:44061_solr
   [junit4]   2> 65651 INFO  (jetty-launcher-533-thread-1) 
[n:127.0.0.1:44061_solr     ] o.a.s.c.Overseer Overseer 
(id=72158592985137159-127.0.0.1:44061_solr-n_0000000000) starting
   [junit4]   2> 65653 INFO  
(OverseerStateUpdate-72158592985137159-127.0.0.1:44061_solr-n_0000000000) 
[n:127.0.0.1:44061_solr     ] o.a.s.c.Overseer Starting to work on the main 
queue : 127.0.0.1:44061_solr
   [junit4]   2> 65653 INFO  (jetty-launcher-533-thread-1) 
[n:127.0.0.1:44061_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:44061_solr
   [junit4]   2> 65654 INFO  (zkCallback-552-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 65654 INFO  (jetty-launcher-533-thread-1) 
[n:127.0.0.1:44061_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 65655 WARN  (jetty-launcher-533-thread-1) 
[n:127.0.0.1:44061_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> 65663 INFO  (jetty-launcher-533-thread-1) 
[n:127.0.0.1:44061_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 65677 INFO  (jetty-launcher-533-thread-1) 
[n:127.0.0.1:44061_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_44061.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1ee5cee5
   [junit4]   2> 65687 INFO  (jetty-launcher-533-thread-1) 
[n:127.0.0.1:44061_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_44061.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1ee5cee5
   [junit4]   2> 65688 INFO  (jetty-launcher-533-thread-1) 
[n:127.0.0.1:44061_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_44061.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1ee5cee5
   [junit4]   2> 65689 INFO  (jetty-launcher-533-thread-1) 
[n:127.0.0.1:44061_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_87F1D41409A21160-001/tempDir-001/node1/.
   [junit4]   2> 65722 INFO  (jetty-launcher-533-thread-2) 
[n:127.0.0.1:45313_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 65723 INFO  (zkConnectionManagerCallback-559-thread-1) [     ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 65723 INFO  (jetty-launcher-533-thread-2) 
[n:127.0.0.1:45313_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 65725 INFO  (jetty-launcher-533-thread-2) 
[n:127.0.0.1:45313_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 65726 INFO  (jetty-launcher-533-thread-2) 
[n:127.0.0.1:45313_solr     ] o.a.s.c.ZkController Publish 
node=127.0.0.1:45313_solr as DOWN
   [junit4]   2> 65726 INFO  (jetty-launcher-533-thread-2) 
[n:127.0.0.1:45313_solr     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 65726 INFO  (jetty-launcher-533-thread-2) 
[n:127.0.0.1:45313_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:45313_solr
   [junit4]   2> 65727 INFO  (zkCallback-552-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 65727 INFO  (zkCallback-558-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 65727 INFO  (jetty-launcher-533-thread-2) 
[n:127.0.0.1:45313_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 65728 WARN  (jetty-launcher-533-thread-2) 
[n:127.0.0.1:45313_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> 65733 INFO  (jetty-launcher-533-thread-2) 
[n:127.0.0.1:45313_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 65742 INFO  (jetty-launcher-533-thread-2) 
[n:127.0.0.1:45313_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_45313.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1ee5cee5
   [junit4]   2> 65746 INFO  (jetty-launcher-533-thread-2) 
[n:127.0.0.1:45313_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_45313.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1ee5cee5
   [junit4]   2> 65747 INFO  (jetty-launcher-533-thread-2) 
[n:127.0.0.1:45313_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_45313.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1ee5cee5
   [junit4]   2> 65747 INFO  (jetty-launcher-533-thread-2) 
[n:127.0.0.1:45313_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_87F1D41409A21160-001/tempDir-001/node2/.
   [junit4]   2> 65759 INFO  
(SUITE-SystemCollectionCompatTest-seed#[87F1D41409A21160]-worker) [     ] 
o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
   [junit4]   2> 65759 INFO  
(SUITE-SystemCollectionCompatTest-seed#[87F1D41409A21160]-worker) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 65760 INFO  (zkConnectionManagerCallback-568-thread-1) [     ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 65760 INFO  
(SUITE-SystemCollectionCompatTest-seed#[87F1D41409A21160]-worker) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 65760 INFO  
(SUITE-SystemCollectionCompatTest-seed#[87F1D41409A21160]-worker) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 65761 INFO  
(SUITE-SystemCollectionCompatTest-seed#[87F1D41409A21160]-worker) [     ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39219/solr ready
   [junit4]   2> 65771 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[87F1D41409A21160]) [     
] o.a.s.SolrTestCaseJ4 ###Starting testBackCompat
   [junit4]   2> 65773 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[87F1D41409A21160]) [     
] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 65773 INFO  (zkConnectionManagerCallback-573-thread-1) [     ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 65773 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[87F1D41409A21160]) [     
] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 65774 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[87F1D41409A21160]) [     
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 65775 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[87F1D41409A21160]) [     
] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39219/solr ready
   [junit4]   2> 65786 INFO  (qtp1052480865-4120) [n:127.0.0.1:44061_solr     ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with 
params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 65789 INFO  (qtp1052480865-4120) [n:127.0.0.1:44061_solr     ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=3
   [junit4]   2> 65798 INFO  (qtp1052480865-4122) [n:127.0.0.1:44061_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:45313_solr&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 65801 INFO  
(OverseerThreadFactory-656-thread-2-processing-n:127.0.0.1:44061_solr) 
[n:127.0.0.1:44061_solr     ] o.a.s.c.a.c.CreateCollectionCmd Create collection 
.system
   [junit4]   2> 65801 INFO  
(OverseerCollectionConfigSetProcessor-72158592985137159-127.0.0.1:44061_solr-n_0000000000)
 [n:127.0.0.1:44061_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> 65903 WARN  
(OverseerThreadFactory-656-thread-2-processing-n:127.0.0.1:44061_solr) 
[n:127.0.0.1:44061_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> 65905 INFO  
(OverseerStateUpdate-72158592985137159-127.0.0.1:44061_solr-n_0000000000) 
[n:127.0.0.1:44061_solr     ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":".system",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":".system_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:45313/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:45313_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 65905 INFO  
(OverseerStateUpdate-72158592985137159-127.0.0.1:44061_solr-n_0000000000) 
[n:127.0.0.1:44061_solr     ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":".system",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":".system_shard1_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:45313/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:45313_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 66130 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_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> 66130 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_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> 66142 WARN  (qtp1248922085-4132) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
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> 66142 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_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> 66143 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_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> 66158 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_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> 66158 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_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> 66160 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_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> 66160 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_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> 66164 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_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> 66164 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_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> 66165 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ManagedIndexSchema Created and persisted managed schema znode at 
/configs/.system/managed-schema
   [junit4]   2> 66165 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.ManagedIndexSchema Managed schema znode at 
/configs/.system/managed-schema already exists - no need to create it
   [junit4]   2> 66166 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_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> 66166 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_45313.solr.core..system.shard1.replica_n2' (registry 
'solr.core..system.shard1.replica_n2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1ee5cee5
   [junit4]   2> 66166 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_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_87F1D41409A21160-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_87F1D41409A21160-001/tempDir-001/node2/./.system_shard1_replica_n2/data/]
   [junit4]   2> 66168 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in 
ZooKeeper, renamed the non-managed schema /configs/.system/schema.xml to 
/configs/.system/schema.xml.bak
   [junit4]   2> 66168 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_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> 66168 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_45313.solr.core..system.shard1.replica_n1' (registry 
'solr.core..system.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1ee5cee5
   [junit4]   2> 66168 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_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_87F1D41409A21160-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_87F1D41409A21160-001/tempDir-001/node2/./.system_shard1_replica_n1/data/]
   [junit4]   2> 66207 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_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> 66207 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_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> 66207 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_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> 66207 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_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> 66208 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_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> 66208 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_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> 66208 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 66208 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 66209 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@5b5e14fe[.system_shard1_replica_n1] 
main]
   [junit4]   2> 66209 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@673b3421[.system_shard1_replica_n2] 
main]
   [junit4]   2> 66211 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_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> 66211 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_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> 66211 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_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> 66212 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_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> 66213 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_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> 66213 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_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> 66214 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_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> 66214 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.ZkIndexSchemaReader Retrieved schema version 0 from ZooKeeper
   [junit4]   2> 66214 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_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> 66214 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_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 
1660860198442500096
   [junit4]   2> 66215 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_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> 66216 INFO  
(searcherExecutor-665-thread-1-processing-n:127.0.0.1:45313_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:45313_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@673b3421[.system_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 66218 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_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_node4=0}, version=0}
   [junit4]   2> 66218 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_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> 66225 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_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> 66225 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 11 ms
   [junit4]   2> 66226 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_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> 66226 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_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 
1660860198455083008
   [junit4]   2> 66229 INFO  
(searcherExecutor-666-thread-1-processing-n:127.0.0.1:45313_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:45313_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@5b5e14fe[.system_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 66229 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/.system/terms/shard1 to Terms{values={core_node3=0, core_node4=0}, 
version=1}
   [junit4]   2> 66229 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_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> 66230 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 66230 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 66230 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.c.SyncStrategy Sync replicas to 
https://127.0.0.1:45313/solr/.system_shard1_replica_n2/
   [junit4]   2> 66233 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.PeerSync 
PeerSync: core=.system_shard1_replica_n2 url=https://127.0.0.1:45313/solr START 
replicas=[https://127.0.0.1:45313/solr/.system_shard1_replica_n1/] nUpdates=100
   [junit4]   2> 66234 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.PeerSync 
PeerSync: core=.system_shard1_replica_n2 url=https://127.0.0.1:45313/solr DONE. 
 We have no versions.  sync failed.
   [junit4]   2> 66244 INFO  (qtp1248922085-4130) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 66244 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the 
next candidate
   [junit4]   2> 66244 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we 
can't sync in that case - we were active before, so become leader anyway
   [junit4]   2> 66244 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node 
/collections/.system/leaders/shard1/leader after winning as 
/collections/.system/leader_elect/shard1/election/72158592985137160-core_node4-n_0000000000
   [junit4]   2> 66245 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
https://127.0.0.1:45313/solr/.system_shard1_replica_n2/ shard1
   [junit4]   2> 66347 INFO  (zkCallback-558-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> 66347 INFO  (zkCallback-558-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> 66347 INFO  (zkCallback-558-thread-3) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] 
for collection [.system] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 66347 INFO  (zkCallback-558-thread-4) [     ] 
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> 66347 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 66349 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_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=218
   [junit4]   2> 66449 INFO  (zkCallback-558-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> 66449 INFO  (zkCallback-558-thread-4) [     ] 
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> 66449 INFO  (zkCallback-558-thread-3) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] 
for collection [.system] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 66449 INFO  (zkCallback-558-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> 66449 INFO  (zkCallback-558-thread-5) [     ] 
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> 67234 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_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=1103
   [junit4]   2> 67235 INFO  (qtp1052480865-4122) [n:127.0.0.1:44061_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> 67333 INFO  (zkCallback-552-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> 67333 INFO  (zkCallback-558-thread-5) [     ] 
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> 67333 INFO  (zkCallback-558-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> 67333 INFO  (zkCallback-558-thread-3) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] 
for collection [.system] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 67333 INFO  (zkCallback-558-thread-4) [     ] 
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> 67333 INFO  (zkCallback-558-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> 67333 INFO  (qtp1052480865-4122) [n:127.0.0.1:44061_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:45313_solr&wt=javabin&version=2}
 status=0 QTime=1535
   [junit4]   2> 67333 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[87F1D41409A21160]) [     
] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: .system
   [junit4]   2> 67345 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_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=1, core_node4=1}, 
version=2}
   [junit4]   2> 67361 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_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={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:45313/solr/.system_shard1_replica_n2/&wt=javabin&version=2}{add=[170c8f8d4bcT9zv0x4bxz8r9mm0oz8p73apa0
 (1660860199626342400)]} 0 1
   [junit4]   2> 67361 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_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={wt=javabin&version=2}{add=[170c8f8d4bcT9zv0x4bxz8r9mm0oz8p73apa0 
(1660860199626342400)]} 0 19
   [junit4]   2> 67363 INFO  (qtp1248922085-4133) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1660860199647313920,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 67363 INFO  (qtp1248922085-4133) [n:127.0.0.1:45313_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@31d8dc5f 
commitCommandVersion:1660860199647313920
   [junit4]   2> 67364 INFO  (qtp1248922085-4130) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1660860199648362496,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 67364 INFO  (qtp1248922085-4130) [n:127.0.0.1:45313_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@7e2f0b8b 
commitCommandVersion:1660860199648362496
   [junit4]   2> 67371 INFO  (qtp1248922085-4130) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@32dbee8a[.system_shard1_replica_n1] 
main]
   [junit4]   2> 67371 INFO  (qtp1248922085-4133) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@11815c62[.system_shard1_replica_n2] 
main]
   [junit4]   2> 67371 INFO  (qtp1248922085-4130) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 67372 INFO  (qtp1248922085-4133) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 67372 INFO  
(searcherExecutor-665-thread-1-processing-n:127.0.0.1:45313_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:45313_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@11815c62[.system_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={source=flush,
 os.arch=amd64, java.runtime.version=12.0.2+10, os.version=5.3.0-40-generic, 
os=Linux, java.vendor=AdoptOpenJDK, timestamp=1583919715548, 
java.version=12.0.2, java.vm.version=12.0.2+10, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 67372 INFO  
(searcherExecutor-666-thread-1-processing-n:127.0.0.1:45313_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:45313_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@32dbee8a[.system_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={source=flush,
 os.arch=amd64, java.runtime.version=12.0.2+10, os.version=5.3.0-40-generic, 
os=Linux, java.vendor=AdoptOpenJDK, timestamp=1583919715549, 
java.version=12.0.2, java.vm.version=12.0.2+10, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 67373 INFO  (qtp1248922085-4130) [n:127.0.0.1:45313_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={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:45313/solr/.system_shard1_replica_n2/&commit_end_point=replicas&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 8
   [junit4]   2> 67373 INFO  (qtp1248922085-4133) [n:127.0.0.1:45313_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={_stateVer_=.system:4&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 11
   [junit4]   2> 67384 INFO  (qtp1248922085-4130) [n:127.0.0.1:45313_solr     ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={action=STATUS&indexInfo=true&wt=javabin&version=2} status=0 QTime=1
   [junit4]   2> 67386 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_solr     ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={action=STATUS&indexInfo=true&wt=javabin&version=2} status=0 QTime=1
   [junit4]   2> 67387 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema 
params={wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 67390 INFO  (qtp1248922085-4133) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.SchemaManager managed schema loaded . version : 1 
   [junit4]   2> 67391 INFO  (qtp1248922085-4133) [n:127.0.0.1:45313_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> 67393 INFO  (qtp1248922085-4133) [n:127.0.0.1:45313_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> 67394 INFO  (qtp1248922085-4133) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.c.ZkController Persisted config data to node 
/configs/.system/schema.xml.bak 
   [junit4]   2> 67395 INFO  (Thread-2591) [     ] o.a.s.c.SolrCore config 
update listener called for core .system_shard1_replica_n2
   [junit4]   2> 67395 INFO  (Thread-2591) [     ] o.a.s.c.SolrCore core reload 
.system_shard1_replica_n2
   [junit4]   2> 67398 INFO  (qtp1248922085-4133) [n:127.0.0.1:45313_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> 67404 INFO  (qtp1248922085-4133) [n:127.0.0.1:45313_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> 67406 INFO  (qtp1248922085-4133) [n:127.0.0.1:45313_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> 67406 INFO  (qtp1248922085-4133) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.c.CoreContainer Reloading SolrCore '.system_shard1_replica_n2' using 
configuration from configset .system
   [junit4]   2> 67437 INFO  (qtp1248922085-4133) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_45313.solr.core..system.shard1.replica_n2' (registry 
'solr.core..system.shard1.replica_n2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1ee5cee5
   [junit4]   2> 67437 INFO  (qtp1248922085-4133) [n:127.0.0.1:45313_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_87F1D41409A21160-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_87F1D41409A21160-001/tempDir-001/node2/./.system_shard1_replica_n2/data/]
   [junit4]   2> 67468 INFO  (qtp1248922085-4133) [n:127.0.0.1:45313_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> 67468 INFO  (qtp1248922085-4133) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 67469 INFO  (qtp1248922085-4133) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@56484d79[.system_shard1_replica_n2] 
main]
   [junit4]   2> 67470 INFO  (qtp1248922085-4133) [n:127.0.0.1:45313_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> 67470 INFO  (qtp1248922085-4133) [n:127.0.0.1:45313_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> 67470 INFO  (qtp1248922085-4133) [n:127.0.0.1:45313_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> 67470 INFO  (qtp1248922085-4133) [n:127.0.0.1:45313_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> 67470 INFO  (qtp1248922085-4133) [n:127.0.0.1:45313_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> 67475 INFO  (qtp1248922085-4133) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 67475 INFO  
(searcherExecutor-675-thread-1-processing-n:127.0.0.1:45313_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:45313_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@56484d79[.system_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={source=flush,
 os.arch=amd64, java.runtime.version=12.0.2+10, os.version=5.3.0-40-generic, 
os=Linux, java.vendor=AdoptOpenJDK, timestamp=1583919715548, 
java.version=12.0.2, java.vm.version=12.0.2+10, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 67476 INFO  (qtp1248922085-4133) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@43405b0a[.system_shard1_replica_n2] 
main]
   [junit4]   2> 67478 INFO  
(searcherExecutor-675-thread-1-processing-n:127.0.0.1:45313_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:45313_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@43405b0a[.system_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={source=flush,
 os.arch=amd64, java.runtime.version=12.0.2+10, os.version=5.3.0-40-generic, 
java.vendor=AdoptOpenJDK, os=Linux, timestamp=1583919715548, 
java.version=12.0.2, java.vm.version=12.0.2+10, 
lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 67478 INFO  (qtp1248922085-4133) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ManagedIndexSchema Waiting up to 599 secs for 1 replicas to apply 
schema update version 2 for collection .system
   [junit4]   2> 67480 INFO  (Thread-2591) [     ] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 9.0.0
   [junit4]   2> 67487 INFO  (Thread-2591) [     ] o.a.s.s.IndexSchema 
[.system_shard1_replica_n2] Schema name=_system collection or core
   [junit4]   2> 67490 INFO  (Thread-2591) [     ] o.a.s.s.IndexSchema Loaded 
schema _system collection or core/1.1 with uniqueid field id
   [junit4]   2> 67490 INFO  (Thread-2591) [     ] o.a.s.c.CoreContainer 
Reloading SolrCore '.system_shard1_replica_n2' using configuration from 
configset .system
   [junit4]   2> 67491 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 67491 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_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> 67491 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=1
   [junit4]   2> 67492 ERROR 
(managedSchemaExecutor-679-thread-1-processing-n:127.0.0.1:45313_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:45313_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:45313/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 67494 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 67494 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_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> 67494 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 67494 ERROR 
(managedSchemaExecutor-679-thread-1-processing-n:127.0.0.1:45313_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:45313_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:45313/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 67495 INFO  (qtp1248922085-4130) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 67496 INFO  (qtp1248922085-4130) [n:127.0.0.1:45313_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> 67496 INFO  (qtp1248922085-4130) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 67496 ERROR 
(managedSchemaExecutor-679-thread-1-processing-n:127.0.0.1:45313_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:45313_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:45313/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 67497 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 67497 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_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> 67497 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 67497 ERROR 
(managedSchemaExecutor-679-thread-1-processing-n:127.0.0.1:45313_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:45313_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:45313/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 67498 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 67498 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_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> 67498 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 67498 ERROR 
(managedSchemaExecutor-679-thread-1-processing-n:127.0.0.1:45313_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:45313_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:45313/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 67499 INFO  (qtp1248922085-4130) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 67499 INFO  (qtp1248922085-4130) [n:127.0.0.1:45313_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> 67499 INFO  (qtp1248922085-4130) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 67499 ERROR 
(managedSchemaExecutor-679-thread-1-processing-n:127.0.0.1:45313_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:45313_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:45313/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 67500 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 67500 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_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> 67500 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 67500 ERROR 
(managedSchemaExecutor-679-thread-1-processing-n:127.0.0.1:45313_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:45313_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:45313/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 67500 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 67501 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_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> 67501 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 67501 ERROR 
(managedSchemaExecutor-679-thread-1-processing-n:127.0.0.1:45313_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:45313_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:45313/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 67501 INFO  (qtp1248922085-4130) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 67502 INFO  (qtp1248922085-4130) [n:127.0.0.1:45313_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> 67502 INFO  (qtp1248922085-4130) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 67502 ERROR 
(managedSchemaExecutor-679-thread-1-processing-n:127.0.0.1:45313_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:45313_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:45313/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 67502 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 67502 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_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> 67502 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 67503 ERROR 
(managedSchemaExecutor-679-thread-1-processing-n:127.0.0.1:45313_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:45313_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:45313/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 67503 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 67503 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_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> 67503 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 67503 ERROR 
(managedSchemaExecutor-679-thread-1-processing-n:127.0.0.1:45313_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:45313_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:45313/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 67504 INFO  (qtp1248922085-4130) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 67504 INFO  (qtp1248922085-4130) [n:127.0.0.1:45313_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> 67504 INFO  (qtp1248922085-4130) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 67504 ERROR 
(managedSchemaExecutor-679-thread-1-processing-n:127.0.0.1:45313_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:45313_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:45313/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 67505 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 67505 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_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> 67505 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 67505 ERROR 
(managedSchemaExecutor-679-thread-1-processing-n:127.0.0.1:45313_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:45313_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:45313/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 67506 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 67506 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_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> 67506 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 67506 ERROR 
(managedSchemaExecutor-679-thread-1-processing-n:127.0.0.1:45313_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:45313_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:45313/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 67507 INFO  (qtp1248922085-4130) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 67507 INFO  (qtp1248922085-4130) [n:127.0.0.1:45313_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> 67507 INFO  (qtp1248922085-4130) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 67507 ERROR 
(managedSchemaExecutor-679-thread-1-processing-n:127.0.0.1:45313_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:45313_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:45313/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 67507 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 67508 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_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> 67508 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 67508 ERROR 
(managedSchemaExecutor-679-thread-1-processing-n:127.0.0.1:45313_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:45313_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:45313/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 67508 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 67508 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_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> 67508 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 67508 ERROR 
(managedSchemaExecutor-679-thread-1-processing-n:127.0.0.1:45313_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:45313_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:45313/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 67509 INFO  (qtp1248922085-4130) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 67509 INFO  (qtp1248922085-4130) [n:127.0.0.1:45313_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> 67509 INFO  (qtp1248922085-4130) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 67510 ERROR 
(managedSchemaExecutor-679-thread-1-processing-n:127.0.0.1:45313_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:45313_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:45313/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 67510 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 67510 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_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> 67510 INFO  (qtp1248922085-4129) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 67510 ERROR 
(managedSchemaExecutor-679-thread-1-processing-n:127.0.0.1:45313_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:45313_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:45313/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 67511 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 67511 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_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> 67511 INFO  (qtp1248922085-4132) [n:127.0.0.1:45313_solr c

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

_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Failed to get 
/schema/zkversion from https://127.0.0.1:45313/solr/.system_shard1_replica_n1/ 
due to: java.util.concurrent.ExecutionException: 
org.apache.solr.client.solrj.SolrServerException: Server refused connection at: 
https://127.0.0.1:45313/solr/.system_shard1_replica_n1
   [junit4]   2> 202881 WARN  
(managedSchemaExecutor-679-thread-1-processing-n:127.0.0.1:45313_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:45313_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Failed to get 
/schema/zkversion from https://127.0.0.1:45313/solr/.system_shard1_replica_n1/ 
due to: java.util.concurrent.ExecutionException: 
org.apache.solr.client.solrj.SolrServerException: Server refused connection at: 
https://127.0.0.1:45313/solr/.system_shard1_replica_n1
   [junit4]   2> 202881 WARN  
(managedSchemaExecutor-679-thread-1-processing-n:127.0.0.1:45313_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:45313_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Failed to get 
/schema/zkversion from https://127.0.0.1:45313/solr/.system_shard1_replica_n1/ 
due to: java.util.concurrent.ExecutionException: 
org.apache.solr.client.solrj.SolrServerException: Server refused connection at: 
https://127.0.0.1:45313/solr/.system_shard1_replica_n1
   [junit4]   2> 202882 WARN  
(managedSchemaExecutor-679-thread-1-processing-n:127.0.0.1:45313_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:45313_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Failed to get 
/schema/zkversion from https://127.0.0.1:45313/solr/.system_shard1_replica_n1/ 
due to: java.util.concurrent.ExecutionException: 
org.apache.solr.client.solrj.SolrServerException: Server refused connection at: 
https://127.0.0.1:45313/solr/.system_shard1_replica_n1
   [junit4]   2> 202882 WARN  
(managedSchemaExecutor-679-thread-1-processing-n:127.0.0.1:45313_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:45313_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Failed to get 
/schema/zkversion from https://127.0.0.1:45313/solr/.system_shard1_replica_n1/ 
due to: java.util.concurrent.ExecutionException: 
org.apache.solr.client.solrj.SolrServerException: Server refused connection at: 
https://127.0.0.1:45313/solr/.system_shard1_replica_n1
   [junit4]   2> 203249 INFO  
(httpUriRequest-244559-thread-1-processing-x:.system_shard1_replica_n2 
r:core_node4 n:127.0.0.1:45313_solr 
https:////127.0.0.1:45313//solr//.system_shard1_replica_n1 c:.system s:shard1) 
[n:127.0.0.1:45313_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.h.i.e.RetryExec I/O exception 
(java.net.SocketException) caught when processing request to 
{s}->https://127.0.0.1:45313: Socket closed
   [junit4]   2> 203249 INFO  
(httpUriRequest-244559-thread-1-processing-x:.system_shard1_replica_n2 
r:core_node4 n:127.0.0.1:45313_solr 
https:////127.0.0.1:45313//solr//.system_shard1_replica_n1 c:.system s:shard1) 
[n:127.0.0.1:45313_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.h.i.e.RetryExec Retrying request to 
{s}->https://127.0.0.1:45313
   [junit4]   2> 203250 WARN  (qtp1248922085-4133) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ManagedIndexSchema Core core_node4 was interrupted waiting for schema 
version 2 to propagate to 1 replicas for collection .system
   [junit4]   2> 203251 INFO  (qtp1248922085-4133) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ManagedIndexSchema Took 135774.0ms for 1 replicas to apply schema 
update version 2 for collection .system
   [junit4]   2> 203251 INFO  (qtp1248922085-4133) [n:127.0.0.1:45313_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=135861
   [junit4]   2> 203251 INFO  (qtp1248922085-4133) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.SolrCore 
[.system_shard1_replica_n2]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@2be43d73
   [junit4]   2> 203251 WARN  (qtp1248922085-4133) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.m.SolrMetricManager Interrupted while trying to obtain lock to modify 
reporters registry: solr.core..system.shard1.replica_n2
   [junit4]   2> 203251 INFO  (qtp1248922085-4133) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.collection..system.shard1.leader, tag=SolrCore@2be43d73
   [junit4]   2> 203251 INFO  (qtp1248922085-4133) [n:127.0.0.1:45313_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() .
   [junit4]   2> 203251 INFO  (qtp1248922085-4133) [n:127.0.0.1:45313_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@90e2189 commitCommandVersion:0
   [junit4]   2> 203253 INFO  
(SUITE-SystemCollectionCompatTest-seed#[87F1D41409A21160]-worker) [     ] 
o.a.s.c.ZkTestServer Shutting down ZkTestServer.
   [junit4]   2> 203457 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/aliases.json
   [junit4]   2>        6       /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>        28      /solr/collections/.system/state.json
   [junit4]   2>        7       /solr/clusterstate.json
   [junit4]   2>        7       /solr/clusterprops.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/overseer/collection-queue-work
   [junit4]   2>        11      /solr/live_nodes
   [junit4]   2> 
   [junit4]   2> 203457 INFO  
(SUITE-SystemCollectionCompatTest-seed#[87F1D41409A21160]-worker) [     ] 
o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:39219
   [junit4]   2> 203457 INFO  
(SUITE-SystemCollectionCompatTest-seed#[87F1D41409A21160]-worker) [     ] 
o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:39219
   [junit4]   2> 203457 INFO  
(SUITE-SystemCollectionCompatTest-seed#[87F1D41409A21160]-worker) [     ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1 39219
   [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_87F1D41409A21160-001
   [junit4]   2> Mar 11, 2020 9:44:11 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=MockRandom), type=PostingsFormat(name=Asserting)}, 
docValues:{timestamp_l=DocValuesFormat(name=Asserting), 
_version_=DocValuesFormat(name=Lucene80), 
time_l=DocValuesFormat(name=Lucene80), 
timestamp=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=1996, 
maxMBSortInHeap=7.811501516487973, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@6f03932),
 locale=es-DO, timezone=America/Campo_Grande
   [junit4]   2> NOTE: Linux 5.3.0-40-generic amd64/AdoptOpenJDK 12.0.2 
(64-bit)/cpus=16,threads=1,free=94861768,total=536346624
   [junit4]   2> NOTE: All tests run in this JVM: [ZkControllerTest, 
DistributedFacetSimpleRefinementLongTailTest, TestExtendedDismaxParser, 
UpdateLogTest, TestBulkSchemaAPI, TaggingAttributeTest, 
NodeAddedTriggerIntegrationTest, RuleEngineTest, SolrIndexSplitterTest, 
SolrJmxReporterCloudTest, TestSolrCachePerf, RemoteQueryErrorTest, 
TestSimComputePlanAction, SystemCollectionCompatTest]
   [junit4] Completed [131/905 (1!)] on J0 in 138.49s, 1 test, 1 error <<< 
FAILURES!

[...truncated 55225 lines...]
[repro] Jenkins log URL: 
https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/25986/consoleText

[repro] Revision: 9842744956561c9053bffbc8e36eca5581d5df31

[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseParallelGC"
[repro] Repro line:  ant test  -Dtestcase=SystemCollectionCompatTest 
-Dtests.method=testBackCompat -Dtests.seed=87F1D41409A21160 
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=es-DO 
-Dtests.timezone=America/Campo_Grande -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8

[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean

[...truncated 6 lines...]
[repro] Test suites by module:
[repro]    solr/core
[repro]       SystemCollectionCompatTest
[repro] ant compile-test

[...truncated 3128 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 
-Dtests.class="*.SystemCollectionCompatTest" -Dtests.showOutput=onerror 
"-Dargs=-XX:-UseCompressedOops -XX:+UseParallelGC" 
-Dtests.seed=87F1D41409A21160 -Dtests.multiplier=3 -Dtests.slow=true 
-Dtests.locale=es-DO -Dtests.timezone=America/Campo_Grande -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8

[...truncated 81 lines...]
[repro] Failures w/original seeds:
[repro]   0/5 failed: org.apache.solr.cloud.SystemCollectionCompatTest
[repro] Exiting with code 0

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

Reply via email to