You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2020/01/28 07:17:43 UTC
[JENKINS] Lucene-Solr-master-Windows (64bit/jdk-12.0.2) - Build #
8387 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8387/
Java: 64bit/jdk-12.0.2 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.cloud.SystemCollectionCompatTest.testBackCompat
Error Message:
No live SolrServers available to handle this request:[http://127.0.0.1:49532/solr/.system]
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:49532/solr/.system]
at __randomizedtesting.SeedInfo.seed([5EE8DD78544B5CAA:2E1D7ED13483F5DC]: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:567)
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:835)
Caused by: org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: http://127.0.0.1:49532/solr/.system
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:676)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:265)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:368)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:296)
... 43 more
Caused by: java.net.SocketTimeoutException: Read timed out
at java.base/java.net.SocketInputStream.socketRead0(Native Method)
at java.base/java.net.SocketInputStream.socketRead(SocketInputStream.java:115)
at java.base/java.net.SocketInputStream.read(SocketInputStream.java:168)
at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:157)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:186)
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:564)
... 47 more
Build Log:
[...truncated 13106 lines...]
[junit4] Suite: org.apache.solr.cloud.SystemCollectionCompatTest
[junit4] 2> 119855 INFO (SUITE-SystemCollectionCompatTest-seed#[5EE8DD78544B5CAA]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 119856 INFO (SUITE-SystemCollectionCompatTest-seed#[5EE8DD78544B5CAA]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J2\temp\solr.cloud.SystemCollectionCompatTest_5EE8DD78544B5CAA-001\data-dir-9-001
[junit4] 2> 119856 WARN (SUITE-SystemCollectionCompatTest-seed#[5EE8DD78544B5CAA]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 119858 INFO (SUITE-SystemCollectionCompatTest-seed#[5EE8DD78544B5CAA]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 119864 INFO (SUITE-SystemCollectionCompatTest-seed#[5EE8DD78544B5CAA]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 119870 INFO (SUITE-SystemCollectionCompatTest-seed#[5EE8DD78544B5CAA]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J2\temp\solr.cloud.SystemCollectionCompatTest_5EE8DD78544B5CAA-001\tempDir-001
[junit4] 2> 119871 INFO (SUITE-SystemCollectionCompatTest-seed#[5EE8DD78544B5CAA]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 119872 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 119872 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 119972 INFO (SUITE-SystemCollectionCompatTest-seed#[5EE8DD78544B5CAA]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:49501
[junit4] 2> 119972 INFO (SUITE-SystemCollectionCompatTest-seed#[5EE8DD78544B5CAA]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:49501
[junit4] 2> 119972 INFO (SUITE-SystemCollectionCompatTest-seed#[5EE8DD78544B5CAA]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:49501
[junit4] 2> 119972 INFO (SUITE-SystemCollectionCompatTest-seed#[5EE8DD78544B5CAA]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 49501
[junit4] 2> 119978 INFO (SUITE-SystemCollectionCompatTest-seed#[5EE8DD78544B5CAA]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 119991 INFO (zkConnectionManagerCallback-406-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 119992 INFO (SUITE-SystemCollectionCompatTest-seed#[5EE8DD78544B5CAA]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 120052 INFO (SUITE-SystemCollectionCompatTest-seed#[5EE8DD78544B5CAA]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 120070 INFO (zkConnectionManagerCallback-408-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 120070 INFO (SUITE-SystemCollectionCompatTest-seed#[5EE8DD78544B5CAA]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 120071 INFO (SUITE-SystemCollectionCompatTest-seed#[5EE8DD78544B5CAA]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 120087 INFO (zkConnectionManagerCallback-410-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 120087 INFO (SUITE-SystemCollectionCompatTest-seed#[5EE8DD78544B5CAA]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 120211 WARN (jetty-launcher-411-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 120211 INFO (jetty-launcher-411-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 120211 INFO (jetty-launcher-411-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 120211 INFO (jetty-launcher-411-thread-2) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 12.0.2+10
[junit4] 2> 120211 WARN (jetty-launcher-411-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 120211 INFO (jetty-launcher-411-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 120211 INFO (jetty-launcher-411-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 120211 INFO (jetty-launcher-411-thread-1) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 12.0.2+10
[junit4] 2> 120211 INFO (jetty-launcher-411-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 120213 INFO (jetty-launcher-411-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 120213 INFO (jetty-launcher-411-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 120213 INFO (jetty-launcher-411-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@18b1832{/solr,null,AVAILABLE}
[junit4] 2> 120213 INFO (jetty-launcher-411-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 120213 INFO (jetty-launcher-411-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 120213 INFO (jetty-launcher-411-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 120213 INFO (jetty-launcher-411-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2660b30c{/solr,null,AVAILABLE}
[junit4] 2> 120215 INFO (jetty-launcher-411-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7993d9c6{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:49532}
[junit4] 2> 120216 INFO (jetty-launcher-411-thread-2) [ ] o.e.j.s.Server Started @120244ms
[junit4] 2> 120216 INFO (jetty-launcher-411-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=49532}
[junit4] 2> 120216 INFO (jetty-launcher-411-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@16481ece{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:49535}
[junit4] 2> 120216 INFO (jetty-launcher-411-thread-1) [ ] o.e.j.s.Server Started @120244ms
[junit4] 2> 120224 ERROR (jetty-launcher-411-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 120224 INFO (jetty-launcher-411-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 120224 INFO (jetty-launcher-411-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 120224 INFO (jetty-launcher-411-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 120224 INFO (jetty-launcher-411-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
[junit4] 2> 120224 INFO (jetty-launcher-411-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-28T06:29:25.523268300Z
[junit4] 2> 120226 INFO (jetty-launcher-411-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 120216 INFO (jetty-launcher-411-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=49535}
[junit4] 2> 120227 ERROR (jetty-launcher-411-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 120227 INFO (jetty-launcher-411-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 120228 INFO (jetty-launcher-411-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 120228 INFO (jetty-launcher-411-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 120228 INFO (jetty-launcher-411-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
[junit4] 2> 120228 INFO (jetty-launcher-411-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-28T06:29:25.527135700Z
[junit4] 2> 120230 INFO (jetty-launcher-411-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 120238 INFO (zkConnectionManagerCallback-413-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 120238 INFO (jetty-launcher-411-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 120238 INFO (jetty-launcher-411-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 120256 INFO (zkConnectionManagerCallback-415-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 120259 INFO (jetty-launcher-411-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 120354 INFO (jetty-launcher-411-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 120552 INFO (jetty-launcher-411-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 120554 WARN (jetty-launcher-411-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@68328ad4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 120554 WARN (jetty-launcher-411-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@68328ad4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 120559 WARN (jetty-launcher-411-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@792d9dfb[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 120559 WARN (jetty-launcher-411-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@792d9dfb[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 120559 INFO (jetty-launcher-411-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49501/solr
[junit4] 2> 120560 INFO (jetty-launcher-411-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 120564 INFO (zkConnectionManagerCallback-423-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 120564 INFO (jetty-launcher-411-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 120668 INFO (jetty-launcher-411-thread-1) [n:127.0.0.1:49535_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 120670 INFO (zkConnectionManagerCallback-425-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 120670 INFO (jetty-launcher-411-thread-1) [n:127.0.0.1:49535_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 120779 INFO (jetty-launcher-411-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 120782 WARN (jetty-launcher-411-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@41d41da[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 120782 WARN (jetty-launcher-411-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@41d41da[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 120782 INFO (jetty-launcher-411-thread-1) [n:127.0.0.1:49535_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:49535_solr
[junit4] 2> 120783 INFO (jetty-launcher-411-thread-1) [n:127.0.0.1:49535_solr ] o.a.s.c.Overseer Overseer (id=72081922434138118-127.0.0.1:49535_solr-n_0000000000) starting
[junit4] 2> 120785 WARN (jetty-launcher-411-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@67c85a37[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 120785 WARN (jetty-launcher-411-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@67c85a37[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 120786 INFO (jetty-launcher-411-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49501/solr
[junit4] 2> 120788 INFO (jetty-launcher-411-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 120790 INFO (jetty-launcher-411-thread-1) [n:127.0.0.1:49535_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49535_solr
[junit4] 2> 120790 INFO (OverseerStateUpdate-72081922434138118-127.0.0.1:49535_solr-n_0000000000) [n:127.0.0.1:49535_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:49535_solr
[junit4] 2> 120790 INFO (zkConnectionManagerCallback-433-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 120790 INFO (jetty-launcher-411-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 120791 INFO (OverseerStateUpdate-72081922434138118-127.0.0.1:49535_solr-n_0000000000) [n:127.0.0.1:49535_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 120794 INFO (jetty-launcher-411-thread-1) [n:127.0.0.1:49535_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 120794 WARN (jetty-launcher-411-thread-1) [n:127.0.0.1:49535_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> 120824 INFO (jetty-launcher-411-thread-1) [n:127.0.0.1:49535_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 120855 INFO (jetty-launcher-411-thread-1) [n:127.0.0.1:49535_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49535.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9c6ef67
[junit4] 2> 120864 INFO (jetty-launcher-411-thread-1) [n:127.0.0.1:49535_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49535.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9c6ef67
[junit4] 2> 120864 INFO (jetty-launcher-411-thread-1) [n:127.0.0.1:49535_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49535.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9c6ef67
[junit4] 2> 120865 INFO (jetty-launcher-411-thread-1) [n:127.0.0.1:49535_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J2\temp\solr.cloud.SystemCollectionCompatTest_5EE8DD78544B5CAA-001\tempDir-001\node2\.
[junit4] 2> 120895 INFO (jetty-launcher-411-thread-2) [n:127.0.0.1:49532_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 120899 INFO (zkConnectionManagerCallback-437-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 120899 INFO (jetty-launcher-411-thread-2) [n:127.0.0.1:49532_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 120906 INFO (jetty-launcher-411-thread-2) [n:127.0.0.1:49532_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 120912 INFO (jetty-launcher-411-thread-2) [n:127.0.0.1:49532_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:49532_solr as DOWN
[junit4] 2> 120913 INFO (jetty-launcher-411-thread-2) [n:127.0.0.1:49532_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 120913 INFO (jetty-launcher-411-thread-2) [n:127.0.0.1:49532_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49532_solr
[junit4] 2> 120915 INFO (zkCallback-424-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 120916 INFO (zkCallback-436-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 120917 INFO (jetty-launcher-411-thread-2) [n:127.0.0.1:49532_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 120919 WARN (jetty-launcher-411-thread-2) [n:127.0.0.1:49532_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> 120943 INFO (jetty-launcher-411-thread-2) [n:127.0.0.1:49532_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 120971 INFO (jetty-launcher-411-thread-2) [n:127.0.0.1:49532_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49532.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9c6ef67
[junit4] 2> 120983 INFO (jetty-launcher-411-thread-2) [n:127.0.0.1:49532_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49532.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9c6ef67
[junit4] 2> 120983 INFO (jetty-launcher-411-thread-2) [n:127.0.0.1:49532_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49532.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9c6ef67
[junit4] 2> 120987 INFO (jetty-launcher-411-thread-2) [n:127.0.0.1:49532_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J2\temp\solr.cloud.SystemCollectionCompatTest_5EE8DD78544B5CAA-001\tempDir-001\node1\.
[junit4] 2> 121007 INFO (SUITE-SystemCollectionCompatTest-seed#[5EE8DD78544B5CAA]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 121013 INFO (SUITE-SystemCollectionCompatTest-seed#[5EE8DD78544B5CAA]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 121015 INFO (zkConnectionManagerCallback-446-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 121015 INFO (SUITE-SystemCollectionCompatTest-seed#[5EE8DD78544B5CAA]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 121015 INFO (SUITE-SystemCollectionCompatTest-seed#[5EE8DD78544B5CAA]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 121019 INFO (SUITE-SystemCollectionCompatTest-seed#[5EE8DD78544B5CAA]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49501/solr ready
[junit4] 2> 121043 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[5EE8DD78544B5CAA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBackCompat
[junit4] 2> 121047 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[5EE8DD78544B5CAA]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 121051 INFO (zkConnectionManagerCallback-451-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 121051 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[5EE8DD78544B5CAA]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 121051 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[5EE8DD78544B5CAA]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 121052 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[5EE8DD78544B5CAA]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49501/solr ready
[junit4] 2> 121055 INFO (qtp412083119-1248) [n:127.0.0.1:49535_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 121062 INFO (qtp412083119-1248) [n:127.0.0.1:49535_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=8
[junit4] 2> 121069 INFO (qtp412083119-1247) [n:127.0.0.1:49535_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:49532_solr&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 121079 INFO (OverseerThreadFactory-319-thread-2-processing-n:127.0.0.1:49535_solr) [n:127.0.0.1:49535_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection .system
[junit4] 2> 121079 INFO (OverseerCollectionConfigSetProcessor-72081922434138118-127.0.0.1:49535_solr-n_0000000000) [n:127.0.0.1:49535_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> 121187 WARN (OverseerThreadFactory-319-thread-2-processing-n:127.0.0.1:49535_solr) [n:127.0.0.1:49535_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> 121191 INFO (OverseerStateUpdate-72081922434138118-127.0.0.1:49535_solr-n_0000000000) [n:127.0.0.1:49535_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":".system",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:49532/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 121195 INFO (OverseerStateUpdate-72081922434138118-127.0.0.1:49535_solr-n_0000000000) [n:127.0.0.1:49535_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":".system",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":".system_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:49532/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 121399 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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> 121399 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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> 122451 WARN (qtp2002004316-1241) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.SolrConfig You should not use LATEST as luceneMatchVersion property: if you use this setting, and then Solr upgrades to a newer release of Lucene, sizable changes may happen. If precise back compatibility is important then you should instead explicitly specify an actual Lucene version.
[junit4] 2> 122451 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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> 122451 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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> 122533 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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> 122534 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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> 122543 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.IndexSchema [.system_shard1_replica_n2] Schema name=_system collection or core
[junit4] 2> 122543 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.IndexSchema [.system_shard1_replica_n1] Schema name=_system collection or core
[junit4] 2> 122547 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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> 122547 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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> 122559 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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> 122559 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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> 122561 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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> 122563 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49532.solr.core..system.shard1.replica_n2' (registry 'solr.core..system.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9c6ef67
[junit4] 2> 122563 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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 [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J2\temp\solr.cloud.SystemCollectionCompatTest_5EE8DD78544B5CAA-001\tempDir-001\node1\.system_shard1_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J2\temp\solr.cloud.SystemCollectionCompatTest_5EE8DD78544B5CAA-001\tempDir-001\node1\.\.system_shard1_replica_n2\data\]
[junit4] 2> 122567 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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> 122567 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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> 122567 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49532.solr.core..system.shard1.replica_n1' (registry 'solr.core..system.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9c6ef67
[junit4] 2> 122568 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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 [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J2\temp\solr.cloud.SystemCollectionCompatTest_5EE8DD78544B5CAA-001\tempDir-001\node1\.system_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J2\temp\solr.cloud.SystemCollectionCompatTest_5EE8DD78544B5CAA-001\tempDir-001\node1\.\.system_shard1_replica_n1\data\]
[junit4] 2> 122647 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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> 122647 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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> 122651 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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> 122651 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 122651 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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> 122651 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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> 122652 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@72087730[.system_shard1_replica_n2] main]
[junit4] 2> 122654 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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> 122654 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 122655 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6ca5d621[.system_shard1_replica_n1] main]
[junit4] 2> 122655 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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> 122656 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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> 122656 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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> 122656 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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> 122659 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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> 122659 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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> 122659 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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> 122659 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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> 122663 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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> 122663 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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 1656952421573722112
[junit4] 2> 122663 INFO (searcherExecutor-328-thread-1-processing-n:127.0.0.1:49532_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:49532_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@72087730[.system_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 122663 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.IndexSchema [.system_shard1_replica_n1] Schema name=_system collection or core
[junit4] 2> 122667 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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> 122667 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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> 122671 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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> 122674 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 10 ms
[junit4] 2> 122674 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=2 found=1 timeoutin=9998ms
[junit4] 2> 122675 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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> 122675 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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 1656952421586305024
[junit4] 2> 122678 INFO (searcherExecutor-329-thread-1-processing-n:127.0.0.1:49532_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:49532_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@6ca5d621[.system_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 122681 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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> 122681 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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> 123175 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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> 123175 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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> 123175 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:49532/solr/.system_shard1_replica_n2/
[junit4] 2> 123176 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.PeerSync PeerSync: core=.system_shard1_replica_n2 url=http://127.0.0.1:49532/solr START replicas=[http://127.0.0.1:49532/solr/.system_shard1_replica_n1/] nUpdates=100
[junit4] 2> 123176 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.PeerSync PeerSync: core=.system_shard1_replica_n2 url=http://127.0.0.1:49532/solr DONE. We have no versions. sync failed.
[junit4] 2> 123180 INFO (qtp2002004316-1242) [n:127.0.0.1:49532_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> 123180 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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> 123180 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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> 123180 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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/72081922434138120-core_node4-n_0000000000
[junit4] 2> 123183 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:49532/solr/.system_shard1_replica_n2/ shard1
[junit4] 2> 123285 INFO (zkCallback-436-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> 123286 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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> 123291 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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=1891
[junit4] 2> 123390 INFO (zkCallback-436-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> 123390 INFO (zkCallback-436-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> 123689 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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=2288
[junit4] 2> 123691 INFO (qtp412083119-1247) [n:127.0.0.1:49535_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> 123786 INFO (zkCallback-424-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> 123786 INFO (zkCallback-436-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> 123786 INFO (zkCallback-436-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> 123787 INFO (qtp412083119-1247) [n:127.0.0.1:49535_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:49532_solr&wt=javabin&version=2} status=0 QTime=2718
[junit4] 2> 123789 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[5EE8DD78544B5CAA]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: .system
[junit4] 2> 123805 INFO (qtp2002004316-1242) [n:127.0.0.1:49532_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> 123812 INFO (qtp2002004316-1236) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:49532/solr/.system_shard1_replica_n2/&wt=javabin&version=2}{add=[16fead73182Tb961x9pv3okphgvlipri61onb (1656952422762807296)]} 0 3
[junit4] 2> 123814 INFO (qtp2002004316-1242) [n:127.0.0.1:49532_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=[16fead73182Tb961x9pv3okphgvlipri61onb (1656952422762807296)]} 0 19
[junit4] 2> 123816 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1656952422782730240,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 123816 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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@4869b20d commitCommandVersion:1656952422782730240
[junit4] 2> 123818 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1656952422784827392,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 123818 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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@17df1097 commitCommandVersion:1656952422784827392
[junit4] 2> 123826 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@729859e[.system_shard1_replica_n2] main]
[junit4] 2> 123826 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@670ea3d8[.system_shard1_replica_n1] main]
[junit4] 2> 123827 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 123827 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 123828 INFO (searcherExecutor-329-thread-1-processing-n:127.0.0.1:49532_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:49532_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@670ea3d8[.system_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={timestamp=1580192969122, java.vendor=AdoptOpenJDK, os=Windows 10, os.version=10.0, java.runtime.version=12.0.2+10, os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=12.0.2+10, java.version=12.0.2}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_COMPRESSION}])))}
[junit4] 2> 123828 INFO (searcherExecutor-328-thread-1-processing-n:127.0.0.1:49532_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:49532_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@729859e[.system_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={timestamp=1580192969122, java.vendor=AdoptOpenJDK, os=Windows 10, os.version=10.0, java.runtime.version=12.0.2+10, os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=12.0.2+10, java.version=12.0.2}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_COMPRESSION}])))}
[junit4] 2> 123829 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:49532/solr/.system_shard1_replica_n2/&commit_end_point=replicas&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 11
[junit4] 2> 123830 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n2] webapp=/solr path=/update params={_stateVer_=.system:6&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 14
[junit4] 2> 123836 INFO (qtp2002004316-1242) [n:127.0.0.1:49532_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={action=STATUS&indexInfo=true&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 123838 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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> 123844 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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=1
[junit4] 2> 123856 INFO (qtp2002004316-1236) [n:127.0.0.1:49532_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> 123860 INFO (qtp2002004316-1236) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.IndexSchema [.system_shard1_replica_n2] Schema name=_system collection or core
[junit4] 2> 123866 INFO (qtp2002004316-1236) [n:127.0.0.1:49532_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> 123876 INFO (qtp2002004316-1236) [n:127.0.0.1:49532_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> 123878 INFO (Thread-203) [ ] o.a.s.c.SolrCore config update listener called for core .system_shard1_replica_n2
[junit4] 2> 123879 INFO (Thread-203) [ ] o.a.s.c.SolrCore core reload .system_shard1_replica_n2
[junit4] 2> 123888 INFO (qtp2002004316-1236) [n:127.0.0.1:49532_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> 123898 INFO (qtp2002004316-1236) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.IndexSchema [.system_shard1_replica_n2] Schema name=_system collection or core
[junit4] 2> 123902 INFO (qtp2002004316-1236) [n:127.0.0.1:49532_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> 123902 INFO (qtp2002004316-1236) [n:127.0.0.1:49532_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> 123957 INFO (qtp2002004316-1236) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49532.solr.core..system.shard1.replica_n2' (registry 'solr.core..system.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9c6ef67
[junit4] 2> 123957 INFO (qtp2002004316-1236) [n:127.0.0.1:49532_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 [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J2\temp\solr.cloud.SystemCollectionCompatTest_5EE8DD78544B5CAA-001\tempDir-001\node1\.system_shard1_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J2\temp\solr.cloud.SystemCollectionCompatTest_5EE8DD78544B5CAA-001\tempDir-001\node1\.\.system_shard1_replica_n2\data\]
[junit4] 2> 124083 INFO (qtp2002004316-1236) [n:127.0.0.1:49532_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> 124083 INFO (qtp2002004316-1236) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 124087 INFO (qtp2002004316-1236) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@210349a6[.system_shard1_replica_n2] main]
[junit4] 2> 124096 INFO (qtp2002004316-1236) [n:127.0.0.1:49532_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> 124100 INFO (qtp2002004316-1236) [n:127.0.0.1:49532_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> 124100 INFO (qtp2002004316-1236) [n:127.0.0.1:49532_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> 124108 INFO (qtp2002004316-1236) [n:127.0.0.1:49532_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> 124108 INFO (qtp2002004316-1236) [n:127.0.0.1:49532_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> 124116 INFO (qtp2002004316-1236) [n:127.0.0.1:49532_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> 124116 INFO (searcherExecutor-338-thread-1-processing-n:127.0.0.1:49532_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:49532_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@210349a6[.system_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={timestamp=1580192969122, java.vendor=AdoptOpenJDK, os=Windows 10, os.version=10.0, java.runtime.version=12.0.2+10, os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=12.0.2+10, java.version=12.0.2}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_COMPRESSION}])))}
[junit4] 2> 124122 INFO (qtp2002004316-1236) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@29442a7d[.system_shard1_replica_n2] main]
[junit4] 2> 124126 INFO (searcherExecutor-338-thread-1-processing-n:127.0.0.1:49532_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:49532_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@29442a7d[.system_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={timestamp=1580192969122, os=Windows 10, java.vendor=AdoptOpenJDK, os.version=10.0, java.runtime.version=12.0.2+10, os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=12.0.2+10, java.version=12.0.2}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_COMPRESSION}])))}
[junit4] 2> 124155 INFO (qtp2002004316-1236) [n:127.0.0.1:49532_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> 124182 INFO (qtp2002004316-1242) [n:127.0.0.1:49532_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> 124190 INFO (qtp2002004316-1242) [n:127.0.0.1:49532_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> 124190 INFO (qtp2002004316-1242) [n:127.0.0.1:49532_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=11
[junit4] 2> 124194 ERROR (managedSchemaExecutor-342-thread-1-processing-n:127.0.0.1:49532_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:49532/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 124220 INFO (Thread-203) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 124230 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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> 124231 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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> 124231 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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=1
[junit4] 2> 124231 ERROR (managedSchemaExecutor-342-thread-1-processing-n:127.0.0.1:49532_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:49532/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 124242 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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> 124244 INFO (Thread-203) [ ] o.a.s.s.IndexSchema [.system_shard1_replica_n2] Schema name=_system collection or core
[junit4] 2> 124246 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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> 124246 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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=5
[junit4] 2> 124250 ERROR (managedSchemaExecutor-342-thread-1-processing-n:127.0.0.1:49532_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:49532/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 124250 INFO (Thread-203) [ ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id
[junit4] 2> 124250 INFO (Thread-203) [ ] o.a.s.c.CoreContainer Reloading SolrCore '.system_shard1_replica_n2' using configuration from configset .system
[junit4] 2> 124260 INFO (qtp2002004316-1242) [n:127.0.0.1:49532_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> 124263 INFO (qtp2002004316-1242) [n:127.0.0.1:49532_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> 124265 INFO (qtp2002004316-1242) [n:127.0.0.1:49532_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> 124265 ERROR (managedSchemaExecutor-342-thread-1-processing-n:127.0.0.1:49532_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:49532/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 124270 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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> 124272 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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> 124272 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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=1
[junit4] 2> 124274 ERROR (managedSchemaExecutor-342-thread-1-processing-n:127.0.0.1:49532_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:49532/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 124282 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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> 124286 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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> 124286 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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=5
[junit4] 2> 124286 ERROR (managedSchemaExecutor-342-thread-1-processing-n:127.0.0.1:49532_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:49532/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 124286 INFO (Thread-203) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49532.solr.core..system.shard1.replica_n2' (registry 'solr.core..system.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9c6ef67
[junit4] 2> 124286 INFO (Thread-203) [n:127.0.0.1:49532_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 [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J2\temp\solr.cloud.SystemCollectionCompatTest_5EE8DD78544B5CAA-001\tempDir-001\node1\.system_shard1_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J2\temp\solr.cloud.SystemCollectionCompatTest_5EE8DD78544B5CAA-001\tempDir-001\node1\.\.system_shard1_replica_n2\data\]
[junit4] 2> 124291 INFO (qtp2002004316-1242) [n:127.0.0.1:49532_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> 124292 INFO (qtp2002004316-1242) [n:127.0.0.1:49532_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> 124292 INFO (qtp2002004316-1242) [n:127.0.0.1:49532_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=1
[junit4] 2> 124293 ERROR (managedSchemaExecutor-342-thread-1-processing-n:127.0.0.1:49532_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:49532/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 124298 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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> 124298 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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> 124298 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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=2
[junit4] 2> 124301 ERROR (managedSchemaExecutor-342-thread-1-processing-n:127.0.0.1:49532_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:49532/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 124352 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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> 124352 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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> 124352 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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=1
[junit4] 2> 124353 ERROR (managedSchemaExecutor-342-thread-1-processing-n:127.0.0.1:49532_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:49532/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 124374 INFO (qtp2002004316-1242) [n:127.0.0.1:49532_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> 124384 INFO (qtp2002004316-1242) [n:127.0.0.1:49532_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> 124384 INFO (qtp2002004316-1242) [n:127.0.0.1:49532_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=9
[junit4] 2> 124386 ERROR (managedSchemaExecutor-342-thread-1-processing-n:127.0.0.1:49532_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:49532/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 124530 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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> 124535 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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> 124535 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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=4
[junit4] 2> 124536 ERROR (managedSchemaExecutor-342-thread-1-processing-n:127.0.0.1:49532_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:49532/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 124566 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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> 124581 INFO (Thread-203) [n:127.0.0.1:49532_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> 124581 INFO (Thread-203) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 124584 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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> 124584 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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=18
[junit4] 2> 124588 ERROR (managedSchemaExecutor-342-thread-1-processing-n:127.0.0.1:49532_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:49532/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 124662 INFO (Thread-203) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@b426778[.system_shard1_replica_n2] main]
[junit4] 2> 124662 INFO (qtp2002004316-1242) [n:127.0.0.1:49532_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> 124669 INFO (qtp2002004316-1242) [n:127.0.0.1:49532_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> 124669 INFO (qtp2002004316-1242) [n:127.0.0.1:49532_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=6
[junit4] 2> 124678 ERROR (managedSchemaExecutor-342-thread-1-processing-n:127.0.0.1:49532_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:49532/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 124739 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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> 124742 INFO (Thread-203) [n:127.0.0.1:49532_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> 124750 INFO (Thread-203) [n:127.0.0.1:49532_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> 124750 INFO (Thread-203) [n:127.0.0.1:49532_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> 124750 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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> 124750 INFO (qtp2002004316-1241) [n:127.0.0.1:49532_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=13
[junit4] 2> 124755 ERROR (managedSchemaExecutor-342-thread-1-processing-n:127.0.0.1:49532_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:49532/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 124760 INFO (Thread-203) [n:127.0.0.1:49532_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> 124760 INFO (Thread-203) [n:127.0.0.1:49532_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> 124765 INFO (searcherExecutor-343-thread-1-processing-n:127.0.0.1:49532_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:49532_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@b426778[.system_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={timestamp=1580192969122, os=Windows 10, java.vendor=AdoptOpenJDK, os.version=10.0, java.runtime.version=12.0.2+10, os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=12.0.2+10, java.version=12.0.2}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_COMPRESSION}])))}
[junit4] 2> 124793 INFO (Thread-203) [n:127.0.0.1:49532_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> 124794 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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> 124796 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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> 124796 INFO (qtp2002004316-1240) [n:127.0.0.1:49532_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> 124797 ERROR (managedSchemaExecutor-342-thread-1-processing-n:127.0.0.1:49532_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica http://127.0.0.1:49532/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 124797 INFO (Thread-203) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@56f6b813[.system_shard1_replica_n2] main]
[junit4] 2> 124797 INFO (Thread-203) [n:127.0.0.1:49532_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@2307055e
[junit4] 2> 124797 INFO (Thread-203) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core..system.shard1.replica_n2, tag=SolrCore@2307055e
[junit4] 2> 124797 INFO (Thread-203) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6ee41ada: rootName = solr_49532, domain = solr.core..system.shard1.replica_n2, service url = null, agent id = null] for registry solr.core..system.shard1.replica_n2 / com.codahale.metrics.MetricRegistry@6ad9d451
[junit4] 2> 124802 INFO (searcherExecutor-343-thread-1-processing-n:127.0.0.1:49532_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:49532_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@56f6b813[.system_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={timestamp=1580192969122, os=Windows 10, java.vendor=AdoptOpenJDK, os.version=10.0, java.runtime.version=12.0.2+10, os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=12.0.2+10, java.version=12.0.2}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_COMPRESSION}])))}
[junit4] 2> 124858 INFO (Thread-203) [n:127.0.0.1:49532_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@2307055e
[junit4] 2> 124870 INFO (Thread-203) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.SolrCore config update listener called for core .system_shard1_replica_n1
[junit4] 2> 124870 INFO (qtp2002004316-1242) [n:127.0.0.1:49532_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> 124874 INFO (qtp2002004316-1242) [n:127.0.0.1:49532_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> 124874 INFO (qtp2002004316-1242) [n:127.0.0.1:49532_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=4
[junit4] 2> 124874 ERROR (managedSchemaExecutor-342-thread-1-processing-n:127.0.0.1:49532_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexS
[...truncated too long message...]
Exception: Server refused connection at: http://127.0.0.1:49532/solr/.system_shard1_replica_n1
[junit4] 2> 248670 WARN (managedSchemaExecutor-342-thread-1-processing-n:127.0.0.1:49532_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Failed to get /schema/zkversion from http://127.0.0.1:49532/solr/.system_shard1_replica_n1/ due to: java.util.concurrent.ExecutionException: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:49532/solr/.system_shard1_replica_n1
[junit4] 2> 250675 WARN (managedSchemaExecutor-342-thread-1-processing-n:127.0.0.1:49532_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Failed to get /schema/zkversion from http://127.0.0.1:49532/solr/.system_shard1_replica_n1/ due to: java.util.concurrent.ExecutionException: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:49532/solr/.system_shard1_replica_n1
[junit4] 2> 252677 WARN (managedSchemaExecutor-342-thread-1-processing-n:127.0.0.1:49532_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Failed to get /schema/zkversion from http://127.0.0.1:49532/solr/.system_shard1_replica_n1/ due to: java.util.concurrent.ExecutionException: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:49532/solr/.system_shard1_replica_n1
[junit4] 2> 254686 WARN (managedSchemaExecutor-342-thread-1-processing-n:127.0.0.1:49532_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Failed to get /schema/zkversion from http://127.0.0.1:49532/solr/.system_shard1_replica_n1/ due to: java.util.concurrent.ExecutionException: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:49532/solr/.system_shard1_replica_n1
[junit4] 2> 256694 WARN (managedSchemaExecutor-342-thread-1-processing-n:127.0.0.1:49532_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Failed to get /schema/zkversion from http://127.0.0.1:49532/solr/.system_shard1_replica_n1/ due to: java.util.concurrent.ExecutionException: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:49532/solr/.system_shard1_replica_n1
[junit4] 2> 258699 WARN (managedSchemaExecutor-342-thread-1-processing-n:127.0.0.1:49532_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Failed to get /schema/zkversion from http://127.0.0.1:49532/solr/.system_shard1_replica_n1/ due to: java.util.concurrent.ExecutionException: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:49532/solr/.system_shard1_replica_n1
[junit4] 2> 259618 INFO (httpUriRequest-92798-thread-1-processing-x:.system_shard1_replica_n2 r:core_node4 n:127.0.0.1:49532_solr http:////127.0.0.1:49532//solr//.system_shard1_replica_n1 c:.system s:shard1) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.h.i.e.RetryExec I/O exception (java.net.SocketException) caught when processing request to {}->http://127.0.0.1:49532: Socket operation on nonsocket: configureBlocking
[junit4] 2> 259618 INFO (httpUriRequest-92798-thread-1-processing-x:.system_shard1_replica_n2 r:core_node4 n:127.0.0.1:49532_solr http:////127.0.0.1:49532//solr//.system_shard1_replica_n1 c:.system s:shard1) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.h.i.e.RetryExec Retrying request to {}->http://127.0.0.1:49532
[junit4] 2> 259620 WARN (qtp2002004316-1236) [n:127.0.0.1:49532_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> 259620 INFO (qtp2002004316-1236) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Took 135494.0ms for 1 replicas to apply schema update version 2 for collection .system
[junit4] 2> 259620 INFO (qtp2002004316-1236) [n:127.0.0.1:49532_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=135768
[junit4] 2> 259620 INFO (qtp2002004316-1236) [n:127.0.0.1:49532_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@244f4819
[junit4] 2> 259620 WARN (qtp2002004316-1236) [n:127.0.0.1:49532_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> 259620 INFO (qtp2002004316-1236) [n:127.0.0.1:49532_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@244f4819
[junit4] 2> 259621 INFO (qtp2002004316-1236) [n:127.0.0.1:49532_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() .
[junit4] 2> 259621 INFO (qtp2002004316-1236) [n:127.0.0.1:49532_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@4f95cc87 commitCommandVersion:0
[junit4] 2> 259631 INFO (SUITE-SystemCollectionCompatTest-seed#[5EE8DD78544B5CAA]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 259846 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 7 /solr/clusterprops.json
[junit4] 2> 7 /solr/aliases.json
[junit4] 2> 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> 16 /solr/collections/.system/state.json
[junit4] 2> 7 /solr/clusterstate.json
[junit4] 2> 6 /solr/aliases.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 25 /solr/overseer/queue
[junit4] 2> 18 /solr/collections
[junit4] 2> 11 /solr/live_nodes
[junit4] 2> 11 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 259854 INFO (SUITE-SystemCollectionCompatTest-seed#[5EE8DD78544B5CAA]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:49501
[junit4] 2> 259854 INFO (SUITE-SystemCollectionCompatTest-seed#[5EE8DD78544B5CAA]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:49501
[junit4] 2> 259854 INFO (SUITE-SystemCollectionCompatTest-seed#[5EE8DD78544B5CAA]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 49501
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J2\temp\solr.cloud.SystemCollectionCompatTest_5EE8DD78544B5CAA-001
[junit4] 2> NOTE: test params are: codec=Lucene84, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@5bbbd335), locale=en-DE, timezone=America/Juneau
[junit4] 2> NOTE: Windows 10 10.0 amd64/AdoptOpenJDK 12.0.2 (64-bit)/cpus=6,threads=1,free=157427048,total=255852544
[junit4] 2> NOTE: All tests run in this JVM: [HttpTriggerListenerTest, SystemInfoHandlerTest, TestPayloadScoreQParserPlugin, TestCloudRecovery2, TestRecoveryHdfs, TestBinaryField, TestStressInPlaceUpdates, DeleteLastCustomShardedReplicaTest, ResponseHeaderTest, SystemCollectionCompatTest]
[junit4] Completed [56/900 (1!)] on J2 in 142.07s, 1 test, 1 error <<< FAILURES!
[...truncated 47621 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8387/consoleText
[repro] Revision: 1a14c67426fcfb8834399722150f1f6cb56264da
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC"
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr\core
[repro] SystemCollectionCompatTest
[repro] ant compile-test
[...truncated 3240 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.SystemCollectionCompatTest" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC" -Dtests.seed=5EE8DD78544B5CAA -Dtests.slow=true -Dtests.locale=en-DE -Dtests.timezone=America/Juneau -Dtests.asserts=true -Dtests.file.encoding=Cp1252
[...truncated 82 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.cloud.SystemCollectionCompatTest
[repro] Exiting with code 0
[...truncated 78 lines...]