Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8379/
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:51719/solr/.system]

Stack Trace:
org.apache.solr.client.solrj.SolrServerException: No live SolrServers available 
to handle this request:[http://127.0.0.1:51719/solr/.system]
        at 
__randomizedtesting.SeedInfo.seed([FCF1610423B57765:8C04C2AD437DDE13]: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:51719/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 15137 lines...]
   [junit4] Suite: org.apache.solr.cloud.SystemCollectionCompatTest
   [junit4]   2> 1369407 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FCF1610423B57765]-worker) [     ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 1369408 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FCF1610423B57765]-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_FCF1610423B57765-001\data-dir-114-001
   [junit4]   2> 1369408 WARN  
(SUITE-SystemCollectionCompatTest-seed#[FCF1610423B57765]-worker) [     ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=93 numCloses=93
   [junit4]   2> 1369408 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FCF1610423B57765]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) 
w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 1369409 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FCF1610423B57765]-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> 1369410 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FCF1610423B57765]-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_FCF1610423B57765-001\tempDir-001
   [junit4]   2> 1369411 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FCF1610423B57765]-worker) [     ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1369411 INFO  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1369411 INFO  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1369512 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FCF1610423B57765]-worker) [     ] 
o.a.s.c.ZkTestServer start zk server on port:51704
   [junit4]   2> 1369512 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FCF1610423B57765]-worker) [     ] 
o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:51704
   [junit4]   2> 1369512 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FCF1610423B57765]-worker) [     ] 
o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:51704
   [junit4]   2> 1369512 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FCF1610423B57765]-worker) [     ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1 51704
   [junit4]   2> 1369514 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FCF1610423B57765]-worker) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1369518 INFO  (zkConnectionManagerCallback-6429-thread-1) [    
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1369518 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FCF1610423B57765]-worker) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1369524 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FCF1610423B57765]-worker) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1369531 INFO  (zkConnectionManagerCallback-6431-thread-1) [    
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1369531 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FCF1610423B57765]-worker) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1369532 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FCF1610423B57765]-worker) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1369536 INFO  (zkConnectionManagerCallback-6433-thread-1) [    
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1369536 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FCF1610423B57765]-worker) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1369641 WARN  (jetty-launcher-6434-thread-2) [     ] 
o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, 
break even is size 23
   [junit4]   2> 1369641 WARN  (jetty-launcher-6434-thread-1) [     ] 
o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, 
break even is size 23
   [junit4]   2> 1369641 INFO  (jetty-launcher-6434-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 1369641 INFO  (jetty-launcher-6434-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 1369641 INFO  (jetty-launcher-6434-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 1369641 INFO  (jetty-launcher-6434-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 1369641 INFO  (jetty-launcher-6434-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> 1369641 INFO  (jetty-launcher-6434-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> 1369642 INFO  (jetty-launcher-6434-thread-1) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1369642 INFO  (jetty-launcher-6434-thread-1) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1369642 INFO  (jetty-launcher-6434-thread-1) [     ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 1369642 INFO  (jetty-launcher-6434-thread-1) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@4ce9e0ff{/solr,null,AVAILABLE}
   [junit4]   2> 1369643 INFO  (jetty-launcher-6434-thread-2) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1369643 INFO  (jetty-launcher-6434-thread-2) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1369643 INFO  (jetty-launcher-6434-thread-2) [     ] 
o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 1369643 INFO  (jetty-launcher-6434-thread-2) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@7da767ef{/solr,null,AVAILABLE}
   [junit4]   2> 1369644 INFO  (jetty-launcher-6434-thread-1) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@6f4cd97c{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:51719}
   [junit4]   2> 1369644 INFO  (jetty-launcher-6434-thread-1) [     ] 
o.e.j.s.Server Started @1369673ms
   [junit4]   2> 1369644 INFO  (jetty-launcher-6434-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=51719}
   [junit4]   2> 1369644 INFO  (jetty-launcher-6434-thread-2) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@3a654992{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:51723}
   [junit4]   2> 1369644 INFO  (jetty-launcher-6434-thread-2) [     ] 
o.e.j.s.Server Started @1369674ms
   [junit4]   2> 1369644 ERROR (jetty-launcher-6434-thread-1) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1369644 INFO  (jetty-launcher-6434-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1369644 INFO  (jetty-launcher-6434-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=51723}
   [junit4]   2> 1369644 INFO  (jetty-launcher-6434-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
9.0.0
   [junit4]   2> 1369644 INFO  (jetty-launcher-6434-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1369644 INFO  (jetty-launcher-6434-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1369644 INFO  (jetty-launcher-6434-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-01-26T10:55:06.117589900Z
   [junit4]   2> 1369644 ERROR (jetty-launcher-6434-thread-2) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1369644 INFO  (jetty-launcher-6434-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1369645 INFO  (jetty-launcher-6434-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
9.0.0
   [junit4]   2> 1369645 INFO  (jetty-launcher-6434-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1369645 INFO  (jetty-launcher-6434-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1369645 INFO  (jetty-launcher-6434-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-01-26T10:55:06.118137500Z
   [junit4]   2> 1369645 INFO  (jetty-launcher-6434-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1369645 INFO  (jetty-launcher-6434-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1369648 INFO  (zkConnectionManagerCallback-6436-thread-1) [    
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1369648 INFO  (jetty-launcher-6434-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1369648 INFO  (zkConnectionManagerCallback-6438-thread-1) [    
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1369648 INFO  (jetty-launcher-6434-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1369648 INFO  (jetty-launcher-6434-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1369648 INFO  (jetty-launcher-6434-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1369987 INFO  (jetty-launcher-6434-thread-2) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 1369988 WARN  (jetty-launcher-6434-thread-2) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@4130cdbe[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1369988 WARN  (jetty-launcher-6434-thread-2) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@4130cdbe[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1369999 WARN  (jetty-launcher-6434-thread-2) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@76bc62fa[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1369999 WARN  (jetty-launcher-6434-thread-2) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@76bc62fa[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1370000 INFO  (jetty-launcher-6434-thread-2) [     ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51704/solr
   [junit4]   2> 1370001 INFO  (jetty-launcher-6434-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1370003 INFO  (zkConnectionManagerCallback-6446-thread-1) [    
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1370003 INFO  (jetty-launcher-6434-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1370105 INFO  (jetty-launcher-6434-thread-2) 
[n:127.0.0.1:51723_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 1370108 INFO  (zkConnectionManagerCallback-6448-thread-1) [    
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1370108 INFO  (jetty-launcher-6434-thread-2) 
[n:127.0.0.1:51723_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 1370207 INFO  (jetty-launcher-6434-thread-2) 
[n:127.0.0.1:51723_solr     ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:51723_solr
   [junit4]   2> 1370207 INFO  (jetty-launcher-6434-thread-2) 
[n:127.0.0.1:51723_solr     ] o.a.s.c.Overseer Overseer 
(id=72071642866843654-127.0.0.1:51723_solr-n_0000000000) starting
   [junit4]   2> 1370214 INFO  (jetty-launcher-6434-thread-2) 
[n:127.0.0.1:51723_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:51723_solr
   [junit4]   2> 1370214 INFO  
(OverseerStateUpdate-72071642866843654-127.0.0.1:51723_solr-n_0000000000) 
[n:127.0.0.1:51723_solr     ] o.a.s.c.Overseer Starting to work on the main 
queue : 127.0.0.1:51723_solr
   [junit4]   2> 1370215 INFO  
(OverseerStateUpdate-72071642866843654-127.0.0.1:51723_solr-n_0000000000) 
[n:127.0.0.1:51723_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1370217 INFO  (jetty-launcher-6434-thread-2) 
[n:127.0.0.1:51723_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 1370218 WARN  (jetty-launcher-6434-thread-2) 
[n:127.0.0.1:51723_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> 1370246 INFO  (jetty-launcher-6434-thread-2) 
[n:127.0.0.1:51723_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 1370269 INFO  (jetty-launcher-6434-thread-2) 
[n:127.0.0.1:51723_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_51723.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@67f1e7ba
   [junit4]   2> 1370276 INFO  (jetty-launcher-6434-thread-2) 
[n:127.0.0.1:51723_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_51723.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@67f1e7ba
   [junit4]   2> 1370276 INFO  (jetty-launcher-6434-thread-2) 
[n:127.0.0.1:51723_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_51723.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@67f1e7ba
   [junit4]   2> 1370278 INFO  (jetty-launcher-6434-thread-2) 
[n:127.0.0.1:51723_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_FCF1610423B57765-001\tempDir-001\node2\.
   [junit4]   2> 1370304 INFO  (jetty-launcher-6434-thread-1) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 1370305 WARN  (jetty-launcher-6434-thread-1) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@66b48268[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1370305 WARN  (jetty-launcher-6434-thread-1) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@66b48268[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1370308 WARN  (jetty-launcher-6434-thread-1) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@7275a514[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1370308 WARN  (jetty-launcher-6434-thread-1) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@7275a514[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1370309 INFO  (jetty-launcher-6434-thread-1) [     ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51704/solr
   [junit4]   2> 1370310 INFO  (jetty-launcher-6434-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1370313 INFO  (zkConnectionManagerCallback-6458-thread-1) [    
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1370313 INFO  (jetty-launcher-6434-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1370415 INFO  (jetty-launcher-6434-thread-1) 
[n:127.0.0.1:51719_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 1370418 INFO  (zkConnectionManagerCallback-6460-thread-1) [    
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1370418 INFO  (jetty-launcher-6434-thread-1) 
[n:127.0.0.1:51719_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 1370424 INFO  (jetty-launcher-6434-thread-1) 
[n:127.0.0.1:51719_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1370434 INFO  (jetty-launcher-6434-thread-1) 
[n:127.0.0.1:51719_solr     ] o.a.s.c.ZkController Publish 
node=127.0.0.1:51719_solr as DOWN
   [junit4]   2> 1370435 INFO  (jetty-launcher-6434-thread-1) 
[n:127.0.0.1:51719_solr     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 1370435 INFO  (jetty-launcher-6434-thread-1) 
[n:127.0.0.1:51719_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:51719_solr
   [junit4]   2> 1370437 INFO  (zkCallback-6447-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1370437 INFO  (zkCallback-6459-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1370439 INFO  (jetty-launcher-6434-thread-1) 
[n:127.0.0.1:51719_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 1370439 WARN  (jetty-launcher-6434-thread-1) 
[n:127.0.0.1:51719_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> 1370466 INFO  (jetty-launcher-6434-thread-1) 
[n:127.0.0.1:51719_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 1370488 INFO  (jetty-launcher-6434-thread-1) 
[n:127.0.0.1:51719_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_51719.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@67f1e7ba
   [junit4]   2> 1370498 INFO  (jetty-launcher-6434-thread-1) 
[n:127.0.0.1:51719_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_51719.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@67f1e7ba
   [junit4]   2> 1370499 INFO  (jetty-launcher-6434-thread-1) 
[n:127.0.0.1:51719_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_51719.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@67f1e7ba
   [junit4]   2> 1370499 INFO  (jetty-launcher-6434-thread-1) 
[n:127.0.0.1:51719_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_FCF1610423B57765-001\tempDir-001\node1\.
   [junit4]   2> 1370543 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FCF1610423B57765]-worker) [     ] 
o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
   [junit4]   2> 1370544 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FCF1610423B57765]-worker) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1370546 INFO  (zkConnectionManagerCallback-6469-thread-1) [    
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1370546 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FCF1610423B57765]-worker) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1370547 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FCF1610423B57765]-worker) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1370548 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FCF1610423B57765]-worker) [     ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:51704/solr ready
   [junit4]   2> 1370562 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[FCF1610423B57765]) [     
] o.a.s.SolrTestCaseJ4 ###Starting testBackCompat
   [junit4]   2> 1370564 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[FCF1610423B57765]) [     
] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1370566 INFO  (zkConnectionManagerCallback-6474-thread-1) [    
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1370566 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[FCF1610423B57765]) [     
] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1370566 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[FCF1610423B57765]) [     
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1370566 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[FCF1610423B57765]) [     
] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:51704/solr ready
   [junit4]   2> 1370566 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_solr    
 ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with 
params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1370574 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_solr    
 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=3
   [junit4]   2> 1370575 INFO  (qtp1636514621-19054) [n:127.0.0.1:51719_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:51719_solr&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1370583 INFO  
(OverseerCollectionConfigSetProcessor-72071642866843654-127.0.0.1:51723_solr-n_0000000000)
 [n:127.0.0.1:51723_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> 1370583 INFO  
(OverseerThreadFactory-6187-thread-2-processing-n:127.0.0.1:51723_solr) 
[n:127.0.0.1:51723_solr     ] o.a.s.c.a.c.CreateCollectionCmd Create collection 
.system
   [junit4]   2> 1370686 WARN  
(OverseerThreadFactory-6187-thread-2-processing-n:127.0.0.1:51723_solr) 
[n:127.0.0.1:51723_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> 1370690 INFO  
(OverseerStateUpdate-72071642866843654-127.0.0.1:51723_solr-n_0000000000) 
[n:127.0.0.1:51723_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:51719/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1370691 INFO  
(OverseerStateUpdate-72071642866843654-127.0.0.1:51723_solr-n_0000000000) 
[n:127.0.0.1:51723_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:51719/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1370895 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_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> 1370895 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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> 1371910 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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> 1371910 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_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> 1371924 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_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> 1371924 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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> 1371928 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_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> 1371928 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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> 1371933 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_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> 1371933 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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> 1371935 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ManagedIndexSchema Created and persisted managed schema znode at 
/configs/.system/managed-schema
   [junit4]   2> 1371935 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.ManagedIndexSchema Managed schema znode at 
/configs/.system/managed-schema already exists - no need to create it
   [junit4]   2> 1371936 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_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> 1371936 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_51719.solr.core..system.shard1.replica_n2' (registry 
'solr.core..system.shard1.replica_n2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@67f1e7ba
   [junit4]   2> 1371937 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_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_FCF1610423B57765-001\tempDir-001\node1\.system_shard1_replica_n2],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.SystemCollectionCompatTest_FCF1610423B57765-001\tempDir-001\node1\.\.system_shard1_replica_n2\data\]
   [junit4]   2> 1371939 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in 
ZooKeeper, renamed the non-managed schema /configs/.system/schema.xml to 
/configs/.system/schema.xml.bak
   [junit4]   2> 1371940 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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> 1371940 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_51719.solr.core..system.shard1.replica_n1' (registry 
'solr.core..system.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@67f1e7ba
   [junit4]   2> 1371940 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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_FCF1610423B57765-001\tempDir-001\node1\.system_shard1_replica_n1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.SystemCollectionCompatTest_FCF1610423B57765-001\tempDir-001\node1\.\.system_shard1_replica_n1\data\]
   [junit4]   2> 1372019 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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> 1372019 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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> 1372032 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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> 1372032 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1372034 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1a44c137[.system_shard1_replica_n1] 
main]
   [junit4]   2> 1372036 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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> 1372036 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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> 1372036 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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> 1372037 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.ZkIndexSchemaReader Retrieved schema version 0 from ZooKeeper
   [junit4]   2> 1372037 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_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> 1372037 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_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> 1372038 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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> 1372040 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_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> 1372040 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1372040 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@34f2d895[.system_shard1_replica_n2] 
main]
   [junit4]   2> 1372042 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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> 1372042 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_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> 1372043 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 5 ms
   [junit4]   2> 1372043 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_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> 1372043 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_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> 1372043 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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> 1372044 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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 
1656787942524321792
   [junit4]   2> 1372044 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_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> 1372044 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_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> 1372044 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_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 
1656787942524321792
   [junit4]   2> 1372046 INFO  
(searcherExecutor-6200-thread-1-processing-n:127.0.0.1:51719_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:51719_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@1a44c137[.system_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1372046 INFO  
(searcherExecutor-6199-thread-1-processing-n:127.0.0.1:51719_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:51719_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@34f2d895[.system_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1372046 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/.system/terms/shard1 to Terms{values={core_node4=0}, version=0}
   [junit4]   2> 1372046 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.c.ZkShardTerms Failed to save terms, version is not a match, retrying
   [junit4]   2> 1372046 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_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> 1372046 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/.system/terms/shard1 to Terms{values={core_node3=0, core_node4=0}, 
version=1}
   [junit4]   2> 1372046 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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> 1372053 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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> 1372053 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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> 1372053 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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:51719/solr/.system_shard1_replica_n1/
   [junit4]   2> 1372054 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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:51719/solr START 
replicas=[http://127.0.0.1:51719/solr/.system_shard1_replica_n2/] nUpdates=100
   [junit4]   2> 1372054 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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:51719/solr DONE.  
We have no versions.  sync failed.
   [junit4]   2> 1372056 INFO  (qtp1636514621-19051) [n:127.0.0.1:51719_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> 1372057 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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> 1372057 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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> 1372057 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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/72071642866843656-core_node3-n_0000000000
   [junit4]   2> 1372059 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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:51719/solr/.system_shard1_replica_n1/ shard1
   [junit4]   2> 1372161 INFO  (zkCallback-6459-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> 1372161 INFO  (zkCallback-6459-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> 1372162 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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> 1372166 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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=1271
   [junit4]   2> 1372267 INFO  (zkCallback-6459-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> 1372267 INFO  (zkCallback-6459-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> 1372268 INFO  (zkCallback-6459-thread-3) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] 
for collection [.system] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1373058 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_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=2164
   [junit4]   2> 1373060 INFO  (qtp1636514621-19054) [n:127.0.0.1:51719_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> 1373157 INFO  (zkCallback-6459-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> 1373157 INFO  (zkCallback-6459-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> 1373157 INFO  (zkCallback-6459-thread-3) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] 
for collection [.system] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1373157 INFO  (qtp1636514621-19054) [n:127.0.0.1:51719_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:51719_solr&wt=javabin&version=2}
 status=0 QTime=2582
   [junit4]   2> 1373158 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[FCF1610423B57765]) [     
] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: .system
   [junit4]   2> 1373167 INFO  (qtp1636514621-19051) [n:127.0.0.1:51719_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> 1373170 INFO  (qtp1636514621-19054) [n:127.0.0.1:51719_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:51719/solr/.system_shard1_replica_n1/&wt=javabin&version=2}{add=[16fe17db543T20gkie0ng67l01ohlakrzh5wu
 (1656787943696629760)]} 0 1
   [junit4]   2> 1373171 INFO  (qtp1636514621-19051) [n:127.0.0.1:51719_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=[16fe17db543T20gkie0ng67l01ohlakrzh5wu 
(1656787943696629760)]} 0 7
   [junit4]   2> 1373172 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1656787943707115520,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1373172 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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@1af40ee8 
commitCommandVersion:1656787943707115520
   [junit4]   2> 1373172 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1656787943707115520,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1373172 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_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@be5da26 
commitCommandVersion:1656787943707115520
   [junit4]   2> 1373176 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@77b71793[.system_shard1_replica_n2] 
main]
   [junit4]   2> 1373176 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@4443c58[.system_shard1_replica_n1] 
main]
   [junit4]   2> 1373176 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1373178 INFO  
(searcherExecutor-6199-thread-1-processing-n:127.0.0.1:51719_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:51719_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@77b71793[.system_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={os.version=10.0,
 java.runtime.version=12.0.2+10, os.arch=amd64, source=flush, 
lucene.version=9.0.0, java.vm.version=12.0.2+10, java.version=12.0.2, 
timestamp=1580036109646, java.vendor=AdoptOpenJDK, os=Windows 
10}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1373179 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_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:51719/solr/.system_shard1_replica_n1/&commit_end_point=replicas&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 6
   [junit4]   2> 1373179 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1373179 INFO  
(searcherExecutor-6200-thread-1-processing-n:127.0.0.1:51719_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:51719_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@4443c58[.system_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={os.version=10.0,
 java.runtime.version=12.0.2+10, os.arch=amd64, source=flush, 
lucene.version=9.0.0, java.vm.version=12.0.2+10, java.version=12.0.2, 
timestamp=1580036109646, java.vendor=AdoptOpenJDK, os=Windows 
10}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1373181 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n1]  webapp=/solr 
path=/update 
params={_stateVer_=.system:5&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 9
   [junit4]   2> 1373184 INFO  (qtp1636514621-19054) [n:127.0.0.1:51719_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> 1373185 INFO  (qtp1636514621-19051) [n:127.0.0.1:51719_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> 1373188 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema 
params={wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1373192 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.SchemaManager managed schema loaded . version : 1 
   [junit4]   2> 1373195 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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> 1373198 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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> 1373199 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.c.ZkController Persisted config data to node 
/configs/.system/schema.xml.bak 
   [junit4]   2> 1373200 INFO  (Thread-2305) [     ] o.a.s.c.SolrCore config 
update listener called for core .system_shard1_replica_n1
   [junit4]   2> 1373202 INFO  (Thread-2305) [     ] o.a.s.c.SolrCore config 
update listener called for core .system_shard1_replica_n2
   [junit4]   2> 1373203 INFO  (Thread-2305) [     ] o.a.s.c.SolrCore core 
reload .system_shard1_replica_n2
   [junit4]   2> 1373206 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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> 1373219 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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> 1373222 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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> 1373222 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.c.CoreContainer Reloading SolrCore '.system_shard1_replica_n2' using 
configuration from configset .system
   [junit4]   2> 1373255 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_51719.solr.core..system.shard1.replica_n2' (registry 
'solr.core..system.shard1.replica_n2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@67f1e7ba
   [junit4]   2> 1373255 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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_FCF1610423B57765-001\tempDir-001\node1\.system_shard1_replica_n2],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.SystemCollectionCompatTest_FCF1610423B57765-001\tempDir-001\node1\.\.system_shard1_replica_n2\data\]
   [junit4]   2> 1373324 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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> 1373324 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1373326 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@c7d9634[.system_shard1_replica_n2] 
main]
   [junit4]   2> 1373327 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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> 1373327 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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> 1373327 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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> 1373328 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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> 1373328 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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> 1373330 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1373331 INFO  
(searcherExecutor-6209-thread-1-processing-n:127.0.0.1:51719_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:51719_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@c7d9634[.system_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={os.version=10.0,
 java.runtime.version=12.0.2+10, os.arch=amd64, source=flush, 
lucene.version=9.0.0, java.vm.version=12.0.2+10, java.version=12.0.2, 
timestamp=1580036109646, java.vendor=AdoptOpenJDK, os=Windows 
10}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1373332 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@4fad1cf4[.system_shard1_replica_n2] 
main]
   [junit4]   2> 1373332 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ManagedIndexSchema Waiting up to 599 secs for 1 replicas to apply 
schema update version 2 for collection .system
   [junit4]   2> 1373334 INFO  
(searcherExecutor-6209-thread-1-processing-n:127.0.0.1:51719_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:51719_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@4fad1cf4[.system_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={os.version=10.0,
 java.runtime.version=12.0.2+10, os.arch=amd64, source=flush, 
lucene.version=9.0.0, java.vm.version=12.0.2+10, java.version=12.0.2, 
timestamp=1580036109646, os=Windows 10, 
java.vendor=AdoptOpenJDK}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 1373334 INFO  (qtp1636514621-19051) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 1373334 INFO  (qtp1636514621-19051) [n:127.0.0.1:51719_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> 1373334 INFO  (qtp1636514621-19051) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1373334 ERROR 
(managedSchemaExecutor-6213-thread-1-processing-n:127.0.0.1:51719_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:51719_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:51719/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 1373334 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 1373334 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_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> 1373334 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1373334 ERROR 
(managedSchemaExecutor-6213-thread-1-processing-n:127.0.0.1:51719_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:51719_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:51719/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 1373334 INFO  (qtp1636514621-19054) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 1373334 INFO  (qtp1636514621-19054) [n:127.0.0.1:51719_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> 1373334 INFO  (qtp1636514621-19054) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1373334 ERROR 
(managedSchemaExecutor-6213-thread-1-processing-n:127.0.0.1:51719_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:51719_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:51719/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 1373338 INFO  (qtp1636514621-19051) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 1373339 INFO  (qtp1636514621-19051) [n:127.0.0.1:51719_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> 1373339 INFO  (qtp1636514621-19051) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1373339 ERROR 
(managedSchemaExecutor-6213-thread-1-processing-n:127.0.0.1:51719_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:51719_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:51719/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 1373339 INFO  (Thread-2305) [     ] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 9.0.0
   [junit4]   2> 1373340 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 1373340 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_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> 1373340 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1373340 ERROR 
(managedSchemaExecutor-6213-thread-1-processing-n:127.0.0.1:51719_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:51719_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:51719/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 1373341 INFO  (qtp1636514621-19054) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 1373341 INFO  (qtp1636514621-19054) [n:127.0.0.1:51719_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> 1373341 INFO  (qtp1636514621-19054) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1373342 ERROR 
(managedSchemaExecutor-6213-thread-1-processing-n:127.0.0.1:51719_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:51719_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:51719/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 1373342 INFO  (qtp1636514621-19051) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 1373343 INFO  (qtp1636514621-19051) [n:127.0.0.1:51719_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> 1373343 INFO  (qtp1636514621-19051) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1373343 ERROR 
(managedSchemaExecutor-6213-thread-1-processing-n:127.0.0.1:51719_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:51719_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:51719/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 1373343 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 1373344 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_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> 1373344 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1373344 ERROR 
(managedSchemaExecutor-6213-thread-1-processing-n:127.0.0.1:51719_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:51719_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:51719/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 1373344 INFO  (qtp1636514621-19054) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 1373345 INFO  (qtp1636514621-19054) [n:127.0.0.1:51719_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> 1373345 INFO  (qtp1636514621-19054) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1373345 ERROR 
(managedSchemaExecutor-6213-thread-1-processing-n:127.0.0.1:51719_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:51719_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:51719/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 1373345 INFO  (qtp1636514621-19051) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 1373346 INFO  (qtp1636514621-19051) [n:127.0.0.1:51719_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> 1373346 INFO  (qtp1636514621-19051) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1373346 ERROR 
(managedSchemaExecutor-6213-thread-1-processing-n:127.0.0.1:51719_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:51719_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:51719/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 1373347 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 1373347 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_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> 1373347 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1373347 ERROR 
(managedSchemaExecutor-6213-thread-1-processing-n:127.0.0.1:51719_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:51719_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:51719/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 1373348 INFO  (qtp1636514621-19054) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 1373348 INFO  (qtp1636514621-19054) [n:127.0.0.1:51719_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> 1373348 INFO  (qtp1636514621-19054) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1373348 ERROR 
(managedSchemaExecutor-6213-thread-1-processing-n:127.0.0.1:51719_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:51719_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:51719/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 1373349 INFO  (qtp1636514621-19051) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 1373349 INFO  (qtp1636514621-19051) [n:127.0.0.1:51719_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> 1373349 INFO  (qtp1636514621-19051) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1373349 ERROR 
(managedSchemaExecutor-6213-thread-1-processing-n:127.0.0.1:51719_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:51719_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:51719/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 1373349 INFO  (Thread-2305) [     ] o.a.s.s.IndexSchema 
[.system_shard1_replica_n2] Schema name=_system collection or core
   [junit4]   2> 1373350 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 1373350 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_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> 1373350 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1373350 ERROR 
(managedSchemaExecutor-6213-thread-1-processing-n:127.0.0.1:51719_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:51719_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:51719/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 1373357 INFO  (qtp1636514621-19054) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 1373358 INFO  (qtp1636514621-19054) [n:127.0.0.1:51719_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> 1373358 INFO  (qtp1636514621-19054) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1373368 ERROR 
(managedSchemaExecutor-6213-thread-1-processing-n:127.0.0.1:51719_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:51719_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:51719/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 1373369 INFO  (qtp1636514621-19051) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 1373370 INFO  (qtp1636514621-19051) [n:127.0.0.1:51719_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> 1373370 INFO  (Thread-2305) [     ] o.a.s.s.IndexSchema Loaded 
schema _system collection or core/1.1 with uniqueid field id
   [junit4]   2> 1373370 INFO  (Thread-2305) [     ] o.a.s.c.CoreContainer 
Reloading SolrCore '.system_shard1_replica_n2' using configuration from 
configset .system
   [junit4]   2> 1373370 INFO  (qtp1636514621-19051) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1373370 ERROR 
(managedSchemaExecutor-6213-thread-1-processing-n:127.0.0.1:51719_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:51719_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:51719/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 1373371 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 1373371 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_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> 1373371 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1373371 ERROR 
(managedSchemaExecutor-6213-thread-1-processing-n:127.0.0.1:51719_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:51719_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:51719/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 1373372 INFO  (qtp1636514621-19054) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 1373372 INFO  (qtp1636514621-19054) [n:127.0.0.1:51719_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> 1373372 INFO  (qtp1636514621-19054) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1373372 ERROR 
(managedSchemaExecutor-6213-thread-1-processing-n:127.0.0.1:51719_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:51719_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:51719/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 1373373 INFO  (qtp1636514621-19051) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 1373373 INFO  (qtp1636514621-19051) [n:127.0.0.1:51719_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> 1373373 INFO  (qtp1636514621-19051) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1373373 ERROR 
(managedSchemaExecutor-6213-thread-1-processing-n:127.0.0.1:51719_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:51719_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:51719/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 1373374 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 1373374 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_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> 1373374 INFO  (qtp1636514621-19050) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1373374 ERROR 
(managedSchemaExecutor-6213-thread-1-processing-n:127.0.0.1:51719_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:51719_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:51719/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 1373374 INFO  (qtp1636514621-19054) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, 
currentVersion=0) before returning version!
   [junit4]   2> 1373375 INFO  (qtp1636514621-19054) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.ZkIndexSchemaReader Current sche

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

to get /schema/zkversion from 
http://127.0.0.1:51719/solr/.system_shard1_replica_n1/ due to: 
java.util.concurrent.ExecutionException: 
org.apache.solr.client.solrj.SolrServerException: Server refused connection at: 
http://127.0.0.1:51719/solr/.system_shard1_replica_n1
   [junit4]   2> 1509124 WARN  (qtp1636514621-19052) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ManagedIndexSchema Core core_node4 was interrupted waiting for schema 
version 2 to propagate to 1 replicas for collection .system
   [junit4]   2> 1509124 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ManagedIndexSchema Took 135792.0ms for 1 replicas to apply schema 
update version 2 for collection .system
   [junit4]   2> 1509124 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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=135933
   [junit4]   2> 1509124 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.SolrCore 
[.system_shard1_replica_n2]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@3303183
   [junit4]   2> 1509124 WARN  (qtp1636514621-19052) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.m.SolrMetricManager Interrupted while trying to obtain lock to modify 
reporters registry: solr.core..system.shard1.replica_n2
   [junit4]   2> 1509124 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.collection..system.shard1.leader, tag=SolrCore@3303183
   [junit4]   2> 1509125 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() .
   [junit4]   2> 1509125 INFO  (qtp1636514621-19052) [n:127.0.0.1:51719_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@544450d1 commitCommandVersion:0
   [junit4]   2> 1509125 INFO  
(httpUriRequest-133279-thread-1-processing-x:.system_shard1_replica_n2 
r:core_node4 n:127.0.0.1:51719_solr 
http:////127.0.0.1:51719//solr//.system_shard1_replica_n1 c:.system s:shard1) 
[n:127.0.0.1:51719_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.h.i.e.RetryExec I/O exception 
(java.net.SocketException) caught when processing request to 
{}->http://127.0.0.1:51719: Socket operation on nonsocket: configureBlocking
   [junit4]   2> 1509125 INFO  
(httpUriRequest-133279-thread-1-processing-x:.system_shard1_replica_n2 
r:core_node4 n:127.0.0.1:51719_solr 
http:////127.0.0.1:51719//solr//.system_shard1_replica_n1 c:.system s:shard1) 
[n:127.0.0.1:51719_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.h.i.e.RetryExec Retrying request to 
{}->http://127.0.0.1:51719
   [junit4]   2> 1509165 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FCF1610423B57765]-worker) [     ] 
o.a.s.c.ZkTestServer Shutting down ZkTestServer.
   [junit4]   2> 1509373 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>        5       /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>        16      /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/live_nodes
   [junit4]   2>        11      /solr/overseer/collection-queue-work
   [junit4]   2> 
   [junit4]   2> 1509378 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FCF1610423B57765]-worker) [     ] 
o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:51704
   [junit4]   2> 1509378 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FCF1610423B57765]-worker) [     ] 
o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:51704
   [junit4]   2> 1509378 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FCF1610423B57765]-worker) [     ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1 51704
   [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_FCF1610423B57765-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene84): 
{id=PostingsFormat(name=MockRandom), type=PostingsFormat(name=Direct)}, 
docValues:{timestamp_l=DocValuesFormat(name=Asserting), 
_version_=DocValuesFormat(name=Lucene80), 
time_l=DocValuesFormat(name=Lucene80), 
timestamp=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=514, 
maxMBSortInHeap=6.998743574587346, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@33acc8e2),
 locale=es-ES, timezone=Antarctica/Casey
   [junit4]   2> NOTE: Windows 10 10.0 amd64/AdoptOpenJDK 12.0.2 
(64-bit)/cpus=6,threads=1,free=222967176,total=476053504
   [junit4]   2> NOTE: All tests run in this JVM: [TestCloudSearcherWarming, 
TestSolrConfigHandlerConcurrent, TestDefaultStatsCache, AddReplicaTest, 
ExitableDirectoryReaderTest, DateFieldTest, ZkFailoverTest, OverseerTest, 
TestCorePropertiesReload, TestStressLucene, TestIndexingPerformance, 
RequestLoggingTest, MetricsHistoryIntegrationTest, 
CloneFieldUpdateProcessorFactoryTest, TestGraphMLResponseWriter, 
DistributedExpandComponentTest, TestFieldCache, TestDynamicURP, 
CacheHeaderTest, TestBulkSchemaConcurrent, ShowFileRequestHandlerTest, 
TestDocSet, RestartWhileUpdatingTest, TestSolrCloudWithDelegationTokens, 
TestCollapseQParserPlugin, TestCursorMarkWithoutUniqueKey, TestLazyCores, 
TestCoreBackup, OutputWriterTest, SolrCloudExampleTest, 
UninvertDocValuesMergePolicyTest, TestSimUtils, TestSchemaNameResource, 
TestLocalFSCloudBackupRestore, DocumentBuilderTest, 
PKIAuthenticationIntegrationTest, RestoreTriggerStateTest, 
TestNamedUpdateProcessors, TestDistributedStatsComponentCardinality, 
DeleteNodeTest, CoreAdminHandlerTest, MergeStrategyTest, OverseerRolesTest, 
TestApiFramework, HLLSerializationTest, SchemaVersionSpecificBehaviorTest, 
AuditLoggerIntegrationTest, MoveReplicaHDFSTest, 
SignatureUpdateProcessorFactoryTest, TestDynamicFieldNamesIndexCorrectly, 
ParsingFieldUpdateProcessorsTest, XmlUpdateRequestHandlerTest, 
CircularListTest, FieldMutatingUpdateProcessorTest, CdcrOpsAndBoundariesTest, 
TestSolrFieldCacheBean, TestRangeQuery, HDFSCollectionsAPITest, 
BufferStoreTest, InfixSuggestersTest, PreAnalyzedFieldTest, 
SolrCoreMetricManagerTest, TestReversedWildcardFilterFactory, 
AddBlockUpdateTest, TestHdfsUpdateLog, NodeMutatorTest, BasicZkTest, 
TestSSLRandomization, TestSimDistributedQueue, TestExportWriter, 
CloudMLTQParserTest, TestSimpleTrackingShardHandler, CollectionPropsTest, 
DistributedQueryElevationComponentTest, TestCloudJSONFacetSKG, 
PeerSyncWithLeaderAndIndexFingerprintCachingTest, HdfsThreadLeakTest, 
SmileWriterTest, IndexSizeTriggerMixedBoundsTest, 
WordBreakSolrSpellCheckerTest, TestSmileRequest, TestManagedResourceStorage, 
TaggerTest, TestMiniSolrCloudClusterSSL, TestSimNodeAddedTrigger, 
SortByFunctionTest, HdfsNNFailoverTest, NodeAddedTriggerIntegrationTest, 
TestDocumentBuilder, TestGroupingSearch, TestHighlightDedupGrouping, 
TestSolrTestCaseJ4, TestTolerantSearch, TestTrie, 
PathHierarchyTokenizerFactoryTest, ProtectedTermFilterFactoryTest, 
TokenizerChainTest, TestLuceneIndexBackCompat, 
TestEmbeddedSolrServerAdminHandler, TestEmbeddedSolrServerConstructors, 
TestEmbeddedSolrServerSchemaAPI, ChaosMonkeyNothingIsSafeWithPullReplicasTest, 
ConcurrentCreateRoutedAliasTest, ConfigSetsAPITest, DistributedVersionInfoTest, 
HttpPartitionTest, LeaderFailoverAfterPartitionTest, 
RoutingToNodesWithPropertiesTest, ShardRoutingTest, SystemCollectionCompatTest]
   [junit4] Completed [538/900 (1!)] on J3 in 142.12s, 1 test, 1 error <<< 
FAILURES!

[...truncated 10820 lines...]
BUILD FAILED
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\build.xml:634: The 
following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\build.xml:128: Found 2 
violations in source files (Unescaped symbol "->" on line #43, Unescaped symbol 
"->" on line #52).

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

Reply via email to