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/03/19 07:22:00 UTC
[JENKINS] Lucene-Solr-Tests-master - Build # 4461 - Unstable
Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/4461/
1 tests failed.
FAILED: org.apache.solr.cloud.SystemCollectionCompatTest.testBackCompat
Error Message:
No live SolrServers available to handle this request:[https://127.0.0.1:42568/solr/.system]
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[https://127.0.0.1:42568/solr/.system]
at __randomizedtesting.SeedInfo.seed([E2C1A0D9CC6944D5:92340370ACA1EDA3]:0)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:345)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1143)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:906)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:838)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207)
at org.apache.solr.cloud.SystemCollectionCompatTest.setupSystemCollection(SystemCollectionCompatTest.java:118)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:976)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
at java.base/java.lang.Thread.run(Thread.java:834)
Caused by: org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: https://127.0.0.1:42568/solr/.system
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:676)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:265)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:368)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:296)
... 43 more
Caused by: java.net.SocketTimeoutException: Read timed out
at java.base/java.net.SocketInputStream.socketRead0(Native Method)
at java.base/java.net.SocketInputStream.socketRead(SocketInputStream.java:115)
at java.base/java.net.SocketInputStream.read(SocketInputStream.java:168)
at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
at java.base/sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:448)
at java.base/sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:68)
at java.base/sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1104)
at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:823)
at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:157)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:186)
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:564)
... 47 more
Build Log:
[...truncated 14324 lines...]
[junit4] Suite: org.apache.solr.cloud.SystemCollectionCompatTest
[junit4] 2> 2708347 INFO (SUITE-SystemCollectionCompatTest-seed#[E2C1A0D9CC6944D5]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/server/solr/configsets/_default/conf'
[junit4] 2> 2708347 INFO (SUITE-SystemCollectionCompatTest-seed#[E2C1A0D9CC6944D5]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 2708348 INFO (SUITE-SystemCollectionCompatTest-seed#[E2C1A0D9CC6944D5]-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_E2C1A0D9CC6944D5-001/data-dir-192-001
[junit4] 2> 2708348 WARN (SUITE-SystemCollectionCompatTest-seed#[E2C1A0D9CC6944D5]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=21 numCloses=21
[junit4] 2> 2708348 INFO (SUITE-SystemCollectionCompatTest-seed#[E2C1A0D9CC6944D5]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 2708349 INFO (SUITE-SystemCollectionCompatTest-seed#[E2C1A0D9CC6944D5]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 2708351 INFO (SUITE-SystemCollectionCompatTest-seed#[E2C1A0D9CC6944D5]-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_E2C1A0D9CC6944D5-001/tempDir-001
[junit4] 2> 2708351 INFO (SUITE-SystemCollectionCompatTest-seed#[E2C1A0D9CC6944D5]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2708351 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2708352 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2708452 INFO (SUITE-SystemCollectionCompatTest-seed#[E2C1A0D9CC6944D5]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:38861
[junit4] 2> 2708452 INFO (SUITE-SystemCollectionCompatTest-seed#[E2C1A0D9CC6944D5]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:38861
[junit4] 2> 2708452 INFO (SUITE-SystemCollectionCompatTest-seed#[E2C1A0D9CC6944D5]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:38861
[junit4] 2> 2708452 INFO (SUITE-SystemCollectionCompatTest-seed#[E2C1A0D9CC6944D5]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 38861
[junit4] 2> 2708454 INFO (SUITE-SystemCollectionCompatTest-seed#[E2C1A0D9CC6944D5]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2708456 INFO (zkConnectionManagerCallback-9086-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2708456 INFO (SUITE-SystemCollectionCompatTest-seed#[E2C1A0D9CC6944D5]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2708459 INFO (SUITE-SystemCollectionCompatTest-seed#[E2C1A0D9CC6944D5]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2708461 INFO (zkConnectionManagerCallback-9088-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2708461 INFO (SUITE-SystemCollectionCompatTest-seed#[E2C1A0D9CC6944D5]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2708462 INFO (SUITE-SystemCollectionCompatTest-seed#[E2C1A0D9CC6944D5]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2708463 INFO (zkConnectionManagerCallback-9090-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2708463 INFO (SUITE-SystemCollectionCompatTest-seed#[E2C1A0D9CC6944D5]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2708571 INFO (jetty-launcher-9091-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 2708571 INFO (jetty-launcher-9091-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2708571 INFO (jetty-launcher-9091-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> 2708574 INFO (jetty-launcher-9091-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 2708574 INFO (jetty-launcher-9091-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2708574 INFO (jetty-launcher-9091-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> 2708576 INFO (jetty-launcher-9091-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2708576 INFO (jetty-launcher-9091-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2708576 INFO (jetty-launcher-9091-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2708576 INFO (jetty-launcher-9091-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2708576 INFO (jetty-launcher-9091-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2708576 INFO (jetty-launcher-9091-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2708576 INFO (jetty-launcher-9091-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7fd1b4fd{/solr,null,AVAILABLE}
[junit4] 2> 2708576 INFO (jetty-launcher-9091-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4ee185a8{/solr,null,AVAILABLE}
[junit4] 2> 2708578 INFO (jetty-launcher-9091-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4301268c{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:42568}
[junit4] 2> 2708578 INFO (jetty-launcher-9091-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@606c87ba{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:44726}
[junit4] 2> 2708578 INFO (jetty-launcher-9091-thread-1) [ ] o.e.j.s.Server Started @2708707ms
[junit4] 2> 2708578 INFO (jetty-launcher-9091-thread-2) [ ] o.e.j.s.Server Started @2708707ms
[junit4] 2> 2708578 INFO (jetty-launcher-9091-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44726}
[junit4] 2> 2708578 INFO (jetty-launcher-9091-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42568}
[junit4] 2> 2708578 ERROR (jetty-launcher-9091-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2708578 INFO (jetty-launcher-9091-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2708578 ERROR (jetty-launcher-9091-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2708578 INFO (jetty-launcher-9091-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2708578 INFO (jetty-launcher-9091-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 2708578 INFO (jetty-launcher-9091-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 2708578 INFO (jetty-launcher-9091-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2708578 INFO (jetty-launcher-9091-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr
[junit4] 2> 2708578 INFO (jetty-launcher-9091-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-19T06:20:11.895629Z
[junit4] 2> 2708578 INFO (jetty-launcher-9091-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2708578 INFO (jetty-launcher-9091-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr
[junit4] 2> 2708578 INFO (jetty-launcher-9091-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-19T06:20:11.895740Z
[junit4] 2> 2708579 INFO (jetty-launcher-9091-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2708580 INFO (jetty-launcher-9091-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2708580 INFO (zkConnectionManagerCallback-9093-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2708580 INFO (jetty-launcher-9091-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2708581 INFO (zkConnectionManagerCallback-9095-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2708581 INFO (jetty-launcher-9091-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2708581 INFO (jetty-launcher-9091-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2708581 INFO (jetty-launcher-9091-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2708690 INFO (jetty-launcher-9091-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 2708692 WARN (jetty-launcher-9091-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1fc067d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2708692 WARN (jetty-launcher-9091-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1fc067d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2708696 WARN (jetty-launcher-9091-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@46e314ca[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2708696 WARN (jetty-launcher-9091-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@46e314ca[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2708698 INFO (jetty-launcher-9091-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38861/solr
[junit4] 2> 2708699 INFO (jetty-launcher-9091-thread-1) [n:127.0.0.1:44726_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2708700 INFO (zkConnectionManagerCallback-9102-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2708700 INFO (jetty-launcher-9091-thread-1) [n:127.0.0.1:44726_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2708790 INFO (jetty-launcher-9091-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 2708791 WARN (jetty-launcher-9091-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3ee0fc5e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2708791 WARN (jetty-launcher-9091-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3ee0fc5e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2708795 WARN (jetty-launcher-9091-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7f81e7c8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2708795 WARN (jetty-launcher-9091-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7f81e7c8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2708797 INFO (jetty-launcher-9091-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38861/solr
[junit4] 2> 2708798 INFO (jetty-launcher-9091-thread-2) [n:127.0.0.1:42568_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2708799 INFO (zkConnectionManagerCallback-9111-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2708799 INFO (jetty-launcher-9091-thread-2) [n:127.0.0.1:42568_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2708809 INFO (jetty-launcher-9091-thread-1) [n:127.0.0.1:44726_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:44726_solr
[junit4] 2> 2708809 INFO (jetty-launcher-9091-thread-1) [n:127.0.0.1:44726_solr ] o.a.s.c.Overseer Overseer (id=72475343259631621-127.0.0.1:44726_solr-n_0000000000) starting
[junit4] 2> 2708810 INFO (jetty-launcher-9091-thread-2) [n:127.0.0.1:42568_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42568_solr
[junit4] 2> 2708812 INFO (zkCallback-9110-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2708812 INFO (zkCallback-9101-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2708814 INFO (jetty-launcher-9091-thread-2) [n:127.0.0.1:42568_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 2708815 WARN (jetty-launcher-9091-thread-2) [n:127.0.0.1:42568_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> 2708816 INFO (OverseerStateUpdate-72475343259631621-127.0.0.1:44726_solr-n_0000000000) [n:127.0.0.1:44726_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:44726_solr
[junit4] 2> 2708816 INFO (jetty-launcher-9091-thread-1) [n:127.0.0.1:44726_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:44726_solr as DOWN
[junit4] 2> 2708818 INFO (jetty-launcher-9091-thread-1) [n:127.0.0.1:44726_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 2708818 INFO (jetty-launcher-9091-thread-1) [n:127.0.0.1:44726_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44726_solr
[junit4] 2> 2708819 INFO (zkCallback-9110-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2708819 INFO (zkCallback-9101-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2708821 INFO (jetty-launcher-9091-thread-1) [n:127.0.0.1:44726_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 2708822 WARN (jetty-launcher-9091-thread-1) [n:127.0.0.1:44726_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> 2708838 INFO (jetty-launcher-9091-thread-2) [n:127.0.0.1:42568_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2708845 INFO (jetty-launcher-9091-thread-1) [n:127.0.0.1:44726_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2708876 INFO (jetty-launcher-9091-thread-2) [n:127.0.0.1:42568_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42568.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f9ee016
[junit4] 2> 2708878 INFO (jetty-launcher-9091-thread-1) [n:127.0.0.1:44726_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44726.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f9ee016
[junit4] 2> 2708890 INFO (jetty-launcher-9091-thread-2) [n:127.0.0.1:42568_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42568.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f9ee016
[junit4] 2> 2708890 INFO (jetty-launcher-9091-thread-2) [n:127.0.0.1:42568_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42568.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f9ee016
[junit4] 2> 2708892 INFO (jetty-launcher-9091-thread-2) [n:127.0.0.1:42568_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_E2C1A0D9CC6944D5-001/tempDir-001/node2/.
[junit4] 2> 2708893 INFO (jetty-launcher-9091-thread-1) [n:127.0.0.1:44726_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44726.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f9ee016
[junit4] 2> 2708893 INFO (jetty-launcher-9091-thread-1) [n:127.0.0.1:44726_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44726.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f9ee016
[junit4] 2> 2708895 INFO (jetty-launcher-9091-thread-1) [n:127.0.0.1:44726_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_E2C1A0D9CC6944D5-001/tempDir-001/node1/.
[junit4] 2> 2708962 INFO (SUITE-SystemCollectionCompatTest-seed#[E2C1A0D9CC6944D5]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 2708963 INFO (SUITE-SystemCollectionCompatTest-seed#[E2C1A0D9CC6944D5]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2708964 INFO (zkConnectionManagerCallback-9122-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2708964 INFO (SUITE-SystemCollectionCompatTest-seed#[E2C1A0D9CC6944D5]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2708966 INFO (SUITE-SystemCollectionCompatTest-seed#[E2C1A0D9CC6944D5]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2708968 INFO (SUITE-SystemCollectionCompatTest-seed#[E2C1A0D9CC6944D5]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:38861/solr ready
[junit4] 2> 2708993 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[E2C1A0D9CC6944D5]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBackCompat
[junit4] 2> 2708996 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[E2C1A0D9CC6944D5]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2708997 INFO (zkConnectionManagerCallback-9127-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2708997 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[E2C1A0D9CC6944D5]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2708999 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[E2C1A0D9CC6944D5]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2709000 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[E2C1A0D9CC6944D5]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:38861/solr ready
[junit4] 2> 2709052 INFO (qtp798385667-30997) [n:127.0.0.1:44726_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2709058 INFO (qtp798385667-30997) [n:127.0.0.1:44726_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=6
[junit4] 2> 2709071 INFO (qtp798385667-30997) [n:127.0.0.1:44726_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:42568_solr&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2709080 INFO (OverseerThreadFactory-8859-thread-2-processing-n:127.0.0.1:44726_solr) [n:127.0.0.1:44726_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection .system
[junit4] 2> 2709080 INFO (OverseerCollectionConfigSetProcessor-72475343259631621-127.0.0.1:44726_solr-n_0000000000) [n:127.0.0.1:44726_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> 2709186 WARN (OverseerThreadFactory-8859-thread-2-processing-n:127.0.0.1:44726_solr) [n:127.0.0.1:44726_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> 2709191 INFO (OverseerStateUpdate-72475343259631621-127.0.0.1:44726_solr-n_0000000000) [n:127.0.0.1:44726_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":".system",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:42568/solr",
[junit4] 2> "node_name":"127.0.0.1:42568_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2709194 INFO (OverseerStateUpdate-72475343259631621-127.0.0.1:44726_solr-n_0000000000) [n:127.0.0.1:44726_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":".system",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":".system_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:42568/solr",
[junit4] 2> "node_name":"127.0.0.1:42568_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2709447 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2709448 INFO (qtp217830232-30998) [n:127.0.0.1:42568_solr x:.system_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 2709449 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2709459 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2709459 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2709468 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2709468 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2709471 INFO (qtp217830232-30998) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=_system collection or core
[junit4] 2> 2709471 INFO (qtp217830232-31000) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema name=_system collection or core
[junit4] 2> 2709475 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2709475 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2709477 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2709477 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2709478 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2709479 INFO (qtp217830232-31000) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42568.solr.core..system.shard1.replica_n2' (registry 'solr.core..system.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f9ee016
[junit4] 2> 2709479 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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_E2C1A0D9CC6944D5-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_E2C1A0D9CC6944D5-001/tempDir-001/node2/./.system_shard1_replica_n2/data/]
[junit4] 2> 2709481 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2709482 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2709482 INFO (qtp217830232-30998) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42568.solr.core..system.shard1.replica_n1' (registry 'solr.core..system.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f9ee016
[junit4] 2> 2709482 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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_E2C1A0D9CC6944D5-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_E2C1A0D9CC6944D5-001/tempDir-001/node2/./.system_shard1_replica_n1/data/]
[junit4] 2> 2709583 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2709583 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2709585 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2709585 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2709586 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2709586 INFO (qtp217830232-31000) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2709587 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2709587 INFO (qtp217830232-30998) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2709587 INFO (qtp217830232-31000) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1ec4a9a5[.system_shard1_replica_n2] main]
[junit4] 2> 2709589 INFO (qtp217830232-30998) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6796f652[.system_shard1_replica_n1] main]
[junit4] 2> 2709590 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2709590 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2709590 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2709591 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2709591 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2709592 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2709592 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2709592 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2709592 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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 1661572284844867584
[junit4] 2> 2709592 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2709594 INFO (qtp217830232-30998) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=_system collection or core
[junit4] 2> 2709596 INFO (searcherExecutor-8868-thread-1-processing-n:127.0.0.1:42568_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:42568_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@1ec4a9a5[.system_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2709599 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2709599 INFO (qtp217830232-30998) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 6 ms
[junit4] 2> 2709600 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2709600 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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 1661572284853256192
[junit4] 2> 2709602 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2709602 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2709603 INFO (searcherExecutor-8869-thread-1-processing-n:127.0.0.1:42568_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:42568_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@6796f652[.system_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2709604 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2709604 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2709609 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2709609 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2709609 INFO (qtp217830232-31000) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:42568/solr/.system_shard1_replica_n2/
[junit4] 2> 2709610 INFO (qtp217830232-31000) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.PeerSync PeerSync: core=.system_shard1_replica_n2 url=https://127.0.0.1:42568/solr START replicas=[https://127.0.0.1:42568/solr/.system_shard1_replica_n1/] nUpdates=100
[junit4] 2> 2709610 INFO (qtp217830232-31000) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.PeerSync PeerSync: core=.system_shard1_replica_n2 url=https://127.0.0.1:42568/solr DONE. We have no versions. sync failed.
[junit4] 2> 2709626 INFO (qtp217830232-30996) [n:127.0.0.1:42568_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> 2709626 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2709626 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2709626 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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/72475343259631622-core_node4-n_0000000000
[junit4] 2> 2709629 INFO (qtp217830232-31000) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:42568/solr/.system_shard1_replica_n2/ shard1
[junit4] 2> 2709731 INFO (zkCallback-9110-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> 2709731 INFO (zkCallback-9110-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> 2709732 INFO (zkCallback-9110-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> 2709732 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2709735 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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=286
[junit4] 2> 2709835 INFO (zkCallback-9110-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> 2709835 INFO (zkCallback-9110-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> 2709835 INFO (zkCallback-9110-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> 2709836 INFO (zkCallback-9110-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> 2710611 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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=1164
[junit4] 2> 2710613 INFO (qtp798385667-30997) [n:127.0.0.1:44726_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> 2710712 INFO (zkCallback-9110-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> 2710712 INFO (zkCallback-9110-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> 2710712 INFO (zkCallback-9110-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> 2710712 INFO (zkCallback-9110-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> 2710712 INFO (zkCallback-9101-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> 2710713 INFO (qtp798385667-30997) [n:127.0.0.1:44726_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:42568_solr&wt=javabin&version=2} status=0 QTime=1641
[junit4] 2> 2710713 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[E2C1A0D9CC6944D5]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: .system
[junit4] 2> 2710732 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2710788 INFO (qtp217830232-30998) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:42568/solr/.system_shard1_replica_n2/&wt=javabin&version=2}{add=[170f1730d50Tdbbf550o7m0a0w60wxd6r90cl (1661572286038147072)]} 0 2
[junit4] 2> 2710789 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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=[170f1730d50Tdbbf550o7m0a0w60wxd6r90cl (1661572286038147072)]} 0 60
[junit4] 2> 2710791 INFO (qtp217830232-31063) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1661572286102110208,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2710791 INFO (qtp217830232-31063) [n:127.0.0.1:42568_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@1bb68a5 commitCommandVersion:1661572286102110208
[junit4] 2> 2710792 INFO (qtp217830232-30996) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1661572286103158784,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2710794 INFO (qtp217830232-30996) [n:127.0.0.1:42568_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@5116e4e8 commitCommandVersion:1661572286103158784
[junit4] 2> 2710801 INFO (qtp217830232-31063) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@46520a69[.system_shard1_replica_n2] main]
[junit4] 2> 2710801 INFO (qtp217830232-30996) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@54e7163e[.system_shard1_replica_n1] main]
[junit4] 2> 2710802 INFO (qtp217830232-30996) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2710802 INFO (qtp217830232-31063) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2710804 INFO (searcherExecutor-8868-thread-1-processing-n:127.0.0.1:42568_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:42568_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@46520a69[.system_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={os=Linux, os.version=4.4.0-170-generic, java.runtime.version=11.0.4+10-LTS, os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=11.0.4+10-LTS, java.version=11.0.4, timestamp=1584598814113, java.vendor=Oracle Corporation}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 2710804 INFO (searcherExecutor-8869-thread-1-processing-n:127.0.0.1:42568_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:42568_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@54e7163e[.system_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={os=Linux, os.version=4.4.0-170-generic, java.runtime.version=11.0.4+10-LTS, os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=11.0.4+10-LTS, java.version=11.0.4, timestamp=1584598814113, java.vendor=Oracle Corporation}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 2710805 INFO (qtp217830232-30996) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:42568/solr/.system_shard1_replica_n2/&commit_end_point=replicas&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 13
[junit4] 2> 2710806 INFO (qtp217830232-31063) [n:127.0.0.1:42568_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 15
[junit4] 2> 2710818 INFO (qtp217830232-30996) [n:127.0.0.1:42568_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> 2710820 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2710822 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2710828 INFO (qtp217830232-31063) [n:127.0.0.1:42568_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> 2710830 INFO (qtp217830232-31063) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema name=_system collection or core
[junit4] 2> 2710834 INFO (qtp217830232-31063) [n:127.0.0.1:42568_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> 2710836 INFO (qtp217830232-31063) [n:127.0.0.1:42568_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> 2710837 INFO (Thread-6756) [ ] o.a.s.c.SolrCore config update listener called for core .system_shard1_replica_n2
[junit4] 2> 2710838 INFO (Thread-6756) [ ] o.a.s.c.SolrCore core reload .system_shard1_replica_n2
[junit4] 2> 2710844 INFO (qtp217830232-31063) [n:127.0.0.1:42568_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> 2710855 INFO (qtp217830232-31063) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema name=_system collection or core
[junit4] 2> 2710859 INFO (qtp217830232-31063) [n:127.0.0.1:42568_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> 2710859 INFO (qtp217830232-31063) [n:127.0.0.1:42568_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> 2710942 INFO (qtp217830232-31063) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42568.solr.core..system.shard1.replica_n2' (registry 'solr.core..system.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f9ee016
[junit4] 2> 2710942 INFO (qtp217830232-31063) [n:127.0.0.1:42568_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_E2C1A0D9CC6944D5-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_E2C1A0D9CC6944D5-001/tempDir-001/node2/./.system_shard1_replica_n2/data/]
[junit4] 2> 2711034 INFO (qtp217830232-31063) [n:127.0.0.1:42568_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> 2711034 INFO (qtp217830232-31063) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2711038 INFO (qtp217830232-31063) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@a667a75[.system_shard1_replica_n2] main]
[junit4] 2> 2711041 INFO (qtp217830232-31063) [n:127.0.0.1:42568_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> 2711041 INFO (qtp217830232-31063) [n:127.0.0.1:42568_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> 2711042 INFO (qtp217830232-31063) [n:127.0.0.1:42568_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> 2711042 INFO (qtp217830232-31063) [n:127.0.0.1:42568_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> 2711042 INFO (qtp217830232-31063) [n:127.0.0.1:42568_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> 2711046 INFO (qtp217830232-31063) [n:127.0.0.1:42568_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> 2711049 INFO (searcherExecutor-8878-thread-1-processing-n:127.0.0.1:42568_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:42568_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@a667a75[.system_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={os=Linux, os.version=4.4.0-170-generic, java.runtime.version=11.0.4+10-LTS, os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=11.0.4+10-LTS, java.version=11.0.4, timestamp=1584598814113, java.vendor=Oracle Corporation}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 2711050 INFO (qtp217830232-31063) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@39178c1c[.system_shard1_replica_n2] main]
[junit4] 2> 2711051 INFO (qtp217830232-31063) [n:127.0.0.1:42568_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> 2711055 INFO (searcherExecutor-8878-thread-1-processing-n:127.0.0.1:42568_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:42568_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@39178c1c[.system_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={java.vendor=Oracle Corporation, os.version=4.4.0-170-generic, java.runtime.version=11.0.4+10-LTS, os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=11.0.4+10-LTS, java.version=11.0.4, timestamp=1584598814113, os=Linux}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 2711058 INFO (Thread-6756) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 2711067 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2711068 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2711068 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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=3
[junit4] 2> 2711068 INFO (Thread-6756) [ ] o.a.s.s.IndexSchema Schema name=_system collection or core
[junit4] 2> 2711072 INFO (Thread-6756) [ ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id
[junit4] 2> 2711072 INFO (Thread-6756) [ ] o.a.s.c.CoreContainer Reloading SolrCore '.system_shard1_replica_n2' using configuration from configset .system
[junit4] 2> 2711073 ERROR (managedSchemaExecutor-8882-thread-1-processing-n:127.0.0.1:42568_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:42568/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 2711074 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2711075 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2711075 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2711075 ERROR (managedSchemaExecutor-8882-thread-1-processing-n:127.0.0.1:42568_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:42568/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 2711076 INFO (qtp217830232-30996) [n:127.0.0.1:42568_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> 2711076 INFO (qtp217830232-30996) [n:127.0.0.1:42568_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> 2711076 INFO (qtp217830232-30996) [n:127.0.0.1:42568_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> 2711077 ERROR (managedSchemaExecutor-8882-thread-1-processing-n:127.0.0.1:42568_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:42568/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 2711078 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2711078 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2711078 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2711078 ERROR (managedSchemaExecutor-8882-thread-1-processing-n:127.0.0.1:42568_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:42568/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 2711079 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2711079 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2711079 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2711080 ERROR (managedSchemaExecutor-8882-thread-1-processing-n:127.0.0.1:42568_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:42568/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 2711080 INFO (qtp217830232-30996) [n:127.0.0.1:42568_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> 2711081 INFO (qtp217830232-30996) [n:127.0.0.1:42568_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> 2711081 INFO (qtp217830232-30996) [n:127.0.0.1:42568_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> 2711081 ERROR (managedSchemaExecutor-8882-thread-1-processing-n:127.0.0.1:42568_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:42568/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 2711082 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2711082 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2711082 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2711083 ERROR (managedSchemaExecutor-8882-thread-1-processing-n:127.0.0.1:42568_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:42568/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 2711083 INFO (OverseerCollectionConfigSetProcessor-72475343259631621-127.0.0.1:44726_solr-n_0000000000) [n:127.0.0.1:44726_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 2711083 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2711084 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2711084 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2711084 ERROR (managedSchemaExecutor-8882-thread-1-processing-n:127.0.0.1:42568_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:42568/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 2711085 INFO (qtp217830232-30996) [n:127.0.0.1:42568_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> 2711085 INFO (qtp217830232-30996) [n:127.0.0.1:42568_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> 2711085 INFO (qtp217830232-30996) [n:127.0.0.1:42568_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> 2711086 ERROR (managedSchemaExecutor-8882-thread-1-processing-n:127.0.0.1:42568_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:42568/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 2711086 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2711087 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2711087 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2711087 ERROR (managedSchemaExecutor-8882-thread-1-processing-n:127.0.0.1:42568_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:42568/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 2711088 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2711088 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2711088 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2711088 ERROR (managedSchemaExecutor-8882-thread-1-processing-n:127.0.0.1:42568_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:42568/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 2711089 INFO (qtp217830232-30996) [n:127.0.0.1:42568_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> 2711089 INFO (qtp217830232-30996) [n:127.0.0.1:42568_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> 2711090 INFO (qtp217830232-30996) [n:127.0.0.1:42568_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> 2711090 ERROR (managedSchemaExecutor-8882-thread-1-processing-n:127.0.0.1:42568_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:42568/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 2711091 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2711091 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2711091 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2711091 ERROR (managedSchemaExecutor-8882-thread-1-processing-n:127.0.0.1:42568_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:42568/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 2711092 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2711092 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2711092 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2711093 ERROR (managedSchemaExecutor-8882-thread-1-processing-n:127.0.0.1:42568_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:42568/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 2711093 INFO (qtp217830232-30996) [n:127.0.0.1:42568_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> 2711094 INFO (qtp217830232-30996) [n:127.0.0.1:42568_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> 2711094 INFO (qtp217830232-30996) [n:127.0.0.1:42568_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> 2711094 ERROR (managedSchemaExecutor-8882-thread-1-processing-n:127.0.0.1:42568_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:42568/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 2711095 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2711095 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2711095 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2711096 ERROR (managedSchemaExecutor-8882-thread-1-processing-n:127.0.0.1:42568_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:42568/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 2711096 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2711097 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2711097 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2711097 ERROR (managedSchemaExecutor-8882-thread-1-processing-n:127.0.0.1:42568_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:42568/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 2711098 INFO (qtp217830232-30996) [n:127.0.0.1:42568_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> 2711098 INFO (qtp217830232-30996) [n:127.0.0.1:42568_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> 2711098 INFO (qtp217830232-30996) [n:127.0.0.1:42568_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> 2711098 ERROR (managedSchemaExecutor-8882-thread-1-processing-n:127.0.0.1:42568_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:42568/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 2711099 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2711099 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2711099 INFO (qtp217830232-31000) [n:127.0.0.1:42568_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> 2711100 ERROR (managedSchemaExecutor-8882-thread-1-processing-n:127.0.0.1:42568_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:42568/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 2711100 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2711101 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2711101 INFO (qtp217830232-30998) [n:127.0.0.1:42568_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> 2711101 ERROR (managedSchemaExecutor-8882-thread-1-processing-n:127.0.0.1:42568_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:42568/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[j
[...truncated too long message...]
solr/.system_shard1_replica_n1/ due to: java.util.concurrent.ExecutionException: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: https://127.0.0.1:42568/solr/.system_shard1_replica_n1
[junit4] 2> 2847658 WARN (managedSchemaExecutor-8882-thread-1-processing-n:127.0.0.1:42568_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Failed to get /schema/zkversion from https://127.0.0.1:42568/solr/.system_shard1_replica_n1/ due to: java.util.concurrent.ExecutionException: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: https://127.0.0.1:42568/solr/.system_shard1_replica_n1
[junit4] 2> 2847681 WARN (qtp217830232-31063) [n:127.0.0.1:42568_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> 2847681 INFO (qtp217830232-31063) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Took 136630.0ms for 1 replicas to apply schema update version 2 for collection .system
[junit4] 2> 2847682 INFO (qtp217830232-31063) [n:127.0.0.1:42568_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=136855
[junit4] 2> 2847682 INFO (qtp217830232-31063) [n:127.0.0.1:42568_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@45290234
[junit4] 2> 2847682 WARN (qtp217830232-31063) [n:127.0.0.1:42568_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> 2847682 INFO (qtp217830232-31063) [n:127.0.0.1:42568_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@45290234
[junit4] 2> 2847682 INFO (qtp217830232-31063) [n:127.0.0.1:42568_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() .
[junit4] 2> 2847682 INFO (qtp217830232-31063) [n:127.0.0.1:42568_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@79c1a22d commitCommandVersion:0
[junit4] 2> 2847684 INFO (SUITE-SystemCollectionCompatTest-seed#[E2C1A0D9CC6944D5]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 2847888 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 7 /solr/aliases.json
[junit4] 2> 6 /solr/collections/.system/terms/shard1
[junit4] 2> 4 /solr/configs/.system/managed-schema
[junit4] 2> 2 /solr/configs/.system
[junit4] 2> 2 /solr/packages.json
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/collections/.system/collectionprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 25 /solr/collections/.system/state.json
[junit4] 2> 7 /solr/clusterstate.json
[junit4] 2> 7 /solr/clusterprops.json
[junit4] 2> 6 /solr/aliases.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 24 /solr/overseer/queue
[junit4] 2> 18 /solr/collections
[junit4] 2> 12 /solr/live_nodes
[junit4] 2> 10 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 2847889 INFO (SUITE-SystemCollectionCompatTest-seed#[E2C1A0D9CC6944D5]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:38861
[junit4] 2> 2847890 INFO (SUITE-SystemCollectionCompatTest-seed#[E2C1A0D9CC6944D5]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:38861
[junit4] 2> 2847890 INFO (SUITE-SystemCollectionCompatTest-seed#[E2C1A0D9CC6944D5]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 38861
[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_E2C1A0D9CC6944D5-001
[junit4] 2> Mar 19, 2020 6:22:31 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {id=PostingsFormat(name=LuceneVarGapDocFreqInterval), type=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84))}, docValues:{timestamp_l=DocValuesFormat(name=Asserting), _version_=DocValuesFormat(name=Lucene80), time_l=DocValuesFormat(name=Lucene80), timestamp=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=271, maxMBSortInHeap=5.445940251041142, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@76d0115), locale=lt-LT, timezone=Asia/Muscat
[junit4] 2> NOTE: Linux 4.4.0-170-generic amd64/Oracle Corporation 11.0.4 (64-bit)/cpus=8,threads=1,free=111806696,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [CustomHighlightComponentTest, TestIBSimilarityFactory, TestLegacyFieldReuse, OverseerModifyCollectionTest, SuggesterTSTTest, SolrRequestParserTest, TestReplicationHandler, TestUnifiedSolrHighlighterWithoutStoredId, RecoveryAfterSoftCommitTest, TestCrossCoreJoin, TestFastOutputStream, AdminHandlersProxyTest, AuditLoggerPluginTest, TestFileDictionaryLookup, TestDynamicFieldResource, DocValuesTest, SolrMetricReporterTest, TestExactStatsCache, SolrCoreCheckLockOnStartupTest, HdfsNNFailoverTest, DateRangeFieldTest, DistributedFacetPivotSmallTest, TestSQLHandlerNonCloud, TestExclusionRuleCollectionAccess, TestNestedUpdateProcessor, CollectionsAPIAsyncDistributedZkTest, TestLegacyFieldCache, TestRTGBase, TestUtilizeNode, TestFieldCache, MultiThreadedOCPTest, TestCollectionAPIs, DistributedFacetSimpleRefinementLongTailTest, TestPHPSerializedResponseWriter, OverseerTest, HttpPartitionTest, TestDistributedMissingSort, CdcrWithNodesRestartsTest, TestCloudJSONFacetJoinDomain, TestFieldTypeCollectionResource, HighlighterConfigTest, TestSurroundQueryParser, TestClusterProperties, SolrJmxReporterCloudTest, SolrXmlInZkTest, TestSimComputePlanAction, TestZkChroot, SearchRateTriggerIntegrationTest, TestSolrJacksonAnnotation, HdfsLockFactoryTest, DocValuesNotIndexedTest, TestUninvertingReader, TestSolrCachePerf, TestSolrIndexConfig, TestLeaderElectionWithEmptyReplica, TestPseudoReturnFields, SignificantTermsQParserPluginTest, TestIntervalFaceting, MoveReplicaHDFSTest, PeerSyncWithLeaderTest, TestConfigSetsAPIZkFailure, PluginInfoTest, TriggerIntegrationTest, TestSuggestSpellingConverter, CurrencyRangeFacetCloudTest, UnloadDistributedZkTest, CdcrBidirectionalTest, FullSolrCloudDistribCmdsTest, SliceStateTest, ResponseLogComponentTest, TestSolrCoreProperties, HdfsRestartWhileUpdatingTest, TestFieldCacheSortRandom, SpellCheckCollatorWithCollapseTest, CoreAdminHandlerTest, V2StandaloneTest, SolrCmdDistributorTest, DistributedSpellCheckComponentTest, RandomizedTaggerTest, CursorPagingTest, TestLegacyBM25SimilarityFactory, PropertiesRequestHandlerTest, TestComplexPhraseLeadingWildcard, ComputePlanActionTest, HttpTriggerListenerTest, DocumentAnalysisRequestHandlerTest, TestStressInPlaceUpdates, TestCorePropertiesReload, ShardRoutingCustomTest, TestReloadAndDeleteDocs, RemoteQueryErrorTest, DirectoryFactoryTest, TestCloudConsistency, TestDocBasedVersionConstraints, BasicZkTest, TestMultiValuedNumericRangeQuery, TestDynamicFieldNamesIndexCorrectly, TestClassicSimilarityFactory, TestLockTree, ReplicationFactorTest, TestHighFrequencyDictionaryFactory, ActionThrottleTest, CdcrUpdateLogTest, TestSSLRandomization, TestNumericTokenStream, WrapperMergePolicyFactoryTest, SolrCloudReportersTest, RuleEngineTest, ZkControllerTest, FileUtilsTest, TestSmileRequest, TestDynamicLoading, TestPerFieldSimilarityWithDefaultOverride, IndexSchemaRuntimeFieldTest, TestSimExecutePlanAction, TestGeoJSONResponseWriter, SearchHandlerTest, UpdateLogTest, FacetPivotSmallTest, CreateRoutedAliasTest, TestTextField, TaggingAttributeTest, TestBulkSchemaAPI, MetricsConfigTest, SuggestComponentTest, ConfigSetsAPITest, LeaderElectionIntegrationTest, TestManagedResource, TestJmxIntegration, PhrasesIdentificationComponentTest, ConcurrentDeleteAndCreateCollectionTest, ClassificationUpdateProcessorIntegrationTest, TestHdfsUpdateLog, HttpSolrCallGetCoreTest, PKIAuthenticationIntegrationTest, TestRTimerTree, MoreLikeThisHandlerTest, TestValueSourceCache, TestMissingGroups, BadIndexSchemaTest, TestUnInvertedFieldException, HighlighterWithoutStoredIdTest, LeaderFailureAfterFreshStartTest, CloudMLTQParserTest, TestNRTOpen, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, CloudExitableDirectoryReaderTest, ClusterStateTest, ClusterStateUpdateTest, CreateCollectionCleanupTest, DeleteReplicaTest, DistribJoinFromCollectionTest, DistributedQueueTest, DistributedVersionInfoTest, MissingSegmentRecoveryTest, MoveReplicaHDFSFailoverTest, NodeMutatorTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerSolrResponseTest, OverseerSolrResponseUnsafeSerializationTest, OverseerStatusTest, ReplaceNodeTest, SystemCollectionCompatTest]
[junit4] Completed [535/905 (1!)] on J0 in 139.99s, 1 test, 1 error <<< FAILURES!
[...truncated 53846 lines...]