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/26 23:47:34 UTC
[JENKINS-Experimental-GC] Lucene-Solr-master-Linux
(64bit/jdk-15-ea+9) - Build # 25854 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/25854/
Java: 64bit/jdk-15-ea+9 -XX:-UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseZGC
1 tests failed.
FAILED: org.apache.solr.cloud.SystemCollectionCompatTest.testBackCompat
Error Message:
No live SolrServers available to handle this request:[http://127.0.0.1:33761/solr/.system]
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:33761/solr/.system]
at __randomizedtesting.SeedInfo.seed([E5BF9168EDD154D2:954A32C18D19FDA4]:0)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:345)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1143)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:906)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:838)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207)
at org.apache.solr.cloud.SystemCollectionCompatTest.setupSystemCollection(SystemCollectionCompatTest.java:118)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:976)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
at java.base/java.lang.Thread.run(Thread.java:832)
Caused by: org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: http://127.0.0.1:33761/solr/.system
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:676)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:265)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:368)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:296)
... 43 more
Caused by: java.net.SocketTimeoutException: Read timed out
at java.base/sun.nio.ch.NioSocketImpl.timedRead(NioSocketImpl.java:283)
at java.base/sun.nio.ch.NioSocketImpl.implRead(NioSocketImpl.java:309)
at java.base/sun.nio.ch.NioSocketImpl.read(NioSocketImpl.java:350)
at java.base/sun.nio.ch.NioSocketImpl$1.read(NioSocketImpl.java:803)
at java.base/java.net.Socket$SocketInputStream.read(Socket.java:982)
at 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 13339 lines...]
[junit4] Suite: org.apache.solr.cloud.SystemCollectionCompatTest
[junit4] 2> 76401 INFO (SUITE-SystemCollectionCompatTest-seed#[E5BF9168EDD154D2]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/server/solr/configsets/_default/conf'
[junit4] 2> 76401 INFO (SUITE-SystemCollectionCompatTest-seed#[E5BF9168EDD154D2]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 76401 INFO (SUITE-SystemCollectionCompatTest-seed#[E5BF9168EDD154D2]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_E5BF9168EDD154D2-001/data-dir-16-001
[junit4] 2> 76404 INFO (SUITE-SystemCollectionCompatTest-seed#[E5BF9168EDD154D2]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 76404 INFO (SUITE-SystemCollectionCompatTest-seed#[E5BF9168EDD154D2]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 76405 INFO (SUITE-SystemCollectionCompatTest-seed#[E5BF9168EDD154D2]-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_E5BF9168EDD154D2-001/tempDir-001
[junit4] 2> 76405 INFO (SUITE-SystemCollectionCompatTest-seed#[E5BF9168EDD154D2]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 76405 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 76405 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 76505 INFO (SUITE-SystemCollectionCompatTest-seed#[E5BF9168EDD154D2]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:45945
[junit4] 2> 76505 INFO (SUITE-SystemCollectionCompatTest-seed#[E5BF9168EDD154D2]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:45945
[junit4] 2> 76505 INFO (SUITE-SystemCollectionCompatTest-seed#[E5BF9168EDD154D2]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:45945
[junit4] 2> 76505 INFO (SUITE-SystemCollectionCompatTest-seed#[E5BF9168EDD154D2]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 45945
[junit4] 2> 76506 INFO (SUITE-SystemCollectionCompatTest-seed#[E5BF9168EDD154D2]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 76508 INFO (zkConnectionManagerCallback-373-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 76508 INFO (SUITE-SystemCollectionCompatTest-seed#[E5BF9168EDD154D2]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 76509 INFO (SUITE-SystemCollectionCompatTest-seed#[E5BF9168EDD154D2]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 76510 INFO (zkConnectionManagerCallback-375-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 76510 INFO (SUITE-SystemCollectionCompatTest-seed#[E5BF9168EDD154D2]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 76510 INFO (SUITE-SystemCollectionCompatTest-seed#[E5BF9168EDD154D2]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 76511 INFO (zkConnectionManagerCallback-377-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 76511 INFO (SUITE-SystemCollectionCompatTest-seed#[E5BF9168EDD154D2]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 76614 INFO (jetty-launcher-378-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 76614 INFO (jetty-launcher-378-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 76614 INFO (jetty-launcher-378-thread-1) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 15-ea+9-264
[junit4] 2> 76615 INFO (jetty-launcher-378-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 76615 INFO (jetty-launcher-378-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 76615 INFO (jetty-launcher-378-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 76615 INFO (jetty-launcher-378-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 76615 INFO (jetty-launcher-378-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 76615 INFO (jetty-launcher-378-thread-2) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 15-ea+9-264
[junit4] 2> 76615 INFO (jetty-launcher-378-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5598db76{/solr,null,AVAILABLE}
[junit4] 2> 76617 INFO (jetty-launcher-378-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6884092f{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:39387}
[junit4] 2> 76617 INFO (jetty-launcher-378-thread-1) [ ] o.e.j.s.Server Started @76890ms
[junit4] 2> 76617 INFO (jetty-launcher-378-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=39387}
[junit4] 2> 76617 ERROR (jetty-launcher-378-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 76617 INFO (jetty-launcher-378-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 76617 INFO (jetty-launcher-378-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 76617 INFO (jetty-launcher-378-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 76617 INFO (jetty-launcher-378-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr
[junit4] 2> 76617 INFO (jetty-launcher-378-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-02-26T23:17:12.890888Z
[junit4] 2> 76619 INFO (jetty-launcher-378-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 76619 INFO (jetty-launcher-378-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 76619 INFO (jetty-launcher-378-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 76619 INFO (jetty-launcher-378-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 76620 INFO (jetty-launcher-378-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4fb8882f{/solr,null,AVAILABLE}
[junit4] 2> 76620 INFO (zkConnectionManagerCallback-380-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 76620 INFO (jetty-launcher-378-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 76635 INFO (jetty-launcher-378-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@290e0d78{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:33761}
[junit4] 2> 76635 INFO (jetty-launcher-378-thread-2) [ ] o.e.j.s.Server Started @76908ms
[junit4] 2> 76635 INFO (jetty-launcher-378-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33761}
[junit4] 2> 76635 INFO (jetty-launcher-378-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 76636 ERROR (jetty-launcher-378-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 76636 INFO (jetty-launcher-378-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 76636 INFO (jetty-launcher-378-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 76636 INFO (jetty-launcher-378-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 76636 INFO (jetty-launcher-378-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr
[junit4] 2> 76636 INFO (jetty-launcher-378-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-02-26T23:17:12.909850Z
[junit4] 2> 76637 INFO (jetty-launcher-378-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 76644 INFO (zkConnectionManagerCallback-382-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 76644 INFO (jetty-launcher-378-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 76644 INFO (jetty-launcher-378-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 76745 INFO (jetty-launcher-378-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 76747 WARN (jetty-launcher-378-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7456863a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 76747 WARN (jetty-launcher-378-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7456863a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 76750 WARN (jetty-launcher-378-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@19940d87[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 76750 WARN (jetty-launcher-378-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@19940d87[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 76751 INFO (jetty-launcher-378-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45945/solr
[junit4] 2> 76752 INFO (jetty-launcher-378-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 76752 INFO (zkConnectionManagerCallback-389-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 76752 INFO (jetty-launcher-378-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 76846 INFO (jetty-launcher-378-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 76847 WARN (jetty-launcher-378-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@398aae8b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 76847 WARN (jetty-launcher-378-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@398aae8b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 76849 WARN (jetty-launcher-378-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@545e1165[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 76849 WARN (jetty-launcher-378-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@545e1165[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 76850 INFO (jetty-launcher-378-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45945/solr
[junit4] 2> 76850 INFO (jetty-launcher-378-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 76852 INFO (zkConnectionManagerCallback-396-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 76852 INFO (jetty-launcher-378-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 76945 INFO (jetty-launcher-378-thread-1) [n:127.0.0.1:39387_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 76946 INFO (zkConnectionManagerCallback-398-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 76946 INFO (jetty-launcher-378-thread-1) [n:127.0.0.1:39387_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 76980 INFO (jetty-launcher-378-thread-1) [n:127.0.0.1:39387_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:39387_solr
[junit4] 2> 76980 INFO (jetty-launcher-378-thread-1) [n:127.0.0.1:39387_solr ] o.a.s.c.Overseer Overseer (id=72082526621859847-127.0.0.1:39387_solr-n_0000000000) starting
[junit4] 2> 76982 INFO (jetty-launcher-378-thread-1) [n:127.0.0.1:39387_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39387_solr
[junit4] 2> 76982 INFO (OverseerStateUpdate-72082526621859847-127.0.0.1:39387_solr-n_0000000000) [n:127.0.0.1:39387_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:39387_solr
[junit4] 2> 76983 INFO (OverseerStateUpdate-72082526621859847-127.0.0.1:39387_solr-n_0000000000) [n:127.0.0.1:39387_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 76984 INFO (jetty-launcher-378-thread-1) [n:127.0.0.1:39387_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 76984 WARN (jetty-launcher-378-thread-1) [n:127.0.0.1:39387_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> 76991 INFO (jetty-launcher-378-thread-1) [n:127.0.0.1:39387_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 77003 INFO (jetty-launcher-378-thread-1) [n:127.0.0.1:39387_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39387.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2bcab1c6
[junit4] 2> 77007 INFO (jetty-launcher-378-thread-1) [n:127.0.0.1:39387_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39387.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2bcab1c6
[junit4] 2> 77007 INFO (jetty-launcher-378-thread-1) [n:127.0.0.1:39387_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39387.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2bcab1c6
[junit4] 2> 77008 INFO (jetty-launcher-378-thread-1) [n:127.0.0.1:39387_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_E5BF9168EDD154D2-001/tempDir-001/node1/.
[junit4] 2> 77048 INFO (jetty-launcher-378-thread-2) [n:127.0.0.1:33761_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 77050 INFO (zkConnectionManagerCallback-404-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 77050 INFO (jetty-launcher-378-thread-2) [n:127.0.0.1:33761_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 77054 INFO (jetty-launcher-378-thread-2) [n:127.0.0.1:33761_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 77059 INFO (jetty-launcher-378-thread-2) [n:127.0.0.1:33761_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:33761_solr as DOWN
[junit4] 2> 77061 INFO (jetty-launcher-378-thread-2) [n:127.0.0.1:33761_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 77061 INFO (jetty-launcher-378-thread-2) [n:127.0.0.1:33761_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33761_solr
[junit4] 2> 77063 INFO (zkCallback-397-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 77063 INFO (zkCallback-403-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 77064 INFO (jetty-launcher-378-thread-2) [n:127.0.0.1:33761_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 77064 WARN (jetty-launcher-378-thread-2) [n:127.0.0.1:33761_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> 77070 INFO (jetty-launcher-378-thread-2) [n:127.0.0.1:33761_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 77079 INFO (jetty-launcher-378-thread-2) [n:127.0.0.1:33761_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33761.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2bcab1c6
[junit4] 2> 77083 INFO (jetty-launcher-378-thread-2) [n:127.0.0.1:33761_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33761.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2bcab1c6
[junit4] 2> 77083 INFO (jetty-launcher-378-thread-2) [n:127.0.0.1:33761_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33761.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2bcab1c6
[junit4] 2> 77084 INFO (jetty-launcher-378-thread-2) [n:127.0.0.1:33761_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_E5BF9168EDD154D2-001/tempDir-001/node2/.
[junit4] 2> 77096 INFO (SUITE-SystemCollectionCompatTest-seed#[E5BF9168EDD154D2]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 77096 INFO (SUITE-SystemCollectionCompatTest-seed#[E5BF9168EDD154D2]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 77097 INFO (zkConnectionManagerCallback-413-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 77097 INFO (SUITE-SystemCollectionCompatTest-seed#[E5BF9168EDD154D2]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 77098 INFO (SUITE-SystemCollectionCompatTest-seed#[E5BF9168EDD154D2]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 77098 INFO (SUITE-SystemCollectionCompatTest-seed#[E5BF9168EDD154D2]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:45945/solr ready
[junit4] 2> 77113 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[E5BF9168EDD154D2]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBackCompat
[junit4] 2> 77117 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[E5BF9168EDD154D2]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 77118 INFO (zkConnectionManagerCallback-418-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 77118 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[E5BF9168EDD154D2]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 77119 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[E5BF9168EDD154D2]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 77120 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[E5BF9168EDD154D2]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:45945/solr ready
[junit4] 2> 77125 INFO (qtp667543537-1457) [n:127.0.0.1:33761_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 77133 INFO (qtp667543537-1457) [n:127.0.0.1:33761_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=8
[junit4] 2> 77137 INFO (qtp667543537-1457) [n:127.0.0.1:33761_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:33761_solr&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 77147 INFO (OverseerThreadFactory-344-thread-2-processing-n:127.0.0.1:39387_solr) [n:127.0.0.1:39387_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection .system
[junit4] 2> 77147 INFO (OverseerCollectionConfigSetProcessor-72082526621859847-127.0.0.1:39387_solr-n_0000000000) [n:127.0.0.1:39387_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> 77254 WARN (OverseerThreadFactory-344-thread-2-processing-n:127.0.0.1:39387_solr) [n:127.0.0.1:39387_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> 77257 INFO (OverseerStateUpdate-72082526621859847-127.0.0.1:39387_solr-n_0000000000) [n:127.0.0.1:39387_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":".system",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:33761/solr",
[junit4] 2> "node_name":"127.0.0.1:33761_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 77269 INFO (OverseerStateUpdate-72082526621859847-127.0.0.1:39387_solr-n_0000000000) [n:127.0.0.1:39387_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_n3",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:33761/solr",
[junit4] 2> "node_name":"127.0.0.1:33761_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 77473 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr x:.system_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&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> 77473 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr x:.system_shard1_replica_n3 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n3&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 77482 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 77482 WARN (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] 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> 77482 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 77491 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] 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> 77491 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 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> 77493 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.s.IndexSchema [.system_shard1_replica_n3] Schema name=_system collection or core
[junit4] 2> 77493 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.IndexSchema [.system_shard1_replica_n1] Schema name=_system collection or core
[junit4] 2> 77498 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id
[junit4] 2> 77498 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id
[junit4] 2> 77500 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Created and persisted managed schema znode at /configs/.system/managed-schema
[junit4] 2> 77500 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.s.ManagedIndexSchema Managed schema znode at /configs/.system/managed-schema already exists - no need to create it
[junit4] 2> 77500 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 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> 77501 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33761.solr.core..system.shard1.replica_n1' (registry 'solr.core..system.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2bcab1c6
[junit4] 2> 77501 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 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_E5BF9168EDD154D2-001/tempDir-001/node2/.system_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_E5BF9168EDD154D2-001/tempDir-001/node2/./.system_shard1_replica_n1/data/]
[junit4] 2> 77502 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] 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> 77502 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.c.CoreContainer Creating SolrCore '.system_shard1_replica_n3' using configuration from configset .system, trusted=true
[junit4] 2> 77502 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33761.solr.core..system.shard1.replica_n3' (registry 'solr.core..system.shard1.replica_n3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2bcab1c6
[junit4] 2> 77502 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.c.SolrCore [[.system_shard1_replica_n3] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_E5BF9168EDD154D2-001/tempDir-001/node2/.system_shard1_replica_n3], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_E5BF9168EDD154D2-001/tempDir-001/node2/./.system_shard1_replica_n3/data/]
[junit4] 2> 77550 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 77550 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 77551 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 77551 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 77551 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: if 1 uncommitted docs;
[junit4] 2> 77551 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 77552 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.u.CommitTracker Hard AutoCommit: if 1 uncommitted docs;
[junit4] 2> 77552 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 77553 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7e72698e[.system_shard1_replica_n1] main]
[junit4] 2> 77553 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@634065c9[.system_shard1_replica_n3] main]
[junit4] 2> 77554 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/.system
[junit4] 2> 77554 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/.system
[junit4] 2> 77554 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/.system
[junit4] 2> 77554 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/.system
[junit4] 2> 77554 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/.system/managed-schema
[junit4] 2> 77554 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/.system/managed-schema
[junit4] 2> 77554 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 77554 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 0 from ZooKeeper
[junit4] 2> 77555 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 77555 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1659643136655228928
[junit4] 2> 77557 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.s.IndexSchema [.system_shard1_replica_n3] Schema name=_system collection or core
[junit4] 2> 77557 INFO (searcherExecutor-353-thread-1-processing-n:127.0.0.1:33761_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [.system_shard1_replica_n1] Registered new searcher Searcher@7e72698e[.system_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 77559 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/.system/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 77559 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/.system/leaders/shard1
[junit4] 2> 77561 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id
[junit4] 2> 77561 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 6 ms
[junit4] 2> 77562 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 77562 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1659643136662568960
[junit4] 2> 77563 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=2 found=1 timeoutin=9999ms
[junit4] 2> 77563 INFO (searcherExecutor-354-thread-1-processing-n:127.0.0.1:33761_solr x:.system_shard1_replica_n3 c:.system s:shard1 r:core_node4) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.c.SolrCore [.system_shard1_replica_n3] Registered new searcher Searcher@634065c9[.system_shard1_replica_n3] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 77564 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/.system/terms/shard1 to Terms{values={core_node2=0, core_node4=0}, version=1}
[junit4] 2> 77564 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/.system/leaders/shard1
[junit4] 2> 78064 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 78064 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 78064 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:33761/solr/.system_shard1_replica_n1/
[junit4] 2> 78065 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.PeerSync PeerSync: core=.system_shard1_replica_n1 url=http://127.0.0.1:33761/solr START replicas=[http://127.0.0.1:33761/solr/.system_shard1_replica_n3/] nUpdates=100
[junit4] 2> 78065 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.PeerSync PeerSync: core=.system_shard1_replica_n1 url=http://127.0.0.1:33761/solr DONE. We have no versions. sync failed.
[junit4] 2> 78069 INFO (qtp667543537-1474) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.c.S.Request [.system_shard1_replica_n3] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 78070 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 78070 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 78070 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/.system/leaders/shard1/leader after winning as /collections/.system/leader_elect/shard1/election/72082526621859848-core_node2-n_0000000000
[junit4] 2> 78071 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:33761/solr/.system_shard1_replica_n1/ shard1
[junit4] 2> 78173 INFO (zkCallback-403-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> 78174 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 78178 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&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=704
[junit4] 2> 78276 INFO (zkCallback-403-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> 78276 INFO (zkCallback-403-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> 78568 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] 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_n3&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1095
[junit4] 2> 78570 INFO (qtp667543537-1457) [n:127.0.0.1:33761_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> 78677 INFO (zkCallback-403-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> 78677 INFO (zkCallback-403-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> 78678 INFO (qtp667543537-1457) [n:127.0.0.1:33761_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:33761_solr&wt=javabin&version=2} status=0 QTime=1540
[junit4] 2> 78678 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[E5BF9168EDD154D2]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: .system
[junit4] 2> 78696 INFO (qtp667543537-1474) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/.system/terms/shard1 to Terms{values={core_node2=1, core_node4=1}, version=2}
[junit4] 2> 78716 INFO (qtp667543537-1457) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n3] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:33761/solr/.system_shard1_replica_n1/&wt=javabin&version=2}{add=[17083ca404dTbxmg1uwrepb74tf4zf2kp3tta (1659643137843265536)]} 0 1
[junit4] 2> 78718 INFO (qtp667543537-1474) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[17083ca404dTbxmg1uwrepb74tf4zf2kp3tta (1659643137843265536)]} 0 31
[junit4] 2> 78720 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1659643137876819968,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 78720 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@424bf7d1 commitCommandVersion:1659643137876819968
[junit4] 2> 78722 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1659643137878917120,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 78722 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2303d5f commitCommandVersion:1659643137878917120
[junit4] 2> 78731 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4ebdc6c5[.system_shard1_replica_n3] main]
[junit4] 2> 78731 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 78733 INFO (searcherExecutor-354-thread-1-processing-n:127.0.0.1:33761_solr x:.system_shard1_replica_n3 c:.system s:shard1 r:core_node4) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.c.SolrCore [.system_shard1_replica_n3] Registered new searcher Searcher@4ebdc6c5[.system_shard1_replica_n3] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={timestamp=1582759035001, java.vendor=Oracle Corporation, os=Linux, os.version=5.3.0-40-generic, java.runtime.version=15-ea+9-264, os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=15-ea+9-264, java.version=15-ea}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 78733 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@410a1baf[.system_shard1_replica_n1] main]
[junit4] 2> 78733 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 78733 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n3] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:33761/solr/.system_shard1_replica_n1/&commit_end_point=replicas&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 11
[junit4] 2> 78734 INFO (searcherExecutor-353-thread-1-processing-n:127.0.0.1:33761_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [.system_shard1_replica_n1] Registered new searcher Searcher@410a1baf[.system_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={timestamp=1582759035005, java.vendor=Oracle Corporation, os=Linux, os.version=5.3.0-40-generic, java.runtime.version=15-ea+9-264, os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=15-ea+9-264, java.version=15-ea}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 78735 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=.system:5&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 15
[junit4] 2> 78740 INFO (qtp667543537-1457) [n:127.0.0.1:33761_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={action=STATUS&indexInfo=true&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 78744 INFO (qtp667543537-1474) [n:127.0.0.1:33761_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={action=STATUS&indexInfo=true&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 78748 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 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> 78752 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.SchemaManager managed schema loaded . version : 1
[junit4] 2> 78754 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.IndexSchema [.system_shard1_replica_n1] Schema name=_system collection or core
[junit4] 2> 78760 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id
[junit4] 2> 78761 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ZkController Persisted config data to node /configs/.system/schema.xml.bak
[junit4] 2> 78764 INFO (Thread-251) [ ] o.a.s.c.SolrCore config update listener called for core .system_shard1_replica_n3
[junit4] 2> 78765 INFO (Thread-251) [ ] o.a.s.c.SolrCore config update listener called for core .system_shard1_replica_n1
[junit4] 2> 78765 INFO (Thread-251) [ ] o.a.s.c.SolrCore core reload .system_shard1_replica_n1
[junit4] 2> 78769 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 78778 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.IndexSchema [.system_shard1_replica_n1] Schema name=_system collection or core
[junit4] 2> 78781 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id
[junit4] 2> 78781 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.CoreContainer Reloading SolrCore '.system_shard1_replica_n1' using configuration from configset .system
[junit4] 2> 78814 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33761.solr.core..system.shard1.replica_n1' (registry 'solr.core..system.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2bcab1c6
[junit4] 2> 78814 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 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_E5BF9168EDD154D2-001/tempDir-001/node2/.system_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_E5BF9168EDD154D2-001/tempDir-001/node2/./.system_shard1_replica_n1/data/]
[junit4] 2> 78875 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: if 1 uncommitted docs;
[junit4] 2> 78875 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 78876 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6cd51813[.system_shard1_replica_n1] main]
[junit4] 2> 78877 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/.system
[junit4] 2> 78877 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/.system
[junit4] 2> 78877 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/.system/managed-schema
[junit4] 2> 78881 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 78881 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 78893 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 78893 INFO (searcherExecutor-363-thread-1-processing-n:127.0.0.1:33761_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [.system_shard1_replica_n1] Registered new searcher Searcher@6cd51813[.system_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={timestamp=1582759035005, java.vendor=Oracle Corporation, os=Linux, os.version=5.3.0-40-generic, java.runtime.version=15-ea+9-264, os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=15-ea+9-264, java.version=15-ea}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 78895 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@486da566[.system_shard1_replica_n1] main]
[junit4] 2> 78896 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Waiting up to 599 secs for 1 replicas to apply schema update version 2 for collection .system
[junit4] 2> 78897 INFO (searcherExecutor-363-thread-1-processing-n:127.0.0.1:33761_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [.system_shard1_replica_n1] Registered new searcher Searcher@486da566[.system_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={timestamp=1582759035005, os=Linux, java.vendor=Oracle Corporation, os.version=5.3.0-40-generic, java.runtime.version=15-ea+9-264, os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=15-ea+9-264, java.version=15-ea}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 78902 INFO (Thread-251) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 78924 INFO (Thread-251) [ ] o.a.s.s.IndexSchema [.system_shard1_replica_n1] Schema name=_system collection or core
[junit4] 2> 78935 INFO (Thread-251) [ ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id
[junit4] 2> 78935 INFO (Thread-251) [ ] o.a.s.c.CoreContainer Reloading SolrCore '.system_shard1_replica_n1' using configuration from configset .system
[junit4] 2> 78947 INFO (qtp667543537-1474) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 78948 INFO (qtp667543537-1474) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 78948 INFO (qtp667543537-1474) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.c.S.Request [.system_shard1_replica_n3] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 78951 ERROR (managedSchemaExecutor-367-thread-1-processing-n:127.0.0.1:33761_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:33761/solr/.system_shard1_replica_n3/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 78962 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 78962 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 78962 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.c.S.Request [.system_shard1_replica_n3] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 78963 ERROR (managedSchemaExecutor-367-thread-1-processing-n:127.0.0.1:33761_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:33761/solr/.system_shard1_replica_n3/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 78975 INFO (qtp667543537-1457) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 78975 INFO (qtp667543537-1457) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 78975 INFO (qtp667543537-1457) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.c.S.Request [.system_shard1_replica_n3] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 78976 ERROR (managedSchemaExecutor-367-thread-1-processing-n:127.0.0.1:33761_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:33761/solr/.system_shard1_replica_n3/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 78984 INFO (qtp667543537-1474) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 78985 INFO (qtp667543537-1474) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 78985 INFO (qtp667543537-1474) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.c.S.Request [.system_shard1_replica_n3] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 78993 ERROR (managedSchemaExecutor-367-thread-1-processing-n:127.0.0.1:33761_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:33761/solr/.system_shard1_replica_n3/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 79003 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 79003 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 79003 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.c.S.Request [.system_shard1_replica_n3] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 79004 ERROR (managedSchemaExecutor-367-thread-1-processing-n:127.0.0.1:33761_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:33761/solr/.system_shard1_replica_n3/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 79006 INFO (Thread-251) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33761.solr.core..system.shard1.replica_n1' (registry 'solr.core..system.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2bcab1c6
[junit4] 2> 79006 INFO (Thread-251) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 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_E5BF9168EDD154D2-001/tempDir-001/node2/.system_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_E5BF9168EDD154D2-001/tempDir-001/node2/./.system_shard1_replica_n1/data/]
[junit4] 2> 79006 INFO (qtp667543537-1457) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 79006 INFO (qtp667543537-1457) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 79006 INFO (qtp667543537-1457) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.c.S.Request [.system_shard1_replica_n3] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 79007 ERROR (managedSchemaExecutor-367-thread-1-processing-n:127.0.0.1:33761_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:33761/solr/.system_shard1_replica_n3/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 79008 INFO (qtp667543537-1474) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 79008 INFO (qtp667543537-1474) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 79008 INFO (qtp667543537-1474) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.c.S.Request [.system_shard1_replica_n3] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 79009 ERROR (managedSchemaExecutor-367-thread-1-processing-n:127.0.0.1:33761_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:33761/solr/.system_shard1_replica_n3/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 79010 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 79013 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 79013 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.c.S.Request [.system_shard1_replica_n3] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 79032 ERROR (managedSchemaExecutor-367-thread-1-processing-n:127.0.0.1:33761_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:33761/solr/.system_shard1_replica_n3/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 79037 INFO (qtp667543537-1457) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 79037 INFO (qtp667543537-1457) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 79037 INFO (qtp667543537-1457) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.c.S.Request [.system_shard1_replica_n3] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 79038 ERROR (managedSchemaExecutor-367-thread-1-processing-n:127.0.0.1:33761_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:33761/solr/.system_shard1_replica_n3/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 79040 INFO (qtp667543537-1474) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 79040 INFO (qtp667543537-1474) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 79040 INFO (qtp667543537-1474) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.c.S.Request [.system_shard1_replica_n3] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 79041 ERROR (managedSchemaExecutor-367-thread-1-processing-n:127.0.0.1:33761_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:33761/solr/.system_shard1_replica_n3/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 79052 INFO (Thread-251) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: if 1 uncommitted docs;
[junit4] 2> 79052 INFO (Thread-251) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 79055 INFO (Thread-251) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@283609dc[.system_shard1_replica_n1] main]
[junit4] 2> 79058 INFO (Thread-251) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/.system
[junit4] 2> 79058 INFO (Thread-251) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/.system
[junit4] 2> 79059 INFO (Thread-251) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/.system/managed-schema
[junit4] 2> 79059 INFO (Thread-251) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 79059 INFO (Thread-251) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 79060 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 79064 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 79064 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.c.S.Request [.system_shard1_replica_n3] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 79067 INFO (Thread-251) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 79068 INFO (searcherExecutor-368-thread-1-processing-n:127.0.0.1:33761_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [.system_shard1_replica_n1] Registered new searcher Searcher@283609dc[.system_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={timestamp=1582759035005, os=Linux, java.vendor=Oracle Corporation, os.version=5.3.0-40-generic, java.runtime.version=15-ea+9-264, os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=15-ea+9-264, java.version=15-ea}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 79069 ERROR (managedSchemaExecutor-367-thread-1-processing-n:127.0.0.1:33761_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:33761/solr/.system_shard1_replica_n3/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 79069 INFO (Thread-251) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@47b38dde[.system_shard1_replica_n1] main]
[junit4] 2> 79069 INFO (Thread-251) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [.system_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@50410439
[junit4] 2> 79069 INFO (Thread-251) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core..system.shard1.replica_n1, tag=SolrCore@50410439
[junit4] 2> 79069 INFO (Thread-251) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@28a3a433: rootName = solr_33761, domain = solr.core..system.shard1.replica_n1, service url = null, agent id = null] for registry solr.core..system.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@4fa7f0ed
[junit4] 2> 79072 INFO (searcherExecutor-368-thread-1-processing-n:127.0.0.1:33761_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [.system_shard1_replica_n1] Registered new searcher Searcher@47b38dde[.system_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={timestamp=1582759035005, os=Linux, java.vendor=Oracle Corporation, os.version=5.3.0-40-generic, java.runtime.version=15-ea+9-264, os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=15-ea+9-264, java.version=15-ea}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 79077 INFO (qtp667543537-1457) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 79077 INFO (qtp667543537-1457) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 79077 INFO (qtp667543537-1457) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.c.S.Request [.system_shard1_replica_n3] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 79078 ERROR (managedSchemaExecutor-367-thread-1-processing-n:127.0.0.1:33761_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:33761/solr/.system_shard1_replica_n3/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 79078 INFO (Thread-251) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection..system.shard1.leader, tag=SolrCore@50410439
[junit4] 2> 79086 INFO (qtp667543537-1474) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 79086 INFO (qtp667543537-1474) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 79086 INFO (qtp667543537-1474) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.c.S.Request [.system_shard1_replica_n3] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 79087 ERROR (managedSchemaExecutor-367-thread-1-processing-n:127.0.0.1:33761_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:33761/solr/.system_shard1_replica_n3/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 79089 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 79090 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 79090 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.c.S.Request [.system_shard1_replica_n3] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 79091 ERROR (managedSchemaExecutor-367-thread-1-processing-n:127.0.0.1:33761_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:33761/solr/.system_shard1_replica_n3/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 79094 INFO (qtp667543537-1457) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 79095 INFO (qtp667543537-1457) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 79095 INFO (qtp667543537-1457) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.c.S.Request [.system_shard1_replica_n3] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 79096 ERROR (managedSchemaExecutor-367-thread-1-processing-n:127.0.0.1:33761_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:33761/solr/.system_shard1_replica_n3/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 79102 INFO (qtp667543537-1474) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 79102 INFO (qtp667543537-1474) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 79103 INFO (qtp667543537-1474) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.c.S.Request [.system_shard1_replica_n3] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 79104 ERROR (managedSchemaExecutor-367-thread-1-processing-n:127.0.0.1:33761_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:33761/solr/.system_shard1_replica_n3/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 79109 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 79109 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 79109 INFO (qtp667543537-1465) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n3 ] o.a.s.c.S.Request [.system_shard1_replica_n3] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 79124 ERROR (managedSchemaExecutor-367-thread-1-processing-n:127.0.0.1:33761_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:33761/solr/.system_shard1
[...truncated too long message...]
system s:shard1 r:core_node2) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Failed to get /schema/zkversion from http://127.0.0.1:33761/solr/.system_shard1_replica_n3/ due to: java.util.concurrent.ExecutionException: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:33761/solr/.system_shard1_replica_n3
[junit4] 2> 214542 WARN (managedSchemaExecutor-367-thread-1-processing-n:127.0.0.1:33761_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Failed to get /schema/zkversion from http://127.0.0.1:33761/solr/.system_shard1_replica_n3/ due to: java.util.concurrent.ExecutionException: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:33761/solr/.system_shard1_replica_n3
[junit4] 2> 214542 WARN (managedSchemaExecutor-367-thread-1-processing-n:127.0.0.1:33761_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Failed to get /schema/zkversion from http://127.0.0.1:33761/solr/.system_shard1_replica_n3/ due to: java.util.concurrent.ExecutionException: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:33761/solr/.system_shard1_replica_n3
[junit4] 2> 214542 WARN (managedSchemaExecutor-367-thread-1-processing-n:127.0.0.1:33761_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Failed to get /schema/zkversion from http://127.0.0.1:33761/solr/.system_shard1_replica_n3/ due to: java.util.concurrent.ExecutionException: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:33761/solr/.system_shard1_replica_n3
[junit4] 2> 214548 WARN (managedSchemaExecutor-367-thread-1-processing-n:127.0.0.1:33761_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Failed to get /schema/zkversion from http://127.0.0.1:33761/solr/.system_shard1_replica_n3/ due to: java.util.concurrent.ExecutionException: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:33761/solr/.system_shard1_replica_n3
[junit4] 2> 214549 WARN (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Core core_node2 was interrupted waiting for schema version 2 to propagate to 1 replicas for collection .system
[junit4] 2> 214550 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Took 135653.0ms for 1 replicas to apply schema update version 2 for collection .system
[junit4] 2> 214550 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 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=135799
[junit4] 2> 214550 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [.system_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@695f3694
[junit4] 2> 214550 WARN (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Interrupted while trying to obtain lock to modify reporters registry: solr.core..system.shard1.replica_n1
[junit4] 2> 214550 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection..system.shard1.leader, tag=SolrCore@695f3694
[junit4] 2> 214550 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() .
[junit4] 2> 214550 INFO (qtp667543537-1466) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4e6a8230 commitCommandVersion:0
[junit4] 2> 214550 INFO (httpUriRequest-178623-thread-1-processing-x:.system_shard1_replica_n1 r:core_node2 n:127.0.0.1:33761_solr http:////127.0.0.1:33761//solr//.system_shard1_replica_n3 c:.system s:shard1) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.h.i.e.RetryExec I/O exception (java.net.SocketException) caught when processing request to {}->http://127.0.0.1:33761: Socket closed
[junit4] 2> 214550 INFO (httpUriRequest-178623-thread-1-processing-x:.system_shard1_replica_n1 r:core_node2 n:127.0.0.1:33761_solr http:////127.0.0.1:33761//solr//.system_shard1_replica_n3 c:.system s:shard1) [n:127.0.0.1:33761_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.h.i.e.RetryExec Retrying request to {}->http://127.0.0.1:33761
[junit4] 2> 214557 INFO (SUITE-SystemCollectionCompatTest-seed#[E5BF9168EDD154D2]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 214760 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 7 /solr/clusterprops.json
[junit4] 2> 7 /solr/aliases.json
[junit4] 2> 7 /solr/collections/.system/terms/shard1
[junit4] 2> 4 /solr/configs/.system/managed-schema
[junit4] 2> 2 /solr/configs/.system
[junit4] 2> 2 /solr/packages.json
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/collections/.system/collectionprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 14 /solr/collections/.system/state.json
[junit4] 2> 7 /solr/clusterstate.json
[junit4] 2> 6 /solr/aliases.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 24 /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> 214761 INFO (SUITE-SystemCollectionCompatTest-seed#[E5BF9168EDD154D2]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:45945
[junit4] 2> 214761 INFO (SUITE-SystemCollectionCompatTest-seed#[E5BF9168EDD154D2]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:45945
[junit4] 2> 214761 INFO (SUITE-SystemCollectionCompatTest-seed#[E5BF9168EDD154D2]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 45945
[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_E5BF9168EDD154D2-001
[junit4] 2> Feb 26, 2020 11:19:31 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Lucene84, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@39ec8a1d), locale=en-PR, timezone=America/Atka
[junit4] 2> NOTE: Linux 5.3.0-40-generic amd64/Oracle Corporation 15-ea (64-bit)/cpus=16,threads=1,free=387973120,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [SchemaApiFailureTest, DocValuesNotIndexedTest, TermsComponentTest, CreateRoutedAliasTest, AsyncCallRequestStatusResponseTest, CachingDirectoryFactoryTest, DOMUtilTest, TestComplexPhraseLeadingWildcard, IgnoreLargeDocumentProcessorFactoryTest, PluginInfoTest, HdfsBasicDistributedZkTest, BasicAuthStandaloneTest, WrapperMergePolicyFactoryTest, ConcurrentDeleteAndCreateCollectionTest, TestDocBasedVersionConstraints, MetricsConfigTest, TestSerializedLuceneMatchVersion, ZookeeperStatusHandlerTest, TestSimComputePlanAction, TestClusterProperties, SystemCollectionCompatTest]
[junit4] Completed [156/905 (1!)] on J0 in 139.08s, 1 test, 1 error <<< FAILURES!
[...truncated 47375 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/25854/consoleText
[repro] Revision: fc24fa95063c368cc446441da56712b464a3706a
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseZGC"
[repro] Repro line: ant test -Dtestcase=SystemCollectionCompatTest -Dtests.method=testBackCompat -Dtests.seed=E5BF9168EDD154D2 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en-PR -Dtests.timezone=America/Atka -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 3128 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.SystemCollectionCompatTest" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseZGC" -Dtests.seed=E5BF9168EDD154D2 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en-PR -Dtests.timezone=America/Atka -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...]