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

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

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

Stack Trace:
org.apache.solr.client.solrj.SolrServerException: No live SolrServers available 
to handle this request:[https://127.0.0.1:35559/solr/.system]
        at 
__randomizedtesting.SeedInfo.seed([FF0EC006937952C2:8FFB63AFF3B1FBB4]: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: https://127.0.0.1:35559/solr/.system
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:676)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:265)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
        at 
org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:368)
        at 
org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:296)
        ... 43 more
Caused by: java.net.SocketTimeoutException: Read timed out
        at java.base/java.net.SocketInputStream.socketRead0(Native Method)
        at 
java.base/java.net.SocketInputStream.socketRead(SocketInputStream.java:115)
        at java.base/java.net.SocketInputStream.read(SocketInputStream.java:168)
        at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
        at 
java.base/sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:448)
        at 
java.base/sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:68)
        at 
java.base/sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1104)
        at 
java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:823)
        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 14234 lines...]
   [junit4] Suite: org.apache.solr.cloud.SystemCollectionCompatTest
   [junit4]   2> 3170226 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FF0EC006937952C2]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Created dataDir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.SystemCollectionCompatTest_FF0EC006937952C2-001/data-dir-159-001
   [junit4]   2> 3170226 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FF0EC006937952C2]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 3170227 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FF0EC006937952C2]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, 
clientAuth=0.0/0.0)
   [junit4]   2> 3170228 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FF0EC006937952C2]-worker) [     ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 3170229 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FF0EC006937952C2]-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/J2/temp/solr.cloud.SystemCollectionCompatTest_FF0EC006937952C2-001/tempDir-001
   [junit4]   2> 3170229 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FF0EC006937952C2]-worker) [     ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 3170230 INFO  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 3170230 INFO  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 3170330 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FF0EC006937952C2]-worker) [     ] 
o.a.s.c.ZkTestServer start zk server on port:37322
   [junit4]   2> 3170330 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FF0EC006937952C2]-worker) [     ] 
o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:37322
   [junit4]   2> 3170330 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FF0EC006937952C2]-worker) [     ] 
o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:37322
   [junit4]   2> 3170330 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FF0EC006937952C2]-worker) [     ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1 37322
   [junit4]   2> 3170331 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FF0EC006937952C2]-worker) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 3170333 INFO  (zkConnectionManagerCallback-16354-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 3170333 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FF0EC006937952C2]-worker) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 3170336 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FF0EC006937952C2]-worker) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 3170337 INFO  (zkConnectionManagerCallback-16356-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 3170337 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FF0EC006937952C2]-worker) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 3170338 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FF0EC006937952C2]-worker) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 3170339 INFO  (zkConnectionManagerCallback-16358-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 3170339 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FF0EC006937952C2]-worker) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 3170446 WARN  (jetty-launcher-16359-thread-2) [     ] 
o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, 
break even is size 23
   [junit4]   2> 3170446 WARN  (jetty-launcher-16359-thread-1) [     ] 
o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, 
break even is size 23
   [junit4]   2> 3170446 INFO  (jetty-launcher-16359-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 3170446 INFO  (jetty-launcher-16359-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 3170446 INFO  (jetty-launcher-16359-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 3170446 INFO  (jetty-launcher-16359-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 3170446 INFO  (jetty-launcher-16359-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> 3170446 INFO  (jetty-launcher-16359-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> 3170448 INFO  (jetty-launcher-16359-thread-1) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 3170448 INFO  (jetty-launcher-16359-thread-1) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 3170448 INFO  (jetty-launcher-16359-thread-1) [     ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 3170448 INFO  (jetty-launcher-16359-thread-2) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 3170448 INFO  (jetty-launcher-16359-thread-2) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 3170448 INFO  (jetty-launcher-16359-thread-2) [     ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 3170448 INFO  (jetty-launcher-16359-thread-1) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@bdbea80{/solr,null,AVAILABLE}
   [junit4]   2> 3170448 INFO  (jetty-launcher-16359-thread-2) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@510549ad{/solr,null,AVAILABLE}
   [junit4]   2> 3170450 INFO  (jetty-launcher-16359-thread-1) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@242f17c9{ssl,[ssl, alpn, 
http/1.1, h2]}{127.0.0.1:35559}
   [junit4]   2> 3170450 INFO  (jetty-launcher-16359-thread-2) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@2a799116{ssl,[ssl, alpn, 
http/1.1, h2]}{127.0.0.1:42344}
   [junit4]   2> 3170450 INFO  (jetty-launcher-16359-thread-1) [     ] 
o.e.j.s.Server Started @3170626ms
   [junit4]   2> 3170450 INFO  (jetty-launcher-16359-thread-2) [     ] 
o.e.j.s.Server Started @3170626ms
   [junit4]   2> 3170450 INFO  (jetty-launcher-16359-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=35559}
   [junit4]   2> 3170450 INFO  (jetty-launcher-16359-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=42344}
   [junit4]   2> 3170450 ERROR (jetty-launcher-16359-thread-2) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 3170450 ERROR (jetty-launcher-16359-thread-1) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 3170450 INFO  (jetty-launcher-16359-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 3170450 INFO  (jetty-launcher-16359-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 3170450 INFO  (jetty-launcher-16359-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
9.0.0
   [junit4]   2> 3170450 INFO  (jetty-launcher-16359-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
9.0.0
   [junit4]   2> 3170450 INFO  (jetty-launcher-16359-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 3170450 INFO  (jetty-launcher-16359-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 3170450 INFO  (jetty-launcher-16359-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr
   [junit4]   2> 3170450 INFO  (jetty-launcher-16359-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr
   [junit4]   2> 3170450 INFO  (jetty-launcher-16359-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-02-02T15:46:40.951508Z
   [junit4]   2> 3170450 INFO  (jetty-launcher-16359-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-02-02T15:46:40.951514Z
   [junit4]   2> 3170451 INFO  (jetty-launcher-16359-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 3170451 INFO  (jetty-launcher-16359-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 3170453 INFO  (zkConnectionManagerCallback-16362-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 3170453 INFO  (jetty-launcher-16359-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 3170453 INFO  (zkConnectionManagerCallback-16363-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 3170453 INFO  (jetty-launcher-16359-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 3170453 INFO  (jetty-launcher-16359-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 3170453 INFO  (jetty-launcher-16359-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 3171177 INFO  (jetty-launcher-16359-thread-2) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 3171178 WARN  (jetty-launcher-16359-thread-2) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@37593cda[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 3171178 WARN  (jetty-launcher-16359-thread-2) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@37593cda[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 3171182 WARN  (jetty-launcher-16359-thread-2) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@5ee6378a[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 3171182 WARN  (jetty-launcher-16359-thread-2) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@5ee6378a[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 3171183 INFO  (jetty-launcher-16359-thread-2) [     ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37322/solr
   [junit4]   2> 3171184 INFO  (jetty-launcher-16359-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 3171185 INFO  (zkConnectionManagerCallback-16371-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 3171185 INFO  (jetty-launcher-16359-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 3171288 INFO  (jetty-launcher-16359-thread-2) 
[n:127.0.0.1:42344_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 3171289 INFO  (zkConnectionManagerCallback-16373-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 3171289 INFO  (jetty-launcher-16359-thread-2) 
[n:127.0.0.1:42344_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 3171396 INFO  (jetty-launcher-16359-thread-2) 
[n:127.0.0.1:42344_solr     ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:42344_solr
   [junit4]   2> 3171397 INFO  (jetty-launcher-16359-thread-2) 
[n:127.0.0.1:42344_solr     ] o.a.s.c.Overseer Overseer 
(id=72217104488398854-127.0.0.1:42344_solr-n_0000000000) starting
   [junit4]   2> 3171403 INFO  
(OverseerStateUpdate-72217104488398854-127.0.0.1:42344_solr-n_0000000000) 
[n:127.0.0.1:42344_solr     ] o.a.s.c.Overseer Starting to work on the main 
queue : 127.0.0.1:42344_solr
   [junit4]   2> 3171403 INFO  (jetty-launcher-16359-thread-2) 
[n:127.0.0.1:42344_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:42344_solr
   [junit4]   2> 3171404 INFO  
(OverseerStateUpdate-72217104488398854-127.0.0.1:42344_solr-n_0000000000) 
[n:127.0.0.1:42344_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 3171407 INFO  (jetty-launcher-16359-thread-2) 
[n:127.0.0.1:42344_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 3171407 WARN  (jetty-launcher-16359-thread-2) 
[n:127.0.0.1:42344_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> 3171427 INFO  (jetty-launcher-16359-thread-2) 
[n:127.0.0.1:42344_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 3171460 INFO  (jetty-launcher-16359-thread-2) 
[n:127.0.0.1:42344_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_42344.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@6501d230
   [junit4]   2> 3171472 INFO  (jetty-launcher-16359-thread-2) 
[n:127.0.0.1:42344_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_42344.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@6501d230
   [junit4]   2> 3171472 INFO  (jetty-launcher-16359-thread-2) 
[n:127.0.0.1:42344_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_42344.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@6501d230
   [junit4]   2> 3171473 INFO  (jetty-launcher-16359-thread-2) 
[n:127.0.0.1:42344_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/J2/temp/solr.cloud.SystemCollectionCompatTest_FF0EC006937952C2-001/tempDir-001/node2/.
   [junit4]   2> 3171819 INFO  (jetty-launcher-16359-thread-1) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 3171820 WARN  (jetty-launcher-16359-thread-1) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@78ccb704[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 3171820 WARN  (jetty-launcher-16359-thread-1) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@78ccb704[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 3171824 WARN  (jetty-launcher-16359-thread-1) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@47a0d296[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 3171824 WARN  (jetty-launcher-16359-thread-1) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@47a0d296[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 3171826 INFO  (jetty-launcher-16359-thread-1) [     ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37322/solr
   [junit4]   2> 3171827 INFO  (jetty-launcher-16359-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 3171827 INFO  (zkConnectionManagerCallback-16383-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 3171828 INFO  (jetty-launcher-16359-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 3171930 INFO  (jetty-launcher-16359-thread-1) 
[n:127.0.0.1:35559_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 3171931 INFO  (zkConnectionManagerCallback-16385-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 3171931 INFO  (jetty-launcher-16359-thread-1) 
[n:127.0.0.1:35559_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 3171937 INFO  (jetty-launcher-16359-thread-1) 
[n:127.0.0.1:35559_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 3171941 INFO  (jetty-launcher-16359-thread-1) 
[n:127.0.0.1:35559_solr     ] o.a.s.c.ZkController Publish 
node=127.0.0.1:35559_solr as DOWN
   [junit4]   2> 3171942 INFO  (jetty-launcher-16359-thread-1) 
[n:127.0.0.1:35559_solr     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 3171942 INFO  (jetty-launcher-16359-thread-1) 
[n:127.0.0.1:35559_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:35559_solr
   [junit4]   2> 3171943 INFO  (zkCallback-16372-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 3171943 INFO  (zkCallback-16384-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 3171944 INFO  (jetty-launcher-16359-thread-1) 
[n:127.0.0.1:35559_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 3171945 WARN  (jetty-launcher-16359-thread-1) 
[n:127.0.0.1:35559_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> 3171965 INFO  (jetty-launcher-16359-thread-1) 
[n:127.0.0.1:35559_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 3171994 INFO  (jetty-launcher-16359-thread-1) 
[n:127.0.0.1:35559_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_35559.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@6501d230
   [junit4]   2> 3172009 INFO  (jetty-launcher-16359-thread-1) 
[n:127.0.0.1:35559_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_35559.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@6501d230
   [junit4]   2> 3172009 INFO  (jetty-launcher-16359-thread-1) 
[n:127.0.0.1:35559_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_35559.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@6501d230
   [junit4]   2> 3172011 INFO  (jetty-launcher-16359-thread-1) 
[n:127.0.0.1:35559_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/J2/temp/solr.cloud.SystemCollectionCompatTest_FF0EC006937952C2-001/tempDir-001/node1/.
   [junit4]   2> 3172047 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FF0EC006937952C2]-worker) [     ] 
o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
   [junit4]   2> 3172048 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FF0EC006937952C2]-worker) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 3172049 INFO  (zkConnectionManagerCallback-16394-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 3172049 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FF0EC006937952C2]-worker) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 3172051 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FF0EC006937952C2]-worker) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 3172052 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FF0EC006937952C2]-worker) [     ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37322/solr ready
   [junit4]   2> 3172074 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[FF0EC006937952C2]) [     
] o.a.s.SolrTestCaseJ4 ###Starting testBackCompat
   [junit4]   2> 3172075 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[FF0EC006937952C2]) [     
] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 3172076 INFO  (zkConnectionManagerCallback-16399-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 3172076 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[FF0EC006937952C2]) [     
] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 3172078 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[FF0EC006937952C2]) [     
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 3172079 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[FF0EC006937952C2]) [     
] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37322/solr ready
   [junit4]   2> 3172131 INFO  (qtp1703111149-42181) [n:127.0.0.1:42344_solr    
 ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with 
params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 3172136 INFO  (qtp1703111149-42181) [n:127.0.0.1:42344_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> 3172148 INFO  (qtp1703111149-42181) [n:127.0.0.1:42344_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:35559_solr&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 3172158 INFO  
(OverseerThreadFactory-9736-thread-2-processing-n:127.0.0.1:42344_solr) 
[n:127.0.0.1:42344_solr     ] o.a.s.c.a.c.CreateCollectionCmd Create collection 
.system
   [junit4]   2> 3172158 INFO  
(OverseerCollectionConfigSetProcessor-72217104488398854-127.0.0.1:42344_solr-n_0000000000)
 [n:127.0.0.1:42344_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> 3172262 WARN  
(OverseerThreadFactory-9736-thread-2-processing-n:127.0.0.1:42344_solr) 
[n:127.0.0.1:42344_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> 3172267 INFO  
(OverseerStateUpdate-72217104488398854-127.0.0.1:42344_solr-n_0000000000) 
[n:127.0.0.1:42344_solr     ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":".system",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":".system_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:35559/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 3172270 INFO  
(OverseerStateUpdate-72217104488398854-127.0.0.1:42344_solr-n_0000000000) 
[n:127.0.0.1:42344_solr     ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":".system",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":".system_shard1_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:35559/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 3172521 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3172523 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3173534 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3173534 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3173542 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3173543 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3173545 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3173545 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3173550 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3173550 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3173551 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3173551 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3173553 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3173553 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_35559.solr.core..system.shard1.replica_n2' (registry 
'solr.core..system.shard1.replica_n2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@6501d230
   [junit4]   2> 3173553 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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/J2/temp/solr.cloud.SystemCollectionCompatTest_FF0EC006937952C2-001/tempDir-001/node1/.system_shard1_replica_n2],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.SystemCollectionCompatTest_FF0EC006937952C2-001/tempDir-001/node1/./.system_shard1_replica_n2/data/]
   [junit4]   2> 3173556 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3173556 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3173557 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_35559.solr.core..system.shard1.replica_n1' (registry 
'solr.core..system.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@6501d230
   [junit4]   2> 3173557 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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/J2/temp/solr.cloud.SystemCollectionCompatTest_FF0EC006937952C2-001/tempDir-001/node1/.system_shard1_replica_n1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.SystemCollectionCompatTest_FF0EC006937952C2-001/tempDir-001/node1/./.system_shard1_replica_n1/data/]
   [junit4]   2> 3173649 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3173649 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3173651 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3173651 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 3173653 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3173653 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3173653 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@511d9cb3[.system_shard1_replica_n2] 
main]
   [junit4]   2> 3173655 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3173655 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 3173656 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3173656 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3173656 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3173657 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@23a30f4d[.system_shard1_replica_n1] 
main]
   [junit4]   2> 3173657 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3173658 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3173658 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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 
1657440466799427584
   [junit4]   2> 3173659 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3173659 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3173659 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3173660 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3173661 INFO  
(searcherExecutor-9748-thread-1-processing-n:127.0.0.1:35559_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:35559_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@511d9cb3[.system_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 3173662 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3173665 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3173665 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3173665 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3173665 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3173666 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3173666 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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 
1657440466807816192
   [junit4]   2> 3173669 INFO  
(searcherExecutor-9749-thread-1-processing-n:127.0.0.1:35559_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:35559_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@23a30f4d[.system_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 3173670 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3173671 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3173671 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for 
shard shard1: total=2 found=1 timeoutin=9998ms
   [junit4]   2> 3174173 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3174173 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3174173 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.c.SyncStrategy Sync replicas to 
https://127.0.0.1:35559/solr/.system_shard1_replica_n2/
   [junit4]   2> 3174174 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.PeerSync 
PeerSync: core=.system_shard1_replica_n2 url=https://127.0.0.1:35559/solr START 
replicas=[https://127.0.0.1:35559/solr/.system_shard1_replica_n1/] nUpdates=100
   [junit4]   2> 3174175 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.PeerSync 
PeerSync: core=.system_shard1_replica_n2 url=https://127.0.0.1:35559/solr DONE. 
 We have no versions.  sync failed.
   [junit4]   2> 3174188 INFO  (qtp1809182456-42178) [n:127.0.0.1:35559_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> 3174188 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3174188 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3174188 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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/72217104488398856-core_node4-n_0000000000
   [junit4]   2> 3174190 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
https://127.0.0.1:35559/solr/.system_shard1_replica_n2/ shard1
   [junit4]   2> 3174293 INFO  (zkCallback-16384-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> 3174294 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3174297 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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=1774
   [junit4]   2> 3174397 INFO  (zkCallback-16384-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> 3174397 INFO  (zkCallback-16384-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> 3174676 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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=2155
   [junit4]   2> 3174678 INFO  (qtp1703111149-42181) [n:127.0.0.1:42344_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> 3174777 INFO  (zkCallback-16372-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> 3174777 INFO  (zkCallback-16384-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> 3174777 INFO  (zkCallback-16384-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> 3174777 INFO  (qtp1703111149-42181) [n:127.0.0.1:42344_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:35559_solr&wt=javabin&version=2}
 status=0 QTime=2629
   [junit4]   2> 3174778 INFO  
(TEST-SystemCollectionCompatTest.testBackCompat-seed#[FF0EC006937952C2]) [     
] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: .system
   [junit4]   2> 3174794 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3174848 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n1]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:35559/solr/.system_shard1_replica_n2/&wt=javabin&version=2}{add=[17006952fa0Tb4xgmi4fgk7n5a47m9ta30u4p
 (1657440467988512768)]} 0 1
   [junit4]   2> 3174849 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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=[17006952fa0Tb4xgmi4fgk7n5a47m9ta30u4p 
(1657440467988512768)]} 0 57
   [junit4]   2> 3174850 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1657440468049330176,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 3174850 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_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@bb2b562 
commitCommandVersion:1657440468049330176
   [junit4]   2> 3174851 INFO  (qtp1809182456-42178) [n:127.0.0.1:35559_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1657440468050378752,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 3174852 INFO  (qtp1809182456-42178) [n:127.0.0.1:35559_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@200993ec 
commitCommandVersion:1657440468050378752
   [junit4]   2> 3174858 INFO  (qtp1809182456-42178) [n:127.0.0.1:35559_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1bd76ba7[.system_shard1_replica_n1] 
main]
   [junit4]   2> 3174858 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@462f86de[.system_shard1_replica_n2] 
main]
   [junit4]   2> 3174859 INFO  (qtp1809182456-42178) [n:127.0.0.1:35559_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 3174859 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 3174861 INFO  
(searcherExecutor-9748-thread-1-processing-n:127.0.0.1:35559_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:35559_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@462f86de[.system_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={timestamp=1580658405354,
 java.vendor=Oracle Corporation, os=Linux, os.version=4.4.0-170-generic, 
java.runtime.version=11.0.4+10-LTS, os.arch=amd64, source=flush, 
lucene.version=9.0.0, java.vm.version=11.0.4+10-LTS, 
java.version=11.0.4}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 3174861 INFO  
(searcherExecutor-9749-thread-1-processing-n:127.0.0.1:35559_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:35559_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@1bd76ba7[.system_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={timestamp=1580658405355,
 java.vendor=Oracle Corporation, os=Linux, os.version=4.4.0-170-generic, 
java.runtime.version=11.0.4+10-LTS, os.arch=amd64, source=flush, 
lucene.version=9.0.0, java.vm.version=11.0.4+10-LTS, 
java.version=11.0.4}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 3174862 INFO  (qtp1809182456-42178) [n:127.0.0.1:35559_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n1]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:35559/solr/.system_shard1_replica_n2/&commit_end_point=replicas&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 10
   [junit4]   2> 3174862 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_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:6&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 12
   [junit4]   2> 3174874 INFO  (qtp1809182456-42178) [n:127.0.0.1:35559_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> 3174876 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3174877 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request 
[.system_shard1_replica_n2]  webapp=/solr path=/schema 
params={wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 3174881 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_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> 3174883 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_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> 3174887 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_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> 3174888 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_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> 3174889 INFO  (Thread-4470) [     ] o.a.s.c.SolrCore config 
update listener called for core .system_shard1_replica_n2
   [junit4]   2> 3174891 INFO  (Thread-4470) [     ] o.a.s.c.SolrCore core 
reload .system_shard1_replica_n2
   [junit4]   2> 3174897 INFO  (Thread-4470) [     ] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 9.0.0
   [junit4]   2> 3174907 INFO  (Thread-4470) [     ] o.a.s.s.IndexSchema 
[.system_shard1_replica_n2] Schema name=_system collection or core
   [junit4]   2> 3174911 INFO  (Thread-4470) [     ] o.a.s.s.IndexSchema Loaded 
schema _system collection or core/1.1 with uniqueid field id
   [junit4]   2> 3174911 INFO  (Thread-4470) [     ] o.a.s.c.CoreContainer 
Reloading SolrCore '.system_shard1_replica_n2' using configuration from 
configset .system
   [junit4]   2> 3174966 INFO  (Thread-4470) [n:127.0.0.1:35559_solr c:.system 
s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter 
JMX monitoring for 'solr_35559.solr.core..system.shard1.replica_n2' (registry 
'solr.core..system.shard1.replica_n2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@6501d230
   [junit4]   2> 3174966 INFO  (Thread-4470) [n:127.0.0.1:35559_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/J2/temp/solr.cloud.SystemCollectionCompatTest_FF0EC006937952C2-001/tempDir-001/node1/.system_shard1_replica_n2],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.SystemCollectionCompatTest_FF0EC006937952C2-001/tempDir-001/node1/./.system_shard1_replica_n2/data/]
   [junit4]   2> 3175033 INFO  (Thread-4470) [n:127.0.0.1:35559_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> 3175033 INFO  (Thread-4470) [n:127.0.0.1:35559_solr c:.system 
s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 3175036 INFO  (Thread-4470) [n:127.0.0.1:35559_solr c:.system 
s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@3a8a47d0[.system_shard1_replica_n2] main]
   [junit4]   2> 3175038 INFO  (Thread-4470) [n:127.0.0.1:35559_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> 3175038 INFO  (Thread-4470) [n:127.0.0.1:35559_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> 3175038 INFO  (Thread-4470) [n:127.0.0.1:35559_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> 3175039 INFO  (Thread-4470) [n:127.0.0.1:35559_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> 3175039 INFO  (Thread-4470) [n:127.0.0.1:35559_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> 3175042 INFO  (Thread-4470) [n:127.0.0.1:35559_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> 3175045 INFO  
(searcherExecutor-9758-thread-1-processing-n:127.0.0.1:35559_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:35559_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@3a8a47d0[.system_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={timestamp=1580658405354,
 java.vendor=Oracle Corporation, os=Linux, os.version=4.4.0-170-generic, 
java.runtime.version=11.0.4+10-LTS, os.arch=amd64, source=flush, 
lucene.version=9.0.0, java.vm.version=11.0.4+10-LTS, 
java.version=11.0.4}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 3175046 INFO  (Thread-4470) [n:127.0.0.1:35559_solr c:.system 
s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@71f5cd42[.system_shard1_replica_n2] main]
   [junit4]   2> 3175046 INFO  (Thread-4470) [n:127.0.0.1:35559_solr c:.system 
s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.SolrCore config 
update listener called for core .system_shard1_replica_n1
   [junit4]   2> 3175051 INFO  
(searcherExecutor-9758-thread-1-processing-n:127.0.0.1:35559_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:35559_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@71f5cd42[.system_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={timestamp=1580658405354,
 os=Linux, java.vendor=Oracle Corporation, os.version=4.4.0-170-generic, 
java.runtime.version=11.0.4+10-LTS, os.arch=amd64, source=flush, 
lucene.version=9.0.0, java.vm.version=11.0.4+10-LTS, 
java.version=11.0.4}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 3175053 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_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> 3175063 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_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> 3175067 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_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> 3175067 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_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> 3175150 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_35559.solr.core..system.shard1.replica_n2' (registry 
'solr.core..system.shard1.replica_n2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@6501d230
   [junit4]   2> 3175150 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_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/J2/temp/solr.cloud.SystemCollectionCompatTest_FF0EC006937952C2-001/tempDir-001/node1/.system_shard1_replica_n2],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.SystemCollectionCompatTest_FF0EC006937952C2-001/tempDir-001/node1/./.system_shard1_replica_n2/data/]
   [junit4]   2> 3175246 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_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> 3175246 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 3175252 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@36036658[.system_shard1_replica_n2] 
main]
   [junit4]   2> 3175254 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_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> 3175255 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_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> 3175255 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_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> 3175256 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_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> 3175256 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_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> 3175259 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_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> 3175264 INFO  
(searcherExecutor-9762-thread-1-processing-n:127.0.0.1:35559_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:35559_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@36036658[.system_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={timestamp=1580658405354,
 os=Linux, java.vendor=Oracle Corporation, os.version=4.4.0-170-generic, 
java.runtime.version=11.0.4+10-LTS, os.arch=amd64, source=flush, 
lucene.version=9.0.0, java.vm.version=11.0.4+10-LTS, 
java.version=11.0.4}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 3175265 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@3cf4fc10[.system_shard1_replica_n2] 
main]
   [junit4]   2> 3175265 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_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@7efe28a
   [junit4]   2> 3175265 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core..system.shard1.replica_n2, tag=SolrCore@7efe28a
   [junit4]   2> 3175265 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@f5d5341: rootName = 
solr_35559, domain = solr.core..system.shard1.replica_n2, service url = null, 
agent id = null] for registry solr.core..system.shard1.replica_n2 / 
com.codahale.metrics.MetricRegistry@769b8f36
   [junit4]   2> 3175270 INFO  
(searcherExecutor-9762-thread-1-processing-n:127.0.0.1:35559_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:35559_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@3cf4fc10[.system_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={timestamp=1580658405354,
 os=Linux, java.vendor=Oracle Corporation, os.version=4.4.0-170-generic, 
java.runtime.version=11.0.4+10-LTS, os.arch=amd64, source=flush, 
lucene.version=9.0.0, java.vm.version=11.0.4+10-LTS, 
java.version=11.0.4}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 3175289 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_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@7efe28a
   [junit4]   2> 3175291 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_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> 3175304 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3175305 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3175305 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3175309 ERROR 
(managedSchemaExecutor-9766-thread-1-processing-n:127.0.0.1:35559_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:35559_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:35559/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 3175311 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3175311 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3175311 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3175311 ERROR 
(managedSchemaExecutor-9766-thread-1-processing-n:127.0.0.1:35559_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:35559_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:35559/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 3175312 INFO  (qtp1809182456-42178) [n:127.0.0.1:35559_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> 3175313 INFO  (qtp1809182456-42178) [n:127.0.0.1:35559_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> 3175313 INFO  (qtp1809182456-42178) [n:127.0.0.1:35559_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> 3175313 ERROR 
(managedSchemaExecutor-9766-thread-1-processing-n:127.0.0.1:35559_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:35559_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:35559/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 3175314 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3175314 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3175314 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3175314 ERROR 
(managedSchemaExecutor-9766-thread-1-processing-n:127.0.0.1:35559_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:35559_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:35559/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 3175315 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3175315 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3175315 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3175316 ERROR 
(managedSchemaExecutor-9766-thread-1-processing-n:127.0.0.1:35559_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:35559_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:35559/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 3175316 INFO  (qtp1809182456-42178) [n:127.0.0.1:35559_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> 3175317 INFO  (qtp1809182456-42178) [n:127.0.0.1:35559_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> 3175317 INFO  (qtp1809182456-42178) [n:127.0.0.1:35559_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> 3175317 ERROR 
(managedSchemaExecutor-9766-thread-1-processing-n:127.0.0.1:35559_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:35559_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:35559/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 3175318 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3175318 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3175318 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3175318 ERROR 
(managedSchemaExecutor-9766-thread-1-processing-n:127.0.0.1:35559_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:35559_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:35559/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 3175319 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3175319 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3175319 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3175320 ERROR 
(managedSchemaExecutor-9766-thread-1-processing-n:127.0.0.1:35559_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:35559_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:35559/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 3175320 INFO  (qtp1809182456-42178) [n:127.0.0.1:35559_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> 3175321 INFO  (qtp1809182456-42178) [n:127.0.0.1:35559_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> 3175321 INFO  (qtp1809182456-42178) [n:127.0.0.1:35559_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> 3175321 ERROR 
(managedSchemaExecutor-9766-thread-1-processing-n:127.0.0.1:35559_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:35559_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:35559/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 3175322 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3175322 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3175322 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3175322 ERROR 
(managedSchemaExecutor-9766-thread-1-processing-n:127.0.0.1:35559_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:35559_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:35559/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 3175323 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3175323 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3175323 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3175324 ERROR 
(managedSchemaExecutor-9766-thread-1-processing-n:127.0.0.1:35559_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:35559_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:35559/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 3175324 INFO  (qtp1809182456-42178) [n:127.0.0.1:35559_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> 3175325 INFO  (qtp1809182456-42178) [n:127.0.0.1:35559_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> 3175325 INFO  (qtp1809182456-42178) [n:127.0.0.1:35559_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> 3175325 ERROR 
(managedSchemaExecutor-9766-thread-1-processing-n:127.0.0.1:35559_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:35559_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:35559/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 3175326 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3175326 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3175326 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3175327 ERROR 
(managedSchemaExecutor-9766-thread-1-processing-n:127.0.0.1:35559_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:35559_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:35559/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 3175327 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3175328 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3175328 INFO  (qtp1809182456-42180) [n:127.0.0.1:35559_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> 3175328 ERROR 
(managedSchemaExecutor-9766-thread-1-processing-n:127.0.0.1:35559_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:35559_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:35559/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 3175329 INFO  (qtp1809182456-42178) [n:127.0.0.1:35559_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> 3175329 INFO  (qtp1809182456-42178) [n:127.0.0.1:35559_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> 3175329 INFO  (qtp1809182456-42178) [n:127.0.0.1:35559_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> 3175329 ERROR 
(managedSchemaExecutor-9766-thread-1-processing-n:127.0.0.1:35559_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:35559_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica 
https://127.0.0.1:35559/solr/.system_shard1_replica_n1/ returned schema version 
0 and has not applied schema version 2
   [junit4]   2> 3175330 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3175331 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_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> 3175331 INFO  (qtp1809182456-42182) [n:127.0.0.1:35559_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request 
[.system_shard1

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

version from https://127.0.0.1:35559/solr/.system_shard1_replica_n1/ due to: 
java.util.concurrent.ExecutionException: 
org.apache.solr.client.solrj.SolrServerException: Server refused connection at: 
https://127.0.0.1:35559/solr/.system_shard1_replica_n1
   [junit4]   2> 3312052 WARN  
(managedSchemaExecutor-9766-thread-1-processing-n:127.0.0.1:35559_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) 
[n:127.0.0.1:35559_solr c:.system s:shard1 r:core_node4 
x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Failed to get 
/schema/zkversion from https://127.0.0.1:35559/solr/.system_shard1_replica_n1/ 
due to: java.util.concurrent.ExecutionException: 
org.apache.solr.client.solrj.SolrServerException: Server refused connection at: 
https://127.0.0.1:35559/solr/.system_shard1_replica_n1
   [junit4]   2> 3312120 WARN  (qtp1809182456-42252) [n:127.0.0.1:35559_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> 3312120 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.s.ManagedIndexSchema Took 136829.0ms for 1 replicas to apply schema 
update version 2 for collection .system
   [junit4]   2> 3312120 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_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=137241
   [junit4]   2> 3312121 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_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@2553c824
   [junit4]   2> 3312121 WARN  (qtp1809182456-42252) [n:127.0.0.1:35559_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> 3312121 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_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@2553c824
   [junit4]   2> 3312121 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_solr 
c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] 
o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() .
   [junit4]   2> 3312121 INFO  (qtp1809182456-42252) [n:127.0.0.1:35559_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@6814ec60 commitCommandVersion:0
   [junit4]   2> 3312178 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FF0EC006937952C2]-worker) [     ] 
o.a.s.c.ZkTestServer Shutting down ZkTestServer.
   [junit4]   2> 3312459 WARN  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer Watch limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        7       /solr/aliases.json
   [junit4]   2>        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>        17      /solr/collections/.system/state.json
   [junit4]   2>        7       /solr/clusterstate.json
   [junit4]   2>        7       /solr/clusterprops.json
   [junit4]   2>        6       /solr/aliases.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        27      /solr/overseer/queue
   [junit4]   2>        18      /solr/collections
   [junit4]   2>        12      /solr/overseer/collection-queue-work
   [junit4]   2>        11      /solr/live_nodes
   [junit4]   2> 
   [junit4]   2> 3312461 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FF0EC006937952C2]-worker) [     ] 
o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:37322
   [junit4]   2> 3312461 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FF0EC006937952C2]-worker) [     ] 
o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:37322
   [junit4]   2> 3312461 INFO  
(SUITE-SystemCollectionCompatTest-seed#[FF0EC006937952C2]-worker) [     ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1 37322
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.SystemCollectionCompatTest_FF0EC006937952C2-001
   [junit4]   2> Feb 02, 2020 3:49:02 PM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene84): 
{id=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
type=PostingsFormat(name=LuceneFixedGap)}, 
docValues:{timestamp_l=DocValuesFormat(name=Asserting), 
_version_=DocValuesFormat(name=Lucene80), 
time_l=DocValuesFormat(name=Lucene80), 
timestamp=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=1071, 
maxMBSortInHeap=7.524198556562209, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@78a6f01),
 locale=fr-BF, timezone=America/Indiana/Vevay
   [junit4]   2> NOTE: Linux 4.4.0-170-generic amd64/Oracle Corporation 11.0.4 
(64-bit)/cpus=8,threads=1,free=175166248,total=536870912
   [junit4]   2> NOTE: All tests run in this JVM: 
[OutOfBoxZkACLAndCredentialsProvidersTest, TestRealTimeGet, 
TestStressLiveNodes, AtomicUpdateProcessorFactoryTest, TestRemoteStreaming, 
TestMaxScoreQueryParser, SparseHLLTest, GraphQueryTest, 
DistributedTermsComponentTest, RecoveryAfterSoftCommitTest, SolrXmlInZkTest, 
TestLockTree, SecurityConfHandlerTest, TestImpersonationWithHadoopAuth, 
PingRequestHandlerTest, TestMaxTokenLenTokenizer, 
TestPayloadCheckQParserPlugin, AutoscalingHistoryHandlerTest, 
ResponseBuilderTest, TestCoreContainer, TestManagedSchemaAPI, 
DistributedFacetPivotSmallAdvancedTest, TestDistribIDF, ZkStateWriterTest, 
CategoryRoutedAliasUpdateProcessorTest, TestSolrCloudSnapshots, 
FieldAnalysisRequestHandlerTest, DataDrivenBlockJoinTest, TestCoreBackup, 
TestDocSet, TestLegacyNumericUtils, SimpleMLTQParserTest, ScriptEngineTest, 
ConvertedLegacyTest, NumberUtilsTest, TestDynamicFieldNamesIndexCorrectly, 
EmbeddedSolrNoSerializeTest, TestPointFields, AliasIntegrationTest, 
TestFieldTypeResource, TestInfoStreamLogging, TestHttpServletCarrier, 
TestSolrCoreParser, TestSchemalessBufferedUpdates, 
RoutingToNodesWithPropertiesTest, CdcrRequestHandlerTest, ShardsWhitelistTest, 
SortSpecParsingTest, TestLMDirichletSimilarityFactory, DocValuesNotIndexedTest, 
DeleteNodeTest, TolerantUpdateProcessorTest, TestShardHandlerFactory, 
TestPhraseSuggestions, TestClusterStateMutator, TestFoldingMultitermQuery, 
BadComponentTest, TestLogWatcher, TlogReplayBufferedWhileIndexingTest, 
TestSolr4Spatial, NoCacheHeaderTest, SliceStateTest, TestSortByMinMaxFunction, 
TestManagedSynonymGraphFilterFactory, BasicAuthIntegrationTest, 
TestTermsQParserPlugin, DateMathParserTest, SolrPluginUtilsTest, 
TestSnapshotCloudManager, DistributedQueryElevationComponentTest, 
AutoAddReplicasPlanActionTest, QueryResultKeyTest, TestFieldSortValues, 
SolrShardReporterTest, TestSlowCompositeReaderWrapper, HttpPartitionTest, 
TestInPlaceUpdateWithRouteField, FastVectorHighlighterTest, 
FullSolrCloudDistribCmdsTest, TestCloudManagedSchema, OutputWriterTest, 
RemoteQueryErrorTest, TestAtomicUpdateErrorCases, BufferStoreTest, 
ReplicationFactorTest, TestDistribDocBasedVersion, TestStressUserVersions, 
SolrCoreTest, TestRandomCollapseQParserPlugin, ShardRoutingCustomTest, 
TestCloudJSONFacetSKG, TestSafeXMLParsing, TimeZoneUtilsTest, 
ConnectionReuseTest, TestRebalanceLeaders, IndexSchemaTest, 
TestCopyFieldCollectionResource, TestExclusionRuleCollectionAccess, 
TestFunctionQuery, TestNumericTerms32, TestFacetMethods, RangeFacetCloudTest, 
TestRestoreCore, LeaderElectionIntegrationTest, BJQFilterAccessibleTest, 
TestSystemCollAutoCreate, TestSimScenario, TestNamedUpdateProcessors, 
TestLeaderElectionWithEmptyReplica, TestJsonFacetsStatsParsing, 
MoreLikeThisComponentTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, 
SpellPossibilityIteratorTest, MergeStrategyTest, HdfsSyncSliceTest, 
TestNumericRangeQuery32, BooleanFieldTest, JWTVerificationkeyResolverTest, 
TestLRUStatsCache, TriggerSetPropertiesIntegrationTest, TestTolerantSearch, 
SolrIndexConfigTest, TestSegmentSorting, TriggerCooldownIntegrationTest, 
TestReplicaProperties, TestCloudConsistency, SynonymTokenizerTest, 
TestExpandComponent, RulesTest, CollectionsAPIDistributedZkTest, 
TestEmbeddedSolrServerConstructors, TaggerTest, TestCustomStream, 
TestFreeTextSuggestions, SolrTestCaseJ4Test, IndexBasedSpellCheckerTest, 
TestSimpleTrackingShardHandler, TestUniqueKeyFieldResource, 
LeaderFailureAfterFreshStartTest, TestSkipOverseerOperations, 
MetricsHandlerTest, OverseerTest, TestSolrConfigHandlerCloud, 
DimensionalRoutedAliasUpdateProcessorTest, TestConfigsApi, 
ClassificationUpdateProcessorTest, CleanupOldIndexTest, 
CloudExitableDirectoryReaderTest, ClusterStateTest, ClusterStateUpdateTest, 
CollectionPropsTest, ConcurrentCreateRoutedAliasTest, ConfigSetsAPITest, 
ConnectionManagerTest, CreateCollectionCleanupTest, CreateRoutedAliasTest, 
DeleteReplicaTest, DistribJoinFromCollectionTest, LegacyCloudClusterPropTest, 
MetricsHistoryIntegrationTest, MetricsHistoryWithAuthIntegrationTest, 
MigrateRouteKeyTest, MultiSolrCloudTestCaseTest, MultiThreadedOCPTest, 
OverriddenZkACLAndCredentialsProvidersTest, OverseerRolesTest, 
SystemCollectionCompatTest]
   [junit4] Completed [520/900 (1!)] on J2 in 142.85s, 1 test, 1 error <<< 
FAILURES!

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

Reply via email to