You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2020/01/27 23:12:45 UTC
[JENKINS] Lucene-Solr-Tests-master - Build # 4089 - Unstable
Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/4089/
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:39689/solr/.system]
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:39689/solr/.system]
at __randomizedtesting.SeedInfo.seed([B4FBFCFD959D206A:C40E5F54F555891C]:0)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:345)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1143)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:906)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:838)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207)
at org.apache.solr.cloud.SystemCollectionCompatTest.setupSystemCollection(SystemCollectionCompatTest.java:118)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:976)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
at java.base/java.lang.Thread.run(Thread.java:834)
Caused by: org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: http://127.0.0.1:39689/solr/.system
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:676)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:265)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:368)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:296)
... 43 more
Caused by: java.net.SocketTimeoutException: Read timed out
at java.base/java.net.SocketInputStream.socketRead0(Native Method)
at java.base/java.net.SocketInputStream.socketRead(SocketInputStream.java:115)
at java.base/java.net.SocketInputStream.read(SocketInputStream.java:168)
at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:157)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:186)
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:564)
... 47 more
Build Log:
[...truncated 14451 lines...]
[junit4] Suite: org.apache.solr.cloud.SystemCollectionCompatTest
[junit4] 2> 3053683 INFO (SUITE-SystemCollectionCompatTest-seed#[B4FBFCFD959D206A]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_B4FBFCFD959D206A-001/data-dir-185-001
[junit4] 2> 3053683 WARN (SUITE-SystemCollectionCompatTest-seed#[B4FBFCFD959D206A]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=154 numCloses=154
[junit4] 2> 3053683 INFO (SUITE-SystemCollectionCompatTest-seed#[B4FBFCFD959D206A]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 3053684 INFO (SUITE-SystemCollectionCompatTest-seed#[B4FBFCFD959D206A]-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> 3053684 INFO (SUITE-SystemCollectionCompatTest-seed#[B4FBFCFD959D206A]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 3053685 INFO (SUITE-SystemCollectionCompatTest-seed#[B4FBFCFD959D206A]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_B4FBFCFD959D206A-001/tempDir-001
[junit4] 2> 3053685 INFO (SUITE-SystemCollectionCompatTest-seed#[B4FBFCFD959D206A]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 3053685 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 3053685 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 3053786 INFO (SUITE-SystemCollectionCompatTest-seed#[B4FBFCFD959D206A]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:35828
[junit4] 2> 3053786 INFO (SUITE-SystemCollectionCompatTest-seed#[B4FBFCFD959D206A]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:35828
[junit4] 2> 3053786 INFO (SUITE-SystemCollectionCompatTest-seed#[B4FBFCFD959D206A]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:35828
[junit4] 2> 3053786 INFO (SUITE-SystemCollectionCompatTest-seed#[B4FBFCFD959D206A]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 35828
[junit4] 2> 3053787 INFO (SUITE-SystemCollectionCompatTest-seed#[B4FBFCFD959D206A]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3053789 INFO (zkConnectionManagerCallback-13865-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3053789 INFO (SUITE-SystemCollectionCompatTest-seed#[B4FBFCFD959D206A]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3053791 INFO (SUITE-SystemCollectionCompatTest-seed#[B4FBFCFD959D206A]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3053792 INFO (zkConnectionManagerCallback-13867-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3053792 INFO (SUITE-SystemCollectionCompatTest-seed#[B4FBFCFD959D206A]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3053793 INFO (SUITE-SystemCollectionCompatTest-seed#[B4FBFCFD959D206A]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3053794 INFO (zkConnectionManagerCallback-13869-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3053794 INFO (SUITE-SystemCollectionCompatTest-seed#[B4FBFCFD959D206A]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3053897 WARN (jetty-launcher-13870-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 3053897 WARN (jetty-launcher-13870-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 3053897 INFO (jetty-launcher-13870-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 3053897 INFO (jetty-launcher-13870-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 3053897 INFO (jetty-launcher-13870-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 3053897 INFO (jetty-launcher-13870-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 3053897 INFO (jetty-launcher-13870-thread-1) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+10-LTS
[junit4] 2> 3053897 INFO (jetty-launcher-13870-thread-2) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+10-LTS
[junit4] 2> 3053899 INFO (jetty-launcher-13870-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 3053899 INFO (jetty-launcher-13870-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 3053899 INFO (jetty-launcher-13870-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 3053899 INFO (jetty-launcher-13870-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 3053899 INFO (jetty-launcher-13870-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 3053899 INFO (jetty-launcher-13870-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 3053899 INFO (jetty-launcher-13870-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4d2c87ab{/solr,null,AVAILABLE}
[junit4] 2> 3053899 INFO (jetty-launcher-13870-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7fa1cffa{/solr,null,AVAILABLE}
[junit4] 2> 3053899 INFO (jetty-launcher-13870-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3ca30dd5{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:39689}
[junit4] 2> 3053899 INFO (jetty-launcher-13870-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@265b482d{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:39250}
[junit4] 2> 3053899 INFO (jetty-launcher-13870-thread-1) [ ] o.e.j.s.Server Started @3054057ms
[junit4] 2> 3053899 INFO (jetty-launcher-13870-thread-2) [ ] o.e.j.s.Server Started @3054057ms
[junit4] 2> 3053899 INFO (jetty-launcher-13870-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=39689}
[junit4] 2> 3053899 INFO (jetty-launcher-13870-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=39250}
[junit4] 2> 3053900 ERROR (jetty-launcher-13870-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 3053900 ERROR (jetty-launcher-13870-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 3053900 INFO (jetty-launcher-13870-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 3053900 INFO (jetty-launcher-13870-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 3053900 INFO (jetty-launcher-13870-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 3053900 INFO (jetty-launcher-13870-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 3053900 INFO (jetty-launcher-13870-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 3053900 INFO (jetty-launcher-13870-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 3053900 INFO (jetty-launcher-13870-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr
[junit4] 2> 3053900 INFO (jetty-launcher-13870-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr
[junit4] 2> 3053900 INFO (jetty-launcher-13870-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-27T22:07:51.066234Z
[junit4] 2> 3053900 INFO (jetty-launcher-13870-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-27T22:07:51.066237Z
[junit4] 2> 3053901 INFO (jetty-launcher-13870-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3053901 INFO (jetty-launcher-13870-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3053902 INFO (zkConnectionManagerCallback-13874-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3053902 INFO (jetty-launcher-13870-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3053902 INFO (zkConnectionManagerCallback-13872-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3053902 INFO (jetty-launcher-13870-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3053902 INFO (jetty-launcher-13870-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 3053902 INFO (jetty-launcher-13870-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 3058473 INFO (jetty-launcher-13870-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 3058474 WARN (jetty-launcher-13870-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6db5f338[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3058474 WARN (jetty-launcher-13870-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6db5f338[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3058485 WARN (jetty-launcher-13870-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4e54f2f7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3058485 WARN (jetty-launcher-13870-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4e54f2f7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3058486 INFO (jetty-launcher-13870-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35828/solr
[junit4] 2> 3058487 INFO (jetty-launcher-13870-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3058488 INFO (zkConnectionManagerCallback-13882-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3058500 INFO (jetty-launcher-13870-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3058626 INFO (jetty-launcher-13870-thread-1) [n:127.0.0.1:39250_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3058627 INFO (zkConnectionManagerCallback-13884-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3058627 INFO (jetty-launcher-13870-thread-1) [n:127.0.0.1:39250_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3058836 INFO (jetty-launcher-13870-thread-1) [n:127.0.0.1:39250_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:39250_solr
[junit4] 2> 3058837 INFO (jetty-launcher-13870-thread-1) [n:127.0.0.1:39250_solr ] o.a.s.c.Overseer Overseer (id=72184629442248710-127.0.0.1:39250_solr-n_0000000000) starting
[junit4] 2> 3058842 INFO (OverseerStateUpdate-72184629442248710-127.0.0.1:39250_solr-n_0000000000) [n:127.0.0.1:39250_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:39250_solr
[junit4] 2> 3058843 INFO (jetty-launcher-13870-thread-1) [n:127.0.0.1:39250_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39250_solr
[junit4] 2> 3058845 INFO (OverseerStateUpdate-72184629442248710-127.0.0.1:39250_solr-n_0000000000) [n:127.0.0.1:39250_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 3058874 INFO (jetty-launcher-13870-thread-1) [n:127.0.0.1:39250_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 3058875 WARN (jetty-launcher-13870-thread-1) [n:127.0.0.1:39250_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> 3058992 INFO (jetty-launcher-13870-thread-1) [n:127.0.0.1:39250_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 3059232 INFO (jetty-launcher-13870-thread-1) [n:127.0.0.1:39250_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39250.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45958f12
[junit4] 2> 3059264 INFO (jetty-launcher-13870-thread-1) [n:127.0.0.1:39250_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39250.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45958f12
[junit4] 2> 3059264 INFO (jetty-launcher-13870-thread-1) [n:127.0.0.1:39250_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39250.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45958f12
[junit4] 2> 3059278 INFO (jetty-launcher-13870-thread-1) [n:127.0.0.1:39250_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_B4FBFCFD959D206A-001/tempDir-001/node1/.
[junit4] 2> 3064136 INFO (jetty-launcher-13870-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 3064137 WARN (jetty-launcher-13870-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@123aa26b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3064137 WARN (jetty-launcher-13870-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@123aa26b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3064160 WARN (jetty-launcher-13870-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@41e290fb[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3064160 WARN (jetty-launcher-13870-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@41e290fb[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3064162 INFO (jetty-launcher-13870-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35828/solr
[junit4] 2> 3064163 INFO (jetty-launcher-13870-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3064163 INFO (zkConnectionManagerCallback-13894-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3064163 INFO (jetty-launcher-13870-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3064265 INFO (jetty-launcher-13870-thread-2) [n:127.0.0.1:39689_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3064266 INFO (zkConnectionManagerCallback-13896-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3064266 INFO (jetty-launcher-13870-thread-2) [n:127.0.0.1:39689_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3064272 INFO (jetty-launcher-13870-thread-2) [n:127.0.0.1:39689_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 3064275 INFO (jetty-launcher-13870-thread-2) [n:127.0.0.1:39689_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:39689_solr as DOWN
[junit4] 2> 3064276 INFO (jetty-launcher-13870-thread-2) [n:127.0.0.1:39689_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 3064276 INFO (jetty-launcher-13870-thread-2) [n:127.0.0.1:39689_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39689_solr
[junit4] 2> 3064277 INFO (zkCallback-13895-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 3064277 INFO (zkCallback-13883-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 3064304 INFO (jetty-launcher-13870-thread-2) [n:127.0.0.1:39689_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 3064305 WARN (jetty-launcher-13870-thread-2) [n:127.0.0.1:39689_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> 3064390 INFO (jetty-launcher-13870-thread-2) [n:127.0.0.1:39689_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 3064506 INFO (jetty-launcher-13870-thread-2) [n:127.0.0.1:39689_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39689.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45958f12
[junit4] 2> 3064560 INFO (jetty-launcher-13870-thread-2) [n:127.0.0.1:39689_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39689.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45958f12
[junit4] 2> 3064560 INFO (jetty-launcher-13870-thread-2) [n:127.0.0.1:39689_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39689.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45958f12
[junit4] 2> 3064561 INFO (jetty-launcher-13870-thread-2) [n:127.0.0.1:39689_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_B4FBFCFD959D206A-001/tempDir-001/node2/.
[junit4] 2> 3064693 INFO (SUITE-SystemCollectionCompatTest-seed#[B4FBFCFD959D206A]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 3064694 INFO (SUITE-SystemCollectionCompatTest-seed#[B4FBFCFD959D206A]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3064695 INFO (zkConnectionManagerCallback-13905-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3064695 INFO (SUITE-SystemCollectionCompatTest-seed#[B4FBFCFD959D206A]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3064697 INFO (SUITE-SystemCollectionCompatTest-seed#[B4FBFCFD959D206A]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 3064698 INFO (SUITE-SystemCollectionCompatTest-seed#[B4FBFCFD959D206A]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35828/solr ready
[junit4] 2> 3064793 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[B4FBFCFD959D206A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBackCompat
[junit4] 2> 3064811 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[B4FBFCFD959D206A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3064812 INFO (zkConnectionManagerCallback-13910-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3064812 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[B4FBFCFD959D206A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3064814 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[B4FBFCFD959D206A]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 3064815 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[B4FBFCFD959D206A]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35828/solr ready
[junit4] 2> 3064817 INFO (qtp681354062-33891) [n:127.0.0.1:39250_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 3064822 INFO (qtp681354062-33891) [n:127.0.0.1:39250_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 3064831 INFO (qtp681354062-33889) [n:127.0.0.1:39250_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:39689_solr&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 3064846 INFO (OverseerThreadFactory-8933-thread-2-processing-n:127.0.0.1:39250_solr) [n:127.0.0.1:39250_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection .system
[junit4] 2> 3064846 INFO (OverseerCollectionConfigSetProcessor-72184629442248710-127.0.0.1:39250_solr-n_0000000000) [n:127.0.0.1:39250_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> 3064950 WARN (OverseerThreadFactory-8933-thread-2-processing-n:127.0.0.1:39250_solr) [n:127.0.0.1:39250_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> 3064970 INFO (OverseerStateUpdate-72184629442248710-127.0.0.1:39250_solr-n_0000000000) [n:127.0.0.1:39250_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:39689/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 3064971 INFO (OverseerStateUpdate-72184629442248710-127.0.0.1:39250_solr-n_0000000000) [n:127.0.0.1:39250_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":".system",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":".system_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:39689/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 3065159 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr x:.system_shard1_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n2&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 3065159 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr x:.system_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n1&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 3066193 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 3066193 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 3066241 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 3066241 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 3066244 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.IndexSchema [.system_shard1_replica_n1] Schema name=_system collection or core
[junit4] 2> 3066244 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.IndexSchema [.system_shard1_replica_n2] Schema name=_system collection or core
[junit4] 2> 3066248 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id
[junit4] 2> 3066249 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Created and persisted managed schema znode at /configs/.system/managed-schema
[junit4] 2> 3066264 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id
[junit4] 2> 3066274 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Managed schema znode at /configs/.system/managed-schema already exists - no need to create it
[junit4] 2> 3066282 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore '.system_shard1_replica_n2' using configuration from configset .system, trusted=true
[junit4] 2> 3066282 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39689.solr.core..system.shard1.replica_n2' (registry 'solr.core..system.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45958f12
[junit4] 2> 3066283 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.SolrCore [[.system_shard1_replica_n2] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_B4FBFCFD959D206A-001/tempDir-001/node2/.system_shard1_replica_n2], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_B4FBFCFD959D206A-001/tempDir-001/node2/./.system_shard1_replica_n2/data/]
[junit4] 2> 3066285 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in ZooKeeper, renamed the non-managed schema /configs/.system/schema.xml to /configs/.system/schema.xml.bak
[junit4] 2> 3066285 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore '.system_shard1_replica_n1' using configuration from configset .system, trusted=true
[junit4] 2> 3066285 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39689.solr.core..system.shard1.replica_n1' (registry 'solr.core..system.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45958f12
[junit4] 2> 3066294 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [[.system_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_B4FBFCFD959D206A-001/tempDir-001/node2/.system_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_B4FBFCFD959D206A-001/tempDir-001/node2/./.system_shard1_replica_n1/data/]
[junit4] 2> 3066686 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 3066686 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 3066688 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: if 1 uncommitted docs;
[junit4] 2> 3066701 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 3066702 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@65c174[.system_shard1_replica_n1] main]
[junit4] 2> 3066714 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/.system
[junit4] 2> 3066714 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/.system
[junit4] 2> 3066714 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/.system/managed-schema
[junit4] 2> 3066715 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 0 from ZooKeeper
[junit4] 2> 3066717 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.IndexSchema [.system_shard1_replica_n1] Schema name=_system collection or core
[junit4] 2> 3066717 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 3066717 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 3066719 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: if 1 uncommitted docs;
[junit4] 2> 3066719 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 3066720 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id
[junit4] 2> 3066720 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 5 ms
[junit4] 2> 3066721 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@37cd6a13[.system_shard1_replica_n2] main]
[junit4] 2> 3066721 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 3066735 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/.system
[junit4] 2> 3066735 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/.system
[junit4] 2> 3066735 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/.system/managed-schema
[junit4] 2> 3066736 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 3066736 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 3066736 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1656920876150423552
[junit4] 2> 3066737 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1656920876151472128
[junit4] 2> 3066739 INFO (searcherExecutor-8945-thread-1-processing-n:127.0.0.1:39689_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.SolrCore [.system_shard1_replica_n2] Registered new searcher Searcher@37cd6a13[.system_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 3066740 INFO (searcherExecutor-8946-thread-1-processing-n:127.0.0.1:39689_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [.system_shard1_replica_n1] Registered new searcher Searcher@65c174[.system_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 3066750 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/.system/terms/shard1 to Terms{values={core_node4=0}, version=0}
[junit4] 2> 3066750 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/.system/leaders/shard1
[junit4] 2> 3066750 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/.system/terms/shard1 to Terms{values={core_node3=0, core_node4=0}, version=1}
[junit4] 2> 3066750 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/.system/leaders/shard1
[junit4] 2> 3066754 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 3066754 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 3066754 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:39689/solr/.system_shard1_replica_n2/
[junit4] 2> 3066755 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.PeerSync PeerSync: core=.system_shard1_replica_n2 url=http://127.0.0.1:39689/solr START replicas=[http://127.0.0.1:39689/solr/.system_shard1_replica_n1/] nUpdates=100
[junit4] 2> 3066755 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.PeerSync PeerSync: core=.system_shard1_replica_n2 url=http://127.0.0.1:39689/solr DONE. We have no versions. sync failed.
[junit4] 2> 3066757 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 3066757 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 3066757 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 3066757 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/.system/leaders/shard1/leader after winning as /collections/.system/leader_elect/shard1/election/72184629442248712-core_node4-n_0000000000
[junit4] 2> 3066779 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:39689/solr/.system_shard1_replica_n2/ shard1
[junit4] 2> 3066881 INFO (zkCallback-13895-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> 3066881 INFO (zkCallback-13895-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> 3066881 INFO (zkCallback-13895-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
[junit4] 2> 3066881 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 3066883 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n2&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1725
[junit4] 2> 3066990 INFO (zkCallback-13895-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
[junit4] 2> 3066990 INFO (zkCallback-13895-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> 3066990 INFO (zkCallback-13895-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> 3066991 INFO (zkCallback-13895-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
[junit4] 2> 3067756 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n1&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2597
[junit4] 2> 3067758 INFO (qtp681354062-33889) [n:127.0.0.1:39250_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> 3067857 INFO (zkCallback-13895-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
[junit4] 2> 3067857 INFO (zkCallback-13895-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> 3067857 INFO (zkCallback-13883-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> 3067857 INFO (zkCallback-13895-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
[junit4] 2> 3067857 INFO (zkCallback-13895-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> 3067857 INFO (qtp681354062-33889) [n:127.0.0.1:39250_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:39689_solr&wt=javabin&version=2} status=0 QTime=3026
[junit4] 2> 3067858 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[B4FBFCFD959D206A]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: .system
[junit4] 2> 3067883 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/.system/terms/shard1 to Terms{values={core_node3=1, core_node4=1}, version=2}
[junit4] 2> 3067894 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:39689/solr/.system_shard1_replica_n2/&wt=javabin&version=2}{add=[16fe90c25a1T656pcry2cdyug9do8o1xnowv (1656920877351043072)]} 0 1
[junit4] 2> 3067894 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[16fe90c25a1T656pcry2cdyug9do8o1xnowv (1656920877351043072)]} 0 34
[junit4] 2> 3067895 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1656920877365723136,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3067896 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2d2576d1 commitCommandVersion:1656920877365723136
[junit4] 2> 3067896 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1656920877366771712,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3067896 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1cf5df97 commitCommandVersion:1656920877366771712
[junit4] 2> 3067898 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3b40656d[.system_shard1_replica_n1] main]
[junit4] 2> 3067899 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 3067901 INFO (searcherExecutor-8946-thread-1-processing-n:127.0.0.1:39689_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [.system_shard1_replica_n1] Registered new searcher Searcher@3b40656d[.system_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={os.arch=amd64, java.runtime.version=11.0.4+10-LTS, os.version=4.4.0-170-generic, os=Linux, java.vendor=Oracle Corporation, timestamp=1580162885063, java.version=11.0.4, java.vm.version=11.0.4+10-LTS, lucene.version=9.0.0, source=flush}])))}
[junit4] 2> 3067909 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:39689/solr/.system_shard1_replica_n2/&commit_end_point=replicas&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 13
[junit4] 2> 3067911 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4b12edc2[.system_shard1_replica_n2] main]
[junit4] 2> 3067911 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 3067913 INFO (searcherExecutor-8945-thread-1-processing-n:127.0.0.1:39689_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.SolrCore [.system_shard1_replica_n2] Registered new searcher Searcher@4b12edc2[.system_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={os.arch=amd64, java.runtime.version=11.0.4+10-LTS, os.version=4.4.0-170-generic, os=Linux, java.vendor=Oracle Corporation, timestamp=1580162885075, java.version=11.0.4, java.vm.version=11.0.4+10-LTS, lucene.version=9.0.0, source=flush}])))}
[junit4] 2> 3067914 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n2] webapp=/solr path=/update params={_stateVer_=.system:5&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 18
[junit4] 2> 3067915 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={action=STATUS&indexInfo=true&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 3067916 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={action=STATUS&indexInfo=true&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 3067917 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request [.system_shard1_replica_n2] webapp=/solr path=/schema params={wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 3067922 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.SchemaManager managed schema loaded . version : 1
[junit4] 2> 3067924 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.IndexSchema [.system_shard1_replica_n2] Schema name=_system collection or core
[junit4] 2> 3067940 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id
[junit4] 2> 3067941 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.ZkController Persisted config data to node /configs/.system/schema.xml.bak
[junit4] 2> 3067942 INFO (Thread-4232) [ ] o.a.s.c.SolrCore config update listener called for core .system_shard1_replica_n1
[junit4] 2> 3067943 INFO (Thread-4232) [ ] o.a.s.c.SolrCore config update listener called for core .system_shard1_replica_n2
[junit4] 2> 3067944 INFO (Thread-4232) [ ] o.a.s.c.SolrCore core reload .system_shard1_replica_n2
[junit4] 2> 3067947 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 3068076 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.IndexSchema [.system_shard1_replica_n2] Schema name=_system collection or core
[junit4] 2> 3068080 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id
[junit4] 2> 3068080 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.CoreContainer Reloading SolrCore '.system_shard1_replica_n2' using configuration from configset .system
[junit4] 2> 3068234 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39689.solr.core..system.shard1.replica_n2' (registry 'solr.core..system.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45958f12
[junit4] 2> 3068234 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.SolrCore [[.system_shard1_replica_n2] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_B4FBFCFD959D206A-001/tempDir-001/node2/.system_shard1_replica_n2], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_B4FBFCFD959D206A-001/tempDir-001/node2/./.system_shard1_replica_n2/data/]
[junit4] 2> 3068525 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: if 1 uncommitted docs;
[junit4] 2> 3068525 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 3068541 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@348f3721[.system_shard1_replica_n2] main]
[junit4] 2> 3068543 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/.system
[junit4] 2> 3068543 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/.system
[junit4] 2> 3068543 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/.system/managed-schema
[junit4] 2> 3068543 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 3068543 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 3068555 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 3068574 INFO (searcherExecutor-8955-thread-1-processing-n:127.0.0.1:39689_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.SolrCore [.system_shard1_replica_n2] Registered new searcher Searcher@348f3721[.system_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={os.arch=amd64, java.runtime.version=11.0.4+10-LTS, os.version=4.4.0-170-generic, os=Linux, java.vendor=Oracle Corporation, timestamp=1580162885075, java.version=11.0.4, java.vm.version=11.0.4+10-LTS, lucene.version=9.0.0, source=flush}])))}
[junit4] 2> 3068575 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@63576c41[.system_shard1_replica_n2] main]
[junit4] 2> 3068575 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Waiting up to 599 secs for 1 replicas to apply schema update version 2 for collection .system
[junit4] 2> 3068591 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 3068592 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 3068592 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=14
[junit4] 2> 3068592 INFO (searcherExecutor-8955-thread-1-processing-n:127.0.0.1:39689_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.SolrCore [.system_shard1_replica_n2] Registered new searcher Searcher@63576c41[.system_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={os.arch=amd64, java.runtime.version=11.0.4+10-LTS, os.version=4.4.0-170-generic, java.vendor=Oracle Corporation, os=Linux, timestamp=1580162885075, java.version=11.0.4, java.vm.version=11.0.4+10-LTS, lucene.version=9.0.0, source=flush}])))}
[junit4] 2> 3068593 INFO (Thread-4232) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 3068596 ERROR (managedSchemaExecutor-8959-thread-1-processing-n:127.0.0.1:39689_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:39689/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 3068597 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 3068597 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 3068597 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 3068597 ERROR (managedSchemaExecutor-8959-thread-1-processing-n:127.0.0.1:39689_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:39689/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 3068630 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 3068630 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 3068630 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 3068631 ERROR (managedSchemaExecutor-8959-thread-1-processing-n:127.0.0.1:39689_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:39689/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 3068634 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 3068634 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 3068634 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 3068634 ERROR (managedSchemaExecutor-8959-thread-1-processing-n:127.0.0.1:39689_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:39689/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 3068635 INFO (Thread-4232) [ ] o.a.s.s.IndexSchema [.system_shard1_replica_n2] Schema name=_system collection or core
[junit4] 2> 3068635 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 3068635 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 3068635 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 3068635 ERROR (managedSchemaExecutor-8959-thread-1-processing-n:127.0.0.1:39689_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:39689/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 3068636 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 3068636 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 3068636 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 3068636 ERROR (managedSchemaExecutor-8959-thread-1-processing-n:127.0.0.1:39689_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:39689/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 3068637 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 3068637 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 3068637 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 3068637 ERROR (managedSchemaExecutor-8959-thread-1-processing-n:127.0.0.1:39689_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:39689/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 3068638 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 3068638 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 3068638 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 3068638 ERROR (managedSchemaExecutor-8959-thread-1-processing-n:127.0.0.1:39689_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:39689/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 3068651 INFO (Thread-4232) [ ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id
[junit4] 2> 3068651 INFO (Thread-4232) [ ] o.a.s.c.CoreContainer Reloading SolrCore '.system_shard1_replica_n2' using configuration from configset .system
[junit4] 2> 3068670 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 3068671 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 3068671 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 3068671 ERROR (managedSchemaExecutor-8959-thread-1-processing-n:127.0.0.1:39689_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:39689/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 3068672 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 3068672 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 3068672 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 3068672 ERROR (managedSchemaExecutor-8959-thread-1-processing-n:127.0.0.1:39689_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:39689/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 3068673 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 3068673 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 3068673 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 3068673 ERROR (managedSchemaExecutor-8959-thread-1-processing-n:127.0.0.1:39689_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:39689/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 3068674 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 3068674 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 3068674 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 3068674 ERROR (managedSchemaExecutor-8959-thread-1-processing-n:127.0.0.1:39689_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:39689/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 3068695 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 3068696 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 3068696 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 3068696 ERROR (managedSchemaExecutor-8959-thread-1-processing-n:127.0.0.1:39689_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:39689/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 3068696 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 3068697 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 3068697 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 3068697 ERROR (managedSchemaExecutor-8959-thread-1-processing-n:127.0.0.1:39689_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:39689/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 3068697 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 3068697 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 3068698 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 3068698 ERROR (managedSchemaExecutor-8959-thread-1-processing-n:127.0.0.1:39689_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:39689/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 3068698 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 3068698 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 3068699 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 3068699 ERROR (managedSchemaExecutor-8959-thread-1-processing-n:127.0.0.1:39689_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:39689/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 3068723 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 3068724 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 3068724 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 3068724 ERROR (managedSchemaExecutor-8959-thread-1-processing-n:127.0.0.1:39689_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:39689/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 3068744 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 3068744 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 3068744 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 3068744 ERROR (managedSchemaExecutor-8959-thread-1-processing-n:127.0.0.1:39689_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:39689/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 3068764 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 3068764 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 3068764 INFO (qtp845473257-33890) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 3068765 ERROR (managedSchemaExecutor-8959-thread-1-processing-n:127.0.0.1:39689_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:39689/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 3068765 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=0) before returning version!
[junit4] 2> 3068765 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 3068765 INFO (qtp845473257-33888) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 3068766 ERROR (managedSchemaExecutor-8959-thread-1-processing-n:127.0.0.1:39689_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:39689/solr/.syst
[...truncated too long message...]
t.ExecutionException: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:39689/solr/.system_shard1_replica_n1
[junit4] 2> 3204335 INFO (httpUriRequest-79688-thread-1-processing-x:.system_shard1_replica_n2 r:core_node4 n:127.0.0.1:39689_solr http:////127.0.0.1:39689//solr//.system_shard1_replica_n1 c:.system s:shard1) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.h.i.e.RetryExec I/O exception (java.net.SocketException) caught when processing request to {}->http://127.0.0.1:39689: Socket closed
[junit4] 2> 3204335 INFO (httpUriRequest-79688-thread-1-processing-x:.system_shard1_replica_n2 r:core_node4 n:127.0.0.1:39689_solr http:////127.0.0.1:39689//solr//.system_shard1_replica_n1 c:.system s:shard1) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.h.i.e.RetryExec Retrying request to {}->http://127.0.0.1:39689
[junit4] 2> 3204340 WARN (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Core core_node4 was interrupted waiting for schema version 2 to propagate to 1 replicas for collection .system
[junit4] 2> 3204340 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Took 135765.0ms for 1 replicas to apply schema update version 2 for collection .system
[junit4] 2> 3204340 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request [.system_shard1_replica_n2] webapp=/solr path=/schema params={wt=javabin&version=2} status=0 QTime=136421
[junit4] 2> 3204340 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.SolrCore [.system_shard1_replica_n2] CLOSING SolrCore org.apache.solr.core.SolrCore@13f36e40
[junit4] 2> 3204340 WARN (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.m.SolrMetricManager Interrupted while trying to obtain lock to modify reporters registry: solr.core..system.shard1.replica_n2
[junit4] 2> 3204340 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection..system.shard1.leader, tag=SolrCore@13f36e40
[junit4] 2> 3204341 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() .
[junit4] 2> 3204341 INFO (qtp845473257-33892) [n:127.0.0.1:39689_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@184a4662 commitCommandVersion:0
[junit4] 2> 3204342 INFO (SUITE-SystemCollectionCompatTest-seed#[B4FBFCFD959D206A]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 3204545 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 7 /solr/clusterprops.json
[junit4] 2> 7 /solr/aliases.json
[junit4] 2> 7 /solr/collections/.system/terms/shard1
[junit4] 2> 4 /solr/configs/.system/managed-schema
[junit4] 2> 2 /solr/configs/.system
[junit4] 2> 2 /solr/packages.json
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/collections/.system/collectionprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 25 /solr/collections/.system/state.json
[junit4] 2> 7 /solr/clusterstate.json
[junit4] 2> 6 /solr/aliases.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 23 /solr/overseer/queue
[junit4] 2> 18 /solr/collections
[junit4] 2> 11 /solr/live_nodes
[junit4] 2> 11 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 3204547 INFO (SUITE-SystemCollectionCompatTest-seed#[B4FBFCFD959D206A]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:35828
[junit4] 2> 3204547 INFO (SUITE-SystemCollectionCompatTest-seed#[B4FBFCFD959D206A]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:35828
[junit4] 2> 3204547 INFO (SUITE-SystemCollectionCompatTest-seed#[B4FBFCFD959D206A]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 35828
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.SystemCollectionCompatTest_B4FBFCFD959D206A-001
[junit4] 2> Jan 27, 2020 10:10:21 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=DummyCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=DUMMY, chunkSize=21984, maxDocsPerChunk=443, blockSize=367), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=DUMMY, chunkSize=21984, blockSize=367)), sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@303d373e), locale=fr, timezone=Africa/Lusaka
[junit4] 2> NOTE: Linux 4.4.0-170-generic amd64/Oracle Corporation 11.0.4 (64-bit)/cpus=8,threads=1,free=356607728,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestBackupRepositoryFactory, HealthCheckHandlerTest, TestSimGenericDistributedQueue, TestRequestForwarding, ActionThrottleTest, TestTolerantUpdateProcessorRandomCloud, SSLMigrationTest, TestTrie, BadCopyFieldTest, TestFieldCacheWithThreads, TestSchemaVersionResource, TestUseDocValuesAsStored2, TestRandomDVFaceting, DirectUpdateHandlerTest, TestEmbeddedSolrServerSchemaAPI, CollectionsAPISolrJTest, TestHashPartitioner, ShardRoutingTest, SpatialHeatmapFacetsTest, PeerSyncWithLeaderAndIndexFingerprintCachingTest, RequestLoggingTest, UtilsToolTest, AnalysisAfterCoreReloadTest, TestInPlaceUpdatesDistrib, DistributedDebugComponentTest, TestTlogReplayVsRecovery, TestInitParams, RAMDirectoryFactoryTest, TestFieldCacheSort, TestNumericRangeQuery64, SegmentsInfoRequestHandlerTest, TestCloudRecovery2, DirectSolrSpellCheckerTest, TestGraphTermsQParserPlugin, DistanceFunctionTest, TestCryptoKeys, RestartWhileUpdatingTest, RestoreTriggerStateTest, TestXmlQParserPlugin, TestCoreAdminApis, TestSolrCloudWithDelegationTokens, TestSolrCoreSnapshots, TestCharFilters, TestStressCloudBlindAtomicUpdates, CustomHighlightComponentTest, TestExceedMaxTermLength, BasicAuthOnSingleNodeTest, DistribCursorPagingTest, CoreSorterTest, TestElisionMultitermQuery, CdcrVersionReplicationTest, CdcrOpsAndBoundariesTest, TestRandomFlRTGCloud, HdfsRecoveryZkTest, TestLeaderElectionZkExpiry, TestFaceting, TermsComponentTest, ManagedSchemaRoundRobinCloudTest, TestRecovery, HdfsDirectoryFactoryTest, ChaosMonkeySafeLeaderTest, HttpPartitionWithTlogReplicasTest, TestJavabinTupleStreamParser, CSVRequestHandlerTest, TestSolrCloudWithHadoopAuthPlugin, TestJsonFacetRefinement, JWTAuthPluginTest, TestFieldResource, SmileWriterTest, TestAuthorizationFramework, TestExportTool, AlternateDirectoryTest, VersionInfoTest, TestMergePolicyConfig, DocValuesMultiTest, V2ApiIntegrationTest, ClassificationUpdateProcessorFactoryTest, DeleteInactiveReplicaTest, AuditLoggerIntegrationTest, DateFieldTest, TestQuerySenderNoQuery, AssignTest, TestExportWriter, URLClassifyProcessorTest, TestInitQParser, TestCloudNestedDocsSort, LeaderElectionTest, SolrRrdBackendFactoryTest, IndexSizeEstimatorTest, TestStreamBody, ChangedSchemaMergeTest, TestDistributedMissingSort, MetricTriggerTest, HLLUtilTest, SolrCLIZkUtilsTest, TestBM25SimilarityFactory, HdfsBasicDistributedZk2Test, TestSchemaNameResource, QueryEqualityTest, TestSimDistributedQueue, UpdateRequestProcessorFactoryTest, TestMinMaxOnMultiValuedField, BlockCacheTest, MetricUtilsTest, TestCloudDeleteByQuery, TestConfigSetProperties, SolrMetricManagerTest, ResolveAnalyzerByNameTest, ZkCLITest, TestRestManager, TestSort, MetricsHistoryIntegrationTest, TestJsonRequest, SpatialFilterTest, HdfsDirectoryTest, DirectSolrConnectionTest, DistributedUpdateProcessorTest, PackageManagerCLITest, TestStressVersions, TestSolr4Spatial2, RegexBoostProcessorTest, TestSimpleTextCodec, TestDocTermOrdsUninvertLimit, TestCollapseQParserPlugin, TestMinHashQParser, ExternalFileFieldSortTest, CircularListTest, RandomizedTaggerTest, TestApiFramework, TestSolrFieldCacheBean, TestMiniSolrCloudClusterSSL, FacetPivotSmallTest, AtomicUpdateRemovalJavabinTest, TestXIncludeConfig, SpellCheckCollatorTest, TestHttpShardHandlerFactory, ScheduledTriggerTest, CursorPagingTest, TestSha256AuthenticationProvider, TransactionLogTest, TestSolrCoreProperties, TestDistributedMap, AssignBackwardCompatibilityTest, TestSQLHandler, TestRangeQuery, ClusterStateTest, ClusterStateUpdateTest, CollectionStateFormat2Test, ConnectionManagerTest, CreateRoutedAliasTest, DeleteShardTest, DeleteStatusTest, DistributedQueueTest, DistributedVersionInfoTest, DocValuesNotIndexedTest, ForceLeaderTest, ForceLeaderWithTlogReplicasTest, FullSolrCloudDistribCmdsTest, LegacyCloudClusterPropTest, MissingSegmentRecoveryTest, MultiSolrCloudTestCaseTest, MultiThreadedOCPTest, NestedShardedAtomicUpdateTest, NodeMutatorTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerCollectionConfigSetProcessorTest, OverseerRolesTest, OverseerTest, ReindexCollectionTest, SystemCollectionCompatTest]
[junit4] Completed [521/900 (1!)] on J0 in 151.69s, 1 test, 1 error <<< FAILURES!
[...truncated 53761 lines...]