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

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

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

Stack Trace:
org.apache.solr.client.solrj.SolrServerException: No live SolrServers available 
to handle this request:[http://127.0.0.1:41817/solr/.system]
        at 
__randomizedtesting.SeedInfo.seed([7AF1013C51641E34:A04A29531ACB742]: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:566)
        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:834)
Caused by: org.apache.solr.client.solrj.SolrServerException: Timeout occurred 
while waiting response from server at: http://127.0.0.1:41817/solr/.system
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:676)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:265)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
        at 
org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:368)
        at 
org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:296)
        ... 43 more
Caused by: java.net.SocketTimeoutException: Read timed out
        at java.base/java.net.SocketInputStream.socketRead0(Native Method)
        at 
java.base/java.net.SocketInputStream.socketRead(SocketInputStream.java:115)
        at java.base/java.net.SocketInputStream.read(SocketInputStream.java:168)
        at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
        at 
org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
        at 
org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
        at 
org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
        at 
org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
        at 
org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
        at 
org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
        at 
org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
        at 
org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:157)
        at 
org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
        at 
org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
        at 
org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
        at 
org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:186)
        at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
        at 
org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
        at 
org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
        at 
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
        at 
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:564)
        ... 47 more




Build Log:
[...truncated 13901 lines...]
   [junit4] Suite: org.apache.solr.cloud.SystemCollectionCompatTest
   [junit4]   2> 281903 INFO  
(SUITE-SystemCollectionCompatTest-seed#[7AF1013C51641E34]-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> 281903 INFO  
(SUITE-SystemCollectionCompatTest-seed#[7AF1013C51641E34]-worker) [     ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 281903 INFO  
(SUITE-SystemCollectionCompatTest-seed#[7AF1013C51641E34]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Created dataDir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_7AF1013C51641E34-001/data-dir-44-001
   [junit4]   2> 281903 WARN  
(SUITE-SystemCollectionCompatTest-seed#[7AF1013C51641E34]-worker) [     ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
   [junit4]   2> 281903 INFO  
(SUITE-SystemCollectionCompatTest-seed#[7AF1013C51641E34]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 281904 INFO  
(SUITE-SystemCollectionCompatTest-seed#[7AF1013C51641E34]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, 
clientAuth=0.0/0.0)
   [junit4]   2> 281904 INFO  
(SUITE-SystemCollectionCompatTest-seed#[7AF1013C51641E34]-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_7AF1013C51641E34-001/tempDir-001
   [junit4]   2> 281904 INFO  
(SUITE-SystemCollectionCompatTest-seed#[7AF1013C51641E34]-worker) [     ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 281904 INFO  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 281904 INFO  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 282004 INFO  
(SUITE-SystemCollectionCompatTest-seed#[7AF1013C51641E34]-worker) [     ] 
o.a.s.c.ZkTestServer start zk server on port:42813
   [junit4]   2> 282004 INFO  
(SUITE-SystemCollectionCompatTest-seed#[7AF1013C51641E34]-worker) [     ] 
o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:42813
   [junit4]   2> 282004 INFO  
(SUITE-SystemCollectionCompatTest-seed#[7AF1013C51641E34]-worker) [     ] 
o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:42813
   [junit4]   2> 282004 INFO  
(SUITE-SystemCollectionCompatTest-seed#[7AF1013C51641E34]-worker) [     ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1 42813
   [junit4]   2> 282006 INFO  
(SUITE-SystemCollectionCompatTest-seed#[7AF1013C51641E34]-worker) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 282007 INFO  (zkConnectionManagerCallback-1712-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 282007 INFO  
(SUITE-SystemCollectionCompatTest-seed#[7AF1013C51641E34]-worker) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 282008 INFO  
(SUITE-SystemCollectionCompatTest-seed#[7AF1013C51641E34]-worker) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 282009 INFO  (zkConnectionManagerCallback-1714-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 282009 INFO  
(SUITE-SystemCollectionCompatTest-seed#[7AF1013C51641E34]-worker) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 282010 INFO  
(SUITE-SystemCollectionCompatTest-seed#[7AF1013C51641E34]-worker) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 282010 INFO  (zkConnectionManagerCallback-1716-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 282010 INFO  
(SUITE-SystemCollectionCompatTest-seed#[7AF1013C51641E34]-worker) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 282118 INFO  (jetty-launcher-1717-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 282118 INFO  (jetty-launcher-1717-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 282118 INFO  (jetty-launcher-1717-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 282118 INFO  (jetty-launcher-1717-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 282118 INFO  (jetty-launcher-1717-thread-1) [     ] 
o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 
363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.6+10
   [junit4]   2> 282118 INFO  (jetty-launcher-1717-thread-2) [     ] 
o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 
363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.6+10
   [junit4]   2> 282119 INFO  (jetty-launcher-1717-thread-2) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 282119 INFO  (jetty-launcher-1717-thread-2) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 282119 INFO  (jetty-launcher-1717-thread-2) [     ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 282119 INFO  (jetty-launcher-1717-thread-1) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 282119 INFO  (jetty-launcher-1717-thread-1) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 282119 INFO  (jetty-launcher-1717-thread-1) [     ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 282119 INFO  (jetty-launcher-1717-thread-2) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@bf4d91c{/solr,null,AVAILABLE}
   [junit4]   2> 282123 INFO  (jetty-launcher-1717-thread-1) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@74cb691b{/solr,null,AVAILABLE}
   [junit4]   2> 282124 INFO  (jetty-launcher-1717-thread-2) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@2dd44f99{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:46647}
   [junit4]   2> 282124 INFO  (jetty-launcher-1717-thread-2) [     ] 
o.e.j.s.Server Started @282146ms
   [junit4]   2> 282124 INFO  (jetty-launcher-1717-thread-1) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@255b21f7{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:41817}
   [junit4]   2> 282124 INFO  (jetty-launcher-1717-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=46647}
   [junit4]   2> 282124 INFO  (jetty-launcher-1717-thread-1) [     ] 
o.e.j.s.Server Started @282146ms
   [junit4]   2> 282124 INFO  (jetty-launcher-1717-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=41817}
   [junit4]   2> 282124 ERROR (jetty-launcher-1717-thread-2) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 282124 INFO  (jetty-launcher-1717-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 282124 ERROR (jetty-launcher-1717-thread-1) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 282124 INFO  (jetty-launcher-1717-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
9.0.0
   [junit4]   2> 282124 INFO  (jetty-launcher-1717-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 282124 INFO  (jetty-launcher-1717-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 282124 INFO  (jetty-launcher-1717-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr
   [junit4]   2> 282124 INFO  (jetty-launcher-1717-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-03-05T07:47:51.550587Z
   [junit4]   2> 282124 INFO  (jetty-launcher-1717-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
9.0.0
   [junit4]   2> 282124 INFO  (jetty-launcher-1717-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 282124 INFO  (jetty-launcher-1717-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr
   [junit4]   2> 282124 INFO  (jetty-launcher-1717-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-03-05T07:47:51.550627Z
   [junit4]   2> 282131 INFO  (jetty-launcher-1717-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 282133 INFO  (jetty-launcher-1717-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 282134 INFO  (zkConnectionManagerCallback-1719-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 282134 INFO  (jetty-launcher-1717-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 282134 INFO  (zkConnectionManagerCallback-1721-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 282134 INFO  (jetty-launcher-1717-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 282139 INFO  (jetty-launcher-1717-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 282139 INFO  (jetty-launcher-1717-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 282248 INFO  (jetty-launcher-1717-thread-2) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 282249 WARN  (jetty-launcher-1717-thread-2) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@40c0d013[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 282249 WARN  (jetty-launcher-1717-thread-2) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@40c0d013[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 282250 WARN  (jetty-launcher-1717-thread-2) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@325abedc[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 282250 WARN  (jetty-launcher-1717-thread-2) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@325abedc[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 282251 INFO  (jetty-launcher-1717-thread-2) [     ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42813/solr
   [junit4]   2> 282251 INFO  (jetty-launcher-1717-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 282252 INFO  (zkConnectionManagerCallback-1728-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 282252 INFO  (jetty-launcher-1717-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 282348 INFO  (jetty-launcher-1717-thread-1) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 282349 WARN  (jetty-launcher-1717-thread-1) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@4428981f[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 282349 WARN  (jetty-launcher-1717-thread-1) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@4428981f[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 282350 WARN  (jetty-launcher-1717-thread-1) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@5347bf6d[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 282350 WARN  (jetty-launcher-1717-thread-1) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@5347bf6d[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 282351 INFO  (jetty-launcher-1717-thread-1) [     ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42813/solr
   [junit4]   2> 282351 INFO  (jetty-launcher-1717-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 282352 INFO  (zkConnectionManagerCallback-1735-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 282352 INFO  (jetty-launcher-1717-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 282448 INFO  (jetty-launcher-1717-thread-2) 
[n:127.0.0.1:46647_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 282449 INFO  (zkConnectionManagerCallback-1737-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 282449 INFO  (jetty-launcher-1717-thread-2) 
[n:127.0.0.1:46647_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 282478 INFO  (jetty-launcher-1717-thread-2) 
[n:127.0.0.1:46647_solr     ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:46647_solr
   [junit4]   2> 282478 INFO  (jetty-launcher-1717-thread-2) 
[n:127.0.0.1:46647_solr     ] o.a.s.c.Overseer Overseer 
(id=72124170729488391-127.0.0.1:46647_solr-n_0000000000) starting
   [junit4]   2> 282481 INFO  
(OverseerStateUpdate-72124170729488391-127.0.0.1:46647_solr-n_0000000000) 
[n:127.0.0.1:46647_solr     ] o.a.s.c.Overseer Starting to work on the main 
queue : 127.0.0.1:46647_solr
   [junit4]   2> 282483 INFO  (jetty-launcher-1717-thread-2) 
[n:127.0.0.1:46647_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:46647_solr
   [junit4]   2> 282484 INFO  (zkCallback-1736-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 282484 INFO  (jetty-launcher-1717-thread-2) 
[n:127.0.0.1:46647_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 282484 WARN  (jetty-launcher-1717-thread-2) 
[n:127.0.0.1:46647_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> 282491 INFO  (jetty-launcher-1717-thread-2) 
[n:127.0.0.1:46647_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 282503 INFO  (jetty-launcher-1717-thread-2) 
[n:127.0.0.1:46647_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46647.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@e585c41
   [junit4]   2> 282508 INFO  (jetty-launcher-1717-thread-2) 
[n:127.0.0.1:46647_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46647.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@e585c41
   [junit4]   2> 282508 INFO  (jetty-launcher-1717-thread-2) 
[n:127.0.0.1:46647_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46647.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@e585c41
   [junit4]   2> 282509 INFO  (jetty-launcher-1717-thread-2) 
[n:127.0.0.1:46647_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_7AF1013C51641E34-001/tempDir-001/node2/.
   [junit4]   2> 282548 INFO  (jetty-launcher-1717-thread-1) 
[n:127.0.0.1:41817_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 282549 INFO  (zkConnectionManagerCallback-1743-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 282549 INFO  (jetty-launcher-1717-thread-1) 
[n:127.0.0.1:41817_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 282551 INFO  (jetty-launcher-1717-thread-1) 
[n:127.0.0.1:41817_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 282552 INFO  (jetty-launcher-1717-thread-1) 
[n:127.0.0.1:41817_solr     ] o.a.s.c.ZkController Publish 
node=127.0.0.1:41817_solr as DOWN
   [junit4]   2> 282552 INFO  (jetty-launcher-1717-thread-1) 
[n:127.0.0.1:41817_solr     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 282552 INFO  (jetty-launcher-1717-thread-1) 
[n:127.0.0.1:41817_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:41817_solr
   [junit4]   2> 282553 INFO  (zkCallback-1736-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 282553 INFO  (zkCallback-1742-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 282553 INFO  (jetty-launcher-1717-thread-1) 
[n:127.0.0.1:41817_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 282553 WARN  (jetty-launcher-1717-thread-1) 
[n:127.0.0.1:41817_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> 282558 INFO  (jetty-launcher-1717-thread-1) 
[n:127.0.0.1:41817_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 282567 INFO  (jetty-launcher-1717-thread-1) 
[n:127.0.0.1:41817_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_41817.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@e585c41
   [junit4]   2> 282570 INFO  (jetty-launcher-1717-thread-1) 
[n:127.0.0.1:41817_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_41817.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@e585c41
   [junit4]   2> 282570 INFO  (jetty-launcher-1717-thread-1) 
[n:127.0.0.1:41817_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_41817.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@e585c41
   [junit4]   2> 282571 INFO  (jetty-launcher-1717-thread-1) 
[n:127.0.0.1:41817_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_7AF1013C51641E34-001/tempDir-001/node1/.
   [junit4]   2> 282582 INFO  
(SUITE-SystemCollectionCompatTest-seed#[7AF1013C51641E34]-worker) [     ] 
o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
   [junit4]   2> 282582 INFO  
(SUITE-SystemCollectionCompatTest-seed#[7AF1013C51641E34]-worker) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 282583 INFO  (zkConnectionManagerCallback-1752-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 282583 INFO  
(SUITE-SystemCollectionCompatTest-seed#[7AF1013C51641E34]-worker) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 282584 INFO  
(SUITE-SystemCollectionCompatTest-seed#[7AF1013C51641E34]-worker) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 282584 INFO  
(SUITE-SystemCollectionCompatTest-seed#[7AF1013C51641E34]-worker) [     ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42813/solr ready
   [junit4]   2> 282598 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[7AF1013C51641E34]) [     
] o.a.s.SolrTestCaseJ4 ###Starting testBackCompat
   [junit4]   2> 282599 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[7AF1013C51641E34]) [     
] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 282600 INFO  (zkConnectionManagerCallback-1757-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 282600 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[7AF1013C51641E34]) [     
] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 282600 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[7AF1013C51641E34]) [     
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 282601 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[7AF1013C51641E34]) [     
] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42813/solr ready
   [junit4]   2> 282603 INFO  (qtp731699956-6430) [n:127.0.0.1:41817_solr     ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with 
params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 282605 INFO  (qtp731699956-6430) [n:127.0.0.1:41817_solr     ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=2
   [junit4]   2> 282606 INFO  (qtp731699956-6430) [n:127.0.0.1:41817_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:41817_solr&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 282609 INFO  
(OverseerCollectionConfigSetProcessor-72124170729488391-127.0.0.1:46647_solr-n_0000000000)
 [n:127.0.0.1:46647_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> 282609 INFO  
(OverseerThreadFactory-2015-thread-2-processing-n:127.0.0.1:46647_solr) 
[n:127.0.0.1:46647_solr     ] o.a.s.c.a.c.CreateCollectionCmd Create collection 
.system
   [junit4]   2> 282711 WARN  
(OverseerThreadFactory-2015-thread-2-processing-n:127.0.0.1:46647_solr) 
[n:127.0.0.1:46647_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> 282714 INFO  
(OverseerStateUpdate-72124170729488391-127.0.0.1:46647_solr-n_0000000000) 
[n:127.0.0.1:46647_solr     ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":".system",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":".system_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:41817/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:41817_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 282715 INFO  
(OverseerStateUpdate-72124170729488391-127.0.0.1:46647_solr-n_0000000000) 
[n:127.0.0.1:46647_solr     ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":".system",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":".system_shard1_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:41817/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:41817_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 282917 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_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> 282917 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_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> 282922 WARN  (qtp731699956-6438) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.c.SolrConfig You should not use LATEST as luceneMatchVersion property: if 
you use this setting, and then Solr upgrades to a newer release of Lucene, 
sizable changes may happen. If precise back compatibility is important then you 
should instead explicitly specify an actual Lucene version.
   [junit4]   2> 282922 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_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> 282922 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_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> 282935 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_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> 282935 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_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> 282936 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_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> 282936 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_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> 282939 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_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> 282939 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_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> 282941 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.ManagedIndexSchema Created and persisted managed schema znode at 
/configs/.system/managed-schema
   [junit4]   2> 282941 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ManagedIndexSchema Managed schema znode at 
/configs/.system/managed-schema already exists - no need to create it
   [junit4]   2> 282941 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_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> 282942 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_41817.solr.core..system.shard1.replica_n1' (registry 
'solr.core..system.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@e585c41
   [junit4]   2> 282942 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_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_7AF1013C51641E34-001/tempDir-001/node1/.system_shard1_replica_n1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_7AF1013C51641E34-001/tempDir-001/node1/./.system_shard1_replica_n1/data/]
   [junit4]   2> 282942 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in 
ZooKeeper, renamed the non-managed schema /configs/.system/schema.xml to 
/configs/.system/schema.xml.bak
   [junit4]   2> 282942 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_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> 282943 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_41817.solr.core..system.shard1.replica_n2' (registry 
'solr.core..system.shard1.replica_n2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@e585c41
   [junit4]   2> 282943 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_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_7AF1013C51641E34-001/tempDir-001/node1/.system_shard1_replica_n2],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_7AF1013C51641E34-001/tempDir-001/node1/./.system_shard1_replica_n2/data/]
   [junit4]   2> 282978 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_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> 282978 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_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> 282978 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_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> 282978 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_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> 282979 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_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> 282979 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 282980 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_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> 282980 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 282980 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@de44a82[.system_shard1_replica_n1] 
main]
   [junit4]   2> 282980 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@45338895[.system_shard1_replica_n2] 
main]
   [junit4]   2> 282981 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_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> 282981 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_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> 282981 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_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> 282981 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_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> 282981 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_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> 282981 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_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> 282981 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_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> 282981 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_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> 282982 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_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 
1660309442299691008
   [junit4]   2> 282982 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ZkIndexSchemaReader Retrieved schema version 0 from ZooKeeper
   [junit4]   2> 282983 INFO  
(searcherExecutor-2024-thread-1-processing-n:127.0.0.1:41817_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:41817_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@de44a82[.system_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 282983 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_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> 282988 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/.system/terms/shard1 to Terms{values={core_node3=0}, version=0}
   [junit4]   2> 282988 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_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> 282988 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_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> 282988 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 6 ms
   [junit4]   2> 282989 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_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> 282989 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_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 
1660309442307031040
   [junit4]   2> 282992 INFO  
(searcherExecutor-2025-thread-1-processing-n:127.0.0.1:41817_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:41817_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@45338895[.system_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 282994 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for 
shard shard1: total=2 found=1 timeoutin=9999ms
   [junit4]   2> 282994 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/.system/terms/shard1 to Terms{values={core_node3=0, core_node4=0}, 
version=1}
   [junit4]   2> 282994 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_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> 283495 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 283495 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 283495 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:41817/solr/.system_shard1_replica_n1/
   [junit4]   2> 283496 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.PeerSync 
PeerSync: core=.system_shard1_replica_n1 url=http://127.0.0.1:41817/solr START 
replicas=[http://127.0.0.1:41817/solr/.system_shard1_replica_n2/] nUpdates=100
   [junit4]   2> 283496 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.PeerSync 
PeerSync: core=.system_shard1_replica_n1 url=http://127.0.0.1:41817/solr DONE.  
We have no versions.  sync failed.
   [junit4]   2> 283498 INFO  (qtp731699956-6447) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 283498 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the 
next candidate
   [junit4]   2> 283498 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we 
can't sync in that case - we were active before, so become leader anyway
   [junit4]   2> 283498 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node 
/collections/.system/leaders/shard1/leader after winning as 
/collections/.system/leader_elect/shard1/election/72124170729488392-core_node3-n_0000000000
   [junit4]   2> 283500 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:41817/solr/.system_shard1_replica_n1/ shard1
   [junit4]   2> 283601 INFO  (zkCallback-1742-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> 283601 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 283602 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_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=685
   [junit4]   2> 283703 INFO  (zkCallback-1742-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> 283703 INFO  (zkCallback-1742-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> 283997 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_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=1080
   [junit4]   2> 283999 INFO  (qtp731699956-6430) [n:127.0.0.1:41817_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> 284098 INFO  (zkCallback-1742-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> 284098 INFO  (zkCallback-1742-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> 284098 INFO  (qtp731699956-6430) [n:127.0.0.1:41817_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:41817_solr&wt=javabin&version=2}
 status=0 QTime=1491
   [junit4]   2> 284098 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[7AF1013C51641E34]) [     
] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: .system
   [junit4]   2> 284103 INFO  (qtp731699956-6447) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/.system/terms/shard1 to Terms{values={core_node3=1, core_node4=1}, 
version=2}
   [junit4]   2> 284105 INFO  (qtp731699956-6430) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41817/solr/.system_shard1_replica_n1/&wt=javabin&version=2}{add=[170a9aa4635T7m6qd4f9etnnuad4s4czj3ilg
 (1660309443471998976)]} 0 1
   [junit4]   2> 284105 INFO  (qtp731699956-6447) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n1]  webapp=/solr 
path=/update 
params={wt=javabin&version=2}{add=[170a9aa4635T7m6qd4f9etnnuad4s4czj3ilg 
(1660309443471998976)]} 0 5
   [junit4]   2> 284106 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1660309443478290432,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 284106 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_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@4d52b0d7 
commitCommandVersion:1660309443478290432
   [junit4]   2> 284108 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1660309443480387584,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 284108 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_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@461a579f 
commitCommandVersion:1660309443480387584
   [junit4]   2> 284109 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@415a502[.system_shard1_replica_n1] 
main]
   [junit4]   2> 284109 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@4dfaf3fc[.system_shard1_replica_n2] 
main]
   [junit4]   2> 284109 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 284109 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 284110 INFO  
(searcherExecutor-2025-thread-1-processing-n:127.0.0.1:41817_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:41817_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@4dfaf3fc[.system_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={java.version=11.0.6,
 java.vm.version=11.0.6+10, lucene.version=9.0.0, source=flush, os.arch=amd64, 
java.runtime.version=11.0.6+10, os.version=5.3.0-40-generic, os=Linux, 
java.vendor=AdoptOpenJDK, 
timestamp=1583394473535}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 284110 INFO  
(searcherExecutor-2024-thread-1-processing-n:127.0.0.1:41817_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:41817_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@415a502[.system_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={java.version=11.0.6,
 java.vm.version=11.0.6+10, lucene.version=9.0.0, source=flush, os.arch=amd64, 
java.runtime.version=11.0.6+10, os.version=5.3.0-40-generic, os=Linux, 
java.vendor=AdoptOpenJDK, 
timestamp=1583394473534}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 284110 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:41817/solr/.system_shard1_replica_n1/&commit_end_point=replicas&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 2
   [junit4]   2> 284111 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n1]  webapp=/solr 
path=/update 
params={_stateVer_=.system:5&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 4
   [junit4]   2> 284113 INFO  (qtp731699956-6430) [n:127.0.0.1:41817_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> 284115 INFO  (qtp731699956-6447) [n:127.0.0.1:41817_solr     ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={action=STATUS&indexInfo=true&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 284117 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_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> 284121 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.SchemaManager managed schema loaded . version : 1 
   [junit4]   2> 284122 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_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> 284125 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_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> 284128 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.c.ZkController Persisted config data to node 
/configs/.system/schema.xml.bak 
   [junit4]   2> 284129 INFO  (Thread-1075) [     ] o.a.s.c.SolrCore config 
update listener called for core .system_shard1_replica_n1
   [junit4]   2> 284129 INFO  (Thread-1075) [     ] o.a.s.c.SolrCore core 
reload .system_shard1_replica_n1
   [junit4]   2> 284132 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_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> 284138 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_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> 284140 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_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> 284141 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.c.CoreContainer Reloading SolrCore '.system_shard1_replica_n1' using 
configuration from configset .system
   [junit4]   2> 284168 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_41817.solr.core..system.shard1.replica_n1' (registry 
'solr.core..system.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@e585c41
   [junit4]   2> 284168 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_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_7AF1013C51641E34-001/tempDir-001/node1/.system_shard1_replica_n1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_7AF1013C51641E34-001/tempDir-001/node1/./.system_shard1_replica_n1/data/]
   [junit4]   2> 284201 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_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> 284201 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 284202 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@3dbf6f48[.system_shard1_replica_n1] 
main]
   [junit4]   2> 284203 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_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> 284204 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_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> 284204 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_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> 284204 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_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> 284204 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_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> 284206 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 284206 INFO  
(searcherExecutor-2034-thread-1-processing-n:127.0.0.1:41817_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:41817_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@3dbf6f48[.system_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={java.version=11.0.6,
 java.vm.version=11.0.6+10, lucene.version=9.0.0, source=flush, os.arch=amd64, 
java.runtime.version=11.0.6+10, os.version=5.3.0-40-generic, os=Linux, 
java.vendor=AdoptOpenJDK, 
timestamp=1583394473534}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 284207 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@7341dd93[.system_shard1_replica_n1] 
main]
   [junit4]   2> 284208 INFO  
(searcherExecutor-2034-thread-1-processing-n:127.0.0.1:41817_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:41817_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@7341dd93[.system_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={java.version=11.0.6,
 java.vm.version=11.0.6+10, lucene.version=9.0.0, source=flush, os.arch=amd64, 
java.runtime.version=11.0.6+10, os.version=5.3.0-40-generic, 
java.vendor=AdoptOpenJDK, os=Linux, 
timestamp=1583394473534}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 284210 INFO  (Thread-1075) [     ] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 9.0.0
   [junit4]   2> 284210 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.ManagedIndexSchema Waiting up to 599 secs for 1 replicas to apply 
schema update version 2 for collection .system
   [junit4]   2> 284216 INFO  (qtp731699956-6447) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 284216 INFO  (qtp731699956-6447) [n:127.0.0.1:41817_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> 284216 INFO  (qtp731699956-6447) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 284217 ERROR 
(managedSchemaExecutor-2038-thread-1-processing-n:127.0.0.1:41817_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:41817_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:41817/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 284218 INFO  (Thread-1075) [     ] o.a.s.s.IndexSchema 
[.system_shard1_replica_n1] Schema name=_system collection or core
   [junit4]   2> 284218 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 284218 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_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> 284218 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 284218 ERROR 
(managedSchemaExecutor-2038-thread-1-processing-n:127.0.0.1:41817_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:41817_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:41817/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 284219 INFO  (qtp731699956-6430) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 284219 INFO  (qtp731699956-6430) [n:127.0.0.1:41817_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> 284219 INFO  (qtp731699956-6430) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 284219 ERROR 
(managedSchemaExecutor-2038-thread-1-processing-n:127.0.0.1:41817_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:41817_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:41817/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 284220 INFO  (qtp731699956-6447) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 284220 INFO  (qtp731699956-6447) [n:127.0.0.1:41817_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> 284220 INFO  (qtp731699956-6447) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 284220 ERROR 
(managedSchemaExecutor-2038-thread-1-processing-n:127.0.0.1:41817_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:41817_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:41817/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 284220 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 284220 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_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> 284220 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 284221 ERROR 
(managedSchemaExecutor-2038-thread-1-processing-n:127.0.0.1:41817_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:41817_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:41817/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 284221 INFO  (Thread-1075) [     ] o.a.s.s.IndexSchema Loaded 
schema _system collection or core/1.1 with uniqueid field id
   [junit4]   2> 284221 INFO  (Thread-1075) [     ] o.a.s.c.CoreContainer 
Reloading SolrCore '.system_shard1_replica_n1' using configuration from 
configset .system
   [junit4]   2> 284222 INFO  (qtp731699956-6430) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 284222 INFO  (qtp731699956-6430) [n:127.0.0.1:41817_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> 284222 INFO  (qtp731699956-6430) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 284222 ERROR 
(managedSchemaExecutor-2038-thread-1-processing-n:127.0.0.1:41817_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:41817_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:41817/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 284222 INFO  (qtp731699956-6447) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 284222 INFO  (qtp731699956-6447) [n:127.0.0.1:41817_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> 284222 INFO  (qtp731699956-6447) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 284222 ERROR 
(managedSchemaExecutor-2038-thread-1-processing-n:127.0.0.1:41817_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:41817_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:41817/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 284223 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 284223 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_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> 284223 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 284223 ERROR 
(managedSchemaExecutor-2038-thread-1-processing-n:127.0.0.1:41817_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:41817_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:41817/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 284223 INFO  (qtp731699956-6430) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 284223 INFO  (qtp731699956-6430) [n:127.0.0.1:41817_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> 284223 INFO  (qtp731699956-6430) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 284223 ERROR 
(managedSchemaExecutor-2038-thread-1-processing-n:127.0.0.1:41817_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:41817_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:41817/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 284224 INFO  (qtp731699956-6447) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 284224 INFO  (qtp731699956-6447) [n:127.0.0.1:41817_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> 284224 INFO  (qtp731699956-6447) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 284224 ERROR 
(managedSchemaExecutor-2038-thread-1-processing-n:127.0.0.1:41817_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:41817_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:41817/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 284224 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 284225 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_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> 284225 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 284225 ERROR 
(managedSchemaExecutor-2038-thread-1-processing-n:127.0.0.1:41817_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:41817_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:41817/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 284225 INFO  (qtp731699956-6430) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 284225 INFO  (qtp731699956-6430) [n:127.0.0.1:41817_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> 284225 INFO  (qtp731699956-6430) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 284225 ERROR 
(managedSchemaExecutor-2038-thread-1-processing-n:127.0.0.1:41817_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:41817_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:41817/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 284226 INFO  (qtp731699956-6447) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 284226 INFO  (qtp731699956-6447) [n:127.0.0.1:41817_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> 284226 INFO  (qtp731699956-6447) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 284226 ERROR 
(managedSchemaExecutor-2038-thread-1-processing-n:127.0.0.1:41817_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:41817_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:41817/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 284227 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 284227 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_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> 284227 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 284227 ERROR 
(managedSchemaExecutor-2038-thread-1-processing-n:127.0.0.1:41817_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:41817_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:41817/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 284228 INFO  (qtp731699956-6430) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 284228 INFO  (qtp731699956-6430) [n:127.0.0.1:41817_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> 284228 INFO  (qtp731699956-6430) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 284228 ERROR 
(managedSchemaExecutor-2038-thread-1-processing-n:127.0.0.1:41817_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:41817_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:41817/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 284231 INFO  (qtp731699956-6447) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 284231 INFO  (qtp731699956-6447) [n:127.0.0.1:41817_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> 284231 INFO  (qtp731699956-6447) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 284231 ERROR 
(managedSchemaExecutor-2038-thread-1-processing-n:127.0.0.1:41817_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:41817_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:41817/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 284232 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 284232 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_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> 284232 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 284232 ERROR 
(managedSchemaExecutor-2038-thread-1-processing-n:127.0.0.1:41817_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:41817_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:41817/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 284232 INFO  (qtp731699956-6430) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 284232 INFO  (qtp731699956-6430) [n:127.0.0.1:41817_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> 284232 INFO  (qtp731699956-6430) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 284232 ERROR 
(managedSchemaExecutor-2038-thread-1-processing-n:127.0.0.1:41817_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:41817_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:41817/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 284233 INFO  (qtp731699956-6447) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 284233 INFO  (qtp731699956-6447) [n:127.0.0.1:41817_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> 284233 INFO  (qtp731699956-6447) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 284233 ERROR 
(managedSchemaExecutor-2038-thread-1-processing-n:127.0.0.1:41817_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:41817_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:41817/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 284233 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 284233 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_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> 284233 INFO  (qtp731699956-6438) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 284233 ERROR 
(managedSchemaExecutor-2038-thread-1-processing-n:127.0.0.1:41817_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:41817_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:41817/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 284234 INFO  (qtp731699956-6430) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 284234 INFO  (qtp731699956-6430) [n:127.0.0.1:41817_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> 284234 INFO  (qtp731699956-6430) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 284234 ERROR 
(managedSchemaExecutor-2038-thread-1-processing-n:127.0.0.1:41817_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:41817_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:41817/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 284234 INFO  (qtp731699956-6447) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 284234 INFO  (qtp731699956-6447) [n:127.0.0.1:41817_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> 284234 INFO  (qtp731699956-6447) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} 

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

solrj.SolrServerException: Server refused connection at: 
http://127.0.0.1:41817/solr/.system_shard1_replica_n2
   [junit4]   2> 417683 WARN  
(managedSchemaExecutor-2038-thread-1-processing-n:127.0.0.1:41817_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:41817_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Failed to get 
/schema/zkversion from http://127.0.0.1:41817/solr/.system_shard1_replica_n2/ 
due to: java.util.concurrent.ExecutionException: 
org.apache.solr.client.solrj.SolrServerException: Server refused connection at: 
http://127.0.0.1:41817/solr/.system_shard1_replica_n2
   [junit4]   2> 418707 WARN  
(managedSchemaExecutor-2038-thread-1-processing-n:127.0.0.1:41817_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:41817_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Failed to get 
/schema/zkversion from http://127.0.0.1:41817/solr/.system_shard1_replica_n2/ 
due to: java.util.concurrent.ExecutionException: 
org.apache.solr.client.solrj.SolrServerException: Server refused connection at: 
http://127.0.0.1:41817/solr/.system_shard1_replica_n2
   [junit4]   2> 419731 WARN  
(managedSchemaExecutor-2038-thread-1-processing-n:127.0.0.1:41817_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:41817_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Failed to get 
/schema/zkversion from http://127.0.0.1:41817/solr/.system_shard1_replica_n2/ 
due to: java.util.concurrent.ExecutionException: 
org.apache.solr.client.solrj.SolrServerException: Server refused connection at: 
http://127.0.0.1:41817/solr/.system_shard1_replica_n2
   [junit4]   2> 420048 INFO  
(httpUriRequest-329160-thread-1-processing-x:.system_shard1_replica_n1 
r:core_node3 n:127.0.0.1:41817_solr 
http:////127.0.0.1:41817//solr//.system_shard1_replica_n2 c:.system s:shard1) 
[n:127.0.0.1:41817_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.h.i.e.RetryExec I/O exception 
(java.net.SocketException) caught when processing request to 
{}->http://127.0.0.1:41817: Socket closed
   [junit4]   2> 420048 INFO  
(httpUriRequest-329160-thread-1-processing-x:.system_shard1_replica_n1 
r:core_node3 n:127.0.0.1:41817_solr 
http:////127.0.0.1:41817//solr//.system_shard1_replica_n2 c:.system s:shard1) 
[n:127.0.0.1:41817_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.h.i.e.RetryExec Retrying request to 
{}->http://127.0.0.1:41817
   [junit4]   2> 420050 WARN  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.ManagedIndexSchema Core core_node3 was interrupted waiting for schema 
version 2 to propagate to 1 replicas for collection .system
   [junit4]   2> 420050 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.ManagedIndexSchema Took 135843.0ms for 1 replicas to apply schema 
update version 2 for collection .system
   [junit4]   2> 420051 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_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=135930
   [junit4]   2> 420051 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore 
[.system_shard1_replica_n1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@4845c63a
   [junit4]   2> 420051 WARN  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.m.SolrMetricManager Interrupted while trying to obtain lock to modify 
reporters registry: solr.core..system.shard1.replica_n1
   [junit4]   2> 420051 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.collection..system.shard1.leader, tag=SolrCore@4845c63a
   [junit4]   2> 420051 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() .
   [junit4]   2> 420051 INFO  (qtp731699956-6439) [n:127.0.0.1:41817_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@4a1af0b7 commitCommandVersion:0
   [junit4]   2> 420053 INFO  
(SUITE-SystemCollectionCompatTest-seed#[7AF1013C51641E34]-worker) [     ] 
o.a.s.c.ZkTestServer Shutting down ZkTestServer.
   [junit4]   2> 420257 WARN  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer Watch limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        7       /solr/clusterprops.json
   [junit4]   2>        7       /solr/aliases.json
   [junit4]   2>        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>        14      /solr/collections/.system/state.json
   [junit4]   2>        7       /solr/clusterstate.json
   [junit4]   2>        6       /solr/aliases.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        24      /solr/overseer/queue
   [junit4]   2>        18      /solr/collections
   [junit4]   2>        12      /solr/overseer/collection-queue-work
   [junit4]   2>        11      /solr/live_nodes
   [junit4]   2> 
   [junit4]   2> 420259 INFO  
(SUITE-SystemCollectionCompatTest-seed#[7AF1013C51641E34]-worker) [     ] 
o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:42813
   [junit4]   2> 420259 INFO  
(SUITE-SystemCollectionCompatTest-seed#[7AF1013C51641E34]-worker) [     ] 
o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:42813
   [junit4]   2> 420259 INFO  
(SUITE-SystemCollectionCompatTest-seed#[7AF1013C51641E34]-worker) [     ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1 42813
   [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_7AF1013C51641E34-001
   [junit4]   2> Mar 05, 2020 7:50:09 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), 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@1f71eaa5),
 locale=seh-MZ, timezone=Europe/Saratov
   [junit4]   2> NOTE: Linux 5.3.0-40-generic amd64/AdoptOpenJDK 11.0.6 
(64-bit)/cpus=16,threads=1,free=238648696,total=450363392
   [junit4]   2> NOTE: All tests run in this JVM: [CollectionStateFormat2Test, 
TestDistribDocBasedVersion, DistributedFacetSimpleRefinementLongTailTest, 
HdfsBasicDistributedZkTest, TestDocBasedVersionConstraints, TermsComponentTest, 
DistributedDebugComponentTest, TestSolrJacksonAnnotation, UUIDFieldTest, 
NodeAddedTriggerIntegrationTest, TestIBSimilarityFactory, 
SignificantTermsQParserPluginTest, ZookeeperStatusHandlerTest, 
PropertiesRequestHandlerTest, BasicZkTest, TestRawTransformer, 
TestSimComputePlanAction, TestGeoJSONResponseWriter, TestExportWriter, 
TestCharFilters, IndexSchemaRuntimeFieldTest, MigrateRouteKeyTest, 
TestSimUtils, BigEndianAscendingWordSerializerTest, SolrIndexSplitterTest, 
CleanupOldIndexTest, TestExclusionRuleCollectionAccess, TestPolicyCloud, 
TestLuceneIndexBackCompat, DateRangeFieldTest, TestExactStatsCache, 
OverseerModifyCollectionTest, DistributedSpellCheckComponentTest, 
AtomicUpdatesTest, TestCollectionAPI, 
TestPerFieldSimilarityWithDefaultOverride, SliceStateTest, 
TestSolrConfigHandler, TestFieldTypeCollectionResource, TestSimLargeCluster, 
HttpTriggerListenerTest, LargeFieldTest, DocumentAnalysisRequestHandlerTest, 
TestExtendedDismaxParser, TestReRankQParserPlugin, 
UUIDUpdateProcessorFallbackTest, SystemCollectionCompatTest]
   [junit4] Completed [324/905 (1!)] on J0 in 139.38s, 1 test, 1 error <<< 
FAILURES!

[...truncated 54167 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:635: The following 
error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:101: The following 
error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build.xml:625: The 
following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build.xml:157: The 
following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/solr-ref-guide/build.xml:289:
 Java returned: 255

Total time: 44 minutes 26 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Setting 
ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Setting 
ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
Setting 
ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting 
ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting 
ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting 
ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting 
ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting 
ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to