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 02:59:17 UTC
[JENKINS-EA] Lucene-Solr-BadApples-master-Linux
(64bit/jdk-14-ea+32) - Build # 324 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-BadApples-master-Linux/324/
Java: 64bit/jdk-14-ea+32 -XX:-UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.cloud.SystemCollectionCompatTest.testBackCompat
Error Message:
No live SolrServers available to handle this request:[https://127.0.0.1:38875/solr/.system]
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[https://127.0.0.1:38875/solr/.system]
at __randomizedtesting.SeedInfo.seed([DE1BAEA8659A57BB:AEEE0D010552FECD]:0)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:345)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1143)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:906)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:838)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207)
at org.apache.solr.cloud.SystemCollectionCompatTest.setupSystemCollection(SystemCollectionCompatTest.java:118)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:976)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
at java.base/java.lang.Thread.run(Thread.java:832)
Caused by: org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: https://127.0.0.1:38875/solr/.system
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:676)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:265)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:368)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:296)
... 43 more
Caused by: java.net.SocketTimeoutException: Read timed out
at java.base/sun.nio.ch.NioSocketImpl.timedRead(NioSocketImpl.java:283)
at java.base/sun.nio.ch.NioSocketImpl.implRead(NioSocketImpl.java:309)
at java.base/sun.nio.ch.NioSocketImpl.read(NioSocketImpl.java:350)
at java.base/sun.nio.ch.NioSocketImpl$1.read(NioSocketImpl.java:803)
at java.base/java.net.Socket$SocketInputStream.read(Socket.java:982)
at java.base/sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:457)
at java.base/sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:68)
at java.base/sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1411)
at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:1022)
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 14814 lines...]
[junit4] Suite: org.apache.solr.cloud.SystemCollectionCompatTest
[junit4] 2> 637815 INFO (SUITE-SystemCollectionCompatTest-seed#[DE1BAEA8659A57BB]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J4/temp/solr.cloud.SystemCollectionCompatTest_DE1BAEA8659A57BB-001/data-dir-88-001
[junit4] 2> 637815 WARN (SUITE-SystemCollectionCompatTest-seed#[DE1BAEA8659A57BB]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=25 numCloses=25
[junit4] 2> 637815 INFO (SUITE-SystemCollectionCompatTest-seed#[DE1BAEA8659A57BB]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 637816 INFO (SUITE-SystemCollectionCompatTest-seed#[DE1BAEA8659A57BB]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 637816 INFO (SUITE-SystemCollectionCompatTest-seed#[DE1BAEA8659A57BB]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 637816 INFO (SUITE-SystemCollectionCompatTest-seed#[DE1BAEA8659A57BB]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J4/temp/solr.cloud.SystemCollectionCompatTest_DE1BAEA8659A57BB-001/tempDir-001
[junit4] 2> 637816 INFO (SUITE-SystemCollectionCompatTest-seed#[DE1BAEA8659A57BB]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 637817 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 637817 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 637917 INFO (SUITE-SystemCollectionCompatTest-seed#[DE1BAEA8659A57BB]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:37189
[junit4] 2> 637917 INFO (SUITE-SystemCollectionCompatTest-seed#[DE1BAEA8659A57BB]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:37189
[junit4] 2> 637917 INFO (SUITE-SystemCollectionCompatTest-seed#[DE1BAEA8659A57BB]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:37189
[junit4] 2> 637917 INFO (SUITE-SystemCollectionCompatTest-seed#[DE1BAEA8659A57BB]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 37189
[junit4] 2> 637918 INFO (SUITE-SystemCollectionCompatTest-seed#[DE1BAEA8659A57BB]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 637919 INFO (zkConnectionManagerCallback-3800-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 637919 INFO (SUITE-SystemCollectionCompatTest-seed#[DE1BAEA8659A57BB]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 637919 INFO (SUITE-SystemCollectionCompatTest-seed#[DE1BAEA8659A57BB]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 637920 INFO (zkConnectionManagerCallback-3802-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 637920 INFO (SUITE-SystemCollectionCompatTest-seed#[DE1BAEA8659A57BB]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 637920 INFO (SUITE-SystemCollectionCompatTest-seed#[DE1BAEA8659A57BB]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 637921 INFO (zkConnectionManagerCallback-3804-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 637921 INFO (SUITE-SystemCollectionCompatTest-seed#[DE1BAEA8659A57BB]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 638025 WARN (jetty-launcher-3805-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 638025 INFO (jetty-launcher-3805-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 638025 INFO (jetty-launcher-3805-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 638025 INFO (jetty-launcher-3805-thread-1) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 14-ea+32-1423
[junit4] 2> 638026 INFO (jetty-launcher-3805-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 638026 INFO (jetty-launcher-3805-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 638026 INFO (jetty-launcher-3805-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 638026 WARN (jetty-launcher-3805-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 638026 INFO (jetty-launcher-3805-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 638026 INFO (jetty-launcher-3805-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 638026 INFO (jetty-launcher-3805-thread-2) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 14-ea+32-1423
[junit4] 2> 638026 INFO (jetty-launcher-3805-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@48c1cfca{/solr,null,AVAILABLE}
[junit4] 2> 638026 INFO (jetty-launcher-3805-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 638026 INFO (jetty-launcher-3805-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 638026 INFO (jetty-launcher-3805-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 638026 INFO (jetty-launcher-3805-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@f5101a3{/solr,null,AVAILABLE}
[junit4] 2> 638029 INFO (jetty-launcher-3805-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@9f32e97{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:35565}
[junit4] 2> 638029 INFO (jetty-launcher-3805-thread-1) [ ] o.e.j.s.Server Started @638049ms
[junit4] 2> 638029 INFO (jetty-launcher-3805-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=35565}
[junit4] 2> 638029 ERROR (jetty-launcher-3805-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 638029 INFO (jetty-launcher-3805-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 638029 INFO (jetty-launcher-3805-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 638029 INFO (jetty-launcher-3805-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 638030 INFO (jetty-launcher-3805-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr
[junit4] 2> 638030 INFO (jetty-launcher-3805-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@382f38ca{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:38875}
[junit4] 2> 638030 INFO (jetty-launcher-3805-thread-2) [ ] o.e.j.s.Server Started @638050ms
[junit4] 2> 638030 INFO (jetty-launcher-3805-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-28T02:37:48.761016Z
[junit4] 2> 638030 INFO (jetty-launcher-3805-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38875}
[junit4] 2> 638030 ERROR (jetty-launcher-3805-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 638030 INFO (jetty-launcher-3805-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 638030 INFO (jetty-launcher-3805-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 638030 INFO (jetty-launcher-3805-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 638030 INFO (jetty-launcher-3805-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr
[junit4] 2> 638030 INFO (jetty-launcher-3805-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-28T02:37:48.761244Z
[junit4] 2> 638030 INFO (jetty-launcher-3805-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 638030 INFO (jetty-launcher-3805-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 638035 INFO (zkConnectionManagerCallback-3807-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 638035 INFO (jetty-launcher-3805-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 638035 INFO (zkConnectionManagerCallback-3809-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 638035 INFO (jetty-launcher-3805-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 638041 INFO (jetty-launcher-3805-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 638041 INFO (jetty-launcher-3805-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 638187 INFO (jetty-launcher-3805-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 638188 WARN (jetty-launcher-3805-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@27ae0b8c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 638188 WARN (jetty-launcher-3805-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@27ae0b8c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 638189 WARN (jetty-launcher-3805-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6f69fd50[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 638189 WARN (jetty-launcher-3805-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6f69fd50[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 638190 INFO (jetty-launcher-3805-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37189/solr
[junit4] 2> 638190 INFO (jetty-launcher-3805-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 638191 INFO (zkConnectionManagerCallback-3816-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 638191 INFO (jetty-launcher-3805-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 638306 INFO (jetty-launcher-3805-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 638307 WARN (jetty-launcher-3805-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6c0199e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 638307 WARN (jetty-launcher-3805-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6c0199e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 638308 WARN (jetty-launcher-3805-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@76c766dd[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 638308 WARN (jetty-launcher-3805-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@76c766dd[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 638309 INFO (jetty-launcher-3805-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37189/solr
[junit4] 2> 638309 INFO (jetty-launcher-3805-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 638310 INFO (zkConnectionManagerCallback-3823-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 638310 INFO (jetty-launcher-3805-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 638347 INFO (jetty-launcher-3805-thread-1) [n:127.0.0.1:35565_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 638347 INFO (zkConnectionManagerCallback-3825-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 638347 INFO (jetty-launcher-3805-thread-1) [n:127.0.0.1:35565_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 638370 INFO (jetty-launcher-3805-thread-1) [n:127.0.0.1:35565_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:35565_solr
[junit4] 2> 638370 INFO (jetty-launcher-3805-thread-1) [n:127.0.0.1:35565_solr ] o.a.s.c.Overseer Overseer (id=72086585924452359-127.0.0.1:35565_solr-n_0000000000) starting
[junit4] 2> 638372 INFO (OverseerStateUpdate-72086585924452359-127.0.0.1:35565_solr-n_0000000000) [n:127.0.0.1:35565_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:35565_solr
[junit4] 2> 638372 INFO (jetty-launcher-3805-thread-1) [n:127.0.0.1:35565_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35565_solr
[junit4] 2> 638372 INFO (zkCallback-3824-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 638373 INFO (jetty-launcher-3805-thread-1) [n:127.0.0.1:35565_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 638373 WARN (jetty-launcher-3805-thread-1) [n:127.0.0.1:35565_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> 638387 INFO (jetty-launcher-3805-thread-1) [n:127.0.0.1:35565_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 638397 INFO (jetty-launcher-3805-thread-1) [n:127.0.0.1:35565_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35565.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5ab12508
[junit4] 2> 638401 INFO (jetty-launcher-3805-thread-1) [n:127.0.0.1:35565_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35565.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5ab12508
[junit4] 2> 638401 INFO (jetty-launcher-3805-thread-1) [n:127.0.0.1:35565_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35565.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5ab12508
[junit4] 2> 638401 INFO (jetty-launcher-3805-thread-1) [n:127.0.0.1:35565_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J4/temp/solr.cloud.SystemCollectionCompatTest_DE1BAEA8659A57BB-001/tempDir-001/node1/.
[junit4] 2> 638447 INFO (jetty-launcher-3805-thread-2) [n:127.0.0.1:38875_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 638447 INFO (zkConnectionManagerCallback-3831-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 638448 INFO (jetty-launcher-3805-thread-2) [n:127.0.0.1:38875_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 638449 INFO (jetty-launcher-3805-thread-2) [n:127.0.0.1:38875_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 638450 INFO (jetty-launcher-3805-thread-2) [n:127.0.0.1:38875_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:38875_solr as DOWN
[junit4] 2> 638450 INFO (jetty-launcher-3805-thread-2) [n:127.0.0.1:38875_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 638450 INFO (jetty-launcher-3805-thread-2) [n:127.0.0.1:38875_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38875_solr
[junit4] 2> 638451 INFO (zkCallback-3824-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 638451 INFO (zkCallback-3830-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 638451 INFO (jetty-launcher-3805-thread-2) [n:127.0.0.1:38875_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 638451 WARN (jetty-launcher-3805-thread-2) [n:127.0.0.1:38875_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> 638456 INFO (jetty-launcher-3805-thread-2) [n:127.0.0.1:38875_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 638466 INFO (jetty-launcher-3805-thread-2) [n:127.0.0.1:38875_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38875.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5ab12508
[junit4] 2> 638470 INFO (jetty-launcher-3805-thread-2) [n:127.0.0.1:38875_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38875.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5ab12508
[junit4] 2> 638470 INFO (jetty-launcher-3805-thread-2) [n:127.0.0.1:38875_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38875.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5ab12508
[junit4] 2> 638471 INFO (jetty-launcher-3805-thread-2) [n:127.0.0.1:38875_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J4/temp/solr.cloud.SystemCollectionCompatTest_DE1BAEA8659A57BB-001/tempDir-001/node2/.
[junit4] 2> 638483 INFO (SUITE-SystemCollectionCompatTest-seed#[DE1BAEA8659A57BB]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 638484 INFO (SUITE-SystemCollectionCompatTest-seed#[DE1BAEA8659A57BB]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 638484 INFO (zkConnectionManagerCallback-3840-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 638484 INFO (SUITE-SystemCollectionCompatTest-seed#[DE1BAEA8659A57BB]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 638485 INFO (SUITE-SystemCollectionCompatTest-seed#[DE1BAEA8659A57BB]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 638485 INFO (SUITE-SystemCollectionCompatTest-seed#[DE1BAEA8659A57BB]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37189/solr ready
[junit4] 2> 638498 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[DE1BAEA8659A57BB]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBackCompat
[junit4] 2> 638500 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[DE1BAEA8659A57BB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 638500 INFO (zkConnectionManagerCallback-3845-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 638500 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[DE1BAEA8659A57BB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 638500 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[DE1BAEA8659A57BB]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 638501 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[DE1BAEA8659A57BB]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37189/solr ready
[junit4] 2> 638514 INFO (qtp1175044637-14244) [n:127.0.0.1:35565_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 638516 INFO (qtp1175044637-14244) [n:127.0.0.1:35565_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 638523 INFO (qtp1175044637-14246) [n:127.0.0.1:35565_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:38875_solr&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 638526 INFO (OverseerThreadFactory-3811-thread-2-processing-n:127.0.0.1:35565_solr) [n:127.0.0.1:35565_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection .system
[junit4] 2> 638526 INFO (OverseerCollectionConfigSetProcessor-72086585924452359-127.0.0.1:35565_solr-n_0000000000) [n:127.0.0.1:35565_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> 638628 WARN (OverseerThreadFactory-3811-thread-2-processing-n:127.0.0.1:35565_solr) [n:127.0.0.1:35565_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> 638629 INFO (OverseerStateUpdate-72086585924452359-127.0.0.1:35565_solr-n_0000000000) [n:127.0.0.1:35565_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":".system",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:38875/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 638630 INFO (OverseerStateUpdate-72086585924452359-127.0.0.1:35565_solr-n_0000000000) [n:127.0.0.1:35565_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":".system",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":".system_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:38875/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 638846 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 638846 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 639851 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 639851 WARN (qtp2010501755-14257) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.SolrConfig You should not use LATEST as luceneMatchVersion property: if you use this setting, and then Solr upgrades to a newer release of Lucene, sizable changes may happen. If precise back compatibility is important then you should instead explicitly specify an actual Lucene version.
[junit4] 2> 639851 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 639868 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 639868 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 639870 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 639870 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 639872 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 639872 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 639873 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 639873 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 639873 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 639874 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38875.solr.core..system.shard1.replica_n2' (registry 'solr.core..system.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5ab12508
[junit4] 2> 639874 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.SolrCore [[.system_shard1_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J4/temp/solr.cloud.SystemCollectionCompatTest_DE1BAEA8659A57BB-001/tempDir-001/node2/.system_shard1_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J4/temp/solr.cloud.SystemCollectionCompatTest_DE1BAEA8659A57BB-001/tempDir-001/node2/./.system_shard1_replica_n2/data/]
[junit4] 2> 639875 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 639875 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 639875 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38875.solr.core..system.shard1.replica_n1' (registry 'solr.core..system.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5ab12508
[junit4] 2> 639875 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [[.system_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J4/temp/solr.cloud.SystemCollectionCompatTest_DE1BAEA8659A57BB-001/tempDir-001/node2/.system_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J4/temp/solr.cloud.SystemCollectionCompatTest_DE1BAEA8659A57BB-001/tempDir-001/node2/./.system_shard1_replica_n1/data/]
[junit4] 2> 639922 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 639922 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 639922 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 639922 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 639923 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 639923 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 639923 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 639923 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 639924 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@b239ca3[.system_shard1_replica_n1] main]
[junit4] 2> 639924 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6b2a3bdc[.system_shard1_replica_n2] main]
[junit4] 2> 639925 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 639925 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 639925 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 639925 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 639925 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 639925 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 639925 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 639925 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 639926 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 639926 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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 1656937849193234432
[junit4] 2> 639926 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 639927 INFO (searcherExecutor-3820-thread-1-processing-n:127.0.0.1:38875_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:38875_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@6b2a3bdc[.system_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 639928 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 639928 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 639928 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 639928 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 2 ms
[junit4] 2> 639928 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 639929 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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 1656937849196380160
[junit4] 2> 639929 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=2 found=1 timeoutin=9999ms
[junit4] 2> 639930 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 639930 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 639930 INFO (searcherExecutor-3821-thread-1-processing-n:127.0.0.1:38875_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:38875_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@b239ca3[.system_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 640430 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 640430 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 640430 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:38875/solr/.system_shard1_replica_n2/
[junit4] 2> 640430 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.PeerSync PeerSync: core=.system_shard1_replica_n2 url=https://127.0.0.1:38875/solr START replicas=[https://127.0.0.1:38875/solr/.system_shard1_replica_n1/] nUpdates=100
[junit4] 2> 640430 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.PeerSync PeerSync: core=.system_shard1_replica_n2 url=https://127.0.0.1:38875/solr DONE. We have no versions. sync failed.
[junit4] 2> 640437 INFO (qtp2010501755-14254) [n:127.0.0.1:38875_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> 640437 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 640437 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 640437 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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/72086585924452360-core_node4-n_0000000000
[junit4] 2> 640438 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:38875/solr/.system_shard1_replica_n2/ shard1
[junit4] 2> 640539 INFO (zkCallback-3830-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> 640539 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 640540 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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=1694
[junit4] 2> 640640 INFO (zkCallback-3830-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> 640640 INFO (zkCallback-3830-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> 640931 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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=2085
[junit4] 2> 640932 INFO (qtp1175044637-14246) [n:127.0.0.1:35565_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> 641032 INFO (zkCallback-3824-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> 641032 INFO (zkCallback-3830-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> 641032 INFO (zkCallback-3830-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> 641032 INFO (qtp1175044637-14246) [n:127.0.0.1:35565_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:38875_solr&wt=javabin&version=2} status=0 QTime=2508
[junit4] 2> 641032 INFO (TEST-SystemCollectionCompatTest.testBackCompat-seed#[DE1BAEA8659A57BB]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: .system
[junit4] 2> 641043 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 641058 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:38875/solr/.system_shard1_replica_n2/&wt=javabin&version=2}{add=[16fea032314T63s1fsed20lpiqt0jq1l57otz (1656937850363445248)]} 0 0
[junit4] 2> 641059 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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=[16fea032314T63s1fsed20lpiqt0jq1l57otz (1656937850363445248)]} 0 16
[junit4] 2> 641060 INFO (qtp2010501755-14256) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1656937850382319616,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 641060 INFO (qtp2010501755-14256) [n:127.0.0.1:38875_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@333d4e71 commitCommandVersion:1656937850382319616
[junit4] 2> 641061 INFO (qtp2010501755-14254) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1656937850383368192,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 641061 INFO (qtp2010501755-14254) [n:127.0.0.1:38875_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@74d4b3f8 commitCommandVersion:1656937850383368192
[junit4] 2> 641064 INFO (qtp2010501755-14256) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@cbd13dd[.system_shard1_replica_n2] main]
[junit4] 2> 641064 INFO (qtp2010501755-14254) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@56506fd2[.system_shard1_replica_n1] main]
[junit4] 2> 641064 INFO (qtp2010501755-14256) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 641064 INFO (qtp2010501755-14254) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 641065 INFO (searcherExecutor-3820-thread-1-processing-n:127.0.0.1:38875_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:38875_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@cbd13dd[.system_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={java.version=14-ea, java.vm.version=14-ea+32-1423, lucene.version=9.0.0, source=flush, os.arch=amd64, java.runtime.version=14-ea+32-1423, os.version=5.3.0-26-generic, os=Linux, java.vendor=Oracle Corporation, timestamp=1580179071793}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 641066 INFO (searcherExecutor-3821-thread-1-processing-n:127.0.0.1:38875_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:38875_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@56506fd2[.system_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={java.version=14-ea, java.vm.version=14-ea+32-1423, lucene.version=9.0.0, source=flush, os.arch=amd64, java.runtime.version=14-ea+32-1423, os.version=5.3.0-26-generic, os=Linux, java.vendor=Oracle Corporation, timestamp=1580179071792}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 641066 INFO (qtp2010501755-14254) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:38875/solr/.system_shard1_replica_n2/&commit_end_point=replicas&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 5
[junit4] 2> 641066 INFO (qtp2010501755-14256) [n:127.0.0.1:38875_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 6
[junit4] 2> 641074 INFO (qtp2010501755-14254) [n:127.0.0.1:38875_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> 641076 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 641077 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.S.Request [.system_shard1_replica_n2] webapp=/solr path=/schema params={wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 641080 INFO (qtp2010501755-14256) [n:127.0.0.1:38875_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> 641081 INFO (qtp2010501755-14256) [n:127.0.0.1:38875_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> 641083 INFO (qtp2010501755-14256) [n:127.0.0.1:38875_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> 641084 INFO (qtp2010501755-14256) [n:127.0.0.1:38875_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> 641084 INFO (Thread-3930) [ ] o.a.s.c.SolrCore config update listener called for core .system_shard1_replica_n2
[junit4] 2> 641085 INFO (Thread-3930) [ ] o.a.s.c.SolrCore core reload .system_shard1_replica_n2
[junit4] 2> 641088 INFO (qtp2010501755-14256) [n:127.0.0.1:38875_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> 641112 INFO (qtp2010501755-14256) [n:127.0.0.1:38875_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> 641115 INFO (qtp2010501755-14256) [n:127.0.0.1:38875_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> 641115 INFO (qtp2010501755-14256) [n:127.0.0.1:38875_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> 641152 INFO (qtp2010501755-14256) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38875.solr.core..system.shard1.replica_n2' (registry 'solr.core..system.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5ab12508
[junit4] 2> 641152 INFO (qtp2010501755-14256) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.SolrCore [[.system_shard1_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J4/temp/solr.cloud.SystemCollectionCompatTest_DE1BAEA8659A57BB-001/tempDir-001/node2/.system_shard1_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J4/temp/solr.cloud.SystemCollectionCompatTest_DE1BAEA8659A57BB-001/tempDir-001/node2/./.system_shard1_replica_n2/data/]
[junit4] 2> 641189 INFO (qtp2010501755-14256) [n:127.0.0.1:38875_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> 641189 INFO (qtp2010501755-14256) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 641191 INFO (qtp2010501755-14256) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7acc2f3b[.system_shard1_replica_n2] main]
[junit4] 2> 641192 INFO (qtp2010501755-14256) [n:127.0.0.1:38875_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> 641192 INFO (qtp2010501755-14256) [n:127.0.0.1:38875_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> 641192 INFO (qtp2010501755-14256) [n:127.0.0.1:38875_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> 641192 INFO (qtp2010501755-14256) [n:127.0.0.1:38875_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> 641193 INFO (qtp2010501755-14256) [n:127.0.0.1:38875_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> 641194 INFO (qtp2010501755-14256) [n:127.0.0.1:38875_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> 641195 INFO (searcherExecutor-3830-thread-1-processing-n:127.0.0.1:38875_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:38875_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@7acc2f3b[.system_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={java.version=14-ea, java.vm.version=14-ea+32-1423, lucene.version=9.0.0, source=flush, os.arch=amd64, java.runtime.version=14-ea+32-1423, os.version=5.3.0-26-generic, os=Linux, java.vendor=Oracle Corporation, timestamp=1580179071793}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 641195 INFO (qtp2010501755-14256) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1b83e081[.system_shard1_replica_n2] main]
[junit4] 2> 641196 INFO (qtp2010501755-14256) [n:127.0.0.1:38875_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> 641197 INFO (searcherExecutor-3830-thread-1-processing-n:127.0.0.1:38875_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:38875_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@1b83e081[.system_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={java.version=14-ea, java.vm.version=14-ea+32-1423, lucene.version=9.0.0, source=flush, os.arch=amd64, java.runtime.version=14-ea+32-1423, os.version=5.3.0-26-generic, java.vendor=Oracle Corporation, os=Linux, timestamp=1580179071793}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 641199 INFO (Thread-3930) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 641228 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 641228 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 641228 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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=23
[junit4] 2> 641228 ERROR (managedSchemaExecutor-3834-thread-1-processing-n:127.0.0.1:38875_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:38875/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 641229 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 641230 INFO (Thread-3930) [ ] o.a.s.s.IndexSchema [.system_shard1_replica_n2] Schema name=_system collection or core
[junit4] 2> 641230 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 641230 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 641230 ERROR (managedSchemaExecutor-3834-thread-1-processing-n:127.0.0.1:38875_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:38875/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 641231 INFO (qtp2010501755-14254) [n:127.0.0.1:38875_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> 641231 INFO (qtp2010501755-14254) [n:127.0.0.1:38875_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> 641231 INFO (qtp2010501755-14254) [n:127.0.0.1:38875_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> 641231 ERROR (managedSchemaExecutor-3834-thread-1-processing-n:127.0.0.1:38875_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:38875/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 641232 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 641232 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 641232 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 641232 ERROR (managedSchemaExecutor-3834-thread-1-processing-n:127.0.0.1:38875_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:38875/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 641232 INFO (Thread-3930) [ ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id
[junit4] 2> 641232 INFO (Thread-3930) [ ] o.a.s.c.CoreContainer Reloading SolrCore '.system_shard1_replica_n2' using configuration from configset .system
[junit4] 2> 641233 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 641233 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 641233 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 641233 ERROR (managedSchemaExecutor-3834-thread-1-processing-n:127.0.0.1:38875_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:38875/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 641235 INFO (qtp2010501755-14254) [n:127.0.0.1:38875_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> 641235 INFO (qtp2010501755-14254) [n:127.0.0.1:38875_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> 641236 INFO (qtp2010501755-14254) [n:127.0.0.1:38875_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> 641236 ERROR (managedSchemaExecutor-3834-thread-1-processing-n:127.0.0.1:38875_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:38875/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 641236 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 641238 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 641238 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 641238 ERROR (managedSchemaExecutor-3834-thread-1-processing-n:127.0.0.1:38875_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:38875/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 641239 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 641239 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 641239 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 641240 ERROR (managedSchemaExecutor-3834-thread-1-processing-n:127.0.0.1:38875_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:38875/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 641240 INFO (qtp2010501755-14254) [n:127.0.0.1:38875_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> 641241 INFO (qtp2010501755-14254) [n:127.0.0.1:38875_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> 641241 INFO (qtp2010501755-14254) [n:127.0.0.1:38875_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> 641241 ERROR (managedSchemaExecutor-3834-thread-1-processing-n:127.0.0.1:38875_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:38875/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 641242 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 641242 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 641242 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 641242 ERROR (managedSchemaExecutor-3834-thread-1-processing-n:127.0.0.1:38875_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:38875/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 641243 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 641243 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 641243 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 641243 ERROR (managedSchemaExecutor-3834-thread-1-processing-n:127.0.0.1:38875_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:38875/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 641244 INFO (qtp2010501755-14254) [n:127.0.0.1:38875_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> 641244 INFO (qtp2010501755-14254) [n:127.0.0.1:38875_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> 641244 INFO (qtp2010501755-14254) [n:127.0.0.1:38875_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> 641244 ERROR (managedSchemaExecutor-3834-thread-1-processing-n:127.0.0.1:38875_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:38875/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 641246 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 641246 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 641246 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 641246 ERROR (managedSchemaExecutor-3834-thread-1-processing-n:127.0.0.1:38875_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:38875/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 641247 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 641247 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 641247 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 641247 ERROR (managedSchemaExecutor-3834-thread-1-processing-n:127.0.0.1:38875_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:38875/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 641248 INFO (qtp2010501755-14254) [n:127.0.0.1:38875_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> 641249 INFO (qtp2010501755-14254) [n:127.0.0.1:38875_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> 641249 INFO (qtp2010501755-14254) [n:127.0.0.1:38875_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> 641250 ERROR (managedSchemaExecutor-3834-thread-1-processing-n:127.0.0.1:38875_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:38875/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 641250 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 641251 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 641251 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 641251 ERROR (managedSchemaExecutor-3834-thread-1-processing-n:127.0.0.1:38875_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:38875/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 641252 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 641252 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 641252 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 641252 ERROR (managedSchemaExecutor-3834-thread-1-processing-n:127.0.0.1:38875_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:38875/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 641252 INFO (qtp2010501755-14254) [n:127.0.0.1:38875_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> 641253 INFO (qtp2010501755-14254) [n:127.0.0.1:38875_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> 641253 INFO (qtp2010501755-14254) [n:127.0.0.1:38875_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> 641253 ERROR (managedSchemaExecutor-3834-thread-1-processing-n:127.0.0.1:38875_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:38875/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 641253 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 641257 INFO (Thread-3930) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38875.solr.core..system.shard1.replica_n2' (registry 'solr.core..system.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5ab12508
[junit4] 2> 641258 INFO (Thread-3930) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.c.SolrCore [[.system_shard1_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J4/temp/solr.cloud.SystemCollectionCompatTest_DE1BAEA8659A57BB-001/tempDir-001/node2/.system_shard1_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J4/temp/solr.cloud.SystemCollectionCompatTest_DE1BAEA8659A57BB-001/tempDir-001/node2/./.system_shard1_replica_n2/data/]
[junit4] 2> 641259 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 641259 INFO (qtp2010501755-14253) [n:127.0.0.1:38875_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> 641259 ERROR (managedSchemaExecutor-3834-thread-1-processing-n:127.0.0.1:38875_solr x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node4) [n:127.0.0.1:38875_solr c:.system s:shard1 r:core_node4 x:.system_shard1_replica_n2 ] o.a.s.s.ManagedIndexSchema Replica https://127.0.0.1:38875/solr/.system_shard1_replica_n1/ returned schema version 0 and has not applied schema version 2
[junit4] 2> 641260 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 641260 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 641260 INFO (qtp2010501755-14257) [n:127.0.0.1:38875_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> 641260 ERROR (managedSchemaExecutor-3834-thread-1-processing-n:127.0.0.1:38875_solr x:.system_shard1_r
[...truncated too long message...]
he.solr.client.solrj.SolrServerException: Server refused connection at: https://127.0.0.1:33833/solr/.system_shard1_replica_n2
[junit4] 2> 144345 WARN (managedSchemaExecutor-37-thread-1-processing-n:127.0.0.1:33833_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:33833_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Failed to get /schema/zkversion from https://127.0.0.1:33833/solr/.system_shard1_replica_n2/ due to: java.util.concurrent.ExecutionException: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: https://127.0.0.1:33833/solr/.system_shard1_replica_n2
[junit4] 2> 144345 WARN (managedSchemaExecutor-37-thread-1-processing-n:127.0.0.1:33833_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:33833_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Failed to get /schema/zkversion from https://127.0.0.1:33833/solr/.system_shard1_replica_n2/ due to: java.util.concurrent.ExecutionException: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: https://127.0.0.1:33833/solr/.system_shard1_replica_n2
[junit4] 2> 144345 WARN (managedSchemaExecutor-37-thread-1-processing-n:127.0.0.1:33833_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:33833_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Failed to get /schema/zkversion from https://127.0.0.1:33833/solr/.system_shard1_replica_n2/ due to: java.util.concurrent.ExecutionException: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: https://127.0.0.1:33833/solr/.system_shard1_replica_n2
[junit4] 2> 144345 WARN (managedSchemaExecutor-37-thread-1-processing-n:127.0.0.1:33833_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:33833_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Failed to get /schema/zkversion from https://127.0.0.1:33833/solr/.system_shard1_replica_n2/ due to: java.util.concurrent.ExecutionException: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: https://127.0.0.1:33833/solr/.system_shard1_replica_n2
[junit4] 2> 144346 WARN (managedSchemaExecutor-37-thread-1-processing-n:127.0.0.1:33833_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) [n:127.0.0.1:33833_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Failed to get /schema/zkversion from https://127.0.0.1:33833/solr/.system_shard1_replica_n2/ due to: java.util.concurrent.ExecutionException: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: https://127.0.0.1:33833/solr/.system_shard1_replica_n2
[junit4] 2> 144348 WARN (qtp2103392744-161) [n:127.0.0.1:33833_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Core core_node3 was interrupted waiting for schema version 2 to propagate to 1 replicas for collection .system
[junit4] 2> 144348 INFO (qtp2103392744-161) [n:127.0.0.1:33833_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Took 135768.0ms for 1 replicas to apply schema update version 2 for collection .system
[junit4] 2> 144348 INFO (qtp2103392744-161) [n:127.0.0.1:33833_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/schema params={wt=javabin&version=2} status=0 QTime=136009
[junit4] 2> 144348 INFO (qtp2103392744-161) [n:127.0.0.1:33833_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [.system_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@5beae892
[junit4] 2> 144348 WARN (qtp2103392744-161) [n:127.0.0.1:33833_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Interrupted while trying to obtain lock to modify reporters registry: solr.core..system.shard1.replica_n1
[junit4] 2> 144348 INFO (qtp2103392744-161) [n:127.0.0.1:33833_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection..system.shard1.leader, tag=SolrCore@5beae892
[junit4] 2> 144349 INFO (qtp2103392744-161) [n:127.0.0.1:33833_solr c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() .
[junit4] 2> 144349 INFO (qtp2103392744-161) [n:127.0.0.1:33833_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@62e21e60 commitCommandVersion:0
[junit4] 2> 144353 INFO (SUITE-SystemCollectionCompatTest-seed#[DE1BAEA8659A57BB]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 144558 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 7 /solr/aliases.json
[junit4] 2> 6 /solr/collections/.system/terms/shard1
[junit4] 2> 4 /solr/configs/.system/managed-schema
[junit4] 2> 2 /solr/configs/.system
[junit4] 2> 2 /solr/packages.json
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/collections/.system/collectionprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 17 /solr/collections/.system/state.json
[junit4] 2> 7 /solr/clusterstate.json
[junit4] 2> 7 /solr/clusterprops.json
[junit4] 2> 6 /solr/aliases.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 26 /solr/overseer/queue
[junit4] 2> 18 /solr/collections
[junit4] 2> 11 /solr/live_nodes
[junit4] 2> 10 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 144559 INFO (SUITE-SystemCollectionCompatTest-seed#[DE1BAEA8659A57BB]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:44545
[junit4] 2> 144560 INFO (SUITE-SystemCollectionCompatTest-seed#[DE1BAEA8659A57BB]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:44545
[junit4] 2> 144560 INFO (SUITE-SystemCollectionCompatTest-seed#[DE1BAEA8659A57BB]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 44545
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J3/temp/solr.cloud.SystemCollectionCompatTest_DE1BAEA8659A57BB-001
[junit4] 2> Jan 28, 2020 2:58:27 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {id=FST50, type=PostingsFormat(name=Asserting)}, docValues:{timestamp_l=DocValuesFormat(name=Asserting), _version_=DocValuesFormat(name=Lucene80), time_l=DocValuesFormat(name=Lucene80), timestamp=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=1292, maxMBSortInHeap=7.796003727125319, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@6c5663ef), locale=lag, timezone=Asia/Vientiane
[junit4] 2> NOTE: Linux 5.3.0-26-generic amd64/Oracle Corporation 14-ea (64-bit)/cpus=16,threads=2,free=57583032,total=130023424
[junit4] 2> NOTE: All tests run in this JVM: [SystemCollectionCompatTest]
[junit4] Completed [5/5 (1!)] on J3 in 144.54s, 1 test, 1 error <<< FAILURES!
[...truncated 13 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/lucene/common-build.xml:1510: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/lucene/common-build.xml:1037: There were test failures: 5 suites, 5 tests, 1 error [seed: DE1BAEA8659A57BB]
Total time: 2 minutes 37 seconds
[repro] Setting last failure code to 256
[repro] Failures w/original seeds:
[repro] 1/5 failed: org.apache.solr.cloud.SystemCollectionCompatTest
[repro] Exiting with code 256
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
No credentials specified
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all warnings.
<Git Blamer> GIT_COMMIT=1a14c67426fcfb8834399722150f1f6cb56264da, workspace=/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux
[WARNINGS] Computing warning deltas based on reference build #323
Recording test results
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2