Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8481/
Java: 64bit/jdk-12.0.2 -XX:+UseCompressedOops -XX:+UseG1GC

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:64881/solr/.system]

Stack Trace:
org.apache.solr.client.solrj.SolrServerException: No live SolrServers available 
to handle this request:[http://127.0.0.1:64881/solr/.system]
        at 
__randomizedtesting.SeedInfo.seed([66D7E5A9BB572680:16224600DB9F8FF6]:0)
        at 
org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:345)
        at 
org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1143)
        at 
org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:906)
        at 
org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:838)
        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207)
        at 
org.apache.solr.cloud.SystemCollectionCompatTest.setupSystemCollection(SystemCollectionCompatTest.java:118)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:567)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:976)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
        at java.base/java.lang.Thread.run(Thread.java:835)
Caused by: org.apache.solr.client.solrj.SolrServerException: Timeout occurred 
while waiting response from server at: http://127.0.0.1:64881/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 13502 lines...]
   [junit4] Suite: org.apache.solr.cloud.SystemCollectionCompatTest
   [junit4]   2> 306260 INFO  
(SUITE-SystemCollectionCompatTest-seed#[66D7E5A9BB572680]-worker) [     ] 
o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to 
test-framework derived value of 
'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\server\solr\configsets\_default\conf'
   [junit4]   2> 306260 INFO  
(SUITE-SystemCollectionCompatTest-seed#[66D7E5A9BB572680]-worker) [     ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 306260 INFO  
(SUITE-SystemCollectionCompatTest-seed#[66D7E5A9BB572680]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Created dataDir: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.SystemCollectionCompatTest_66D7E5A9BB572680-001\data-dir-44-001
   [junit4]   2> 306260 WARN  
(SUITE-SystemCollectionCompatTest-seed#[66D7E5A9BB572680]-worker) [     ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=81 numCloses=81
   [junit4]   2> 306261 INFO  
(SUITE-SystemCollectionCompatTest-seed#[66D7E5A9BB572680]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 306262 INFO  
(SUITE-SystemCollectionCompatTest-seed#[66D7E5A9BB572680]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, 
clientAuth=0.0/0.0)
   [junit4]   2> 306263 INFO  
(SUITE-SystemCollectionCompatTest-seed#[66D7E5A9BB572680]-worker) [     ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.SystemCollectionCompatTest_66D7E5A9BB572680-001\tempDir-001
   [junit4]   2> 306263 INFO  
(SUITE-SystemCollectionCompatTest-seed#[66D7E5A9BB572680]-worker) [     ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 306263 INFO  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 306263 INFO  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 306364 INFO  
(SUITE-SystemCollectionCompatTest-seed#[66D7E5A9BB572680]-worker) [     ] 
o.a.s.c.ZkTestServer start zk server on port:64868
   [junit4]   2> 306364 INFO  
(SUITE-SystemCollectionCompatTest-seed#[66D7E5A9BB572680]-worker) [     ] 
o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:64868
   [junit4]   2> 306364 INFO  
(SUITE-SystemCollectionCompatTest-seed#[66D7E5A9BB572680]-worker) [     ] 
o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:64868
   [junit4]   2> 306364 INFO  
(SUITE-SystemCollectionCompatTest-seed#[66D7E5A9BB572680]-worker) [     ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1 64868
   [junit4]   2> 306367 INFO  
(SUITE-SystemCollectionCompatTest-seed#[66D7E5A9BB572680]-worker) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 306372 INFO  (zkConnectionManagerCallback-1379-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 306372 INFO  
(SUITE-SystemCollectionCompatTest-seed#[66D7E5A9BB572680]-worker) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 306376 INFO  
(SUITE-SystemCollectionCompatTest-seed#[66D7E5A9BB572680]-worker) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 306378 INFO  (zkConnectionManagerCallback-1381-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 306378 INFO  
(SUITE-SystemCollectionCompatTest-seed#[66D7E5A9BB572680]-worker) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 306379 INFO  
(SUITE-SystemCollectionCompatTest-seed#[66D7E5A9BB572680]-worker) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 306381 INFO  (zkConnectionManagerCallback-1383-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 306381 INFO  
(SUITE-SystemCollectionCompatTest-seed#[66D7E5A9BB572680]-worker) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 306487 WARN  (jetty-launcher-1384-thread-1) [     ] 
o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, 
break even is size 23
   [junit4]   2> 306487 INFO  (jetty-launcher-1384-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 306487 INFO  (jetty-launcher-1384-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 306487 WARN  (jetty-launcher-1384-thread-2) [     ] 
o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, 
break even is size 23
   [junit4]   2> 306487 INFO  (jetty-launcher-1384-thread-1) [     ] 
o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 
363d5f2df3a8a28de40604320230664b9c793c16; jvm 12.0.2+10
   [junit4]   2> 306487 INFO  (jetty-launcher-1384-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 306487 INFO  (jetty-launcher-1384-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 306487 INFO  (jetty-launcher-1384-thread-2) [     ] 
o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 
363d5f2df3a8a28de40604320230664b9c793c16; jvm 12.0.2+10
   [junit4]   2> 306488 INFO  (jetty-launcher-1384-thread-2) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 306488 INFO  (jetty-launcher-1384-thread-2) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 306488 INFO  (jetty-launcher-1384-thread-2) [     ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 306488 INFO  (jetty-launcher-1384-thread-1) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 306488 INFO  (jetty-launcher-1384-thread-1) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 306488 INFO  (jetty-launcher-1384-thread-1) [     ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 306488 INFO  (jetty-launcher-1384-thread-2) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@5b54fbbc{/solr,null,AVAILABLE}
   [junit4]   2> 306488 INFO  (jetty-launcher-1384-thread-1) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@13d78a9{/solr,null,AVAILABLE}
   [junit4]   2> 306490 INFO  (jetty-launcher-1384-thread-1) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@4280c255{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:?????}
   [junit4]   2> 306491 INFO  (jetty-launcher-1384-thread-1) [     ] 
o.e.j.s.Server Started @??????ms
   [junit4]   2> 306491 INFO  (jetty-launcher-1384-thread-2) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@7dd47f6a{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:?????}
   [junit4]   2> 306491 INFO  (jetty-launcher-1384-thread-2) [     ] 
o.e.j.s.Server Started @??????ms
   [junit4]   2> 306491 INFO  (jetty-launcher-1384-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=64882}
   [junit4]   2> 306491 INFO  (jetty-launcher-1384-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=64881}
   [junit4]   2> 306491 ERROR (jetty-launcher-1384-thread-1) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 306491 ERROR (jetty-launcher-1384-thread-2) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 306491 INFO  (jetty-launcher-1384-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 306491 INFO  (jetty-launcher-1384-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 306491 INFO  (jetty-launcher-1384-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
9.0.0
   [junit4]   2> 306491 INFO  (jetty-launcher-1384-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
9.0.0
   [junit4]   2> 306491 INFO  (jetty-launcher-1384-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 306491 INFO  (jetty-launcher-1384-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 306491 INFO  (jetty-launcher-1384-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
   [junit4]   2> 306491 INFO  (jetty-launcher-1384-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
   [junit4]   2> 306491 INFO  (jetty-launcher-1384-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-02-17T03:32:09.504252400Z
   [junit4]   2> 306491 INFO  (jetty-launcher-1384-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-02-17T03:32:09.504252400Z
   [junit4]   2> 306493 INFO  (jetty-launcher-1384-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 306493 INFO  (jetty-launcher-1384-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 306495 INFO  (zkConnectionManagerCallback-1387-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 306495 INFO  (zkConnectionManagerCallback-1388-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 306495 INFO  (jetty-launcher-1384-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 306495 INFO  (jetty-launcher-1384-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 306495 INFO  (jetty-launcher-1384-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 306495 INFO  (jetty-launcher-1384-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 306738 INFO  (jetty-launcher-1384-thread-1) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 306740 WARN  (jetty-launcher-1384-thread-1) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@14e0cc9e[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 306740 WARN  (jetty-launcher-1384-thread-1) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@14e0cc9e[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 306743 WARN  (jetty-launcher-1384-thread-1) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@2dfb68e6[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 306743 WARN  (jetty-launcher-1384-thread-1) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@2dfb68e6[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 306744 INFO  (jetty-launcher-1384-thread-1) [     ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64868/solr
   [junit4]   2> 306745 INFO  (jetty-launcher-1384-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 306747 INFO  (zkConnectionManagerCallback-1396-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 306747 INFO  (jetty-launcher-1384-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 306850 INFO  (jetty-launcher-1384-thread-1) 
[n:127.0.0.1:64882_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 306852 INFO  (zkConnectionManagerCallback-1398-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 306852 INFO  (jetty-launcher-1384-thread-1) 
[n:127.0.0.1:64882_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 306867 INFO  (jetty-launcher-1384-thread-2) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 306869 WARN  (jetty-launcher-1384-thread-2) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@479457b3[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 306869 WARN  (jetty-launcher-1384-thread-2) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@479457b3[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 306874 WARN  (jetty-launcher-1384-thread-2) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@2de767db[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 306874 WARN  (jetty-launcher-1384-thread-2) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@2de767db[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 306874 INFO  (jetty-launcher-1384-thread-2) [     ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64868/solr
   [junit4]   2> 306876 INFO  (jetty-launcher-1384-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 306880 INFO  (zkConnectionManagerCallback-1406-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 306880 INFO  (jetty-launcher-1384-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 306976 INFO  (jetty-launcher-1384-thread-1) 
[n:127.0.0.1:64882_solr     ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:64882_solr
   [junit4]   2> 306976 INFO  (jetty-launcher-1384-thread-1) 
[n:127.0.0.1:64882_solr     ] o.a.s.c.Overseer Overseer 
(id=72086358360981510-127.0.0.1:64882_solr-n_0000000000) starting
   [junit4]   2> 306984 INFO  (jetty-launcher-1384-thread-2) 
[n:127.0.0.1:64881_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 306984 INFO  (jetty-launcher-1384-thread-1) 
[n:127.0.0.1:64882_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:64882_solr
   [junit4]   2> 306985 INFO  
(OverseerStateUpdate-72086358360981510-127.0.0.1:64882_solr-n_0000000000) 
[n:127.0.0.1:64882_solr     ] o.a.s.c.Overseer Starting to work on the main 
queue : 127.0.0.1:64882_solr
   [junit4]   2> 306988 INFO  (zkConnectionManagerCallback-1408-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 306988 INFO  (jetty-launcher-1384-thread-2) 
[n:127.0.0.1:64881_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 306988 INFO  
(OverseerStateUpdate-72086358360981510-127.0.0.1:64882_solr-n_0000000000) 
[n:127.0.0.1:64882_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 306989 INFO  (jetty-launcher-1384-thread-1) 
[n:127.0.0.1:64882_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 306989 WARN  (jetty-launcher-1384-thread-1) 
[n:127.0.0.1:64882_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> 306996 INFO  (jetty-launcher-1384-thread-2) 
[n:127.0.0.1:64881_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 307000 INFO  (jetty-launcher-1384-thread-2) 
[n:127.0.0.1:64881_solr     ] o.a.s.c.ZkController Publish 
node=127.0.0.1:64881_solr as DOWN
   [junit4]   2> 307000 INFO  (jetty-launcher-1384-thread-2) 
[n:127.0.0.1:64881_solr     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 307000 INFO  (jetty-launcher-1384-thread-2) 
[n:127.0.0.1:64881_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:64881_solr
   [junit4]   2> 307000 INFO  (zkCallback-1397-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 307004 INFO  (zkCallback-1407-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 307004 INFO  (jetty-launcher-1384-thread-2) 
[n:127.0.0.1:64881_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 307006 WARN  (jetty-launcher-1384-thread-2) 
[n:127.0.0.1:64881_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> 307018 INFO  (jetty-launcher-1384-thread-1) 
[n:127.0.0.1:64882_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 307032 INFO  (jetty-launcher-1384-thread-2) 
[n:127.0.0.1:64881_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 307050 INFO  (jetty-launcher-1384-thread-1) 
[n:127.0.0.1:64882_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_64882.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2aaf169f
   [junit4]   2> 307052 INFO  (jetty-launcher-1384-thread-2) 
[n:127.0.0.1:64881_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_64881.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2aaf169f
   [junit4]   2> 307059 INFO  (jetty-launcher-1384-thread-1) 
[n:127.0.0.1:64882_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_64882.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2aaf169f
   [junit4]   2> 307059 INFO  (jetty-launcher-1384-thread-1) 
[n:127.0.0.1:64882_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_64882.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2aaf169f
   [junit4]   2> 307060 INFO  (jetty-launcher-1384-thread-2) 
[n:127.0.0.1:64881_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_64881.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2aaf169f
   [junit4]   2> 307060 INFO  (jetty-launcher-1384-thread-2) 
[n:127.0.0.1:64881_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_64881.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2aaf169f
   [junit4]   2> 307060 INFO  (jetty-launcher-1384-thread-1) 
[n:127.0.0.1:64882_solr     ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.SystemCollectionCompatTest_66D7E5A9BB572680-001\tempDir-001\node1\.
   [junit4]   2> 307062 INFO  (jetty-launcher-1384-thread-2) 
[n:127.0.0.1:64881_solr     ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.SystemCollectionCompatTest_66D7E5A9BB572680-001\tempDir-001\node2\.
   [junit4]   2> 307109 INFO  
(SUITE-SystemCollectionCompatTest-seed#[66D7E5A9BB572680]-worker) [     ] 
o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
   [junit4]   2> 307110 INFO  
(SUITE-SystemCollectionCompatTest-seed#[66D7E5A9BB572680]-worker) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 307112 INFO  (zkConnectionManagerCallback-1419-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 307112 INFO  
(SUITE-SystemCollectionCompatTest-seed#[66D7E5A9BB572680]-worker) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 307112 INFO  
(SUITE-SystemCollectionCompatTest-seed#[66D7E5A9BB572680]-worker) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 307112 INFO  
(SUITE-SystemCollectionCompatTest-seed#[66D7E5A9BB572680]-worker) [     ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:64868/solr ready
   [junit4]   2> 307139 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[66D7E5A9BB572680]) [     
] o.a.s.SolrTestCaseJ4 ###Starting testBackCompat
   [junit4]   2> 307141 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[66D7E5A9BB572680]) [     
] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 307141 INFO  (zkConnectionManagerCallback-1424-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 307141 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[66D7E5A9BB572680]) [     
] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 307144 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[66D7E5A9BB572680]) [     
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 307144 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[66D7E5A9BB572680]) [     
] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:64868/solr ready
   [junit4]   2> 307148 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_solr     
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with 
params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 307156 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=7
   [junit4]   2> 307156 INFO  (qtp1930661752-4086) [n:127.0.0.1:64881_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:64881_solr&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 307169 INFO  
(OverseerThreadFactory-1406-thread-2-processing-n:127.0.0.1:64882_solr) 
[n:127.0.0.1:64882_solr     ] o.a.s.c.a.c.CreateCollectionCmd Create collection 
.system
   [junit4]   2> 307169 INFO  
(OverseerCollectionConfigSetProcessor-72086358360981510-127.0.0.1:64882_solr-n_0000000000)
 [n:127.0.0.1:64882_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> 307273 WARN  
(OverseerThreadFactory-1406-thread-2-processing-n:127.0.0.1:64882_solr) 
[n:127.0.0.1:64882_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> 307276 INFO  
(OverseerStateUpdate-72086358360981510-127.0.0.1:64882_solr-n_0000000000) 
[n:127.0.0.1:64882_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:64881/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:64881_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 307280 INFO  
(OverseerStateUpdate-72086358360981510-127.0.0.1:64882_solr-n_0000000000) 
[n:127.0.0.1:64882_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:64881/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:64881_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 307484 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 307484 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 307500 WARN  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 307500 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 307500 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 307512 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 307512 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 307514 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 307514 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 307516 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 307516 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 307516 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 307516 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 307520 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 307521 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_64881.solr.core..system.shard1.replica_n1' (registry 
'solr.core..system.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2aaf169f
   [junit4]   2> 307521 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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 
[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.SystemCollectionCompatTest_66D7E5A9BB572680-001\tempDir-001\node2\.system_shard1_replica_n1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.SystemCollectionCompatTest_66D7E5A9BB572680-001\tempDir-001\node2\.\.system_shard1_replica_n1\data\]
   [junit4]   2> 307522 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 307524 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 307524 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_64881.solr.core..system.shard1.replica_n2' (registry 
'solr.core..system.shard1.replica_n2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2aaf169f
   [junit4]   2> 307524 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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 
[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.SystemCollectionCompatTest_66D7E5A9BB572680-001\tempDir-001\node2\.system_shard1_replica_n2],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.SystemCollectionCompatTest_66D7E5A9BB572680-001\tempDir-001\node2\.\.system_shard1_replica_n2\data\]
   [junit4]   2> 307608 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 307608 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 307610 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 307610 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 307613 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 307613 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 307613 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@3a183ca3[.system_shard1_replica_n1] 
main]
   [junit4]   2> 307615 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 307615 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 307615 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 307615 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 307615 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 307616 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 307616 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@747b7db[.system_shard1_replica_n2] 
main]
   [junit4]   2> 307616 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 307616 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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 
1658753206849634304
   [junit4]   2> 307616 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 307616 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 307616 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 307616 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 307616 INFO  
(searcherExecutor-1415-thread-1-processing-n:127.0.0.1:64881_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:64881_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@3a183ca3[.system_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 307620 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 307620 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 307622 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 307625 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 307625 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 7 ms
   [junit4]   2> 307625 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 307627 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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 
1658753206861168640
   [junit4]   2> 307628 INFO  
(searcherExecutor-1416-thread-1-processing-n:127.0.0.1:64881_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:64881_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@747b7db[.system_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 307629 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 307631 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 307631 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 308131 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 308131 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 308131 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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:64881/solr/.system_shard1_replica_n1/
   [junit4]   2> 308132 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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:64881/solr START 
replicas=[http://127.0.0.1:64881/solr/.system_shard1_replica_n2/] nUpdates=100
   [junit4]   2> 308132 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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:64881/solr DONE.  
We have no versions.  sync failed.
   [junit4]   2> 308135 INFO  (qtp1930661752-4079) [n:127.0.0.1:64881_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> 308135 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 308135 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 308135 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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/72086358360981512-core_node3-n_0000000000
   [junit4]   2> 308137 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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:64881/solr/.system_shard1_replica_n1/ shard1
   [junit4]   2> 308249 INFO  (zkCallback-1407-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> 308250 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 308257 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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=772
   [junit4]   2> 308352 INFO  (zkCallback-1407-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> 308352 INFO  (zkCallback-1407-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> 308639 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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=1154
   [junit4]   2> 308641 INFO  (qtp1930661752-4086) [n:127.0.0.1:64881_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> 308738 INFO  (zkCallback-1407-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> 308738 INFO  (zkCallback-1407-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> 308738 INFO  (qtp1930661752-4086) [n:127.0.0.1:64881_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:64881_solr&wt=javabin&version=2}
 status=0 QTime=1578
   [junit4]   2> 308739 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[66D7E5A9BB572680]) [     
] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: .system
   [junit4]   2> 308745 INFO  (qtp1930661752-4079) [n:127.0.0.1:64881_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> 308752 INFO  (qtp1930661752-4086) [n:127.0.0.1:64881_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:64881/solr/.system_shard1_replica_n1/&wt=javabin&version=2}{add=[17051341169T63tjfv5k1y6tof361o0dx4kit
 (1658753208031379456)]} 0 3
   [junit4]   2> 308752 INFO  (qtp1930661752-4079) [n:127.0.0.1:64881_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=[17051341169T63tjfv5k1y6tof361o0dx4kit 
(1658753208031379456)]} 0 10
   [junit4]   2> 308754 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1658753208042913792,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 308754 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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@4c134753 
commitCommandVersion:1658753208042913792
   [junit4]   2> 308755 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1658753208043962368,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 308756 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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@2bafeb84 
commitCommandVersion:1658753208043962368
   [junit4]   2> 308756 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@7bc42e9f[.system_shard1_replica_n1] 
main]
   [junit4]   2> 308757 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 308757 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@2ab476e2[.system_shard1_replica_n2] 
main]
   [junit4]   2> 308758 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 308758 INFO  
(searcherExecutor-1415-thread-1-processing-n:127.0.0.1:64881_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:64881_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@7bc42e9f[.system_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={java.runtime.version=12.0.2+10,
 os.version=10.0, os=Windows 10, java.vendor=AdoptOpenJDK, 
timestamp=1581910331769, java.version=12.0.2, java.vm.version=12.0.2+10, 
lucene.version=9.0.0, source=flush, 
os.arch=amd64}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 308759 INFO  
(searcherExecutor-1416-thread-1-processing-n:127.0.0.1:64881_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:64881_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@2ab476e2[.system_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={java.runtime.version=12.0.2+10,
 os.version=10.0, os=Windows 10, java.vendor=AdoptOpenJDK, 
timestamp=1581910331769, java.version=12.0.2, java.vm.version=12.0.2+10, 
lucene.version=9.0.0, source=flush, 
os.arch=amd64}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 308759 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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:64881/solr/.system_shard1_replica_n1/&commit_end_point=replicas&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 4
   [junit4]   2> 308760 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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:6&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 6
   [junit4]   2> 308763 INFO  (qtp1930661752-4086) [n:127.0.0.1:64881_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={action=STATUS&indexInfo=true&wt=javabin&version=2} status=0 QTime=2
   [junit4]   2> 308764 INFO  (qtp1930661752-4079) [n:127.0.0.1:64881_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> 308768 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema 
params={wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 308775 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 308777 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 308779 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 308785 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 308786 INFO  (Thread-611) [     ] o.a.s.c.SolrCore config 
update listener called for core .system_shard1_replica_n1
   [junit4]   2> 308787 INFO  (Thread-611) [     ] o.a.s.c.SolrCore core reload 
.system_shard1_replica_n1
   [junit4]   2> 308792 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 308804 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 308807 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 308807 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 308836 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_64881.solr.core..system.shard1.replica_n1' (registry 
'solr.core..system.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2aaf169f
   [junit4]   2> 308836 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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 
[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.SystemCollectionCompatTest_66D7E5A9BB572680-001\tempDir-001\node2\.system_shard1_replica_n1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.SystemCollectionCompatTest_66D7E5A9BB572680-001\tempDir-001\node2\.\.system_shard1_replica_n1\data\]
   [junit4]   2> 308917 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 308917 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 308920 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@326647d5[.system_shard1_replica_n1] 
main]
   [junit4]   2> 308924 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 308924 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 308924 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 308925 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 308925 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 308928 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 308929 INFO  
(searcherExecutor-1425-thread-1-processing-n:127.0.0.1:64881_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:64881_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@326647d5[.system_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={java.runtime.version=12.0.2+10,
 os.version=10.0, os=Windows 10, java.vendor=AdoptOpenJDK, 
timestamp=1581910331769, java.version=12.0.2, java.vm.version=12.0.2+10, 
lucene.version=9.0.0, source=flush, 
os.arch=amd64}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 308931 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@3cd8a5ca[.system_shard1_replica_n1] 
main]
   [junit4]   2> 308934 INFO  
(searcherExecutor-1425-thread-1-processing-n:127.0.0.1:64881_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:64881_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@3cd8a5ca[.system_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={java.runtime.version=12.0.2+10,
 os.version=10.0, java.vendor=AdoptOpenJDK, os=Windows 10, 
timestamp=1581910331769, java.version=12.0.2, java.vm.version=12.0.2+10, 
lucene.version=9.0.0, source=flush, 
os.arch=amd64}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 308937 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 308943 INFO  (qtp1930661752-4079) [n:127.0.0.1:64881_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> 308944 INFO  (qtp1930661752-4079) [n:127.0.0.1:64881_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> 308944 INFO  (qtp1930661752-4079) [n:127.0.0.1:64881_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=2
   [junit4]   2> 308945 ERROR 
(managedSchemaExecutor-1429-thread-1-processing-n:127.0.0.1:64881_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:64881_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:64881/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 308945 INFO  (Thread-611) [     ] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 9.0.0
   [junit4]   2> 308946 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 308947 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 308947 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 308947 ERROR 
(managedSchemaExecutor-1429-thread-1-processing-n:127.0.0.1:64881_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:64881_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:64881/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 308949 INFO  (qtp1930661752-4086) [n:127.0.0.1:64881_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> 308949 INFO  (qtp1930661752-4086) [n:127.0.0.1:64881_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> 308949 INFO  (qtp1930661752-4086) [n:127.0.0.1:64881_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> 308950 ERROR 
(managedSchemaExecutor-1429-thread-1-processing-n:127.0.0.1:64881_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:64881_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:64881/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 308951 INFO  (qtp1930661752-4079) [n:127.0.0.1:64881_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> 308951 INFO  (qtp1930661752-4079) [n:127.0.0.1:64881_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> 308951 INFO  (qtp1930661752-4079) [n:127.0.0.1:64881_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> 308951 ERROR 
(managedSchemaExecutor-1429-thread-1-processing-n:127.0.0.1:64881_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:64881_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:64881/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 308955 INFO  (Thread-611) [     ] o.a.s.s.IndexSchema 
[.system_shard1_replica_n1] Schema name=_system collection or core
   [junit4]   2> 308955 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 308956 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 308956 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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=1
   [junit4]   2> 308957 ERROR 
(managedSchemaExecutor-1429-thread-1-processing-n:127.0.0.1:64881_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:64881_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:64881/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 308958 INFO  (Thread-611) [     ] o.a.s.s.IndexSchema Loaded 
schema _system collection or core/1.1 with uniqueid field id
   [junit4]   2> 308958 INFO  (Thread-611) [     ] o.a.s.c.CoreContainer 
Reloading SolrCore '.system_shard1_replica_n1' using configuration from 
configset .system
   [junit4]   2> 308958 INFO  (qtp1930661752-4086) [n:127.0.0.1:64881_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> 308959 INFO  (qtp1930661752-4086) [n:127.0.0.1:64881_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> 308959 INFO  (qtp1930661752-4086) [n:127.0.0.1:64881_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> 308959 ERROR 
(managedSchemaExecutor-1429-thread-1-processing-n:127.0.0.1:64881_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:64881_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:64881/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 308960 INFO  (qtp1930661752-4079) [n:127.0.0.1:64881_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> 308960 INFO  (qtp1930661752-4079) [n:127.0.0.1:64881_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> 308960 INFO  (qtp1930661752-4079) [n:127.0.0.1:64881_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> 308960 ERROR 
(managedSchemaExecutor-1429-thread-1-processing-n:127.0.0.1:64881_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:64881_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:64881/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 308960 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 308960 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 308960 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 308960 ERROR 
(managedSchemaExecutor-1429-thread-1-processing-n:127.0.0.1:64881_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:64881_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:64881/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 308960 INFO  (qtp1930661752-4086) [n:127.0.0.1:64881_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> 308960 INFO  (qtp1930661752-4086) [n:127.0.0.1:64881_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> 308960 INFO  (qtp1930661752-4086) [n:127.0.0.1:64881_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> 308964 ERROR 
(managedSchemaExecutor-1429-thread-1-processing-n:127.0.0.1:64881_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:64881_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:64881/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 308965 INFO  (qtp1930661752-4079) [n:127.0.0.1:64881_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> 308965 INFO  (qtp1930661752-4079) [n:127.0.0.1:64881_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> 308965 INFO  (qtp1930661752-4079) [n:127.0.0.1:64881_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> 308966 ERROR 
(managedSchemaExecutor-1429-thread-1-processing-n:127.0.0.1:64881_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:64881_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:64881/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 308967 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 308967 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 308967 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 308968 ERROR 
(managedSchemaExecutor-1429-thread-1-processing-n:127.0.0.1:64881_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:64881_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:64881/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 308969 INFO  (qtp1930661752-4086) [n:127.0.0.1:64881_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> 308970 INFO  (qtp1930661752-4086) [n:127.0.0.1:64881_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> 308970 INFO  (qtp1930661752-4086) [n:127.0.0.1:64881_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> 308970 ERROR 
(managedSchemaExecutor-1429-thread-1-processing-n:127.0.0.1:64881_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:64881_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:64881/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 308971 INFO  (qtp1930661752-4079) [n:127.0.0.1:64881_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> 308972 INFO  (qtp1930661752-4079) [n:127.0.0.1:64881_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> 308972 INFO  (qtp1930661752-4079) [n:127.0.0.1:64881_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> 308972 ERROR 
(managedSchemaExecutor-1429-thread-1-processing-n:127.0.0.1:64881_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:64881_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:64881/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 308973 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 308973 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 308973 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 308973 ERROR 
(managedSchemaExecutor-1429-thread-1-processing-n:127.0.0.1:64881_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:64881_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:64881/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 308974 INFO  (qtp1930661752-4086) [n:127.0.0.1:64881_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> 308975 INFO  (qtp1930661752-4086) [n:127.0.0.1:64881_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> 308975 INFO  (qtp1930661752-4086) [n:127.0.0.1:64881_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> 308975 ERROR 
(managedSchemaExecutor-1429-thread-1-processing-n:127.0.0.1:64881_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:64881_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:64881/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 308976 INFO  (qtp1930661752-4079) [n:127.0.0.1:64881_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> 308976 INFO  (qtp1930661752-4079) [n:127.0.0.1:64881_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> 308976 INFO  (qtp1930661752-4079) [n:127.0.0.1:64881_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> 308976 ERROR 
(managedSchemaExecutor-1429-thread-1-processing-n:127.0.0.1:64881_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:64881_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:64881/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 308978 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 308978 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 308978 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 308979 ERROR 
(managedSchemaExecutor-1429-thread-1-processing-n:127.0.0.1:64881_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:64881_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:64881/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 308980 INFO  (qtp1930661752-4086) [n:127.0.0.1:64881_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> 308980 INFO  (qtp1930661752-4086) [n:127.0.0.1:64881_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> 308980 INFO  (qtp1930661752-4086) [n:127.0.0.1:64881_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> 308981 ERROR 
(managedSchemaExecutor-1429-thread-1-processing-n:127.0.0.1:64881_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:64881_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:64881/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 308981 INFO  (qtp1930661752-4079) [n:127.0.0.1:64881_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> 308982 INFO  (qtp1930661752-4079) [n:127.0.0.1:64881_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> 308982 INFO  (qtp1930661752-4079) [n:127.0.0.1:64881_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> 308983 ERROR 
(managedSchemaExecutor-1429-thread-1-processing-n:127.0.0.1:64881_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:64881_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:64881/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 308984 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 308984 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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> 308985 INFO  (qtp1930661752-4084) [n:127.0.0.1:64881_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=1
   [junit4]   2> 308986 ERROR 
(managedSchemaExecutor-1429-thread-1-processing-n:127.0.0.1:64881_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:64881_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:64881/solr/.system_shard1_replica_n2/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 308987 INFO  (qtp1930661752-4086) [n:127.0.0.1:64881_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> 308988 INFO  (qtp1930661752-4086) [n:127.0.0.1:64881_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> 308988 INFO  (qtp1930661752-4086) [n:127.0.0.1:64881_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=1
   [junit4]   2> 308989 ERROR 
(managedSchemaExecutor-1429-thread-1-processing-n:127.0.0.1:64881_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_nod

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

core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Failed to 
get /schema/zkversion from 
http://127.0.0.1:64881/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:64881/solr/.system_shard1_replica_n2
   [junit4]   2> 438252 WARN  
(managedSchemaExecutor-1429-thread-1-processing-n:127.0.0.1:64881_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:64881_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:64881/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:64881/solr/.system_shard1_replica_n2
   [junit4]   2> 440264 WARN  
(managedSchemaExecutor-1429-thread-1-processing-n:127.0.0.1:64881_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:64881_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:64881/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:64881/solr/.system_shard1_replica_n2
   [junit4]   2> 442269 WARN  
(managedSchemaExecutor-1429-thread-1-processing-n:127.0.0.1:64881_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:64881_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:64881/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:64881/solr/.system_shard1_replica_n2
   [junit4]   2> 444273 WARN  
(managedSchemaExecutor-1429-thread-1-processing-n:127.0.0.1:64881_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:64881_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:64881/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:64881/solr/.system_shard1_replica_n2
   [junit4]   2> 444602 WARN  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 444602 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.ManagedIndexSchema Took 135671.0ms for 1 replicas to apply schema 
update version 2 for collection .system
   [junit4]   2> 444602 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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=135829
   [junit4]   2> 444602 INFO  
(httpUriRequest-47687-thread-1-processing-x:.system_shard1_replica_n1 
r:core_node3 n:127.0.0.1:64881_solr 
http:////127.0.0.1:64881//solr//.system_shard1_replica_n2 c:.system s:shard1) 
[n:127.0.0.1:64881_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:64881: Socket operation on nonsocket: configureBlocking
   [junit4]   2> 444602 INFO  
(httpUriRequest-47687-thread-1-processing-x:.system_shard1_replica_n1 
r:core_node3 n:127.0.0.1:64881_solr 
http:////127.0.0.1:64881//solr//.system_shard1_replica_n2 c:.system s:shard1) 
[n:127.0.0.1:64881_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:64881
   [junit4]   2> 444602 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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@73d62d5b
   [junit4]   2> 444602 WARN  (qtp1930661752-4085) [n:127.0.0.1:64881_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> 444602 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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@73d62d5b
   [junit4]   2> 444603 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() .
   [junit4]   2> 444603 INFO  (qtp1930661752-4085) [n:127.0.0.1:64881_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@514206fc commitCommandVersion:0
   [junit4]   2> 444610 INFO  
(SUITE-SystemCollectionCompatTest-seed#[66D7E5A9BB572680]-worker) [     ] 
o.a.s.c.ZkTestServer Shutting down ZkTestServer.
   [junit4]   2> 444816 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>        25      /solr/overseer/queue
   [junit4]   2>        18      /solr/collections
   [junit4]   2>        11      /solr/overseer/collection-queue-work
   [junit4]   2>        10      /solr/live_nodes
   [junit4]   2> 
   [junit4]   2> 444821 INFO  
(SUITE-SystemCollectionCompatTest-seed#[66D7E5A9BB572680]-worker) [     ] 
o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:64868
   [junit4]   2> 444821 INFO  
(SUITE-SystemCollectionCompatTest-seed#[66D7E5A9BB572680]-worker) [     ] 
o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:64868
   [junit4]   2> 444821 INFO  
(SUITE-SystemCollectionCompatTest-seed#[66D7E5A9BB572680]-worker) [     ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1 64868
   [junit4]   2> NOTE: leaving temporary files on disk at: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.SystemCollectionCompatTest_66D7E5A9BB572680-001
   [junit4]   2> NOTE: test params are: codec=CheapBastard, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@8657225),
 locale=bn, timezone=Antarctica/Macquarie
   [junit4]   2> NOTE: Windows 10 10.0 amd64/AdoptOpenJDK 12.0.2 
(64-bit)/cpus=6,threads=1,free=185242280,total=353370112
   [junit4]   2> NOTE: All tests run in this JVM: [NodeMarkersRegistrationTest, 
TestSolrJ, ZkControllerTest, BooleanFieldTest, TestReloadDeadlock, 
TestBinaryResponseWriter, TestTestInjection, NestedShardedAtomicUpdateTest, 
TestFieldCollectionResource, MetricTriggerTest, TestSkipOverseerOperations, 
TestSolrDeletionPolicy1, OutputWriterTest, TestLockTree, TestWriterPerf, 
TestDynamicLoadingUrl, TestNumericTokenStream, TestPerFieldSimilarity, 
DateRangeFieldTest, TestSchemaResource, SolrMetricManagerTest, 
ExternalFileFieldSortTest, TestRestManager, TestJmxIntegration, 
DistributedQueryComponentCustomSortTest, CursorPagingTest, HdfsSyncSliceTest, 
TestCloudInspectUtil, MaxSizeAutoCommitTest, BJQParserTest, 
SortSpecParsingTest, NumericFieldsTest, AuditLoggerPluginTest, 
TestGroupingSearch, TriggerEventQueueTest, SegmentsInfoRequestHandlerTest, 
OrderedExecutorTest, SuggesterFSTTest, TestDownShardTolerantSearch, 
TestSimpleTrackingShardHandler, HttpTriggerListenerTest, 
PingRequestHandlerTest, SolrLogAuditLoggerPluginTest, SimpleMLTQParserTest, 
DistributedFacetPivotLongTailTest, PeerSyncWithLeaderTest, 
SystemCollectionCompatTest]
   [junit4] Completed [182/904 (1!)] on J3 in 140.60s, 1 test, 1 error <<< 
FAILURES!

[...truncated 54952 lines...]
[repro] Jenkins log URL: 
https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8481/consoleText

[repro] Revision: 188f620208012ba1d726b743c5934abf01988d57

[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC"

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

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

[...truncated 3168 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 
-Dtests.class="*.SystemCollectionCompatTest" -Dtests.showOutput=onerror 
"-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC" -Dtests.seed=66D7E5A9BB572680 
-Dtests.slow=true -Dtests.locale=bn -Dtests.timezone=Antarctica/Macquarie 
-Dtests.asserts=true -Dtests.file.encoding=UTF-8

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

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

Reply via email to