Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/4316/

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

Stack Trace:
org.apache.solr.client.solrj.SolrServerException: No live SolrServers available 
to handle this request:[http://127.0.0.1:42823/solr/.system]
        at 
__randomizedtesting.SeedInfo.seed([8F3E71A8919E46BA:FFCBD201F156EFCC]:0)
        at 
org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:345)
        at 
org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1143)
        at 
org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:906)
        at 
org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:838)
        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207)
        at 
org.apache.solr.cloud.SystemCollectionCompatTest.setupSystemCollection(SystemCollectionCompatTest.java:118)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:566)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:976)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
        at java.base/java.lang.Thread.run(Thread.java:834)
Caused by: org.apache.solr.client.solrj.SolrServerException: Timeout occurred 
while waiting response from server at: http://127.0.0.1:42823/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 14245 lines...]
   [junit4] Suite: org.apache.solr.cloud.SystemCollectionCompatTest
   [junit4]   2> 2945073 INFO  
(SUITE-SystemCollectionCompatTest-seed#[8F3E71A8919E46BA]-worker) [     ] 
o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to 
test-framework derived value of 
'/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/server/solr/configsets/_default/conf'
   [junit4]   2> 2945073 INFO  
(SUITE-SystemCollectionCompatTest-seed#[8F3E71A8919E46BA]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Created dataDir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_8F3E71A8919E46BA-001/data-dir-168-001
   [junit4]   2> 2945073 INFO  
(SUITE-SystemCollectionCompatTest-seed#[8F3E71A8919E46BA]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 2945074 INFO  
(SUITE-SystemCollectionCompatTest-seed#[8F3E71A8919E46BA]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, 
clientAuth=0.0/0.0)
   [junit4]   2> 2945074 INFO  
(SUITE-SystemCollectionCompatTest-seed#[8F3E71A8919E46BA]-worker) [     ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 2945075 INFO  
(SUITE-SystemCollectionCompatTest-seed#[8F3E71A8919E46BA]-worker) [     ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_8F3E71A8919E46BA-001/tempDir-001
   [junit4]   2> 2945075 INFO  
(SUITE-SystemCollectionCompatTest-seed#[8F3E71A8919E46BA]-worker) [     ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2945076 INFO  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2945076 INFO  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 2945176 INFO  
(SUITE-SystemCollectionCompatTest-seed#[8F3E71A8919E46BA]-worker) [     ] 
o.a.s.c.ZkTestServer start zk server on port:37922
   [junit4]   2> 2945176 INFO  
(SUITE-SystemCollectionCompatTest-seed#[8F3E71A8919E46BA]-worker) [     ] 
o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:37922
   [junit4]   2> 2945176 INFO  
(SUITE-SystemCollectionCompatTest-seed#[8F3E71A8919E46BA]-worker) [     ] 
o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:37922
   [junit4]   2> 2945176 INFO  
(SUITE-SystemCollectionCompatTest-seed#[8F3E71A8919E46BA]-worker) [     ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1 37922
   [junit4]   2> 2945178 INFO  
(SUITE-SystemCollectionCompatTest-seed#[8F3E71A8919E46BA]-worker) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2945181 INFO  (zkConnectionManagerCallback-10883-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2945181 INFO  
(SUITE-SystemCollectionCompatTest-seed#[8F3E71A8919E46BA]-worker) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2945184 INFO  
(SUITE-SystemCollectionCompatTest-seed#[8F3E71A8919E46BA]-worker) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2945185 INFO  (zkConnectionManagerCallback-10885-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2945185 INFO  
(SUITE-SystemCollectionCompatTest-seed#[8F3E71A8919E46BA]-worker) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2945186 INFO  
(SUITE-SystemCollectionCompatTest-seed#[8F3E71A8919E46BA]-worker) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2945187 INFO  (zkConnectionManagerCallback-10887-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2945187 INFO  
(SUITE-SystemCollectionCompatTest-seed#[8F3E71A8919E46BA]-worker) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2945292 INFO  (jetty-launcher-10888-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 2945292 INFO  (jetty-launcher-10888-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 2945292 INFO  (jetty-launcher-10888-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 2945292 INFO  (jetty-launcher-10888-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 2945292 INFO  (jetty-launcher-10888-thread-1) [     ] 
o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 
363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+10-LTS
   [junit4]   2> 2945292 INFO  (jetty-launcher-10888-thread-2) [     ] 
o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 
363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+10-LTS
   [junit4]   2> 2945295 INFO  (jetty-launcher-10888-thread-2) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2945295 INFO  (jetty-launcher-10888-thread-2) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2945295 INFO  (jetty-launcher-10888-thread-2) [     ] 
o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 2945295 INFO  (jetty-launcher-10888-thread-1) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2945295 INFO  (jetty-launcher-10888-thread-1) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2945295 INFO  (jetty-launcher-10888-thread-1) [     ] 
o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 2945295 INFO  (jetty-launcher-10888-thread-2) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@4d6f2489{/solr,null,AVAILABLE}
   [junit4]   2> 2945296 INFO  (jetty-launcher-10888-thread-1) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@70ffa2bb{/solr,null,AVAILABLE}
   [junit4]   2> 2945296 INFO  (jetty-launcher-10888-thread-2) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@5ea1c60f{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:42823}
   [junit4]   2> 2945296 INFO  (jetty-launcher-10888-thread-2) [     ] 
o.e.j.s.Server Started @2945334ms
   [junit4]   2> 2945296 INFO  (jetty-launcher-10888-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=42823}
   [junit4]   2> 2945296 ERROR (jetty-launcher-10888-thread-2) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2945296 INFO  (jetty-launcher-10888-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2945296 INFO  (jetty-launcher-10888-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
9.0.0
   [junit4]   2> 2945296 INFO  (jetty-launcher-10888-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2945296 INFO  (jetty-launcher-10888-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr
   [junit4]   2> 2945296 INFO  (jetty-launcher-10888-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-02-23T10:15:29.393964Z
   [junit4]   2> 2945297 INFO  (jetty-launcher-10888-thread-1) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@58e4aff9{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:36967}
   [junit4]   2> 2945297 INFO  (jetty-launcher-10888-thread-1) [     ] 
o.e.j.s.Server Started @2945335ms
   [junit4]   2> 2945297 INFO  (jetty-launcher-10888-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=36967}
   [junit4]   2> 2945297 ERROR (jetty-launcher-10888-thread-1) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2945297 INFO  (jetty-launcher-10888-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2945297 INFO  (jetty-launcher-10888-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
9.0.0
   [junit4]   2> 2945297 INFO  (jetty-launcher-10888-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2945297 INFO  (jetty-launcher-10888-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr
   [junit4]   2> 2945297 INFO  (jetty-launcher-10888-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-02-23T10:15:29.394911Z
   [junit4]   2> 2945298 INFO  (jetty-launcher-10888-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2945299 INFO  (jetty-launcher-10888-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2945300 INFO  (zkConnectionManagerCallback-10890-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2945300 INFO  (jetty-launcher-10888-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2945300 INFO  (zkConnectionManagerCallback-10892-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2945300 INFO  (jetty-launcher-10888-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2945301 INFO  (jetty-launcher-10888-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2945301 INFO  (jetty-launcher-10888-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2945737 INFO  (jetty-launcher-10888-thread-1) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 2945742 WARN  (jetty-launcher-10888-thread-1) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@2c4200cc[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2945742 WARN  (jetty-launcher-10888-thread-1) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@2c4200cc[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2945745 WARN  (jetty-launcher-10888-thread-1) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@dc48795[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2945745 WARN  (jetty-launcher-10888-thread-1) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@dc48795[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2945747 INFO  (jetty-launcher-10888-thread-1) [     ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37922/solr
   [junit4]   2> 2945749 INFO  (jetty-launcher-10888-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2945750 INFO  (zkConnectionManagerCallback-10900-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2945750 INFO  (jetty-launcher-10888-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2945853 INFO  (jetty-launcher-10888-thread-1) 
[n:127.0.0.1:36967_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 2945858 INFO  (zkConnectionManagerCallback-10902-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2945858 INFO  (jetty-launcher-10888-thread-1) 
[n:127.0.0.1:36967_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 2946003 INFO  (jetty-launcher-10888-thread-1) 
[n:127.0.0.1:36967_solr     ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:36967_solr
   [junit4]   2> 2946004 INFO  (jetty-launcher-10888-thread-1) 
[n:127.0.0.1:36967_solr     ] o.a.s.c.Overseer Overseer 
(id=72334710704832518-127.0.0.1:36967_solr-n_0000000000) starting
   [junit4]   2> 2946012 INFO  
(OverseerStateUpdate-72334710704832518-127.0.0.1:36967_solr-n_0000000000) 
[n:127.0.0.1:36967_solr     ] o.a.s.c.Overseer Starting to work on the main 
queue : 127.0.0.1:36967_solr
   [junit4]   2> 2946014 INFO  (jetty-launcher-10888-thread-1) 
[n:127.0.0.1:36967_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:36967_solr
   [junit4]   2> 2946016 INFO  (zkCallback-10901-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2946018 INFO  (jetty-launcher-10888-thread-1) 
[n:127.0.0.1:36967_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 2946019 WARN  (jetty-launcher-10888-thread-1) 
[n:127.0.0.1:36967_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> 2946053 INFO  (jetty-launcher-10888-thread-1) 
[n:127.0.0.1:36967_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 2946092 INFO  (jetty-launcher-10888-thread-1) 
[n:127.0.0.1:36967_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_36967.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@27c9a70a
   [junit4]   2> 2946106 INFO  (jetty-launcher-10888-thread-1) 
[n:127.0.0.1:36967_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_36967.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@27c9a70a
   [junit4]   2> 2946106 INFO  (jetty-launcher-10888-thread-1) 
[n:127.0.0.1:36967_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_36967.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@27c9a70a
   [junit4]   2> 2946108 INFO  (jetty-launcher-10888-thread-1) 
[n:127.0.0.1:36967_solr     ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_8F3E71A8919E46BA-001/tempDir-001/node1/.
   [junit4]   2> 2948093 INFO  (jetty-launcher-10888-thread-2) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 2948111 WARN  (jetty-launcher-10888-thread-2) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@47818b71[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2948111 WARN  (jetty-launcher-10888-thread-2) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@47818b71[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2948121 WARN  (jetty-launcher-10888-thread-2) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@4089dbb5[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2948121 WARN  (jetty-launcher-10888-thread-2) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@4089dbb5[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2948123 INFO  (jetty-launcher-10888-thread-2) [     ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37922/solr
   [junit4]   2> 2948124 INFO  (jetty-launcher-10888-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2948127 INFO  (zkConnectionManagerCallback-10912-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2948127 INFO  (jetty-launcher-10888-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2948230 INFO  (jetty-launcher-10888-thread-2) 
[n:127.0.0.1:42823_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 2948231 INFO  (zkConnectionManagerCallback-10914-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2948231 INFO  (jetty-launcher-10888-thread-2) 
[n:127.0.0.1:42823_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 2948241 INFO  (jetty-launcher-10888-thread-2) 
[n:127.0.0.1:42823_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 2948246 INFO  (jetty-launcher-10888-thread-2) 
[n:127.0.0.1:42823_solr     ] o.a.s.c.ZkController Publish 
node=127.0.0.1:42823_solr as DOWN
   [junit4]   2> 2948248 INFO  (jetty-launcher-10888-thread-2) 
[n:127.0.0.1:42823_solr     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 2948248 INFO  (jetty-launcher-10888-thread-2) 
[n:127.0.0.1:42823_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:42823_solr
   [junit4]   2> 2948249 INFO  (zkCallback-10901-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2948250 INFO  (zkCallback-10913-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2948251 INFO  (jetty-launcher-10888-thread-2) 
[n:127.0.0.1:42823_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 2948252 WARN  (jetty-launcher-10888-thread-2) 
[n:127.0.0.1:42823_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> 2948273 INFO  (jetty-launcher-10888-thread-2) 
[n:127.0.0.1:42823_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 2948306 INFO  (jetty-launcher-10888-thread-2) 
[n:127.0.0.1:42823_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_42823.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@27c9a70a
   [junit4]   2> 2948322 INFO  (jetty-launcher-10888-thread-2) 
[n:127.0.0.1:42823_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_42823.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@27c9a70a
   [junit4]   2> 2948322 INFO  (jetty-launcher-10888-thread-2) 
[n:127.0.0.1:42823_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_42823.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@27c9a70a
   [junit4]   2> 2948326 INFO  (jetty-launcher-10888-thread-2) 
[n:127.0.0.1:42823_solr     ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_8F3E71A8919E46BA-001/tempDir-001/node2/.
   [junit4]   2> 2948364 INFO  
(SUITE-SystemCollectionCompatTest-seed#[8F3E71A8919E46BA]-worker) [     ] 
o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
   [junit4]   2> 2948365 INFO  
(SUITE-SystemCollectionCompatTest-seed#[8F3E71A8919E46BA]-worker) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2948366 INFO  (zkConnectionManagerCallback-10923-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2948366 INFO  
(SUITE-SystemCollectionCompatTest-seed#[8F3E71A8919E46BA]-worker) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2948369 INFO  
(SUITE-SystemCollectionCompatTest-seed#[8F3E71A8919E46BA]-worker) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 2948371 INFO  
(SUITE-SystemCollectionCompatTest-seed#[8F3E71A8919E46BA]-worker) [     ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37922/solr ready
   [junit4]   2> 2948401 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[8F3E71A8919E46BA]) [     
] o.a.s.SolrTestCaseJ4 ###Starting testBackCompat
   [junit4]   2> 2948404 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[8F3E71A8919E46BA]) [     
] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2948406 INFO  (zkConnectionManagerCallback-10928-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2948406 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[8F3E71A8919E46BA]) [     
] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2948408 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[8F3E71A8919E46BA]) [     
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 2948410 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[8F3E71A8919E46BA]) [     
] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37922/solr ready
   [junit4]   2> 2948413 INFO  (qtp1445153613-32658) [n:127.0.0.1:36967_solr    
 ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with 
params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2948418 INFO  (qtp1445153613-32658) [n:127.0.0.1:36967_solr    
 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=5
   [junit4]   2> 2948420 INFO  (qtp1445153613-32656) [n:127.0.0.1:36967_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:42823_solr&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 2948430 INFO  
(OverseerThreadFactory-9062-thread-2-processing-n:127.0.0.1:36967_solr) 
[n:127.0.0.1:36967_solr     ] o.a.s.c.a.c.CreateCollectionCmd Create collection 
.system
   [junit4]   2> 2948430 INFO  
(OverseerCollectionConfigSetProcessor-72334710704832518-127.0.0.1:36967_solr-n_0000000000)
 [n:127.0.0.1:36967_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> 2948535 WARN  
(OverseerThreadFactory-9062-thread-2-processing-n:127.0.0.1:36967_solr) 
[n:127.0.0.1:36967_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> 2948540 INFO  
(OverseerStateUpdate-72334710704832518-127.0.0.1:36967_solr-n_0000000000) 
[n:127.0.0.1:36967_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:42823/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:42823_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2948543 INFO  
(OverseerStateUpdate-72334710704832518-127.0.0.1:36967_solr-n_0000000000) 
[n:127.0.0.1:36967_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:42823/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:42823_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2948752 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2948752 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2948771 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2948771 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2948806 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2948806 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2948810 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2948812 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2948815 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2948815 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2948816 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2948816 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2948819 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2948820 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_42823.solr.core..system.shard1.replica_n2' (registry 
'solr.core..system.shard1.replica_n2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@27c9a70a
   [junit4]   2> 2948821 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.SolrCore 
[[.system_shard1_replica_n2] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_8F3E71A8919E46BA-001/tempDir-001/node2/.system_shard1_replica_n2],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_8F3E71A8919E46BA-001/tempDir-001/node2/./.system_shard1_replica_n2/data/]
   [junit4]   2> 2948822 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2948823 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2948823 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_42823.solr.core..system.shard1.replica_n1' (registry 
'solr.core..system.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@27c9a70a
   [junit4]   2> 2948823 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore 
[[.system_shard1_replica_n1] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_8F3E71A8919E46BA-001/tempDir-001/node2/.system_shard1_replica_n1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_8F3E71A8919E46BA-001/tempDir-001/node2/./.system_shard1_replica_n1/data/]
   [junit4]   2> 2948932 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2948932 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2948934 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2948934 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2948936 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2948936 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2948936 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@6b38d141[.system_shard1_replica_n2] 
main]
   [junit4]   2> 2948938 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2948938 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2948940 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@27e6a292[.system_shard1_replica_n1] 
main]
   [junit4]   2> 2948941 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2948942 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2948942 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2948943 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2948944 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2948944 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2948944 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2948945 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2948945 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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 
1659322166717448192
   [junit4]   2> 2948945 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2948947 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2948949 INFO  
(searcherExecutor-9074-thread-1-processing-n:127.0.0.1:42823_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:42823_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@6b38d141[.system_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2948950 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2948950 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2948951 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2948951 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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 
1659322166723739648
   [junit4]   2> 2948954 INFO  
(searcherExecutor-9075-thread-1-processing-n:127.0.0.1:42823_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:42823_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@27e6a292[.system_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2948956 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2948956 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2948957 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2948957 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2948965 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2948965 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 2948965 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:42823/solr/.system_shard1_replica_n2/
   [junit4]   2> 2948965 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.PeerSync 
PeerSync: core=.system_shard1_replica_n2 url=http://127.0.0.1:42823/solr START 
replicas=[http://127.0.0.1:42823/solr/.system_shard1_replica_n1/] nUpdates=100
   [junit4]   2> 2948966 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.PeerSync 
PeerSync: core=.system_shard1_replica_n2 url=http://127.0.0.1:42823/solr DONE.  
We have no versions.  sync failed.
   [junit4]   2> 2948968 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2948968 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the 
next candidate
   [junit4]   2> 2948968 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we 
can't sync in that case - we were active before, so become leader anyway
   [junit4]   2> 2948968 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node 
/collections/.system/leaders/shard1/leader after winning as 
/collections/.system/leader_elect/shard1/election/72334710704832520-core_node4-n_0000000000
   [junit4]   2> 2948970 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:42823/solr/.system_shard1_replica_n2/ shard1
   [junit4]   2> 2949072 INFO  (zkCallback-10913-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> 2949072 INFO  (zkCallback-10913-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> 2949073 INFO  (zkCallback-10913-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> 2949073 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 2949076 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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=324
   [junit4]   2> 2949176 INFO  (zkCallback-10913-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> 2949176 INFO  (zkCallback-10913-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> 2949176 INFO  (zkCallback-10913-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> 2949176 INFO  (zkCallback-10913-thread-4) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] 
for collection [.system] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 2949967 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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=1216
   [junit4]   2> 2949971 INFO  (qtp1445153613-32656) [n:127.0.0.1:36967_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> 2950069 INFO  (zkCallback-10913-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> 2950069 INFO  (zkCallback-10913-thread-4) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] 
for collection [.system] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 2950069 INFO  (zkCallback-10901-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> 2950069 INFO  (zkCallback-10913-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> 2950069 INFO  (zkCallback-10913-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> 2950070 INFO  (qtp1445153613-32656) [n:127.0.0.1:36967_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:42823_solr&wt=javabin&version=2}
 status=0 QTime=1649
   [junit4]   2> 2950070 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[8F3E71A8919E46BA]) [     
] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: .system
   [junit4]   2> 2950080 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/.system/terms/shard1 to Terms{values={core_node3=1, core_node4=1}, 
version=2}
   [junit4]   2> 2950085 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n1]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:42823/solr/.system_shard1_replica_n2/&wt=javabin&version=2}{add=[170718b861bTumyipttnmwd3k6aqcjrxc409
 (1659322167904436224)]} 0 1
   [junit4]   2> 2950086 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n2]  webapp=/solr 
path=/update 
params={wt=javabin&version=2}{add=[170718b861bTumyipttnmwd3k6aqcjrxc409 
(1659322167904436224)]} 0 10
   [junit4]   2> 2950087 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1659322167914921984,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2950087 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_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@1f2848a 
commitCommandVersion:1659322167914921984
   [junit4]   2> 2950088 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1659322167915970560,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2950088 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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@4d56e061 
commitCommandVersion:1659322167915970560
   [junit4]   2> 2950092 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@69a32364[.system_shard1_replica_n1] 
main]
   [junit4]   2> 2950093 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@966bf7b[.system_shard1_replica_n2] 
main]
   [junit4]   2> 2950093 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2950093 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2950096 INFO  
(searcherExecutor-9075-thread-1-processing-n:127.0.0.1:42823_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:42823_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@69a32364[.system_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={java.version=11.0.4,
 java.vm.version=11.0.4+10-LTS, lucene.version=9.0.0, source=flush, 
os.arch=amd64, java.runtime.version=11.0.4+10-LTS, 
os.version=4.4.0-170-generic, os=Linux, java.vendor=Oracle Corporation, 
timestamp=1582452934187}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 2950096 INFO  
(searcherExecutor-9074-thread-1-processing-n:127.0.0.1:42823_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:42823_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@966bf7b[.system_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={java.version=11.0.4,
 java.vm.version=11.0.4+10-LTS, lucene.version=9.0.0, source=flush, 
os.arch=amd64, java.runtime.version=11.0.4+10-LTS, 
os.version=4.4.0-170-generic, os=Linux, java.vendor=Oracle Corporation, 
timestamp=1582452934187}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 2950096 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n1]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:42823/solr/.system_shard1_replica_n2/&commit_end_point=replicas&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 8
   [junit4]   2> 2950097 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n2]  webapp=/solr 
path=/update 
params={_stateVer_=.system:5&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 10
   [junit4]   2> 2950099 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2950100 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_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> 2950101 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2950107 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2950109 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2950112 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2950114 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2950116 INFO  (Thread-5593) [     ] o.a.s.c.SolrCore config 
update listener called for core .system_shard1_replica_n1
   [junit4]   2> 2950117 INFO  (Thread-5593) [     ] o.a.s.c.SolrCore config 
update listener called for core .system_shard1_replica_n2
   [junit4]   2> 2950121 INFO  (Thread-5593) [     ] o.a.s.c.SolrCore core 
reload .system_shard1_replica_n2
   [junit4]   2> 2950128 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2950137 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2950142 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2950142 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2950268 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_42823.solr.core..system.shard1.replica_n2' (registry 
'solr.core..system.shard1.replica_n2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@27c9a70a
   [junit4]   2> 2950268 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.SolrCore 
[[.system_shard1_replica_n2] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_8F3E71A8919E46BA-001/tempDir-001/node2/.system_shard1_replica_n2],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_8F3E71A8919E46BA-001/tempDir-001/node2/./.system_shard1_replica_n2/data/]
   [junit4]   2> 2950351 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2950351 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2950355 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@290c5767[.system_shard1_replica_n2] 
main]
   [junit4]   2> 2950357 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2950358 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2950358 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2950358 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2950358 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2950362 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2950365 INFO  
(searcherExecutor-9084-thread-1-processing-n:127.0.0.1:42823_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:42823_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@290c5767[.system_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={java.version=11.0.4,
 java.vm.version=11.0.4+10-LTS, lucene.version=9.0.0, source=flush, 
os.arch=amd64, java.runtime.version=11.0.4+10-LTS, 
os.version=4.4.0-170-generic, os=Linux, java.vendor=Oracle Corporation, 
timestamp=1582452934187}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 2950366 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@3e8c4c7[.system_shard1_replica_n2] 
main]
   [junit4]   2> 2950366 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 2950371 INFO  
(searcherExecutor-9084-thread-1-processing-n:127.0.0.1:42823_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:42823_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@3e8c4c7[.system_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={java.version=11.0.4,
 java.vm.version=11.0.4+10-LTS, lucene.version=9.0.0, source=flush, 
os.arch=amd64, java.runtime.version=11.0.4+10-LTS, 
os.version=4.4.0-170-generic, java.vendor=Oracle Corporation, os=Linux, 
timestamp=1582452934187}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 2950372 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2950372 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2950372 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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=3
   [junit4]   2> 2950374 INFO  (Thread-5593) [     ] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 9.0.0
   [junit4]   2> 2950377 ERROR 
(managedSchemaExecutor-9088-thread-1-processing-n:127.0.0.1:42823_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:42823_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:42823/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 2950378 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_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> 2950379 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_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> 2950379 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_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> 2950379 ERROR 
(managedSchemaExecutor-9088-thread-1-processing-n:127.0.0.1:42823_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:42823_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:42823/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 2950380 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2950380 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2950380 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2950381 ERROR 
(managedSchemaExecutor-9088-thread-1-processing-n:127.0.0.1:42823_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:42823_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:42823/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 2950381 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_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> 2950382 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_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> 2950382 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_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> 2950382 ERROR 
(managedSchemaExecutor-9088-thread-1-processing-n:127.0.0.1:42823_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:42823_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:42823/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 2950382 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2950383 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2950383 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2950384 ERROR 
(managedSchemaExecutor-9088-thread-1-processing-n:127.0.0.1:42823_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:42823_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:42823/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 2950385 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_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> 2950386 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_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> 2950386 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/schema/zkversion 
params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=1
   [junit4]   2> 2950387 ERROR 
(managedSchemaExecutor-9088-thread-1-processing-n:127.0.0.1:42823_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:42823_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:42823/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 2950387 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2950388 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2950388 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2950388 ERROR 
(managedSchemaExecutor-9088-thread-1-processing-n:127.0.0.1:42823_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:42823_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:42823/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 2950389 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_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> 2950389 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_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> 2950389 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_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> 2950390 ERROR 
(managedSchemaExecutor-9088-thread-1-processing-n:127.0.0.1:42823_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:42823_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:42823/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 2950390 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2950391 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2950391 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2950391 ERROR 
(managedSchemaExecutor-9088-thread-1-processing-n:127.0.0.1:42823_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:42823_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:42823/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 2950391 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_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> 2950392 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_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> 2950392 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_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> 2950392 ERROR 
(managedSchemaExecutor-9088-thread-1-processing-n:127.0.0.1:42823_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:42823_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:42823/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 2950393 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2950385 INFO  (Thread-5593) [     ] o.a.s.s.IndexSchema 
[.system_shard1_replica_n2] Schema name=_system collection or core
   [junit4]   2> 2950393 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2950393 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2950393 ERROR 
(managedSchemaExecutor-9088-thread-1-processing-n:127.0.0.1:42823_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:42823_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:42823/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 2950394 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_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> 2950394 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_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> 2950394 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_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> 2950395 ERROR 
(managedSchemaExecutor-9088-thread-1-processing-n:127.0.0.1:42823_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:42823_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:42823/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 2950395 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2950396 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2950396 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2950396 ERROR 
(managedSchemaExecutor-9088-thread-1-processing-n:127.0.0.1:42823_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:42823_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:42823/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 2950397 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_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> 2950397 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_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> 2950397 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_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> 2950397 INFO  (Thread-5593) [     ] o.a.s.s.IndexSchema Loaded 
schema _system collection or core/1.1 with uniqueid field id
   [junit4]   2> 2950397 ERROR 
(managedSchemaExecutor-9088-thread-1-processing-n:127.0.0.1:42823_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:42823_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:42823/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 2950397 INFO  (Thread-5593) [     ] o.a.s.c.CoreContainer 
Reloading SolrCore '.system_shard1_replica_n2' using configuration from 
configset .system
   [junit4]   2> 2950398 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2950398 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2950398 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2950399 ERROR 
(managedSchemaExecutor-9088-thread-1-processing-n:127.0.0.1:42823_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:42823_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:42823/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 2950399 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_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> 2950403 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_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> 2950403 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_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=3
   [junit4]   2> 2950404 ERROR 
(managedSchemaExecutor-9088-thread-1-processing-n:127.0.0.1:42823_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:42823_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:42823/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 2950412 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2950412 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2950412 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2950412 ERROR 
(managedSchemaExecutor-9088-thread-1-processing-n:127.0.0.1:42823_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:42823_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:42823/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 2950413 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_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> 2950414 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_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> 2950414 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_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> 2950414 ERROR 
(managedSchemaExecutor-9088-thread-1-processing-n:127.0.0.1:42823_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:42823_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:42823/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 2950414 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2950415 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2950415 INFO  (qtp1348874867-32653) [n:127.0.0.1:42823_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> 2950415 ERROR 
(managedSchemaExecutor-9088-thread-1-processing-n:127.0.0.1:42823_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:42823_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
http://127.0.0.1:42823/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 2950416 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_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> 2950416 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_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> 2950416 INFO  (qtp1348874867-32657) [n:127.0.0.1:42823_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> 2950417 ERROR 
(managedSchemaExecutor-9088-thread-1-processing-n:127.0.

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

m s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ManagedIndexSchema Failed to get /schema/zkversion from 
http://127.0.0.1:42823/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:42823/solr/.system_shard1_replica_n1
   [junit4]   2> 3086828 WARN  
(managedSchemaExecutor-9088-thread-1-processing-n:127.0.0.1:42823_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:42823_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Failed to get 
/schema/zkversion from http://127.0.0.1:42823/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:42823/solr/.system_shard1_replica_n1
   [junit4]   2> 3086872 WARN  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 3086872 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ManagedIndexSchema Took 136505.0ms for 1 replicas to apply schema 
update version 2 for collection .system
   [junit4]   2> 3086872 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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=136768
   [junit4]   2> 3086872 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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@12fcb76f
   [junit4]   2> 3086872 WARN  (qtp1348874867-32655) [n:127.0.0.1:42823_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> 3086872 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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@12fcb76f
   [junit4]   2> 3086872 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() .
   [junit4]   2> 3086872 INFO  (qtp1348874867-32655) [n:127.0.0.1:42823_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@6565e977 commitCommandVersion:0
   [junit4]   2> 3086875 INFO  
(SUITE-SystemCollectionCompatTest-seed#[8F3E71A8919E46BA]-worker) [     ] 
o.a.s.c.ZkTestServer Shutting down ZkTestServer.
   [junit4]   2> 3087117 WARN  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer Watch limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        7       /solr/clusterprops.json
   [junit4]   2>        7       /solr/aliases.json
   [junit4]   2>        7       /solr/collections/.system/terms/shard1
   [junit4]   2>        4       /solr/configs/.system/managed-schema
   [junit4]   2>        2       /solr/configs/.system
   [junit4]   2>        2       /solr/packages.json
   [junit4]   2>        2       /solr/security.json
   [junit4]   2>        2       /solr/collections/.system/collectionprops.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        25      /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>        9       /solr/overseer/collection-queue-work
   [junit4]   2> 
   [junit4]   2> 3087118 INFO  
(SUITE-SystemCollectionCompatTest-seed#[8F3E71A8919E46BA]-worker) [     ] 
o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:37922
   [junit4]   2> 3087118 INFO  
(SUITE-SystemCollectionCompatTest-seed#[8F3E71A8919E46BA]-worker) [     ] 
o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:37922
   [junit4]   2> 3087118 INFO  
(SUITE-SystemCollectionCompatTest-seed#[8F3E71A8919E46BA]-worker) [     ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1 37922
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_8F3E71A8919E46BA-001
   [junit4]   2> Feb 23, 2020 10:17:51 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene84): {id=FST50, 
type=Lucene84}, docValues:{timestamp_l=DocValuesFormat(name=Lucene80), 
_version_=DocValuesFormat(name=Asserting), 
time_l=DocValuesFormat(name=Asserting), 
timestamp=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=741, 
maxMBSortInHeap=5.519633679747632, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@7fa9c85),
 locale=fr-MQ, timezone=Asia/Jakarta
   [junit4]   2> NOTE: Linux 4.4.0-170-generic amd64/Oracle Corporation 11.0.4 
(64-bit)/cpus=8,threads=1,free=101015040,total=479199232
   [junit4]   2> NOTE: All tests run in this JVM: [SystemInfoHandlerTest, 
TestClusterStateMutator, TestRuleBasedAuthorizationPlugin, 
MultiThreadedOCPTest, LoggingHandlerTest, SimpleCollectionCreateDeleteTest, 
TestRetrieveFieldsOptimizer, JavaBinAtomicUpdateMultivalueTest, 
TestJsonFacetsStatsParsing, ExitableDirectoryReaderTest, TestFunctionQuery, 
MaxSizeAutoCommitTest, CreateRoutedAliasTest, 
PeerSyncWithIndexFingerprintCachingTest, TestMultiWordSynonyms, TestQueryTypes, 
CheckHdfsIndexTest, TestPushWriter, TestFoldingMultitermQuery, 
RemoteQueryErrorTest, SolrCoreTest, BasicAuthIntegrationTest, TestSimScenario, 
ShardRoutingCustomTest, HttpPartitionTest, TestFieldSortValues, 
TestCloudConsistency, SolrCoreMetricManagerTest, 
LeaderFailureAfterFreshStartTest, TestUpdate, SolrMetricsIntegrationTest, 
TestPointFields, CursorPagingTest, BooleanFieldTest, HDFSCollectionsAPITest, 
TestRandomCollapseQParserPlugin, ConfigSetsAPITest, Tagger2Test, 
ScriptEngineTest, TestManagedSynonymGraphFilterFactory, 
TestSweetSpotSimilarityFactory, TestInPlaceUpdateWithRouteField, 
TestBlobHandler, TestDistributedStatsComponentCardinality, BasicZkTest, 
TestFieldCollectionResource, TestReload, TestSolrConfigHandlerCloud, 
EchoParamsTest, TestFieldCacheVsDocValues, RulesTest, DaemonStreamApiTest, 
TestImpersonationWithHadoopAuth, TestAtomicUpdateErrorCases, 
TestSegmentSorting, TestFieldTypeResource, TestLazyCores, HLLSerializationTest, 
TestLMDirichletSimilarityFactory, SuggesterTest, TestAnalyzedSuggestions, 
TestCollationFieldDocValues, ActionThrottleTest, TestStressLucene, 
DeleteStatusTest, TestSortByMinMaxFunction, AutoscalingHistoryHandlerTest, 
TestManagedSchemaAPI, WordBreakSolrSpellCheckerTest, 
CategoryRoutedAliasUpdateProcessorTest, TestCopyFieldCollectionResource, 
HdfsChaosMonkeyNothingIsSafeTest, SparseHLLTest, TestDistribDocBasedVersion, 
TestPostingsSolrHighlighter, TestLuceneIndexBackCompat, TestLockTree, 
RequiredFieldsTest, TestEmbeddedSolrServerSchemaAPI, TestFieldCacheReopen, 
ZkStateWriterTest, SortSpecParsingTest, SynonymTokenizerTest, 
ReplicationFactorTest, CollectionsAPIDistributedZkTest, TestStressUserVersions, 
TestSolrCoreProperties, TestReversedWildcardFilterFactory, CleanupOldIndexTest, 
StatelessScriptUpdateProcessorFactoryTest, HdfsRecoverLeaseTest, 
TestMaxTokenLenTokenizer, CustomCollectionTest, TestConfigsApi, TestXmlQParser, 
JSONWriterTest, NodeAddedTriggerTest, TestCloudPhrasesIdentificationComponent, 
XmlInterpolationTest, SearchHandlerTest, BufferStoreTest, 
TestCSVResponseWriter, TimeZoneUtilsTest, TestReplicationHandlerBackup, 
DistributedFacetPivotWhiteBoxTest, TermVectorComponentDistributedTest, 
TestSkipOverseerOperations, SolrXmlInZkTest, TestCoreBackup, BadComponentTest, 
NumberUtilsTest, RootFieldTest, SolrJmxReporterTest, TestLogWatcher, 
TestSubQueryTransformerCrossCore, StatsComponentTest, 
TestConfigSetsAPIZkFailure, TestDocTermOrds, HdfsUnloadDistributedZkTest, 
TestGroupingSearch, FieldAnalysisRequestHandlerTest, 
UniqFieldsUpdateProcessorFactoryTest, FullSolrCloudDistribCmdsTest, 
CoreAdminOperationTest, TestExclusionRuleCollectionAccess, 
SimpleMLTQParserTest, TestCloudJSONFacetSKG, TestSystemCollAutoCreate, 
TestCollectionsAPIViaSolrCloudCluster, 
DimensionalRoutedAliasUpdateProcessorTest, TestCloudManagedSchema, 
ClusterStateTest, TestLRUStatsCache, TestCaffeineCache, TestWriterPerf, 
TestWaitForStateWithJettyShutdowns, SpellPossibilityIteratorTest, 
PreAnalyzedUpdateProcessorTest, UpdateParamsTest, ChaosMonkeySafeLeaderTest, 
TolerantUpdateProcessorTest, ScheduledTriggerIntegrationTest, 
CustomTermsComponentTest, CollectionReloadTest, TestSystemIdResolver, 
DistributedExpandComponentTest, TestSolr4Spatial, OverseerTest, 
TestSnapshotCloudManager, HttpPartitionOnCommitTest, LeaderElectionTest, 
LegacyCloudClusterPropTest, MetricsHistoryIntegrationTest, 
MetricsHistoryWithAuthIntegrationTest, MigrateRouteKeyTest, 
MultiSolrCloudTestCaseTest, NestedShardedAtomicUpdateTest, 
OverseerSolrResponseUnsafeSerializationTest, RecoveryAfterSoftCommitTest, 
RestartWhileUpdatingTest, RollingRestartTest, RoutingToNodesWithPropertiesTest, 
ShardRoutingTest, SharedFSAutoReplicaFailoverTest, SliceStateTest, 
SystemCollectionCompatTest]
   [junit4] Completed [506/904 (1!)] on J0 in 142.46s, 1 test, 1 error <<< 
FAILURES!

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

Reply via email to