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 06:18:03 UTC
[JENKINS] Lucene-Solr-master-Linux (64bit/jdk-11.0.6) - Build #
25553 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/25553/
Java: 64bit/jdk-11.0.6 -XX:-UseCompressedOops -XX:+UseParallelGC
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:40663/solr/.system]
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[https://127.0.0.1:40663/solr/.system]
at __randomizedtesting.SeedInfo.seed([73E1AC1116ADEDC8:3140FB8766544BE]:0)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:345)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1143)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:906)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:838)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207)
at org.apache.solr.cloud.SystemCollectionCompatTest.setupSystemCollection(SystemCollectionCompatTest.java:118)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:976)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
at java.base/java.lang.Thread.run(Thread.java:834)
Caused by: org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: https://127.0.0.1:40663/solr/.system
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:676)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:265)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:368)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:296)
... 43 more
Caused by: java.net.SocketTimeoutException: Read timed out
at java.base/java.net.SocketInputStream.socketRead0(Native Method)
at java.base/java.net.SocketInputStream.socketRead(SocketInputStream.java:115)
at java.base/java.net.SocketInputStream.read(SocketInputStream.java:168)
at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
at java.base/sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:448)
at java.base/sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:68)
at java.base/sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1103)
at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:823)
at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:157)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:186)
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:564)
... 47 more
Build Log:
[...truncated 14512 lines...]
[junit4] Suite: org.apache.solr.cloud.SystemCollectionCompatTest
[junit4] 2> 769110 INFO (SUITE-SystemCollectionCompatTest-seed#[73E1AC1116ADEDC8]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 769110 INFO (SUITE-SystemCollectionCompatTest-seed#[73E1AC1116ADEDC8]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_73E1AC1116ADEDC8-001/data-dir-88-001
[junit4] 2> 769110 WARN (SUITE-SystemCollectionCompatTest-seed#[73E1AC1116ADEDC8]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=25 numCloses=25
[junit4] 2> 769110 INFO (SUITE-SystemCollectionCompatTest-seed#[73E1AC1116ADEDC8]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 769111 INFO (SUITE-SystemCollectionCompatTest-seed#[73E1AC1116ADEDC8]-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> 769111 INFO (SUITE-SystemCollectionCompatTest-seed#[73E1AC1116ADEDC8]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_73E1AC1116ADEDC8-001/tempDir-001
[junit4] 2> 769111 INFO (SUITE-SystemCollectionCompatTest-seed#[73E1AC1116ADEDC8]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 769112 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 769112 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 769212 INFO (SUITE-SystemCollectionCompatTest-seed#[73E1AC1116ADEDC8]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:41619
[junit4] 2> 769212 INFO (SUITE-SystemCollectionCompatTest-seed#[73E1AC1116ADEDC8]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:41619
[junit4] 2> 769212 INFO (SUITE-SystemCollectionCompatTest-seed#[73E1AC1116ADEDC8]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:41619
[junit4] 2> 769212 INFO (SUITE-SystemCollectionCompatTest-seed#[73E1AC1116ADEDC8]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 41619
[junit4] 2> 769213 INFO (SUITE-SystemCollectionCompatTest-seed#[73E1AC1116ADEDC8]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 769214 INFO (zkConnectionManagerCallback-4058-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 769214 INFO (SUITE-SystemCollectionCompatTest-seed#[73E1AC1116ADEDC8]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 769216 INFO (SUITE-SystemCollectionCompatTest-seed#[73E1AC1116ADEDC8]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 769217 INFO (zkConnectionManagerCallback-4060-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 769217 INFO (SUITE-SystemCollectionCompatTest-seed#[73E1AC1116ADEDC8]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 769217 INFO (SUITE-SystemCollectionCompatTest-seed#[73E1AC1116ADEDC8]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 769217 INFO (zkConnectionManagerCallback-4062-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 769217 INFO (SUITE-SystemCollectionCompatTest-seed#[73E1AC1116ADEDC8]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 769325 WARN (jetty-launcher-4063-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 769325 INFO (jetty-launcher-4063-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 769325 WARN (jetty-launcher-4063-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 769325 INFO (jetty-launcher-4063-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 769325 INFO (jetty-launcher-4063-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 769325 INFO (jetty-launcher-4063-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 769326 INFO (jetty-launcher-4063-thread-1) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.6+10
[junit4] 2> 769326 INFO (jetty-launcher-4063-thread-2) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.6+10
[junit4] 2> 769330 INFO (jetty-launcher-4063-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 769330 INFO (jetty-launcher-4063-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 769330 INFO (jetty-launcher-4063-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 769330 INFO (jetty-launcher-4063-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 769330 INFO (jetty-launcher-4063-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 769330 INFO (jetty-launcher-4063-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 769331 INFO (jetty-launcher-4063-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@41141e5a{/solr,null,AVAILABLE}
[junit4] 2> 769331 INFO (jetty-launcher-4063-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2282f717{/solr,null,AVAILABLE}
[junit4] 2> 769339 INFO (jetty-launcher-4063-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1a71077d{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:40757}
[junit4] 2> 769339 INFO (jetty-launcher-4063-thread-2) [ ] o.e.j.s.Server Started @769359ms
[junit4] 2> 769339 INFO (jetty-launcher-4063-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3900318f{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:40663}
[junit4] 2> 769339 INFO (jetty-launcher-4063-thread-1) [ ] o.e.j.s.Server Started @769359ms
[junit4] 2> 769339 INFO (jetty-launcher-4063-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=40757}
[junit4] 2> 769339 INFO (jetty-launcher-4063-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=40663}
[junit4] 2> 769339 ERROR (jetty-launcher-4063-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 769339 INFO (jetty-launcher-4063-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 769339 ERROR (jetty-launcher-4063-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 769339 INFO (jetty-launcher-4063-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 769339 INFO (jetty-launcher-4063-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 769339 INFO (jetty-launcher-4063-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 769339 INFO (jetty-launcher-4063-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 769339 INFO (jetty-launcher-4063-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 769339 INFO (jetty-launcher-4063-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr
[junit4] 2> 769339 INFO (jetty-launcher-4063-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr
[junit4] 2> 769339 INFO (jetty-launcher-4063-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-02-01T05:55:01.827567Z
[junit4] 2> 769339 INFO (jetty-launcher-4063-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-02-01T05:55:01.827570Z
[junit4] 2> 769343 INFO (jetty-launcher-4063-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 769343 INFO (jetty-launcher-4063-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 769352 INFO (zkConnectionManagerCallback-4067-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 769352 INFO (jetty-launcher-4063-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 769352 INFO (zkConnectionManagerCallback-4066-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 769352 INFO (jetty-launcher-4063-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 769356 INFO (jetty-launcher-4063-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 769359 INFO (jetty-launcher-4063-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 769493 INFO (jetty-launcher-4063-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 769494 WARN (jetty-launcher-4063-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@589499b3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 769494 WARN (jetty-launcher-4063-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@589499b3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 769495 WARN (jetty-launcher-4063-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@797c03a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 769495 WARN (jetty-launcher-4063-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@797c03a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 769496 INFO (jetty-launcher-4063-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41619/solr
[junit4] 2> 769497 INFO (jetty-launcher-4063-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 769497 INFO (zkConnectionManagerCallback-4074-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 769497 INFO (jetty-launcher-4063-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 769633 INFO (jetty-launcher-4063-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 769634 WARN (jetty-launcher-4063-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1b1718b5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 769634 WARN (jetty-launcher-4063-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1b1718b5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 769635 WARN (jetty-launcher-4063-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7eb31c03[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 769635 WARN (jetty-launcher-4063-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7eb31c03[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 769635 INFO (jetty-launcher-4063-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41619/solr
[junit4] 2> 769636 INFO (jetty-launcher-4063-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 769636 INFO (zkConnectionManagerCallback-4081-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 769636 INFO (jetty-launcher-4063-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 769665 INFO (jetty-launcher-4063-thread-2) [n:127.0.0.1:40757_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 769666 INFO (zkConnectionManagerCallback-4083-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 769666 INFO (jetty-launcher-4063-thread-2) [n:127.0.0.1:40757_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 769692 INFO (jetty-launcher-4063-thread-2) [n:127.0.0.1:40757_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:40757_solr
[junit4] 2> 769692 INFO (jetty-launcher-4063-thread-2) [n:127.0.0.1:40757_solr ] o.a.s.c.Overseer Overseer (id=72110010656948231-127.0.0.1:40757_solr-n_0000000000) starting
[junit4] 2> 769694 INFO (OverseerStateUpdate-72110010656948231-127.0.0.1:40757_solr-n_0000000000) [n:127.0.0.1:40757_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:40757_solr
[junit4] 2> 769698 INFO (jetty-launcher-4063-thread-2) [n:127.0.0.1:40757_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40757_solr
[junit4] 2> 769698 INFO (OverseerStateUpdate-72110010656948231-127.0.0.1:40757_solr-n_0000000000) [n:127.0.0.1:40757_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 769699 INFO (jetty-launcher-4063-thread-2) [n:127.0.0.1:40757_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 769700 WARN (jetty-launcher-4063-thread-2) [n:127.0.0.1:40757_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> 769714 INFO (jetty-launcher-4063-thread-2) [n:127.0.0.1:40757_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 769723 INFO (jetty-launcher-4063-thread-2) [n:127.0.0.1:40757_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40757.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@176378ab
[junit4] 2> 769727 INFO (jetty-launcher-4063-thread-2) [n:127.0.0.1:40757_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40757.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@176378ab
[junit4] 2> 769727 INFO (jetty-launcher-4063-thread-2) [n:127.0.0.1:40757_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40757.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@176378ab
[junit4] 2> 769728 INFO (jetty-launcher-4063-thread-2) [n:127.0.0.1:40757_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_73E1AC1116ADEDC8-001/tempDir-001/node2/.
[junit4] 2> 769765 INFO (jetty-launcher-4063-thread-1) [n:127.0.0.1:40663_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 769766 INFO (zkConnectionManagerCallback-4089-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 769766 INFO (jetty-launcher-4063-thread-1) [n:127.0.0.1:40663_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 769768 INFO (jetty-launcher-4063-thread-1) [n:127.0.0.1:40663_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 769769 INFO (jetty-launcher-4063-thread-1) [n:127.0.0.1:40663_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:40663_solr as DOWN
[junit4] 2> 769769 INFO (jetty-launcher-4063-thread-1) [n:127.0.0.1:40663_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 769769 INFO (jetty-launcher-4063-thread-1) [n:127.0.0.1:40663_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40663_solr
[junit4] 2> 769770 INFO (zkCallback-4082-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 769770 INFO (zkCallback-4088-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 769770 INFO (jetty-launcher-4063-thread-1) [n:127.0.0.1:40663_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 769770 WARN (jetty-launcher-4063-thread-1) [n:127.0.0.1:40663_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> 769775 INFO (jetty-launcher-4063-thread-1) [n:127.0.0.1:40663_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 769784 INFO (jetty-launcher-4063-thread-1) [n:127.0.0.1:40663_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40663.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@176378ab
[junit4] 2> 769788 INFO (jetty-launcher-4063-thread-1) [n:127.0.0.1:40663_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40663.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@176378ab
[junit4] 2> 769788 INFO (jetty-launcher-4063-thread-1) [n:127.0.0.1:40663_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40663.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@176378ab
[junit4] 2> 769788 INFO (jetty-launcher-4063-thread-1) [n:127.0.0.1:40663_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_73E1AC1116ADEDC8-001/tempDir-001/node1/.
[junit4] 2> 769799 INFO (SUITE-SystemCollectionCompatTest-seed#[73E1AC1116ADEDC8]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 769799 INFO (SUITE-SystemCollectionCompatTest-seed#[73E1AC1116ADEDC8]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 769800 INFO (zkConnectionManagerCallback-4098-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 769800 INFO (SUITE-SystemCollectionCompatTest-seed#[73E1AC1116ADEDC8]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 769800 INFO (SUITE-SystemCollectionCompatTest-seed#[73E1AC1116ADEDC8]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 769802 INFO (SUITE-SystemCollectionCompatTest-seed#[73E1AC1116ADEDC8]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41619/solr ready
[junit4] 2> 769817 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[73E1AC1116ADEDC8]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBackCompat
[junit4] 2> 769819 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[73E1AC1116ADEDC8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 769820 INFO (zkConnectionManagerCallback-4103-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 769820 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[73E1AC1116ADEDC8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 769820 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[73E1AC1116ADEDC8]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 769821 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[73E1AC1116ADEDC8]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41619/solr ready
[junit4] 2> 769839 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 769847 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=7
[junit4] 2> 769854 INFO (qtp1996147483-13632) [n:127.0.0.1:40663_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:40663_solr&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 769857 INFO (OverseerThreadFactory-3694-thread-2-processing-n:127.0.0.1:40757_solr) [n:127.0.0.1:40757_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection .system
[junit4] 2> 769858 INFO (OverseerCollectionConfigSetProcessor-72110010656948231-127.0.0.1:40757_solr-n_0000000000) [n:127.0.0.1:40757_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> 769959 WARN (OverseerThreadFactory-3694-thread-2-processing-n:127.0.0.1:40757_solr) [n:127.0.0.1:40757_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> 769960 INFO (OverseerStateUpdate-72110010656948231-127.0.0.1:40757_solr-n_0000000000) [n:127.0.0.1:40757_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:40663/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 769961 INFO (OverseerStateUpdate-72110010656948231-127.0.0.1:40757_solr-n_0000000000) [n:127.0.0.1:40757_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:40663/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 770170 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_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> 770176 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_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> 771181 WARN (qtp1996147483-13630) [n:127.0.0.1:40663_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> 771181 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_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> 771181 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_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> 771193 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_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> 771193 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_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> 771194 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_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> 771194 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_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> 771197 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_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> 771197 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_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> 771198 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Created and persisted managed schema znode at /configs/.system/managed-schema
[junit4] 2> 771198 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Managed schema znode at /configs/.system/managed-schema already exists - no need to create it
[junit4] 2> 771198 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_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> 771199 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40663.solr.core..system.shard1.replica_n2' (registry 'solr.core..system.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@176378ab
[junit4] 2> 771199 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_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/J0/temp/solr.cloud.SystemCollectionCompatTest_73E1AC1116ADEDC8-001/tempDir-001/node1/.system_shard1_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_73E1AC1116ADEDC8-001/tempDir-001/node1/./.system_shard1_replica_n2/data/]
[junit4] 2> 771199 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in ZooKeeper, renamed the non-managed schema /configs/.system/schema.xml to /configs/.system/schema.xml.bak
[junit4] 2> 771200 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_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> 771200 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40663.solr.core..system.shard1.replica_n1' (registry 'solr.core..system.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@176378ab
[junit4] 2> 771200 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_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/J0/temp/solr.cloud.SystemCollectionCompatTest_73E1AC1116ADEDC8-001/tempDir-001/node1/.system_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_73E1AC1116ADEDC8-001/tempDir-001/node1/./.system_shard1_replica_n1/data/]
[junit4] 2> 771227 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_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> 771227 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_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> 771228 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_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> 771228 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 771229 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1f75ee70[.system_shard1_replica_n2] main]
[junit4] 2> 771230 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_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> 771230 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_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> 771230 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_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> 771230 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_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> 771230 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_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> 771230 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_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 1657312644922605568
[junit4] 2> 771232 INFO (searcherExecutor-3703-thread-1-processing-n:127.0.0.1:40663_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:40663_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@1f75ee70[.system_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 771232 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/.system/terms/shard1 to Terms{values={core_node4=0}, version=0}
[junit4] 2> 771233 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_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> 771234 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=2 found=1 timeoutin=9999ms
[junit4] 2> 771235 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_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> 771235 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_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> 771236 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_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> 771236 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 771236 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6b30838e[.system_shard1_replica_n1] main]
[junit4] 2> 771237 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_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> 771237 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_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> 771237 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_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> 771237 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 0 from ZooKeeper
[junit4] 2> 771238 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_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> 771241 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_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> 771241 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 3 ms
[junit4] 2> 771241 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_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> 771241 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_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 1657312644934139904
[junit4] 2> 771242 INFO (searcherExecutor-3704-thread-1-processing-n:127.0.0.1:40663_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:40663_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@6b30838e[.system_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 771243 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/.system/terms/shard1 to Terms{values={core_node3=0, core_node4=0}, version=1}
[junit4] 2> 771243 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_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> 771735 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 771735 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 771735 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:40663/solr/.system_shard1_replica_n2/
[junit4] 2> 771735 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.PeerSync PeerSync: core=.system_shard1_replica_n2 url=https://127.0.0.1:40663/solr START replicas=[https://127.0.0.1:40663/solr/.system_shard1_replica_n1/] nUpdates=100
[junit4] 2> 771735 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.PeerSync PeerSync: core=.system_shard1_replica_n2 url=https://127.0.0.1:40663/solr DONE. We have no versions. sync failed.
[junit4] 2> 771742 INFO (qtp1996147483-13639) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 771742 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 771742 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 771742 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/.system/leaders/shard1/leader after winning as /collections/.system/leader_elect/shard1/election/72110010656948232-core_node4-n_0000000000
[junit4] 2> 771743 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:40663/solr/.system_shard1_replica_n2/ shard1
[junit4] 2> 771843 INFO (zkCallback-4088-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> 771844 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 771845 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_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=1668
[junit4] 2> 771945 INFO (zkCallback-4088-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> 771945 INFO (zkCallback-4088-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> 772245 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_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=2075
[junit4] 2> 772247 INFO (qtp1996147483-13632) [n:127.0.0.1:40663_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> 772346 INFO (zkCallback-4088-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> 772346 INFO (zkCallback-4088-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> 772346 INFO (qtp1996147483-13632) [n:127.0.0.1:40663_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:40663_solr&wt=javabin&version=2} status=0 QTime=2492
[junit4] 2> 772347 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[73E1AC1116ADEDC8]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: .system
[junit4] 2> 772353 INFO (qtp1996147483-13648) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/.system/terms/shard1 to Terms{values={core_node3=1, core_node4=1}, version=2}
[junit4] 2> 772371 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:40663/solr/.system_shard1_replica_n2/&wt=javabin&version=2}{add=[16fff512203T8uxancbsnzxf823hw30oe1g84 (1657312646098059264)]} 0 1
[junit4] 2> 772372 INFO (qtp1996147483-13648) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[16fff512203T8uxancbsnzxf823hw30oe1g84 (1657312646098059264)]} 0 21
[junit4] 2> 772373 INFO (qtp1996147483-13632) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1657312646121127936,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 772373 INFO (qtp1996147483-13632) [n:127.0.0.1:40663_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@161cbef4 commitCommandVersion:1657312646121127936
[junit4] 2> 772374 INFO (qtp1996147483-13639) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1657312646122176512,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 772374 INFO (qtp1996147483-13639) [n:127.0.0.1:40663_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@2a39a348 commitCommandVersion:1657312646122176512
[junit4] 2> 772379 INFO (qtp1996147483-13639) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@50e8dab9[.system_shard1_replica_n1] main]
[junit4] 2> 772379 INFO (qtp1996147483-13632) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@10828017[.system_shard1_replica_n2] main]
[junit4] 2> 772380 INFO (qtp1996147483-13639) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 772380 INFO (qtp1996147483-13632) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 772404 INFO (searcherExecutor-3704-thread-1-processing-n:127.0.0.1:40663_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:40663_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@50e8dab9[.system_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={java.vendor=AdoptOpenJDK, timestamp=1580536504866, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0, source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 772404 INFO (searcherExecutor-3703-thread-1-processing-n:127.0.0.1:40663_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:40663_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@10828017[.system_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={java.vendor=AdoptOpenJDK, timestamp=1580536504865, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0, source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 772404 INFO (qtp1996147483-13639) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:40663/solr/.system_shard1_replica_n2/&commit_end_point=replicas&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 30
[junit4] 2> 772405 INFO (qtp1996147483-13632) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n2] webapp=/solr path=/update params={_stateVer_=.system:6&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 32
[junit4] 2> 772406 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_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> 772407 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_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> 772410 INFO (qtp1996147483-13648) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema params={wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 772415 INFO (qtp1996147483-13639) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.SchemaManager managed schema loaded . version : 1
[junit4] 2> 772416 INFO (qtp1996147483-13639) [n:127.0.0.1:40663_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> 772418 INFO (qtp1996147483-13639) [n:127.0.0.1:40663_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> 772420 INFO (qtp1996147483-13639) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.ZkController Persisted config data to node /configs/.system/schema.xml.bak
[junit4] 2> 772424 INFO (qtp1996147483-13639) [n:127.0.0.1:40663_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> 772430 INFO (Thread-2753) [ ] o.a.s.c.SolrCore config update listener called for core .system_shard1_replica_n2
[junit4] 2> 772435 INFO (qtp1996147483-13639) [n:127.0.0.1:40663_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> 772436 INFO (Thread-2753) [ ] o.a.s.c.SolrCore core reload .system_shard1_replica_n2
[junit4] 2> 772437 INFO (qtp1996147483-13639) [n:127.0.0.1:40663_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> 772437 INFO (qtp1996147483-13639) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.CoreContainer Reloading SolrCore '.system_shard1_replica_n2' using configuration from configset .system
[junit4] 2> 772462 INFO (qtp1996147483-13639) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40663.solr.core..system.shard1.replica_n2' (registry 'solr.core..system.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@176378ab
[junit4] 2> 772462 INFO (qtp1996147483-13639) [n:127.0.0.1:40663_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/J0/temp/solr.cloud.SystemCollectionCompatTest_73E1AC1116ADEDC8-001/tempDir-001/node1/.system_shard1_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_73E1AC1116ADEDC8-001/tempDir-001/node1/./.system_shard1_replica_n2/data/]
[junit4] 2> 772487 INFO (qtp1996147483-13639) [n:127.0.0.1:40663_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> 772487 INFO (qtp1996147483-13639) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 772489 INFO (qtp1996147483-13639) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@667f221a[.system_shard1_replica_n2] main]
[junit4] 2> 772490 INFO (qtp1996147483-13639) [n:127.0.0.1:40663_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> 772490 INFO (qtp1996147483-13639) [n:127.0.0.1:40663_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> 772490 INFO (qtp1996147483-13639) [n:127.0.0.1:40663_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> 772491 INFO (qtp1996147483-13639) [n:127.0.0.1:40663_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> 772491 INFO (qtp1996147483-13639) [n:127.0.0.1:40663_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> 772493 INFO (qtp1996147483-13639) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 772493 INFO (searcherExecutor-3713-thread-1-processing-n:127.0.0.1:40663_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:40663_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@667f221a[.system_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={java.vendor=AdoptOpenJDK, timestamp=1580536504865, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0, source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, os=Linux}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 772494 INFO (qtp1996147483-13639) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@56c23ac4[.system_shard1_replica_n2] main]
[junit4] 2> 772495 INFO (searcherExecutor-3713-thread-1-processing-n:127.0.0.1:40663_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:40663_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@56c23ac4[.system_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={os=Linux, timestamp=1580536504865, java.version=11.0.6, java.vm.version=11.0.6+10, lucene.version=9.0.0, source=flush, os.arch=amd64, java.runtime.version=11.0.6+10, os.version=5.3.0-26-generic, java.vendor=AdoptOpenJDK}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 772497 INFO (Thread-2753) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 772498 INFO (qtp1996147483-13639) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Waiting up to 599 secs for 1 replicas to apply schema update version 2 for collection .system
[junit4] 2> 772524 INFO (Thread-2753) [ ] o.a.s.s.IndexSchema [.system_shard1_replica_n2] Schema name=_system collection or core
[junit4] 2> 772527 INFO (Thread-2753) [ ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id
[junit4] 2> 772527 INFO (Thread-2753) [ ] o.a.s.c.CoreContainer Reloading SolrCore '.system_shard1_replica_n2' using configuration from configset .system
[junit4] 2> 772531 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 772531 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_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> 772531 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 772532 ERROR (managedSchemaExecutor-3717-thread-1-processing-n:127.0.0.1:40663_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:40663/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 772533 INFO (qtp1996147483-13632) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 772534 INFO (qtp1996147483-13632) [n:127.0.0.1:40663_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> 772534 INFO (qtp1996147483-13632) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 772534 ERROR (managedSchemaExecutor-3717-thread-1-processing-n:127.0.0.1:40663_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:40663/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 772538 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 772538 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_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> 772539 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 772539 ERROR (managedSchemaExecutor-3717-thread-1-processing-n:127.0.0.1:40663_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:40663/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 772539 INFO (qtp1996147483-13648) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 772540 INFO (qtp1996147483-13648) [n:127.0.0.1:40663_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> 772540 INFO (qtp1996147483-13648) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 772540 ERROR (managedSchemaExecutor-3717-thread-1-processing-n:127.0.0.1:40663_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:40663/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 772540 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 772541 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_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> 772541 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 772541 ERROR (managedSchemaExecutor-3717-thread-1-processing-n:127.0.0.1:40663_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:40663/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 772541 INFO (qtp1996147483-13632) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 772541 INFO (qtp1996147483-13632) [n:127.0.0.1:40663_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> 772541 INFO (qtp1996147483-13632) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 772542 ERROR (managedSchemaExecutor-3717-thread-1-processing-n:127.0.0.1:40663_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:40663/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 772543 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 772544 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_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> 772544 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 772544 ERROR (managedSchemaExecutor-3717-thread-1-processing-n:127.0.0.1:40663_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:40663/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 772544 INFO (qtp1996147483-13648) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 772545 INFO (qtp1996147483-13648) [n:127.0.0.1:40663_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> 772545 INFO (qtp1996147483-13648) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 772545 ERROR (managedSchemaExecutor-3717-thread-1-processing-n:127.0.0.1:40663_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:40663/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 772545 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 772545 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_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> 772545 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 772546 ERROR (managedSchemaExecutor-3717-thread-1-processing-n:127.0.0.1:40663_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:40663/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 772550 INFO (qtp1996147483-13632) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 772550 INFO (qtp1996147483-13632) [n:127.0.0.1:40663_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> 772550 INFO (qtp1996147483-13632) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 772551 ERROR (managedSchemaExecutor-3717-thread-1-processing-n:127.0.0.1:40663_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:40663/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 772551 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 772552 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_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> 772552 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 772552 INFO (Thread-2753) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40663.solr.core..system.shard1.replica_n2' (registry 'solr.core..system.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@176378ab
[junit4] 2> 772552 ERROR (managedSchemaExecutor-3717-thread-1-processing-n:127.0.0.1:40663_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:40663/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 772552 INFO (Thread-2753) [n:127.0.0.1:40663_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/J0/temp/solr.cloud.SystemCollectionCompatTest_73E1AC1116ADEDC8-001/tempDir-001/node1/.system_shard1_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_73E1AC1116ADEDC8-001/tempDir-001/node1/./.system_shard1_replica_n2/data/]
[junit4] 2> 772552 INFO (qtp1996147483-13648) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 772552 INFO (qtp1996147483-13648) [n:127.0.0.1:40663_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> 772552 INFO (qtp1996147483-13648) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 772552 ERROR (managedSchemaExecutor-3717-thread-1-processing-n:127.0.0.1:40663_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:40663/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 772553 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 772553 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_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> 772553 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 772553 ERROR (managedSchemaExecutor-3717-thread-1-processing-n:127.0.0.1:40663_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:40663/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 772554 INFO (qtp1996147483-13632) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 772554 INFO (qtp1996147483-13632) [n:127.0.0.1:40663_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> 772554 INFO (qtp1996147483-13632) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 772554 ERROR (managedSchemaExecutor-3717-thread-1-processing-n:127.0.0.1:40663_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:40663/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 772554 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 772555 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_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> 772555 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 772555 ERROR (managedSchemaExecutor-3717-thread-1-processing-n:127.0.0.1:40663_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:40663/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 772555 INFO (qtp1996147483-13648) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 772555 INFO (qtp1996147483-13648) [n:127.0.0.1:40663_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> 772555 INFO (qtp1996147483-13648) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 772555 ERROR (managedSchemaExecutor-3717-thread-1-processing-n:127.0.0.1:40663_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:40663/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 772556 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 772556 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_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> 772556 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 772556 ERROR (managedSchemaExecutor-3717-thread-1-processing-n:127.0.0.1:40663_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:40663/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 772556 INFO (qtp1996147483-13632) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 772557 INFO (qtp1996147483-13632) [n:127.0.0.1:40663_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> 772557 INFO (qtp1996147483-13632) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 772557 ERROR (managedSchemaExecutor-3717-thread-1-processing-n:127.0.0.1:40663_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:40663/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 772557 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 772557 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_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> 772557 INFO (qtp1996147483-13640) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 772558 ERROR (managedSchemaExecutor-3717-thread-1-processing-n:127.0.0.1:40663_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:40663/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 772558 INFO (qtp1996147483-13648) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 772558 INFO (qtp1996147483-13648) [n:127.0.0.1:40663_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> 772558 INFO (qtp1996147483-13648) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 772558 ERROR (managedSchemaExecutor-3717-thread-1-processing-n:127.0.0.1:40663_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:40663/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 772559 INFO (qtp1996147483-13630) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfB
[...truncated too long message...]
https://127.0.0.1:40663/solr/.system_shard1_replica_n1/ due to: java.util.concurrent.ExecutionException: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: https://127.0.0.1:40663/solr/.system_shard1_replica_n1
[junit4] 2> 908218 WARN (managedSchemaExecutor-3717-thread-1-processing-n:127.0.0.1:40663_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Failed to get /schema/zkversion from https://127.0.0.1:40663/solr/.system_shard1_replica_n1/ due to: java.util.concurrent.ExecutionException: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: https://127.0.0.1:40663/solr/.system_shard1_replica_n1
[junit4] 2> 908219 WARN (managedSchemaExecutor-3717-thread-1-processing-n:127.0.0.1:40663_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Failed to get /schema/zkversion from https://127.0.0.1:40663/solr/.system_shard1_replica_n1/ due to: java.util.concurrent.ExecutionException: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: https://127.0.0.1:40663/solr/.system_shard1_replica_n1
[junit4] 2> 908222 WARN (qtp1996147483-13639) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Core core_node4 was interrupted waiting for schema version 2 to propagate to 1 replicas for collection .system
[junit4] 2> 908222 INFO (qtp1996147483-13639) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Took 135728.0ms for 1 replicas to apply schema update version 2 for collection .system
[junit4] 2> 908222 INFO (qtp1996147483-13639) [n:127.0.0.1:40663_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=135808
[junit4] 2> 908222 INFO (qtp1996147483-13639) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.SolrCore [.system_shard1_replica_n2] CLOSING SolrCore org.apache.solr.core.SolrCore@519740d0
[junit4] 2> 908222 WARN (qtp1996147483-13639) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.m.SolrMetricManager Interrupted while trying to obtain lock to modify reporters registry: solr.core..system.shard1.replica_n2
[junit4] 2> 908222 INFO (qtp1996147483-13639) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection..system.shard1.leader, tag=SolrCore@519740d0
[junit4] 2> 908222 INFO (qtp1996147483-13639) [n:127.0.0.1:40663_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() .
[junit4] 2> 908222 INFO (qtp1996147483-13639) [n:127.0.0.1:40663_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@32cc00 commitCommandVersion:0
[junit4] 2> 908224 INFO (SUITE-SystemCollectionCompatTest-seed#[73E1AC1116ADEDC8]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 908426 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> 5 /solr/collections/.system/terms/shard1
[junit4] 2> 4 /solr/configs/.system/managed-schema
[junit4] 2> 2 /solr/configs/.system
[junit4] 2> 2 /solr/packages.json
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/collections/.system/collectionprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 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/overseer/collection-queue-work
[junit4] 2> 11 /solr/live_nodes
[junit4] 2>
[junit4] 2> 908427 INFO (SUITE-SystemCollectionCompatTest-seed#[73E1AC1116ADEDC8]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:41619
[junit4] 2> 908427 INFO (SUITE-SystemCollectionCompatTest-seed#[73E1AC1116ADEDC8]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:41619
[junit4] 2> 908427 INFO (SUITE-SystemCollectionCompatTest-seed#[73E1AC1116ADEDC8]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 41619
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_73E1AC1116ADEDC8-001
[junit4] 2> Feb 01, 2020 5:57:20 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=PostingsFormat(name=LuceneVarGapDocFreqInterval), type=Lucene84}, docValues:{timestamp_l=DocValuesFormat(name=Asserting), _version_=DocValuesFormat(name=Lucene80), time_l=DocValuesFormat(name=Lucene80), timestamp=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=1375, maxMBSortInHeap=6.563298330441571, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@6fd9671c), locale=bg-BG, timezone=Pacific/Guam
[junit4] 2> NOTE: Linux 5.3.0-26-generic amd64/AdoptOpenJDK 11.0.6 (64-bit)/cpus=16,threads=1,free=144192960,total=536346624
[junit4] 2> NOTE: All tests run in this JVM: [EchoParamsTest, ActionThrottleTest, TestSha256AuthenticationProvider, TriggerEventQueueTest, TestShortCircuitedRequests, ForceLeaderTest, DistribCursorPagingTest, TestFaceting, TestRandomFlRTGCloud, LeaderTragicEventTest, TestExportTool, TestHalfAndHalfDocValues, ResolveAnalyzerByNameTest, AutoScalingHandlerTest, TestRestManager, LukeRequestHandlerTest, ZkNodePropsTest, CollectionTooManyReplicasTest, TestAuthenticationFramework, DateFieldTest, TestSimDistributedQueue, NodeLostTriggerIntegrationTest, TestJsonFacetRefinement, TestRangeQuery, TestDFRSimilarityFactory, TestInitParams, TestCloudPivotFacet, HdfsDirectoryTest, TestBackupRepositoryFactory, TestCloudNestedDocsSort, HLLUtilTest, TestCustomDocTransformer, BasicAuthOnSingleNodeTest, TestGroupingSearch, TemplateUpdateProcessorTest, SpellingQueryConverterTest, TestQuerySenderNoQuery, MissingSegmentRecoveryTest, TestCrossCoreJoin, TestDistributedMap, URLClassifyProcessorTest, ZkCollectionPropsCachingTest, HdfsChaosMonkeySafeLeaderTest, TestHdfsCloudBackupRestore, TestReloadDeadlock, TestConfigSetProperties, OpenExchangeRatesOrgProviderTest, TestSchemaVersionResource, SegmentsInfoRequestHandlerTest, SolrGraphiteReporterTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, TestHashPartitioner, TestMinMaxOnMultiValuedField, OrderedExecutorTest, TestSolrCoreProperties, TestPayloadScoreQParserPlugin, CdcrVersionReplicationTest, SSLMigrationTest, TestLuceneIndexBackCompat, BasicZkTest, TestRawResponseWriter, TestSolr4Spatial2, TestReplicationHandlerDiskOverFlow, BigEndianAscendingWordDeserializerTest, DeleteInactiveReplicaTest, ResponseLogComponentTest, TestUseDocValuesAsStored2, TestJsonRequest, SolrRrdBackendFactoryTest, TestSolrCLIRunExample, IndexSizeTriggerSizeEstimationTest, PackageManagerCLITest, TestExactSharedStatsCache, TestHttpShardHandlerFactory, TestCharFilters, TestDeprecatedFilters, TestLuceneMatchVersion, TestReversedWildcardFilterFactory, TestWordDelimiterFilterFactory, TokenizerChainTest, CleanupOldIndexTest, ClusterStateTest, ClusterStateUpdateTest, CollectionStateFormat2Test, ConcurrentCreateRoutedAliasTest, ConfigSetsAPITest, LeaderElectionContextKeyTest, LeaderElectionIntegrationTest, LeaderElectionTest, LeaderFailureAfterFreshStartTest, ReplaceNodeNoTargetTest, ReplaceNodeTest, RoutingToNodesWithPropertiesTest, SolrCloudExampleTest, SystemCollectionCompatTest]
[junit4] Completed [523/900 (1!)] on J0 in 139.94s, 1 test, 1 error <<< FAILURES!
[...truncated 53820 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/25553/consoleText
[repro] Revision: a2c53dad726435e1d1bcbfd08be5e0a60187ce46
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseParallelGC"
[repro] Repro line: ant test -Dtestcase=SystemCollectionCompatTest -Dtests.method=testBackCompat -Dtests.seed=73E1AC1116ADEDC8 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=bg-BG -Dtests.timezone=Pacific/Guam -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:+UseParallelGC" -Dtests.seed=73E1AC1116ADEDC8 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=bg-BG -Dtests.timezone=Pacific/Guam -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...]