You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2020/02/01 10:14:37 UTC
[JENKINS-Experimental-GC] Lucene-Solr-master-Linux
(64bit/jdk-14-ea+32) - Build # 25555 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/25555/
Java: 64bit/jdk-14-ea+32 -XX:-UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseZGC
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:33019/solr/.system]
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[https://127.0.0.1:33019/solr/.system]
at __randomizedtesting.SeedInfo.seed([44F32DE667A4B8CD:34068E4F076C11BB]: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:564)
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:832)
Caused by: org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: https://127.0.0.1:33019/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/sun.nio.ch.NioSocketImpl.timedRead(NioSocketImpl.java:283)
at java.base/sun.nio.ch.NioSocketImpl.implRead(NioSocketImpl.java:309)
at java.base/sun.nio.ch.NioSocketImpl.read(NioSocketImpl.java:350)
at java.base/sun.nio.ch.NioSocketImpl$1.read(NioSocketImpl.java:803)
at java.base/java.net.Socket$SocketInputStream.read(Socket.java:982)
at java.base/sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:457)
at java.base/sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:68)
at java.base/sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1411)
at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:1022)
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 13477 lines...]
[junit4] Suite: org.apache.solr.cloud.SystemCollectionCompatTest
[junit4] 2> 162106 INFO (SUITE-SystemCollectionCompatTest-seed#[44F32DE667A4B8CD]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J5/temp/solr.cloud.SystemCollectionCompatTest_44F32DE667A4B8CD-001/data-dir-19-001
[junit4] 2> 162107 WARN (SUITE-SystemCollectionCompatTest-seed#[44F32DE667A4B8CD]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=4 numCloses=4
[junit4] 2> 162111 INFO (SUITE-SystemCollectionCompatTest-seed#[44F32DE667A4B8CD]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 162112 INFO (SUITE-SystemCollectionCompatTest-seed#[44F32DE667A4B8CD]-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> 162113 INFO (SUITE-SystemCollectionCompatTest-seed#[44F32DE667A4B8CD]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 162113 INFO (SUITE-SystemCollectionCompatTest-seed#[44F32DE667A4B8CD]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J5/temp/solr.cloud.SystemCollectionCompatTest_44F32DE667A4B8CD-001/tempDir-001
[junit4] 2> 162114 INFO (SUITE-SystemCollectionCompatTest-seed#[44F32DE667A4B8CD]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 162118 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 162118 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 162218 INFO (SUITE-SystemCollectionCompatTest-seed#[44F32DE667A4B8CD]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:46401
[junit4] 2> 162218 INFO (SUITE-SystemCollectionCompatTest-seed#[44F32DE667A4B8CD]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:46401
[junit4] 2> 162219 INFO (SUITE-SystemCollectionCompatTest-seed#[44F32DE667A4B8CD]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:46401
[junit4] 2> 162219 INFO (SUITE-SystemCollectionCompatTest-seed#[44F32DE667A4B8CD]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 46401
[junit4] 2> 162228 INFO (SUITE-SystemCollectionCompatTest-seed#[44F32DE667A4B8CD]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 162234 INFO (zkConnectionManagerCallback-497-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 162234 INFO (SUITE-SystemCollectionCompatTest-seed#[44F32DE667A4B8CD]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 162257 INFO (SUITE-SystemCollectionCompatTest-seed#[44F32DE667A4B8CD]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 162265 INFO (zkConnectionManagerCallback-499-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 162265 INFO (SUITE-SystemCollectionCompatTest-seed#[44F32DE667A4B8CD]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 162265 INFO (SUITE-SystemCollectionCompatTest-seed#[44F32DE667A4B8CD]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 162277 INFO (zkConnectionManagerCallback-501-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 162277 INFO (SUITE-SystemCollectionCompatTest-seed#[44F32DE667A4B8CD]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 162408 WARN (jetty-launcher-502-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 162408 INFO (jetty-launcher-502-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 162408 INFO (jetty-launcher-502-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 162408 INFO (jetty-launcher-502-thread-1) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 14-ea+32-1423
[junit4] 2> 162416 WARN (jetty-launcher-502-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 162416 INFO (jetty-launcher-502-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 162416 INFO (jetty-launcher-502-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 162416 INFO (jetty-launcher-502-thread-2) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 14-ea+32-1423
[junit4] 2> 162440 INFO (jetty-launcher-502-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 162440 INFO (jetty-launcher-502-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 162440 INFO (jetty-launcher-502-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 162440 INFO (jetty-launcher-502-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 162440 INFO (jetty-launcher-502-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 162440 INFO (jetty-launcher-502-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 162441 INFO (jetty-launcher-502-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7b4680db{/solr,null,AVAILABLE}
[junit4] 2> 162444 INFO (jetty-launcher-502-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2c8fe047{/solr,null,AVAILABLE}
[junit4] 2> 162449 INFO (jetty-launcher-502-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@417ca8c8{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:33019}
[junit4] 2> 162449 INFO (jetty-launcher-502-thread-1) [ ] o.e.j.s.Server Started @162745ms
[junit4] 2> 162449 INFO (jetty-launcher-502-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33019}
[junit4] 2> 162449 INFO (jetty-launcher-502-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7da93690{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:40901}
[junit4] 2> 162449 INFO (jetty-launcher-502-thread-2) [ ] o.e.j.s.Server Started @162746ms
[junit4] 2> 162449 INFO (jetty-launcher-502-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=40901}
[junit4] 2> 162449 ERROR (jetty-launcher-502-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 162449 INFO (jetty-launcher-502-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 162449 INFO (jetty-launcher-502-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 162449 ERROR (jetty-launcher-502-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 162449 INFO (jetty-launcher-502-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 162449 INFO (jetty-launcher-502-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 162449 INFO (jetty-launcher-502-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 162449 INFO (jetty-launcher-502-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr
[junit4] 2> 162449 INFO (jetty-launcher-502-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 162449 INFO (jetty-launcher-502-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr
[junit4] 2> 162450 INFO (jetty-launcher-502-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-02-01T09:43:45.914987Z
[junit4] 2> 162450 INFO (jetty-launcher-502-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-02-01T09:43:45.915007Z
[junit4] 2> 162459 INFO (jetty-launcher-502-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 162459 INFO (jetty-launcher-502-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 162465 INFO (zkConnectionManagerCallback-506-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 162465 INFO (jetty-launcher-502-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 162469 INFO (zkConnectionManagerCallback-505-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 162469 INFO (jetty-launcher-502-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 162477 INFO (jetty-launcher-502-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 162481 INFO (jetty-launcher-502-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 162693 INFO (jetty-launcher-502-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 162694 WARN (jetty-launcher-502-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@51eebaa6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 162694 WARN (jetty-launcher-502-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@51eebaa6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 162698 WARN (jetty-launcher-502-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7352831a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 162698 WARN (jetty-launcher-502-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7352831a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 162699 INFO (jetty-launcher-502-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46401/solr
[junit4] 2> 162700 INFO (jetty-launcher-502-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 162705 INFO (zkConnectionManagerCallback-514-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 162705 INFO (jetty-launcher-502-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 162787 INFO (jetty-launcher-502-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 162788 WARN (jetty-launcher-502-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5a2ea3e5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 162788 WARN (jetty-launcher-502-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5a2ea3e5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 162792 WARN (jetty-launcher-502-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5a61cf83[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 162792 WARN (jetty-launcher-502-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5a61cf83[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 162793 INFO (jetty-launcher-502-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46401/solr
[junit4] 2> 162794 INFO (jetty-launcher-502-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 162797 INFO (zkConnectionManagerCallback-520-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 162797 INFO (jetty-launcher-502-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 162821 INFO (jetty-launcher-502-thread-2) [n:127.0.0.1:40901_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 162824 INFO (zkConnectionManagerCallback-522-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 162824 INFO (jetty-launcher-502-thread-2) [n:127.0.0.1:40901_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 162908 INFO (jetty-launcher-502-thread-1) [n:127.0.0.1:33019_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 162913 INFO (zkConnectionManagerCallback-526-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 162913 INFO (jetty-launcher-502-thread-1) [n:127.0.0.1:33019_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 162919 INFO (jetty-launcher-502-thread-2) [n:127.0.0.1:40901_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:40901_solr
[junit4] 2> 162920 INFO (jetty-launcher-502-thread-2) [n:127.0.0.1:40901_solr ] o.a.s.c.Overseer Overseer (id=72110910073405447-127.0.0.1:40901_solr-n_0000000000) starting
[junit4] 2> 162921 INFO (jetty-launcher-502-thread-1) [n:127.0.0.1:33019_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33019_solr
[junit4] 2> 162923 INFO (jetty-launcher-502-thread-1) [n:127.0.0.1:33019_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 162924 WARN (jetty-launcher-502-thread-1) [n:127.0.0.1:33019_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> 162929 INFO (zkCallback-525-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 162947 INFO (zkCallback-521-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 162947 INFO (OverseerStateUpdate-72110910073405447-127.0.0.1:40901_solr-n_0000000000) [n:127.0.0.1:40901_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:40901_solr
[junit4] 2> 162949 INFO (jetty-launcher-502-thread-2) [n:127.0.0.1:40901_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:40901_solr as DOWN
[junit4] 2> 162950 INFO (jetty-launcher-502-thread-2) [n:127.0.0.1:40901_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 162950 INFO (jetty-launcher-502-thread-2) [n:127.0.0.1:40901_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40901_solr
[junit4] 2> 162950 INFO (zkCallback-525-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 162951 INFO (zkCallback-521-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 162951 INFO (jetty-launcher-502-thread-2) [n:127.0.0.1:40901_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 162952 WARN (jetty-launcher-502-thread-2) [n:127.0.0.1:40901_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> 162953 INFO (jetty-launcher-502-thread-1) [n:127.0.0.1:33019_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 162969 INFO (jetty-launcher-502-thread-2) [n:127.0.0.1:40901_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 162972 INFO (jetty-launcher-502-thread-1) [n:127.0.0.1:33019_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33019.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@61f8a50c
[junit4] 2> 162978 INFO (jetty-launcher-502-thread-1) [n:127.0.0.1:33019_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33019.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@61f8a50c
[junit4] 2> 162979 INFO (jetty-launcher-502-thread-1) [n:127.0.0.1:33019_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33019.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@61f8a50c
[junit4] 2> 162980 INFO (jetty-launcher-502-thread-1) [n:127.0.0.1:33019_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J5/temp/solr.cloud.SystemCollectionCompatTest_44F32DE667A4B8CD-001/tempDir-001/node1/.
[junit4] 2> 162982 INFO (jetty-launcher-502-thread-2) [n:127.0.0.1:40901_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40901.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@61f8a50c
[junit4] 2> 162992 INFO (jetty-launcher-502-thread-2) [n:127.0.0.1:40901_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40901.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@61f8a50c
[junit4] 2> 162992 INFO (jetty-launcher-502-thread-2) [n:127.0.0.1:40901_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40901.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@61f8a50c
[junit4] 2> 162993 INFO (jetty-launcher-502-thread-2) [n:127.0.0.1:40901_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J5/temp/solr.cloud.SystemCollectionCompatTest_44F32DE667A4B8CD-001/tempDir-001/node2/.
[junit4] 2> 163012 INFO (SUITE-SystemCollectionCompatTest-seed#[44F32DE667A4B8CD]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 163014 INFO (SUITE-SystemCollectionCompatTest-seed#[44F32DE667A4B8CD]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 163018 INFO (zkConnectionManagerCallback-537-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 163018 INFO (SUITE-SystemCollectionCompatTest-seed#[44F32DE667A4B8CD]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 163019 INFO (SUITE-SystemCollectionCompatTest-seed#[44F32DE667A4B8CD]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 163019 INFO (SUITE-SystemCollectionCompatTest-seed#[44F32DE667A4B8CD]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:46401/solr ready
[junit4] 2> 163034 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[44F32DE667A4B8CD]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBackCompat
[junit4] 2> 163036 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[44F32DE667A4B8CD]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 163041 INFO (zkConnectionManagerCallback-542-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 163041 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[44F32DE667A4B8CD]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 163042 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[44F32DE667A4B8CD]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 163043 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[44F32DE667A4B8CD]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:46401/solr ready
[junit4] 2> 163061 INFO (qtp243682643-1763) [n:127.0.0.1:33019_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 163069 INFO (qtp243682643-1763) [n:127.0.0.1:33019_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=8
[junit4] 2> 163077 INFO (qtp243682643-1765) [n:127.0.0.1:33019_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:33019_solr&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 163081 INFO (OverseerCollectionConfigSetProcessor-72110910073405447-127.0.0.1:40901_solr-n_0000000000) [n:127.0.0.1:40901_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> 163081 INFO (OverseerThreadFactory-668-thread-2-processing-n:127.0.0.1:40901_solr) [n:127.0.0.1:40901_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection .system
[junit4] 2> 163184 WARN (OverseerThreadFactory-668-thread-2-processing-n:127.0.0.1:40901_solr) [n:127.0.0.1:40901_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> 163186 INFO (OverseerStateUpdate-72110910073405447-127.0.0.1:40901_solr-n_0000000000) [n:127.0.0.1:40901_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:33019/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 163187 INFO (OverseerStateUpdate-72110910073405447-127.0.0.1:40901_solr-n_0000000000) [n:127.0.0.1:40901_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:33019/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 163400 INFO (qtp243682643-1772) [n:127.0.0.1:33019_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> 163400 INFO (qtp243682643-1772) [n:127.0.0.1:33019_solr x:.system_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 163407 INFO (qtp243682643-1763) [n:127.0.0.1:33019_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> 164410 WARN (qtp243682643-1772) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.SolrConfig You should not use LATEST as luceneMatchVersion property: if you use this setting, and then Solr upgrades to a newer release of Lucene, sizable changes may happen. If precise back compatibility is important then you should instead explicitly specify an actual Lucene version.
[junit4] 2> 164410 INFO (qtp243682643-1772) [n:127.0.0.1:33019_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> 164417 INFO (qtp243682643-1763) [n:127.0.0.1:33019_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> 164421 INFO (qtp243682643-1772) [n:127.0.0.1:33019_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> 164425 INFO (qtp243682643-1763) [n:127.0.0.1:33019_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> 164425 INFO (qtp243682643-1772) [n:127.0.0.1:33019_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> 164427 INFO (qtp243682643-1763) [n:127.0.0.1:33019_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> 164432 INFO (qtp243682643-1763) [n:127.0.0.1:33019_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> 164432 INFO (qtp243682643-1772) [n:127.0.0.1:33019_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> 164440 INFO (qtp243682643-1772) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Created and persisted managed schema znode at /configs/.system/managed-schema
[junit4] 2> 164441 INFO (qtp243682643-1763) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Managed schema znode at /configs/.system/managed-schema already exists - no need to create it
[junit4] 2> 164441 INFO (qtp243682643-1772) [n:127.0.0.1:33019_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> 164442 INFO (qtp243682643-1772) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33019.solr.core..system.shard1.replica_n1' (registry 'solr.core..system.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@61f8a50c
[junit4] 2> 164442 INFO (qtp243682643-1772) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [[.system_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J5/temp/solr.cloud.SystemCollectionCompatTest_44F32DE667A4B8CD-001/tempDir-001/node1/.system_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J5/temp/solr.cloud.SystemCollectionCompatTest_44F32DE667A4B8CD-001/tempDir-001/node1/./.system_shard1_replica_n1/data/]
[junit4] 2> 164442 INFO (qtp243682643-1763) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in ZooKeeper, renamed the non-managed schema /configs/.system/schema.xml to /configs/.system/schema.xml.bak
[junit4] 2> 164443 INFO (qtp243682643-1763) [n:127.0.0.1:33019_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> 164443 INFO (qtp243682643-1763) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33019.solr.core..system.shard1.replica_n2' (registry 'solr.core..system.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@61f8a50c
[junit4] 2> 164444 INFO (qtp243682643-1763) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.SolrCore [[.system_shard1_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J5/temp/solr.cloud.SystemCollectionCompatTest_44F32DE667A4B8CD-001/tempDir-001/node1/.system_shard1_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J5/temp/solr.cloud.SystemCollectionCompatTest_44F32DE667A4B8CD-001/tempDir-001/node1/./.system_shard1_replica_n2/data/]
[junit4] 2> 164478 INFO (qtp243682643-1772) [n:127.0.0.1:33019_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> 164478 INFO (qtp243682643-1772) [n:127.0.0.1:33019_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> 164479 INFO (qtp243682643-1772) [n:127.0.0.1:33019_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> 164479 INFO (qtp243682643-1772) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 164481 INFO (qtp243682643-1772) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6af4ab4c[.system_shard1_replica_n1] main]
[junit4] 2> 164490 INFO (qtp243682643-1772) [n:127.0.0.1:33019_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> 164492 INFO (qtp243682643-1772) [n:127.0.0.1:33019_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> 164492 INFO (qtp243682643-1772) [n:127.0.0.1:33019_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> 164494 INFO (qtp243682643-1772) [n:127.0.0.1:33019_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> 164495 INFO (qtp243682643-1772) [n:127.0.0.1:33019_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> 164495 INFO (qtp243682643-1772) [n:127.0.0.1:33019_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 1657327035833384960
[junit4] 2> 164497 INFO (searcherExecutor-674-thread-1-processing-n:127.0.0.1:33019_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:33019_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@6af4ab4c[.system_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 164499 INFO (qtp243682643-1772) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/.system/terms/shard1 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 164499 INFO (qtp243682643-1772) [n:127.0.0.1:33019_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> 164503 INFO (qtp243682643-1772) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=2 found=1 timeoutin=9999ms
[junit4] 2> 164537 INFO (qtp243682643-1763) [n:127.0.0.1:33019_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> 164537 INFO (qtp243682643-1763) [n:127.0.0.1:33019_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> 164539 INFO (qtp243682643-1763) [n:127.0.0.1:33019_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> 164539 INFO (qtp243682643-1763) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 164542 INFO (qtp243682643-1763) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@19170674[.system_shard1_replica_n2] main]
[junit4] 2> 164545 INFO (qtp243682643-1763) [n:127.0.0.1:33019_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> 164545 INFO (qtp243682643-1763) [n:127.0.0.1:33019_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> 164545 INFO (qtp243682643-1763) [n:127.0.0.1:33019_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> 164546 INFO (qtp243682643-1763) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 0 from ZooKeeper
[junit4] 2> 164550 INFO (qtp243682643-1763) [n:127.0.0.1:33019_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> 164555 INFO (qtp243682643-1763) [n:127.0.0.1:33019_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> 164555 INFO (qtp243682643-1763) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 8 ms
[junit4] 2> 164556 INFO (qtp243682643-1763) [n:127.0.0.1:33019_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> 164556 INFO (qtp243682643-1763) [n:127.0.0.1:33019_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 1657327035897348096
[junit4] 2> 164558 INFO (searcherExecutor-675-thread-1-processing-n:127.0.0.1:33019_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:33019_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@19170674[.system_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 164559 INFO (qtp243682643-1763) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/.system/terms/shard1 to Terms{values={core_node3=0, core_node4=0}, version=1}
[junit4] 2> 164559 INFO (qtp243682643-1763) [n:127.0.0.1:33019_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> 165004 INFO (qtp243682643-1772) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 165004 INFO (qtp243682643-1772) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 165004 INFO (qtp243682643-1772) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:33019/solr/.system_shard1_replica_n1/
[junit4] 2> 165005 INFO (qtp243682643-1772) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.PeerSync PeerSync: core=.system_shard1_replica_n1 url=https://127.0.0.1:33019/solr START replicas=[https://127.0.0.1:33019/solr/.system_shard1_replica_n2/] nUpdates=100
[junit4] 2> 165005 INFO (qtp243682643-1772) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.PeerSync PeerSync: core=.system_shard1_replica_n1 url=https://127.0.0.1:33019/solr DONE. We have no versions. sync failed.
[junit4] 2> 165017 INFO (qtp243682643-1773) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request [.system_shard1_replica_n2] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 165017 INFO (qtp243682643-1772) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 165017 INFO (qtp243682643-1772) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 165017 INFO (qtp243682643-1772) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/.system/leaders/shard1/leader after winning as /collections/.system/leader_elect/shard1/election/72110910073405448-core_node3-n_0000000000
[junit4] 2> 165019 INFO (qtp243682643-1772) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:33019/solr/.system_shard1_replica_n1/ shard1
[junit4] 2> 165120 INFO (zkCallback-525-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> 165121 INFO (qtp243682643-1772) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 165123 INFO (qtp243682643-1772) [n:127.0.0.1:33019_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=1723
[junit4] 2> 165225 INFO (zkCallback-525-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> 165225 INFO (zkCallback-525-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> 165566 INFO (qtp243682643-1763) [n:127.0.0.1:33019_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=2159
[junit4] 2> 165568 INFO (qtp243682643-1765) [n:127.0.0.1:33019_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> 165666 INFO (zkCallback-525-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> 165666 INFO (zkCallback-525-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> 165667 INFO (qtp243682643-1765) [n:127.0.0.1:33019_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:33019_solr&wt=javabin&version=2} status=0 QTime=2589
[junit4] 2> 165667 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[44F32DE667A4B8CD]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: .system
[junit4] 2> 165681 INFO (qtp243682643-1781) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/.system/terms/shard1 to Terms{values={core_node3=1, core_node4=1}, version=2}
[junit4] 2> 165724 INFO (qtp243682643-1763) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n2] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:33019/solr/.system_shard1_replica_n1/&wt=javabin&version=2}{add=[17000228c8dTcyu05if6d2x6ro9b10zen5fk5 (1657327037067558912)]} 0 1
[junit4] 2> 165726 INFO (qtp243682643-1781) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[17000228c8dTcyu05if6d2x6ro9b10zen5fk5 (1657327037067558912)]} 0 55
[junit4] 2> 165727 INFO (qtp243682643-1765) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1657327037125230592,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 165727 INFO (qtp243682643-1765) [n:127.0.0.1:33019_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@678e1b71 commitCommandVersion:1657327037125230592
[junit4] 2> 165729 INFO (qtp243682643-1773) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1657327037127327744,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 165729 INFO (qtp243682643-1773) [n:127.0.0.1:33019_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@6331543d commitCommandVersion:1657327037127327744
[junit4] 2> 165735 INFO (qtp243682643-1773) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@682d0208[.system_shard1_replica_n2] main]
[junit4] 2> 165737 INFO (searcherExecutor-675-thread-1-processing-n:127.0.0.1:33019_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:33019_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@682d0208[.system_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=14-ea+32-1423, java.version=14-ea, timestamp=1580550229196, java.vendor=Oracle Corporation, os=Linux, os.version=5.3.0-26-generic, java.runtime.version=14-ea+32-1423}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 165738 INFO (qtp243682643-1773) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 165738 INFO (qtp243682643-1773) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n2] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:33019/solr/.system_shard1_replica_n1/&commit_end_point=replicas&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 9
[junit4] 2> 165739 INFO (qtp243682643-1765) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@405aba02[.system_shard1_replica_n1] main]
[junit4] 2> 165739 INFO (qtp243682643-1765) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 165740 INFO (searcherExecutor-674-thread-1-processing-n:127.0.0.1:33019_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:33019_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@405aba02[.system_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=14-ea+32-1423, java.version=14-ea, timestamp=1580550229196, java.vendor=Oracle Corporation, os=Linux, os.version=5.3.0-26-generic, java.runtime.version=14-ea+32-1423}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 165741 INFO (qtp243682643-1765) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=.system:6&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 14
[junit4] 2> 165753 INFO (qtp243682643-1772) [n:127.0.0.1:33019_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={action=STATUS&indexInfo=true&wt=javabin&version=2} status=0 QTime=10
[junit4] 2> 165755 INFO (qtp243682643-1772) [n:127.0.0.1:33019_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> 165759 INFO (qtp243682643-1763) [n:127.0.0.1:33019_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> 165773 INFO (qtp243682643-1781) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.SchemaManager managed schema loaded . version : 1
[junit4] 2> 165779 INFO (qtp243682643-1781) [n:127.0.0.1:33019_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> 165784 INFO (qtp243682643-1781) [n:127.0.0.1:33019_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> 165793 INFO (qtp243682643-1781) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.ZkController Persisted config data to node /configs/.system/schema.xml.bak
[junit4] 2> 165809 INFO (Thread-286) [ ] o.a.s.c.SolrCore config update listener called for core .system_shard1_replica_n1
[junit4] 2> 165809 INFO (Thread-286) [ ] o.a.s.c.SolrCore core reload .system_shard1_replica_n1
[junit4] 2> 165816 INFO (Thread-286) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 165825 INFO (Thread-286) [ ] o.a.s.s.IndexSchema [.system_shard1_replica_n1] Schema name=_system collection or core
[junit4] 2> 165831 INFO (Thread-286) [ ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id
[junit4] 2> 165831 INFO (Thread-286) [ ] o.a.s.c.CoreContainer Reloading SolrCore '.system_shard1_replica_n1' using configuration from configset .system
[junit4] 2> 165859 INFO (Thread-286) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33019.solr.core..system.shard1.replica_n1' (registry 'solr.core..system.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@61f8a50c
[junit4] 2> 165859 INFO (Thread-286) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [[.system_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J5/temp/solr.cloud.SystemCollectionCompatTest_44F32DE667A4B8CD-001/tempDir-001/node1/.system_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J5/temp/solr.cloud.SystemCollectionCompatTest_44F32DE667A4B8CD-001/tempDir-001/node1/./.system_shard1_replica_n1/data/]
[junit4] 2> 165884 INFO (Thread-286) [n:127.0.0.1:33019_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> 165884 INFO (Thread-286) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 165885 INFO (Thread-286) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6ae8fc6b[.system_shard1_replica_n1] main]
[junit4] 2> 165886 INFO (Thread-286) [n:127.0.0.1:33019_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> 165886 INFO (Thread-286) [n:127.0.0.1:33019_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> 165886 INFO (Thread-286) [n:127.0.0.1:33019_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> 165887 INFO (Thread-286) [n:127.0.0.1:33019_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> 165887 INFO (Thread-286) [n:127.0.0.1:33019_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> 165893 INFO (Thread-286) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 165893 INFO (searcherExecutor-684-thread-1-processing-n:127.0.0.1:33019_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:33019_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@6ae8fc6b[.system_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=14-ea+32-1423, java.version=14-ea, timestamp=1580550229196, java.vendor=Oracle Corporation, os=Linux, os.version=5.3.0-26-generic, java.runtime.version=14-ea+32-1423}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 165894 INFO (Thread-286) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7b35acfa[.system_shard1_replica_n1] main]
[junit4] 2> 165894 INFO (Thread-286) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore config update listener called for core .system_shard1_replica_n2
[junit4] 2> 165896 INFO (searcherExecutor-684-thread-1-processing-n:127.0.0.1:33019_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:33019_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@7b35acfa[.system_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=14-ea+32-1423, java.version=14-ea, timestamp=1580550229196, os=Linux, java.vendor=Oracle Corporation, os.version=5.3.0-26-generic, java.runtime.version=14-ea+32-1423}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 165899 INFO (qtp243682643-1781) [n:127.0.0.1:33019_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> 165923 INFO (qtp243682643-1781) [n:127.0.0.1:33019_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> 165927 INFO (qtp243682643-1781) [n:127.0.0.1:33019_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> 165927 INFO (qtp243682643-1781) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.CoreContainer Reloading SolrCore '.system_shard1_replica_n1' using configuration from configset .system
[junit4] 2> 165966 INFO (qtp243682643-1781) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33019.solr.core..system.shard1.replica_n1' (registry 'solr.core..system.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@61f8a50c
[junit4] 2> 165967 INFO (qtp243682643-1781) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [[.system_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J5/temp/solr.cloud.SystemCollectionCompatTest_44F32DE667A4B8CD-001/tempDir-001/node1/.system_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J5/temp/solr.cloud.SystemCollectionCompatTest_44F32DE667A4B8CD-001/tempDir-001/node1/./.system_shard1_replica_n1/data/]
[junit4] 2> 166006 INFO (qtp243682643-1781) [n:127.0.0.1:33019_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> 166006 INFO (qtp243682643-1781) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 166009 INFO (qtp243682643-1781) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1bb684d3[.system_shard1_replica_n1] main]
[junit4] 2> 166010 INFO (qtp243682643-1781) [n:127.0.0.1:33019_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> 166010 INFO (qtp243682643-1781) [n:127.0.0.1:33019_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> 166010 INFO (qtp243682643-1781) [n:127.0.0.1:33019_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> 166011 INFO (qtp243682643-1781) [n:127.0.0.1:33019_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> 166011 INFO (qtp243682643-1781) [n:127.0.0.1:33019_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> 166014 INFO (qtp243682643-1781) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 166015 INFO (searcherExecutor-688-thread-1-processing-n:127.0.0.1:33019_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:33019_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@1bb684d3[.system_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=14-ea+32-1423, java.version=14-ea, timestamp=1580550229196, os=Linux, java.vendor=Oracle Corporation, os.version=5.3.0-26-generic, java.runtime.version=14-ea+32-1423}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 166016 INFO (qtp243682643-1781) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@77fb8663[.system_shard1_replica_n1] main]
[junit4] 2> 166017 INFO (qtp243682643-1781) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [.system_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@7d167388
[junit4] 2> 166017 INFO (qtp243682643-1781) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core..system.shard1.replica_n1, tag=SolrCore@7d167388
[junit4] 2> 166017 INFO (qtp243682643-1781) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4319b037: rootName = solr_33019, domain = solr.core..system.shard1.replica_n1, service url = null, agent id = null] for registry solr.core..system.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@6f425c60
[junit4] 2> 166018 INFO (searcherExecutor-688-thread-1-processing-n:127.0.0.1:33019_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:33019_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@77fb8663[.system_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=14-ea+32-1423, java.version=14-ea, timestamp=1580550229196, os=Linux, java.vendor=Oracle Corporation, os.version=5.3.0-26-generic, java.runtime.version=14-ea+32-1423}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 166026 INFO (qtp243682643-1781) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection..system.shard1.leader, tag=SolrCore@7d167388
[junit4] 2> 166030 INFO (qtp243682643-1781) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Waiting up to 599 secs for 1 replicas to apply schema update version 2 for collection .system
[junit4] 2> 166059 INFO (qtp243682643-1772) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 166059 INFO (qtp243682643-1772) [n:127.0.0.1:33019_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> 166059 INFO (qtp243682643-1772) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request [.system_shard1_replica_n2] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 166061 ERROR (managedSchemaExecutor-692-thread-1-processing-n:127.0.0.1:33019_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:33019/solr/.system_shard1_replica_n2/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 166062 INFO (qtp243682643-1773) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 166062 INFO (qtp243682643-1773) [n:127.0.0.1:33019_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> 166062 INFO (qtp243682643-1773) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request [.system_shard1_replica_n2] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 166062 ERROR (managedSchemaExecutor-692-thread-1-processing-n:127.0.0.1:33019_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:33019/solr/.system_shard1_replica_n2/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 166069 INFO (qtp243682643-1765) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 166070 INFO (qtp243682643-1765) [n:127.0.0.1:33019_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> 166070 INFO (qtp243682643-1765) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request [.system_shard1_replica_n2] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 166070 ERROR (managedSchemaExecutor-692-thread-1-processing-n:127.0.0.1:33019_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:33019/solr/.system_shard1_replica_n2/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 166071 INFO (qtp243682643-1763) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 166073 INFO (qtp243682643-1763) [n:127.0.0.1:33019_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> 166073 INFO (qtp243682643-1763) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request [.system_shard1_replica_n2] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 166073 ERROR (managedSchemaExecutor-692-thread-1-processing-n:127.0.0.1:33019_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:33019/solr/.system_shard1_replica_n2/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 166074 INFO (qtp243682643-1772) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 166074 INFO (qtp243682643-1772) [n:127.0.0.1:33019_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> 166074 INFO (qtp243682643-1772) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request [.system_shard1_replica_n2] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 166075 ERROR (managedSchemaExecutor-692-thread-1-processing-n:127.0.0.1:33019_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:33019/solr/.system_shard1_replica_n2/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 166075 INFO (qtp243682643-1773) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 166075 INFO (qtp243682643-1773) [n:127.0.0.1:33019_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> 166075 INFO (qtp243682643-1773) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request [.system_shard1_replica_n2] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 166076 ERROR (managedSchemaExecutor-692-thread-1-processing-n:127.0.0.1:33019_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:33019/solr/.system_shard1_replica_n2/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 166077 INFO (qtp243682643-1765) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 166081 INFO (qtp243682643-1765) [n:127.0.0.1:33019_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> 166081 INFO (qtp243682643-1765) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request [.system_shard1_replica_n2] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 166081 ERROR (managedSchemaExecutor-692-thread-1-processing-n:127.0.0.1:33019_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:33019/solr/.system_shard1_replica_n2/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 166082 INFO (qtp243682643-1763) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 166090 INFO (qtp243682643-1763) [n:127.0.0.1:33019_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> 166090 INFO (qtp243682643-1763) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request [.system_shard1_replica_n2] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=7
[junit4] 2> 166090 ERROR (managedSchemaExecutor-692-thread-1-processing-n:127.0.0.1:33019_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:33019/solr/.system_shard1_replica_n2/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 166091 INFO (qtp243682643-1772) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 166091 INFO (qtp243682643-1772) [n:127.0.0.1:33019_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> 166091 INFO (qtp243682643-1772) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request [.system_shard1_replica_n2] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 166091 ERROR (managedSchemaExecutor-692-thread-1-processing-n:127.0.0.1:33019_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:33019/solr/.system_shard1_replica_n2/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 166093 INFO (qtp243682643-1773) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 166093 INFO (qtp243682643-1773) [n:127.0.0.1:33019_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> 166093 INFO (qtp243682643-1773) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request [.system_shard1_replica_n2] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 166093 ERROR (managedSchemaExecutor-692-thread-1-processing-n:127.0.0.1:33019_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:33019/solr/.system_shard1_replica_n2/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 166109 INFO (qtp243682643-1765) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 166110 INFO (qtp243682643-1765) [n:127.0.0.1:33019_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> 166110 INFO (qtp243682643-1765) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request [.system_shard1_replica_n2] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 166110 ERROR (managedSchemaExecutor-692-thread-1-processing-n:127.0.0.1:33019_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:33019/solr/.system_shard1_replica_n2/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 166111 INFO (qtp243682643-1763) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 166111 INFO (qtp243682643-1763) [n:127.0.0.1:33019_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> 166111 INFO (qtp243682643-1763) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request [.system_shard1_replica_n2] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 166111 ERROR (managedSchemaExecutor-692-thread-1-processing-n:127.0.0.1:33019_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:33019/solr/.system_shard1_replica_n2/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 166119 INFO (qtp243682643-1772) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 166120 INFO (qtp243682643-1772) [n:127.0.0.1:33019_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> 166120 INFO (qtp243682643-1772) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request [.system_shard1_replica_n2] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 166125 ERROR (managedSchemaExecutor-692-thread-1-processing-n:127.0.0.1:33019_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:33019/solr/.system_shard1_replica_n2/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 166133 INFO (qtp243682643-1773) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 166134 INFO (qtp243682643-1773) [n:127.0.0.1:33019_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> 166134 INFO (qtp243682643-1773) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request [.system_shard1_replica_n2] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 166134 ERROR (managedSchemaExecutor-692-thread-1-processing-n:127.0.0.1:33019_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:33019/solr/.system_shard1_replica_n2/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 166139 INFO (qtp243682643-1765) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 166140 INFO (qtp243682643-1765) [n:127.0.0.1:33019_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> 166140 INFO (qtp243682643-1765) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request [.system_shard1_replica_n2] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 166140 ERROR (managedSchemaExecutor-692-thread-1-processing-n:127.0.0.1:33019_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:33019/solr/.system_shard1_replica_n2/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 166141 INFO (qtp243682643-1763) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 166142 INFO (qtp243682643-1763) [n:127.0.0.1:33019_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> 166142 INFO (qtp243682643-1763) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request [.system_shard1_replica_n2] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 166142 ERROR (managedSchemaExecutor-692-thread-1-processing-n:127.0.0.1:33019_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:33019/solr/.system_shard1_replica_n2/ returned schema version 0 and has not applied s
[...truncated too long message...]
0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Failed to get /schema/zkversion from https://127.0.0.1:33019/solr/.system_shard1_replica_n2/ due to: java.util.concurrent.ExecutionException: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: https://127.0.0.1:33019/solr/.system_shard1_replica_n2
[junit4] 2> 301694 WARN (managedSchemaExecutor-692-thread-1-processing-n:127.0.0.1:33019_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Failed to get /schema/zkversion from https://127.0.0.1:33019/solr/.system_shard1_replica_n2/ due to: java.util.concurrent.ExecutionException: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: https://127.0.0.1:33019/solr/.system_shard1_replica_n2
[junit4] 2> 301695 WARN (managedSchemaExecutor-692-thread-1-processing-n:127.0.0.1:33019_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Failed to get /schema/zkversion from https://127.0.0.1:33019/solr/.system_shard1_replica_n2/ due to: java.util.concurrent.ExecutionException: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: https://127.0.0.1:33019/solr/.system_shard1_replica_n2
[junit4] 2> 301715 WARN (managedSchemaExecutor-692-thread-1-processing-n:127.0.0.1:33019_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Failed to get /schema/zkversion from https://127.0.0.1:33019/solr/.system_shard1_replica_n2/ due to: java.util.concurrent.ExecutionException: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: https://127.0.0.1:33019/solr/.system_shard1_replica_n2
[junit4] 2> 301728 WARN (managedSchemaExecutor-692-thread-1-processing-n:127.0.0.1:33019_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Failed to get /schema/zkversion from https://127.0.0.1:33019/solr/.system_shard1_replica_n2/ due to: java.util.concurrent.ExecutionException: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: https://127.0.0.1:33019/solr/.system_shard1_replica_n2
[junit4] 2> 301728 WARN (managedSchemaExecutor-692-thread-1-processing-n:127.0.0.1:33019_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Failed to get /schema/zkversion from https://127.0.0.1:33019/solr/.system_shard1_replica_n2/ due to: java.util.concurrent.ExecutionException: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: https://127.0.0.1:33019/solr/.system_shard1_replica_n2
[junit4] 2> 301732 WARN (qtp243682643-1781) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Core core_node3 was interrupted waiting for schema version 2 to propagate to 1 replicas for collection .system
[junit4] 2> 301732 INFO (qtp243682643-1781) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Took 135704.0ms for 1 replicas to apply schema update version 2 for collection .system
[junit4] 2> 301732 INFO (qtp243682643-1781) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema params={wt=javabin&version=2} status=0 QTime=135962
[junit4] 2> 301732 INFO (qtp243682643-1781) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [.system_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@2a467ca0
[junit4] 2> 301732 WARN (qtp243682643-1781) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Interrupted while trying to obtain lock to modify reporters registry: solr.core..system.shard1.replica_n1
[junit4] 2> 301732 INFO (qtp243682643-1781) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection..system.shard1.leader, tag=SolrCore@2a467ca0
[junit4] 2> 301733 INFO (qtp243682643-1781) [n:127.0.0.1:33019_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() .
[junit4] 2> 301733 INFO (qtp243682643-1781) [n:127.0.0.1:33019_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@2e9cda28 commitCommandVersion:0
[junit4] 2> 301748 INFO (SUITE-SystemCollectionCompatTest-seed#[44F32DE667A4B8CD]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 301959 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> 6 /solr/collections/.system/terms/shard1
[junit4] 2> 4 /solr/configs/.system/managed-schema
[junit4] 2> 2 /solr/configs/.system
[junit4] 2> 2 /solr/packages.json
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/collections/.system/collectionprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 15 /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> 25 /solr/overseer/queue
[junit4] 2> 18 /solr/collections
[junit4] 2> 12 /solr/live_nodes
[junit4] 2> 10 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 301961 INFO (SUITE-SystemCollectionCompatTest-seed#[44F32DE667A4B8CD]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:46401
[junit4] 2> 301961 INFO (SUITE-SystemCollectionCompatTest-seed#[44F32DE667A4B8CD]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:46401
[junit4] 2> 301961 INFO (SUITE-SystemCollectionCompatTest-seed#[44F32DE667A4B8CD]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 46401
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J5/temp/solr.cloud.SystemCollectionCompatTest_44F32DE667A4B8CD-001
[junit4] 2> Feb 01, 2020 9:46:05 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {id=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), type=PostingsFormat(name=LuceneFixedGap)}, docValues:{timestamp_l=DocValuesFormat(name=Asserting), _version_=DocValuesFormat(name=Lucene80), time_l=DocValuesFormat(name=Lucene80), timestamp=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=286, maxMBSortInHeap=5.3118169777713105, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@18050cbf), locale=jgo, timezone=NZ-CHAT
[junit4] 2> NOTE: Linux 5.3.0-26-generic amd64/Oracle Corporation 14-ea (64-bit)/cpus=16,threads=1,free=377487360,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [RoutingToNodesWithPropertiesTest, TestFieldCollectionResource, TestFunctionQuery, TestManagedResourceStorage, MigrateRouteKeyTest, BadComponentTest, TestMaxScoreQueryParser, PreAnalyzedUpdateProcessorTest, TestChildDocTransformerHierarchy, SpellPossibilityIteratorTest, TestXmlQParser, DistributedMLTComponentTest, TestReload, RequiredFieldsTest, CollectionStateFormat2Test, EnumFieldTest, SynonymTokenizerTest, TestCloudJSONFacetSKG, MultiThreadedOCPTest, TestSolrQueryParser, SystemCollectionCompatTest]
[junit4] Completed [209/900 (1!)] on J5 in 139.99s, 1 test, 1 error <<< FAILURES!
[...truncated 47106 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/25555/consoleText
[repro] Revision: b457c2ee2ea0180f3a11c5d0b4d443083316aa31
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseZGC"
[repro] Repro line: ant test -Dtestcase=SystemCollectionCompatTest -Dtests.method=testBackCompat -Dtests.seed=44F32DE667A4B8CD -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=jgo -Dtests.timezone=NZ-CHAT -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] SystemCollectionCompatTest
[repro] ant compile-test
[...truncated 3150 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.SystemCollectionCompatTest" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseZGC" -Dtests.seed=44F32DE667A4B8CD -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=jgo -Dtests.timezone=NZ-CHAT -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 81 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.cloud.SystemCollectionCompatTest
[repro] Exiting with code 0
[...truncated 43 lines...]