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/03/18 06:08:22 UTC
[JENKINS] Lucene-Solr-8.5-MacOSX (64bit/jdk1.8.0) - Build # 34 -
Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.5-MacOSX/34/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseSerialGC
3 tests failed.
FAILED: org.apache.solr.cloud.HttpPartitionWithTlogReplicasTest.test
Error Message:
Timeout occurred while waiting response from server at: http://127.0.0.1:62212/iw_h/s
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: http://127.0.0.1:62212/iw_h/s
at __randomizedtesting.SeedInfo.seed([14443BEF90E91F31:9C1004353E1572C9]:0)
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)
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:211)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:228)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createServers(AbstractFullDistribZkTestBase.java:338)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1080)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
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:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
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 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:368)
at java.lang.Thread.run(Thread.java:748)
Caused by: java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
at java.net.SocketInputStream.read(SocketInputStream.java:171)
at java.net.SocketInputStream.read(SocketInputStream.java:141)
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)
... 43 more
FAILED: org.apache.solr.cloud.HttpPartitionWithTlogReplicasTest.test
Error Message:
Timeout occurred while waiting response from server at: http://127.0.0.1:49720/iw_h/s
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: http://127.0.0.1:49720/iw_h/s
at __randomizedtesting.SeedInfo.seed([14443BEF90E91F31:9C1004353E1572C9]:0)
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)
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:211)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:228)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createServers(AbstractFullDistribZkTestBase.java:338)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1080)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
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:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
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 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:368)
at java.lang.Thread.run(Thread.java:748)
Caused by: java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
at java.net.SocketInputStream.read(SocketInputStream.java:171)
at java.net.SocketInputStream.read(SocketInputStream.java:141)
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)
... 43 more
FAILED: org.apache.solr.cloud.LeaderFailoverAfterPartitionTest.test
Error Message:
Timeout occurred while waiting response from server at: http://127.0.0.1:49721/xxnh/y
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: http://127.0.0.1:49721/xxnh/y
at __randomizedtesting.SeedInfo.seed([14443BEF90E91F31:9C1004353E1572C9]:0)
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)
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:211)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:228)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createServers(AbstractFullDistribZkTestBase.java:338)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1080)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
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:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
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 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:368)
at java.lang.Thread.run(Thread.java:748)
Caused by: java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
at java.net.SocketInputStream.read(SocketInputStream.java:171)
at java.net.SocketInputStream.read(SocketInputStream.java:141)
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)
... 43 more
Build Log:
[...truncated 13253 lines...]
[junit4] Suite: org.apache.solr.cloud.HttpPartitionWithTlogReplicasTest
[junit4] 2> 6945 INFO (SUITE-HttpPartitionWithTlogReplicasTest-seed#[14443BEF90E91F31]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/server/solr/configsets/_default/conf'
[junit4] 2> 6996 INFO (SUITE-HttpPartitionWithTlogReplicasTest-seed#[14443BEF90E91F31]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 7020 INFO (SUITE-HttpPartitionWithTlogReplicasTest-seed#[14443BEF90E91F31]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionWithTlogReplicasTest_14443BEF90E91F31-001/data-dir-1-001
[junit4] 2> 7419 INFO (SUITE-HttpPartitionWithTlogReplicasTest-seed#[14443BEF90E91F31]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 7668 INFO (SUITE-HttpPartitionWithTlogReplicasTest-seed#[14443BEF90E91F31]-worker) [ ] o.e.j.u.log Logging initialized @7738ms to org.eclipse.jetty.util.log.Slf4jLog
[junit4] 2> 7692 INFO (SUITE-HttpPartitionWithTlogReplicasTest-seed#[14443BEF90E91F31]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-5776) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 7817 INFO (SUITE-HttpPartitionWithTlogReplicasTest-seed#[14443BEF90E91F31]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /iw_h/s
[junit4] 2> 8030 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 8036 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 8036 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 8396 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer start zk server on port:49705
[junit4] 2> 8396 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:49705
[junit4] 2> 8408 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:49705
[junit4] 2> 8409 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 49705
[junit4] 2> 8760 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 8868 INFO (zkConnectionManagerCallback-3-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 8869 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 8949 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 8954 INFO (zkConnectionManagerCallback-5-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 8954 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 9045 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 9100 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 9115 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 9125 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 9132 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 9145 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 9152 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 9161 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 9166 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 9171 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 9180 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 9188 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use TLOG replicas unless explicitly asked otherwise
[junit4] 2> 11353 WARN (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 11353 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 11353 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 11372 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 1.8.0_201-b09
[junit4] 2> 11399 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 11399 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 11412 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 11476 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@10965aae{/iw_h/s,null,AVAILABLE}
[junit4] 2> 11518 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7b9721e4{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:49737}
[junit4] 2> 11518 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.e.j.s.Server Started @11600ms
[junit4] 2> 11518 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionWithTlogReplicasTest_14443BEF90E91F31-001/tempDir-001/control/data, hostContext=/iw_h/s, hostPort=49720, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionWithTlogReplicasTest_14443BEF90E91F31-001/control-001/cores}
[junit4] 2> 12015 ERROR (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 12015 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 12015 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.5.0
[junit4] 2> 12021 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 12021 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr
[junit4] 2> 12021 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-18T04:59:52.057Z
[junit4] 2> 12114 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 12123 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 12128 INFO (zkConnectionManagerCallback-7-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 12128 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 12248 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 12253 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionWithTlogReplicasTest_14443BEF90E91F31-001/control-001/solr.xml
[junit4] 2> 12343 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 12343 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 12418 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@f0cf914, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 15351 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 15755 WARN (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1a7da632[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 15755 WARN (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1a7da632[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 16691 WARN (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7f521cd6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 16691 WARN (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7f521cd6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 16774 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49705/solr
[junit4] 2> 16849 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 16853 INFO (zkConnectionManagerCallback-14-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 16853 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 16986 INFO (zkConnectionManagerCallback-16-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 16997 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [n:127.0.0.1:49720_iw_h%2Fs ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 16997 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [n:127.0.0.1:49720_iw_h%2Fs ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 17949 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [n:127.0.0.1:49720_iw_h%2Fs ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:49720_iw_h%2Fs
[junit4] 2> 17955 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [n:127.0.0.1:49720_iw_h%2Fs ] o.a.s.c.Overseer Overseer (id=72155371872387076-127.0.0.1:49720_iw_h%2Fs-n_0000000000) starting
[junit4] 2> 18503 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [n:127.0.0.1:49720_iw_h%2Fs ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49720_iw_h%2Fs
[junit4] 2> 18504 INFO (OverseerStateUpdate-72155371872387076-127.0.0.1:49720_iw_h%2Fs-n_0000000000) [n:127.0.0.1:49720_iw_h%2Fs ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:49720_iw_h%2Fs
[junit4] 2> 18512 INFO (OverseerStateUpdate-72155371872387076-127.0.0.1:49720_iw_h%2Fs-n_0000000000) [n:127.0.0.1:49720_iw_h%2Fs ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 18680 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [n:127.0.0.1:49720_iw_h%2Fs ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 18752 WARN (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [n:127.0.0.1:49720_iw_h%2Fs ] 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> 19244 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [n:127.0.0.1:49720_iw_h%2Fs ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 19620 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [n:127.0.0.1:49720_iw_h%2Fs ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f0cf914
[junit4] 2> 19625 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [n:127.0.0.1:49720_iw_h%2Fs ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f0cf914
[junit4] 2> 19625 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [n:127.0.0.1:49720_iw_h%2Fs ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f0cf914
[junit4] 2> 19751 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [n:127.0.0.1:49720_iw_h%2Fs ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionWithTlogReplicasTest_14443BEF90E91F31-001/control-001/cores
[junit4] 2> 19883 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 19887 INFO (zkConnectionManagerCallback-25-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 19887 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 19893 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 19898 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49705/solr ready
[junit4] 2> 20236 INFO (SocketProxy-Acceptor-49720) [ ] o.a.s.c.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=49767,localport=49720], receiveBufferSize:408300
[junit4] 2> 20244 INFO (SocketProxy-Acceptor-49720) [ ] o.a.s.c.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=49737,localport=49768], receiveBufferSize=408300
[junit4] 2> 20616 INFO (qtp131189363-45) [ ] o.e.j.u.TypeUtil JVM Runtime does not support Modules
[junit4] 2> 20918 INFO (qtp131189363-45) [n:127.0.0.1:49720_iw_h%2Fs ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:49720_iw_h%252Fs&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 20950 INFO (OverseerThreadFactory-10-thread-1-processing-n:127.0.0.1:49720_iw_h%2Fs) [n:127.0.0.1:49720_iw_h%2Fs ] o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 21166 INFO (SocketProxy-Acceptor-49720) [ ] o.a.s.c.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=49772,localport=49720], receiveBufferSize:408300
[junit4] 2> 21167 INFO (SocketProxy-Acceptor-49720) [ ] o.a.s.c.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=49737,localport=49773], receiveBufferSize=408300
[junit4] 2> 21192 INFO (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs x:control_collection_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 21196 INFO (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs x:control_collection_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 22299 INFO (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.5.0
[junit4] 2> 22604 INFO (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema [control_collection_shard1_replica_n1] Schema name=test
[junit4] 2> 24546 WARN (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.WordDelimiterFilterFactory]. Please consult documentation how to replace it accordingly.
[junit4] 2> 24674 WARN (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.GeoHashField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 24692 WARN (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.LatLonType]. Please consult documentation how to replace it accordingly.
[junit4] 2> 24719 WARN (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.CurrencyField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 26027 INFO (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 26658 INFO (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1' using configuration from configset conf1, trusted=true
[junit4] 2> 26714 INFO (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.control_collection.shard1.replica_n1' (registry 'solr.core.control_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f0cf914
[junit4] 2> 26758 INFO (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionWithTlogReplicasTest_14443BEF90E91F31-001/control-001/cores/control_collection_shard1_replica_n1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionWithTlogReplicasTest_14443BEF90E91F31-001/control-001/cores/control_collection_shard1_replica_n1/data/]
[junit4] 2> 26918 INFO (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=14, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 28483 WARN (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 31056 INFO (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 31056 INFO (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 31086 INFO (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 31086 INFO (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 31089 INFO (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=21, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 31150 INFO (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2af2cd14[control_collection_shard1_replica_n1] main]
[junit4] 2> 31180 INFO (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 31235 INFO (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 31337 INFO (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 31377 INFO (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1661476653144997888
[junit4] 2> 31398 INFO (searcherExecutor-15-thread-1-processing-n:127.0.0.1:49720_iw_h%2Fs x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher Searcher@2af2cd14[control_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 31420 INFO (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/control_collection/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 31433 INFO (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/control_collection/leaders/shard1
[junit4] 2> 31451 INFO (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 31451 INFO (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 31451 INFO (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:49720/iw_h/s/control_collection_shard1_replica_n1/
[junit4] 2> 31455 INFO (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 31456 INFO (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:49720/iw_h/s/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 31466 INFO (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/72155371872387076-core_node2-n_0000000000
[junit4] 2> 31493 INFO (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:49720/iw_h/s/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 31520 INFO (zkCallback-15-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 31524 INFO (zkCallback-15-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 31533 INFO (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 31560 INFO (qtp131189363-47) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=10386
[junit4] 2> 31667 INFO (zkCallback-15-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 31667 INFO (zkCallback-15-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 31667 INFO (zkCallback-15-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 31812 INFO (qtp131189363-45) [n:127.0.0.1:49720_iw_h%2Fs ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 31814 INFO (qtp131189363-45) [n:127.0.0.1:49720_iw_h%2Fs ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:49720_iw_h%252Fs&wt=javabin&version=2} status=0 QTime=11015
[junit4] 2> 31818 INFO (closeThreadPool-28-thread-2) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=280943267
[junit4] 2> 31818 INFO (closeThreadPool-28-thread-2) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:49720_iw_h%2Fs
[junit4] 2> 31834 INFO (closeThreadPool-28-thread-2) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 31834 INFO (closeThreadPool-28-thread-2) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:49720_iw_h%2Fs as DOWN
[junit4] 2> 31847 INFO (coreCloseExecutor-20-thread-1) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@41717105
[junit4] 2> 31847 INFO (coreCloseExecutor-20-thread-1) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.control_collection.shard1.replica_n1, tag=SolrCore@41717105
[junit4] 2> 31848 INFO (coreCloseExecutor-20-thread-1) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@37b6f6d2: rootName = null, domain = solr.core.control_collection.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.control_collection.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@61e66e97
[junit4] 2> 31900 INFO (coreCloseExecutor-20-thread-1) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.control_collection.shard1.leader, tag=SolrCore@41717105
[junit4] 2> 31909 INFO (coreCloseExecutor-20-thread-1) [n:127.0.0.1:49720_iw_h%2Fs c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 31933 INFO (closeThreadPool-28-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 31933 INFO (closeThreadPool-28-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5333a19e: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@93e2bdc
[junit4] 2> 31940 INFO (closeThreadPool-28-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 31940 INFO (closeThreadPool-28-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2e94d4b0: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@590e855c
[junit4] 2> 31943 INFO (closeThreadPool-28-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 31943 INFO (closeThreadPool-28-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1b37aec8: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@1151275
[junit4] 2> 31943 INFO (closeThreadPool-28-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 31947 INFO (closeThreadPool-32-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72155371872387076-127.0.0.1:49720_iw_h%2Fs-n_0000000000) closing
[junit4] 2> 31947 INFO (OverseerStateUpdate-72155371872387076-127.0.0.1:49720_iw_h%2Fs-n_0000000000) [n:127.0.0.1:49720_iw_h%2Fs ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:49720_iw_h%2Fs
[junit4] 2> 31949 INFO (OverseerAutoScalingTriggerThread-72155371872387076-127.0.0.1:49720_iw_h%2Fs-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 31957 INFO (closeThreadPool-32-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72155371872387076-127.0.0.1:49720_iw_h%2Fs-n_0000000000) closing
[junit4] 2> 32066 INFO (closeThreadPool-28-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72155371872387076-127.0.0.1:49720_iw_h%2Fs-n_0000000000) closing
[junit4] 2> 32075 INFO (closeThreadPool-28-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@7b9721e4{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 32082 INFO (closeThreadPool-28-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@10965aae{/iw_h/s,null,UNAVAILABLE}
[junit4] 2> 32082 INFO (closeThreadPool-28-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 32083 WARN (closeThreadPool-28-thread-2) [ ] o.a.s.c.s.c.SocketProxy Closing 2 connections to: http://127.0.0.1:49720/iw_h/s, target: http://127.0.0.1:49737/iw_h/s
[junit4] 2> 32084 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 32301 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> 2 /solr/aliases.json
[junit4] 2> 2 /solr/collections/control_collection/terms/shard1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 8 /solr/collections/control_collection/state.json
[junit4] 2> 2 /solr/clusterstate.json
[junit4] 2> 2 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 10 /solr/overseer/queue
[junit4] 2> 4 /solr/overseer/collection-queue-work
[junit4] 2> 3 /solr/live_nodes
[junit4] 2> 2 /solr/collections
[junit4] 2>
[junit4] 2> 32304 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:49705
[junit4] 2> 32304 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:49705
[junit4] 2> 32304 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 49705
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=HttpPartitionWithTlogReplicasTest -Dtests.method=test -Dtests.seed=14443BEF90E91F31 -Dtests.slow=true -Dtests.locale=und -Dtests.timezone=Asia/Oral -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 24.5s J0 | HttpPartitionWithTlogReplicasTest.test <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: http://127.0.0.1:49720/iw_h/s
[junit4] > at __randomizedtesting.SeedInfo.seed([14443BEF90E91F31:9C1004353E1572C9]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:676)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:265)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
[junit4] > at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:368)
[junit4] > at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:296)
[junit4] > at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1143)
[junit4] > at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:906)
[junit4] > at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:838)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:228)
[junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createServers(AbstractFullDistribZkTestBase.java:338)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1080)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > Caused by: java.net.SocketTimeoutException: Read timed out
[junit4] > at java.net.SocketInputStream.socketRead0(Native Method)
[junit4] > at java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
[junit4] > at java.net.SocketInputStream.read(SocketInputStream.java:171)
[junit4] > at java.net.SocketInputStream.read(SocketInputStream.java:141)
[junit4] > at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
[junit4] > at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
[junit4] > at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
[junit4] > at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
[junit4] > at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
[junit4] > at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:157)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
[junit4] > at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
[junit4] > at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:186)
[junit4] > at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
[junit4] > at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
[junit4] > at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:564)
[junit4] > ... 43 more
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionWithTlogReplicasTest_14443BEF90E91F31-001
[junit4] 2> Mar 18, 2020 5:00:12 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): {}, docValues:{}, maxPointsInLeafNode=872, maxMBSortInHeap=7.3584339291258285, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@427e465d), locale=und, timezone=Asia/Oral
[junit4] 2> NOTE: Mac OS X 10.14.6 x86_64/Oracle Corporation 1.8.0_201 (64-bit)/cpus=6,threads=2,free=127553456,total=194772992
[junit4] 2> NOTE: All tests run in this JVM: [HttpPartitionWithTlogReplicasTest]
[junit4] Completed [2/900 (1!)] on J0 in 31.32s, 1 test, 1 error <<< FAILURES!
[...truncated 1 lines...]
[junit4] Suite: org.apache.solr.cloud.LeaderFailoverAfterPartitionTest
[junit4] 2> 6792 INFO (SUITE-LeaderFailoverAfterPartitionTest-seed#[14443BEF90E91F31]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/server/solr/configsets/_default/conf'
[junit4] 2> 6855 INFO (SUITE-LeaderFailoverAfterPartitionTest-seed#[14443BEF90E91F31]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 6881 INFO (SUITE-LeaderFailoverAfterPartitionTest-seed#[14443BEF90E91F31]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.LeaderFailoverAfterPartitionTest_14443BEF90E91F31-001/data-dir-1-001
[junit4] 2> 7263 INFO (SUITE-LeaderFailoverAfterPartitionTest-seed#[14443BEF90E91F31]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 7506 INFO (SUITE-LeaderFailoverAfterPartitionTest-seed#[14443BEF90E91F31]-worker) [ ] o.e.j.u.log Logging initialized @7567ms to org.eclipse.jetty.util.log.Slf4jLog
[junit4] 2> 7566 INFO (SUITE-LeaderFailoverAfterPartitionTest-seed#[14443BEF90E91F31]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-5776) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 7677 INFO (SUITE-LeaderFailoverAfterPartitionTest-seed#[14443BEF90E91F31]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /xxnh/y
[junit4] 2> 7799 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 7810 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 7811 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 8235 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer start zk server on port:49702
[junit4] 2> 8235 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:49702
[junit4] 2> 8243 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:49702
[junit4] 2> 8244 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 49702
[junit4] 2> 8659 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 8760 INFO (zkConnectionManagerCallback-3-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 8760 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 8835 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 8840 INFO (zkConnectionManagerCallback-5-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 8841 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 8933 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 8974 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 8981 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 8986 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 8991 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 8996 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 9001 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 9007 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 9011 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 9016 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 9021 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 9027 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise
[junit4] 2> 11285 WARN (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 11285 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 11285 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 11306 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 1.8.0_201-b09
[junit4] 2> 11338 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 11338 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 11348 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 11409 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@38c5add7{/xxnh/y,null,AVAILABLE}
[junit4] 2> 11451 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@25fc9254{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:49736}
[junit4] 2> 11451 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.e.j.s.Server Started @11532ms
[junit4] 2> 11451 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.LeaderFailoverAfterPartitionTest_14443BEF90E91F31-001/tempDir-001/control/data, hostContext=/xxnh/y, hostPort=49721, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.LeaderFailoverAfterPartitionTest_14443BEF90E91F31-001/control-001/cores}
[junit4] 2> 11996 ERROR (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 11996 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 11996 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.5.0
[junit4] 2> 12002 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 12002 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr
[junit4] 2> 12002 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-18T04:59:52.047Z
[junit4] 2> 12081 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 12087 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 12093 INFO (zkConnectionManagerCallback-7-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 12094 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 12217 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 12222 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.LeaderFailoverAfterPartitionTest_14443BEF90E91F31-001/control-001/solr.xml
[junit4] 2> 12339 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 12339 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 12399 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@6da436f, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 15312 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 15704 WARN (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5d980915[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 15704 WARN (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5d980915[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 16614 WARN (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3f91176a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 16614 WARN (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3f91176a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 16700 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49702/solr
[junit4] 2> 16777 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 16782 INFO (zkConnectionManagerCallback-14-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 16783 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 16910 INFO (zkConnectionManagerCallback-16-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 16922 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [n:127.0.0.1:49721_xxnh%2Fy ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 16922 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [n:127.0.0.1:49721_xxnh%2Fy ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 17865 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [n:127.0.0.1:49721_xxnh%2Fy ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:49721_xxnh%2Fy
[junit4] 2> 17872 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [n:127.0.0.1:49721_xxnh%2Fy ] o.a.s.c.Overseer Overseer (id=72155371865833476-127.0.0.1:49721_xxnh%2Fy-n_0000000000) starting
[junit4] 2> 18420 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [n:127.0.0.1:49721_xxnh%2Fy ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49721_xxnh%2Fy
[junit4] 2> 18421 INFO (OverseerStateUpdate-72155371865833476-127.0.0.1:49721_xxnh%2Fy-n_0000000000) [n:127.0.0.1:49721_xxnh%2Fy ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:49721_xxnh%2Fy
[junit4] 2> 18435 INFO (zkCallback-15-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 18659 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [n:127.0.0.1:49721_xxnh%2Fy ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 18726 WARN (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [n:127.0.0.1:49721_xxnh%2Fy ] 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> 19236 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [n:127.0.0.1:49721_xxnh%2Fy ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 19603 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [n:127.0.0.1:49721_xxnh%2Fy ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6da436f
[junit4] 2> 19606 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [n:127.0.0.1:49721_xxnh%2Fy ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6da436f
[junit4] 2> 19606 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [n:127.0.0.1:49721_xxnh%2Fy ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6da436f
[junit4] 2> 19744 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [n:127.0.0.1:49721_xxnh%2Fy ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.LeaderFailoverAfterPartitionTest_14443BEF90E91F31-001/control-001/cores
[junit4] 2> 19874 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 19878 INFO (zkConnectionManagerCallback-25-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 19879 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 19882 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 19889 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49702/solr ready
[junit4] 2> 20215 INFO (SocketProxy-Acceptor-49721) [ ] o.a.s.c.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=49765,localport=49721], receiveBufferSize:408300
[junit4] 2> 20224 INFO (SocketProxy-Acceptor-49721) [ ] o.a.s.c.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=49736,localport=49766], receiveBufferSize=408300
[junit4] 2> 20600 INFO (qtp1453031220-45) [ ] o.e.j.u.TypeUtil JVM Runtime does not support Modules
[junit4] 2> 20921 INFO (qtp1453031220-45) [n:127.0.0.1:49721_xxnh%2Fy ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:49721_xxnh%252Fy&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 20958 INFO (OverseerThreadFactory-10-thread-1-processing-n:127.0.0.1:49721_xxnh%2Fy) [n:127.0.0.1:49721_xxnh%2Fy ] o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 21172 INFO (SocketProxy-Acceptor-49721) [ ] o.a.s.c.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=49774,localport=49721], receiveBufferSize:408300
[junit4] 2> 21173 INFO (SocketProxy-Acceptor-49721) [ ] o.a.s.c.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=49736,localport=49775], receiveBufferSize=408300
[junit4] 2> 21198 INFO (qtp1453031220-47) [n:127.0.0.1:49721_xxnh%2Fy x:control_collection_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 21204 INFO (qtp1453031220-47) [n:127.0.0.1:49721_xxnh%2Fy x:control_collection_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 22301 INFO (qtp1453031220-47) [n:127.0.0.1:49721_xxnh%2Fy c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.5.0
[junit4] 2> 22603 INFO (qtp1453031220-47) [n:127.0.0.1:49721_xxnh%2Fy c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema [control_collection_shard1_replica_n1] Schema name=test
[junit4] 2> 24472 WARN (qtp1453031220-47) [n:127.0.0.1:49721_xxnh%2Fy c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.WordDelimiterFilterFactory]. Please consult documentation how to replace it accordingly.
[junit4] 2> 24588 WARN (qtp1453031220-47) [n:127.0.0.1:49721_xxnh%2Fy c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.GeoHashField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 24615 WARN (qtp1453031220-47) [n:127.0.0.1:49721_xxnh%2Fy c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.LatLonType]. Please consult documentation how to replace it accordingly.
[junit4] 2> 24647 WARN (qtp1453031220-47) [n:127.0.0.1:49721_xxnh%2Fy c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.CurrencyField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 25948 INFO (qtp1453031220-47) [n:127.0.0.1:49721_xxnh%2Fy c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 26533 INFO (qtp1453031220-47) [n:127.0.0.1:49721_xxnh%2Fy c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1' using configuration from configset conf1, trusted=true
[junit4] 2> 26605 INFO (qtp1453031220-47) [n:127.0.0.1:49721_xxnh%2Fy c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.control_collection.shard1.replica_n1' (registry 'solr.core.control_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6da436f
[junit4] 2> 26676 INFO (qtp1453031220-47) [n:127.0.0.1:49721_xxnh%2Fy c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.LeaderFailoverAfterPartitionTest_14443BEF90E91F31-001/control-001/cores/control_collection_shard1_replica_n1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.LeaderFailoverAfterPartitionTest_14443BEF90E91F31-001/control-001/cores/control_collection_shard1_replica_n1/data/]
[junit4] 2> 26835 INFO (qtp1453031220-47) [n:127.0.0.1:49721_xxnh%2Fy c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=49, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=922337203685
[...truncated too long message...]
9149 INFO (closeThreadPool-32-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72155631502163972-127.0.0.1:62212_iw_h%2Fs-n_0000000000) closing
[junit4] 2> 59293 INFO (closeThreadPool-27-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72155631502163972-127.0.0.1:62212_iw_h%2Fs-n_0000000000) closing
[junit4] 2> 59339 INFO (closeThreadPool-27-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@28a880e3{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 59349 INFO (closeThreadPool-27-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@66101df8{/iw_h/s,null,UNAVAILABLE}
[junit4] 2> 59349 INFO (closeThreadPool-27-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 59353 WARN (closeThreadPool-27-thread-2) [ ] o.a.s.c.s.c.SocketProxy Closing 2 connections to: http://127.0.0.1:62212/iw_h/s, target: http://127.0.0.1:62217/iw_h/s
[junit4] 2> 59354 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 59573 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> 2 /solr/aliases.json
[junit4] 2> 2 /solr/collections/control_collection/terms/shard1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 7 /solr/collections/control_collection/state.json
[junit4] 2> 2 /solr/clusterstate.json
[junit4] 2> 2 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 11 /solr/overseer/queue
[junit4] 2> 4 /solr/overseer/collection-queue-work
[junit4] 2> 3 /solr/live_nodes
[junit4] 2> 2 /solr/collections
[junit4] 2>
[junit4] 2> 59591 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:62194
[junit4] 2> 59591 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:62194
[junit4] 2> 59591 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[14443BEF90E91F31]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 62194
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=HttpPartitionWithTlogReplicasTest -Dtests.method=test -Dtests.seed=14443BEF90E91F31 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=ja-JP -Dtests.timezone=Europe/Sarajevo -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 47.2s J0 | HttpPartitionWithTlogReplicasTest.test <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: http://127.0.0.1:62212/iw_h/s
[junit4] > at __randomizedtesting.SeedInfo.seed([14443BEF90E91F31:9C1004353E1572C9]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:676)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:265)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
[junit4] > at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:368)
[junit4] > at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:296)
[junit4] > at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1143)
[junit4] > at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:906)
[junit4] > at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:838)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:228)
[junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createServers(AbstractFullDistribZkTestBase.java:338)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1080)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > Caused by: java.net.SocketTimeoutException: Read timed out
[junit4] > at java.net.SocketInputStream.socketRead0(Native Method)
[junit4] > at java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
[junit4] > at java.net.SocketInputStream.read(SocketInputStream.java:171)
[junit4] > at java.net.SocketInputStream.read(SocketInputStream.java:141)
[junit4] > at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
[junit4] > at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
[junit4] > at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
[junit4] > at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
[junit4] > at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
[junit4] > at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:157)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
[junit4] > at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
[junit4] > at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:186)
[junit4] > at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
[junit4] > at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
[junit4] > at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:564)
[junit4] > ... 43 more
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionWithTlogReplicasTest_14443BEF90E91F31-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {}, docValues:{}, maxPointsInLeafNode=872, maxMBSortInHeap=7.3584339291258285, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@562d2740), locale=ja-JP, timezone=Europe/Sarajevo
[junit4] 2> NOTE: Mac OS X 10.14.6 x86_64/Oracle Corporation 1.8.0_201 (64-bit)/cpus=6,threads=2,free=128375880,total=194772992
[junit4] 2> NOTE: All tests run in this JVM: [HttpPartitionWithTlogReplicasTest]
[junit4] Completed [5/10 (5!)] on J0 in 58.04s, 1 test, 1 error <<< FAILURES!
[...truncated 37 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/lucene/common-build.xml:1594: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/lucene/common-build.xml:1121: There were test failures: 10 suites, 10 tests, 5 errors [seed: 14443BEF90E91F31]
Total time: 2 minutes 7 seconds
[repro] Setting last failure code to 256
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.cloud.LeaderFailoverAfterPartitionTest
[repro] 5/5 failed: org.apache.solr.cloud.HttpPartitionWithTlogReplicasTest
[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=/Users/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=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
No credentials specified
Setting ANT_1_8_2_HOME=/Users/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=1050e459027c35d15d4d1ea91a6c14cf2052594f, workspace=/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX
[WARNINGS] Computing warning deltas based on reference build #33
Recording test results
Setting ANT_1_8_2_HOME=/Users/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=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[JENKINS] Lucene-Solr-8.5-MacOSX (64bit/jdk-11.0.6) - Build # 35 -
Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.5-MacOSX/35/
Java: 64bit/jdk-11.0.6 -XX:-UseCompressedOops -XX:+UseParallelGC
2 tests failed.
FAILED: org.apache.solr.core.TestDynamicLoading.testDynamicLoading
Error Message:
{ "responseHeader":{ "status":500, "QTime":30219}, "errorMessages":["3 out of 5 the property overlay to be of version 0 within 30 seconds! Failed cores: [https://127.0.0.1:52025/wze/collection1_shard2_replica_n1/, https://127.0.0.1:52065/wze/collection1_shard2_replica_n6/, https://127.0.0.1:52087/wze/collection1_shard1_replica_n5/]\n"], "WARNING":"This response format is experimental. It is likely to change in the future.", "error":{ "metadata":[ "error-class","org.apache.solr.common.SolrException", "root-error-class","org.apache.solr.common.SolrException"], "msg":"3 out of 5 the property overlay to be of version 0 within 30 seconds! Failed cores: [https://127.0.0.1:52025/wze/collection1_shard2_replica_n1/, https://127.0.0.1:52065/wze/collection1_shard2_replica_n6/, https://127.0.0.1:52087/wze/collection1_shard1_replica_n5/]", "trace":"org.apache.solr.common.SolrException: 3 out of 5 the property overlay to be of version 0 within 30 seconds! Failed cores: [https://127.0.0.1:52025/wze/collection1_shard2_replica_n1/, https://127.0.0.1:52065/wze/collection1_shard2_replica_n6/, https://127.0.0.1:52087/wze/collection1_shard1_replica_n5/]\n\tat org.apache.solr.handler.SolrConfigHandler.waitForAllReplicasState(SolrConfigHandler.java:813)\n\tat org.apache.solr.handler.SolrConfigHandler.access$400(SolrConfigHandler.java:104)\n\tat org.apache.solr.handler.SolrConfigHandler$Command.handleCommands(SolrConfigHandler.java:522)\n\tat org.apache.solr.handler.SolrConfigHandler$Command.handlePOST(SolrConfigHandler.java:363)\n\tat org.apache.solr.handler.SolrConfigHandler$Command.access$100(SolrConfigHandler.java:161)\n\tat org.apache.solr.handler.SolrConfigHandler.handleRequestBody(SolrConfigHandler.java:139)\n\tat org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:211)\n\tat org.apache.solr.core.SolrCore.execute(SolrCore.java:2596)\n\tat org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:802)\n\tat org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:579)\n\tat org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:420)\n\tat org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:352)\n\tat org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)\n\tat org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166)\n\tat org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)\n\tat org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:545)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)\n\tat org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1607)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)\n\tat org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1297)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)\n\tat org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:485)\n\tat org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1577)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)\n\tat org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1212)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)\n\tat org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)\n\tat org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322)\n\tat org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:717)\n\tat org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)\n\tat org.eclipse.jetty.server.Server.handle(Server.java:500)\n\tat org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:383)\n\tat org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:547)\n\tat org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375)\n\tat org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:270)\n\tat org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)\n\tat org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)\n\tat org.eclipse.jetty.io.ssl.SslConnection$1.run(SslConnection.java:146)\n\tat org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:806)\n\tat org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:938)\n\tat java.base/java.lang.Thread.run(Thread.java:834)\n", "code":500}} expected null, but was:<[3 out of 5 the property overlay to be of version 0 within 30 seconds! Failed cores: [https://127.0.0.1:52025/wze/collection1_shard2_replica_n1/, https://127.0.0.1:52065/wze/collection1_shard2_replica_n6/, https://127.0.0.1:52087/wze/collection1_shard1_replica_n5/] ]>
Stack Trace:
java.lang.AssertionError: {
"responseHeader":{
"status":500,
"QTime":30219},
"errorMessages":["3 out of 5 the property overlay to be of version 0 within 30 seconds! Failed cores: [https://127.0.0.1:52025/wze/collection1_shard2_replica_n1/, https://127.0.0.1:52065/wze/collection1_shard2_replica_n6/, https://127.0.0.1:52087/wze/collection1_shard1_replica_n5/]\n"],
"WARNING":"This response format is experimental. It is likely to change in the future.",
"error":{
"metadata":[
"error-class","org.apache.solr.common.SolrException",
"root-error-class","org.apache.solr.common.SolrException"],
"msg":"3 out of 5 the property overlay to be of version 0 within 30 seconds! Failed cores: [https://127.0.0.1:52025/wze/collection1_shard2_replica_n1/, https://127.0.0.1:52065/wze/collection1_shard2_replica_n6/, https://127.0.0.1:52087/wze/collection1_shard1_replica_n5/]",
"trace":"org.apache.solr.common.SolrException: 3 out of 5 the property overlay to be of version 0 within 30 seconds! Failed cores: [https://127.0.0.1:52025/wze/collection1_shard2_replica_n1/, https://127.0.0.1:52065/wze/collection1_shard2_replica_n6/, https://127.0.0.1:52087/wze/collection1_shard1_replica_n5/]\n\tat org.apache.solr.handler.SolrConfigHandler.waitForAllReplicasState(SolrConfigHandler.java:813)\n\tat org.apache.solr.handler.SolrConfigHandler.access$400(SolrConfigHandler.java:104)\n\tat org.apache.solr.handler.SolrConfigHandler$Command.handleCommands(SolrConfigHandler.java:522)\n\tat org.apache.solr.handler.SolrConfigHandler$Command.handlePOST(SolrConfigHandler.java:363)\n\tat org.apache.solr.handler.SolrConfigHandler$Command.access$100(SolrConfigHandler.java:161)\n\tat org.apache.solr.handler.SolrConfigHandler.handleRequestBody(SolrConfigHandler.java:139)\n\tat org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:211)\n\tat org.apache.solr.core.SolrCore.execute(SolrCore.java:2596)\n\tat org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:802)\n\tat org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:579)\n\tat org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:420)\n\tat org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:352)\n\tat org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)\n\tat org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166)\n\tat org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)\n\tat org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:545)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)\n\tat org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1607)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)\n\tat org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1297)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)\n\tat org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:485)\n\tat org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1577)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)\n\tat org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1212)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)\n\tat org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)\n\tat org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322)\n\tat org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:717)\n\tat org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)\n\tat org.eclipse.jetty.server.Server.handle(Server.java:500)\n\tat org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:383)\n\tat org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:547)\n\tat org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375)\n\tat org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:270)\n\tat org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)\n\tat org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)\n\tat org.eclipse.jetty.io.ssl.SslConnection$1.run(SslConnection.java:146)\n\tat org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:806)\n\tat org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:938)\n\tat java.base/java.lang.Thread.run(Thread.java:834)\n",
"code":500}}
expected null, but was:<[3 out of 5 the property overlay to be of version 0 within 30 seconds! Failed cores: [https://127.0.0.1:52025/wze/collection1_shard2_replica_n1/, https://127.0.0.1:52065/wze/collection1_shard2_replica_n6/, https://127.0.0.1:52087/wze/collection1_shard1_replica_n5/]
]>
at __randomizedtesting.SeedInfo.seed([1CB8A3A034CE39A:D986A76DF491463A]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotNull(Assert.java:755)
at org.junit.Assert.assertNull(Assert.java:737)
at org.apache.solr.core.TestSolrConfigHandler.runConfigCommand(TestSolrConfigHandler.java:179)
at org.apache.solr.core.TestDynamicLoading.testDynamicLoading(TestDynamicLoading.java:65)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
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:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
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:368)
at java.base/java.lang.Thread.run(Thread.java:834)
FAILED: org.apache.solr.core.TestDynamicLoading.testDynamicLoading
Error Message:
{ "responseHeader":{ "status":500, "QTime":30066}, "errorMessages":["4 out of 5 the property overlay to be of version 0 within 30 seconds! Failed cores: [https://127.0.0.1:49286/wze/collection1_shard2_replica_n1/, https://127.0.0.1:49299/wze/collection1_shard2_replica_n6/, https://127.0.0.1:49293/wze/collection1_shard1_replica_n2/, https://127.0.0.1:49314/wze/collection1_shard1_replica_n5/]\n"], "WARNING":"This response format is experimental. It is likely to change in the future.", "error":{ "metadata":[ "error-class","org.apache.solr.common.SolrException", "root-error-class","org.apache.solr.common.SolrException"], "msg":"4 out of 5 the property overlay to be of version 0 within 30 seconds! Failed cores: [https://127.0.0.1:49286/wze/collection1_shard2_replica_n1/, https://127.0.0.1:49299/wze/collection1_shard2_replica_n6/, https://127.0.0.1:49293/wze/collection1_shard1_replica_n2/, https://127.0.0.1:49314/wze/collection1_shard1_replica_n5/]", "trace":"org.apache.solr.common.SolrException: 4 out of 5 the property overlay to be of version 0 within 30 seconds! Failed cores: [https://127.0.0.1:49286/wze/collection1_shard2_replica_n1/, https://127.0.0.1:49299/wze/collection1_shard2_replica_n6/, https://127.0.0.1:49293/wze/collection1_shard1_replica_n2/, https://127.0.0.1:49314/wze/collection1_shard1_replica_n5/]\n\tat org.apache.solr.handler.SolrConfigHandler.waitForAllReplicasState(SolrConfigHandler.java:813)\n\tat org.apache.solr.handler.SolrConfigHandler.access$400(SolrConfigHandler.java:104)\n\tat org.apache.solr.handler.SolrConfigHandler$Command.handleCommands(SolrConfigHandler.java:522)\n\tat org.apache.solr.handler.SolrConfigHandler$Command.handlePOST(SolrConfigHandler.java:363)\n\tat org.apache.solr.handler.SolrConfigHandler$Command.access$100(SolrConfigHandler.java:161)\n\tat org.apache.solr.handler.SolrConfigHandler.handleRequestBody(SolrConfigHandler.java:139)\n\tat org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:211)\n\tat org.apache.solr.core.SolrCore.execute(SolrCore.java:2596)\n\tat org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:802)\n\tat org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:579)\n\tat org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:420)\n\tat org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:352)\n\tat org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)\n\tat org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166)\n\tat org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)\n\tat org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:545)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)\n\tat org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1607)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)\n\tat org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1297)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)\n\tat org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:485)\n\tat org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1577)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)\n\tat org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1212)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)\n\tat org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)\n\tat org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322)\n\tat org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:717)\n\tat org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)\n\tat org.eclipse.jetty.server.Server.handle(Server.java:500)\n\tat org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:383)\n\tat org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:547)\n\tat org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375)\n\tat org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:270)\n\tat org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)\n\tat org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)\n\tat org.eclipse.jetty.io.ssl.SslConnection$1.run(SslConnection.java:146)\n\tat org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:806)\n\tat org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:938)\n\tat java.base/java.lang.Thread.run(Thread.java:834)\n", "code":500}} expected null, but was:<[4 out of 5 the property overlay to be of version 0 within 30 seconds! Failed cores: [https://127.0.0.1:49286/wze/collection1_shard2_replica_n1/, https://127.0.0.1:49299/wze/collection1_shard2_replica_n6/, https://127.0.0.1:49293/wze/collection1_shard1_replica_n2/, https://127.0.0.1:49314/wze/collection1_shard1_replica_n5/] ]>
Stack Trace:
java.lang.AssertionError: {
"responseHeader":{
"status":500,
"QTime":30066},
"errorMessages":["4 out of 5 the property overlay to be of version 0 within 30 seconds! Failed cores: [https://127.0.0.1:49286/wze/collection1_shard2_replica_n1/, https://127.0.0.1:49299/wze/collection1_shard2_replica_n6/, https://127.0.0.1:49293/wze/collection1_shard1_replica_n2/, https://127.0.0.1:49314/wze/collection1_shard1_replica_n5/]\n"],
"WARNING":"This response format is experimental. It is likely to change in the future.",
"error":{
"metadata":[
"error-class","org.apache.solr.common.SolrException",
"root-error-class","org.apache.solr.common.SolrException"],
"msg":"4 out of 5 the property overlay to be of version 0 within 30 seconds! Failed cores: [https://127.0.0.1:49286/wze/collection1_shard2_replica_n1/, https://127.0.0.1:49299/wze/collection1_shard2_replica_n6/, https://127.0.0.1:49293/wze/collection1_shard1_replica_n2/, https://127.0.0.1:49314/wze/collection1_shard1_replica_n5/]",
"trace":"org.apache.solr.common.SolrException: 4 out of 5 the property overlay to be of version 0 within 30 seconds! Failed cores: [https://127.0.0.1:49286/wze/collection1_shard2_replica_n1/, https://127.0.0.1:49299/wze/collection1_shard2_replica_n6/, https://127.0.0.1:49293/wze/collection1_shard1_replica_n2/, https://127.0.0.1:49314/wze/collection1_shard1_replica_n5/]\n\tat org.apache.solr.handler.SolrConfigHandler.waitForAllReplicasState(SolrConfigHandler.java:813)\n\tat org.apache.solr.handler.SolrConfigHandler.access$400(SolrConfigHandler.java:104)\n\tat org.apache.solr.handler.SolrConfigHandler$Command.handleCommands(SolrConfigHandler.java:522)\n\tat org.apache.solr.handler.SolrConfigHandler$Command.handlePOST(SolrConfigHandler.java:363)\n\tat org.apache.solr.handler.SolrConfigHandler$Command.access$100(SolrConfigHandler.java:161)\n\tat org.apache.solr.handler.SolrConfigHandler.handleRequestBody(SolrConfigHandler.java:139)\n\tat org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:211)\n\tat org.apache.solr.core.SolrCore.execute(SolrCore.java:2596)\n\tat org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:802)\n\tat org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:579)\n\tat org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:420)\n\tat org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:352)\n\tat org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)\n\tat org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166)\n\tat org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)\n\tat org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:545)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)\n\tat org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1607)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)\n\tat org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1297)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)\n\tat org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:485)\n\tat org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1577)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)\n\tat org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1212)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)\n\tat org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)\n\tat org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322)\n\tat org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:717)\n\tat org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)\n\tat org.eclipse.jetty.server.Server.handle(Server.java:500)\n\tat org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:383)\n\tat org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:547)\n\tat org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375)\n\tat org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:270)\n\tat org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)\n\tat org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)\n\tat org.eclipse.jetty.io.ssl.SslConnection$1.run(SslConnection.java:146)\n\tat org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:806)\n\tat org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:938)\n\tat java.base/java.lang.Thread.run(Thread.java:834)\n",
"code":500}}
expected null, but was:<[4 out of 5 the property overlay to be of version 0 within 30 seconds! Failed cores: [https://127.0.0.1:49286/wze/collection1_shard2_replica_n1/, https://127.0.0.1:49299/wze/collection1_shard2_replica_n6/, https://127.0.0.1:49293/wze/collection1_shard1_replica_n2/, https://127.0.0.1:49314/wze/collection1_shard1_replica_n5/]
]>
at __randomizedtesting.SeedInfo.seed([1CB8A3A034CE39A:D986A76DF491463A]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotNull(Assert.java:755)
at org.junit.Assert.assertNull(Assert.java:737)
at org.apache.solr.core.TestSolrConfigHandler.runConfigCommand(TestSolrConfigHandler.java:179)
at org.apache.solr.core.TestDynamicLoading.testDynamicLoading(TestDynamicLoading.java:65)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
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:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
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:368)
at java.base/java.lang.Thread.run(Thread.java:834)
Build Log:
[...truncated 14607 lines...]
[junit4] Suite: org.apache.solr.core.TestDynamicLoading
[junit4] 2> 892764 INFO (SUITE-TestDynamicLoading-seed#[1CB8A3A034CE39A]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/server/solr/configsets/_default/conf'
[junit4] 2> 892764 INFO (SUITE-TestDynamicLoading-seed#[1CB8A3A034CE39A]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 892765 INFO (SUITE-TestDynamicLoading-seed#[1CB8A3A034CE39A]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading_1CB8A3A034CE39A-001/data-dir-96-001
[junit4] 2> 892766 WARN (SUITE-TestDynamicLoading-seed#[1CB8A3A034CE39A]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
[junit4] 2> 892766 INFO (SUITE-TestDynamicLoading-seed#[1CB8A3A034CE39A]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 892766 INFO (SUITE-TestDynamicLoading-seed#[1CB8A3A034CE39A]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 892767 INFO (SUITE-TestDynamicLoading-seed#[1CB8A3A034CE39A]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /wze/
[junit4] 2> 892775 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 892776 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 892776 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 892882 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.ZkTestServer start zk server on port:49230
[junit4] 2> 892882 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:49230
[junit4] 2> 892882 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:49230
[junit4] 2> 892882 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 49230
[junit4] 2> 892886 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 892892 INFO (zkConnectionManagerCallback-4054-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 892892 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 892908 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 892910 INFO (zkConnectionManagerCallback-4056-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 892911 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 892915 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 892920 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 892931 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 892935 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 892939 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 892943 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 892952 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 892957 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 892964 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 892973 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 892986 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 893008 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 893009 INFO (zkConnectionManagerCallback-4060-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 893010 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 893129 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise
[junit4] 2> 893804 WARN (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 893804 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 893804 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 893804 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.6+10
[junit4] 2> 893808 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 893808 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 893808 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 893810 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@189b6823{/wze,null,AVAILABLE}
[junit4] 2> 893812 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5c861fb{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:49239}
[junit4] 2> 893812 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.e.j.s.Server Started @893885ms
[junit4] 2> 893812 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/wze, solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading_1CB8A3A034CE39A-001/tempDir-001/control/data, hostPort=49239, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading_1CB8A3A034CE39A-001/control-001/cores, replicaType=NRT}
[junit4] 2> 893813 ERROR (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 893813 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 893813 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.5.0
[junit4] 2> 893813 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 893813 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr
[junit4] 2> 893813 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-18T12:36:18.266710Z
[junit4] 2> 893817 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 893819 INFO (zkConnectionManagerCallback-4062-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 893819 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 893926 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 893926 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading_1CB8A3A034CE39A-001/control-001/solr.xml
[junit4] 2> 893930 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 893930 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 893931 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@52bd2878, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 894215 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 894217 WARN (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4a675b50[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 894217 WARN (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4a675b50[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 894226 WARN (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@28c34314[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 894226 WARN (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@28c34314[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 894228 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49230/solr
[junit4] 2> 894230 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 894238 INFO (zkConnectionManagerCallback-4069-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 894238 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 894348 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [n:127.0.0.1:49239_wze ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 894351 INFO (zkConnectionManagerCallback-4071-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 894352 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [n:127.0.0.1:49239_wze ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 894784 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [n:127.0.0.1:49239_wze ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:49239_wze
[junit4] 2> 894786 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [n:127.0.0.1:49239_wze ] o.a.s.c.Overseer Overseer (id=72157166822817797-127.0.0.1:49239_wze-n_0000000000) starting
[junit4] 2> 894802 INFO (OverseerStateUpdate-72157166822817797-127.0.0.1:49239_wze-n_0000000000) [n:127.0.0.1:49239_wze ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:49239_wze
[junit4] 2> 894803 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [n:127.0.0.1:49239_wze ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49239_wze
[junit4] 2> 894806 INFO (OverseerStateUpdate-72157166822817797-127.0.0.1:49239_wze-n_0000000000) [n:127.0.0.1:49239_wze ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 894816 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [n:127.0.0.1:49239_wze ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 894817 WARN (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [n:127.0.0.1:49239_wze ] 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> 894842 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [n:127.0.0.1:49239_wze ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 894873 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [n:127.0.0.1:49239_wze ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52bd2878
[junit4] 2> 894883 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [n:127.0.0.1:49239_wze ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52bd2878
[junit4] 2> 894883 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [n:127.0.0.1:49239_wze ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52bd2878
[junit4] 2> 894886 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [n:127.0.0.1:49239_wze ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading_1CB8A3A034CE39A-001/control-001/cores
[junit4] 2> 894904 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 894907 INFO (zkConnectionManagerCallback-4080-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 894907 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 894910 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 894914 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49230/solr ready
[junit4] 2> 894932 INFO (qtp1960755947-13237) [n:127.0.0.1:49239_wze ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:49239_wze&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 894946 INFO (OverseerThreadFactory-4559-thread-1-processing-n:127.0.0.1:49239_wze) [n:127.0.0.1:49239_wze ] o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 895083 INFO (qtp1960755947-13235) [n:127.0.0.1:49239_wze x:control_collection_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 895083 INFO (qtp1960755947-13235) [n:127.0.0.1:49239_wze x:control_collection_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 896230 INFO (qtp1960755947-13235) [n:127.0.0.1:49239_wze c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.5.0
[junit4] 2> 896271 INFO (qtp1960755947-13235) [n:127.0.0.1:49239_wze c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema [control_collection_shard1_replica_n1] Schema name=test
[junit4] 2> 896464 INFO (qtp1960755947-13235) [n:127.0.0.1:49239_wze c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 896652 INFO (qtp1960755947-13235) [n:127.0.0.1:49239_wze c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1' using configuration from configset conf1, trusted=true
[junit4] 2> 896655 INFO (qtp1960755947-13235) [n:127.0.0.1:49239_wze c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.control_collection.shard1.replica_n1' (registry 'solr.core.control_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52bd2878
[junit4] 2> 896655 INFO (qtp1960755947-13235) [n:127.0.0.1:49239_wze c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading_1CB8A3A034CE39A-001/control-001/cores/control_collection_shard1_replica_n1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading_1CB8A3A034CE39A-001/control-001/cores/control_collection_shard1_replica_n1/data/]
[junit4] 2> 896658 INFO (qtp1960755947-13235) [n:127.0.0.1:49239_wze c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=42.9111328125, floorSegmentMB=2.0595703125, forceMergeDeletesPctAllowed=20.443327529237894, segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=31.009999031422645
[junit4] 2> 896665 WARN (qtp1960755947-13235) [n:127.0.0.1:49239_wze c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 896743 INFO (qtp1960755947-13235) [n:127.0.0.1:49239_wze c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 896743 INFO (qtp1960755947-13235) [n:127.0.0.1:49239_wze c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 896826 INFO (qtp1960755947-13235) [n:127.0.0.1:49239_wze c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 896826 INFO (qtp1960755947-13235) [n:127.0.0.1:49239_wze c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 896827 INFO (qtp1960755947-13235) [n:127.0.0.1:49239_wze c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=43, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=64.2314453125, floorSegmentMB=1.2177734375, forceMergeDeletesPctAllowed=9.429363824141678, segmentsPerTier=11.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.25086854580407225, deletesPctAllowed=30.897404216116254
[junit4] 2> 896828 INFO (qtp1960755947-13235) [n:127.0.0.1:49239_wze c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@71496112[control_collection_shard1_replica_n1] main]
[junit4] 2> 896858 INFO (qtp1960755947-13235) [n:127.0.0.1:49239_wze c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 896860 INFO (qtp1960755947-13235) [n:127.0.0.1:49239_wze c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 896861 INFO (qtp1960755947-13235) [n:127.0.0.1:49239_wze c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 896861 INFO (qtp1960755947-13235) [n:127.0.0.1:49239_wze c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1661505352566308864
[junit4] 2> 896872 INFO (searcherExecutor-4564-thread-1-processing-n:127.0.0.1:49239_wze x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:49239_wze c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher Searcher@71496112[control_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 896916 INFO (qtp1960755947-13235) [n:127.0.0.1:49239_wze c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/control_collection/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 896916 INFO (qtp1960755947-13235) [n:127.0.0.1:49239_wze c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/control_collection/leaders/shard1
[junit4] 2> 897006 INFO (qtp1960755947-13235) [n:127.0.0.1:49239_wze c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 897006 INFO (qtp1960755947-13235) [n:127.0.0.1:49239_wze c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 897007 INFO (qtp1960755947-13235) [n:127.0.0.1:49239_wze c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:49239/wze/control_collection_shard1_replica_n1/
[junit4] 2> 897009 INFO (qtp1960755947-13235) [n:127.0.0.1:49239_wze c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 897013 INFO (qtp1960755947-13235) [n:127.0.0.1:49239_wze c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:49239/wze/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 897013 INFO (qtp1960755947-13235) [n:127.0.0.1:49239_wze c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/72157166822817797-core_node2-n_0000000000
[junit4] 2> 897032 INFO (qtp1960755947-13235) [n:127.0.0.1:49239_wze c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:49239/wze/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 897153 INFO (zkCallback-4070-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 897154 INFO (zkCallback-4070-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 897157 INFO (qtp1960755947-13235) [n:127.0.0.1:49239_wze c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 897173 INFO (qtp1960755947-13235) [n:127.0.0.1:49239_wze c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2090
[junit4] 2> 897183 INFO (qtp1960755947-13237) [n:127.0.0.1:49239_wze ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 897284 INFO (zkCallback-4070-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 897284 INFO (zkCallback-4070-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 897285 INFO (zkCallback-4070-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 897299 INFO (qtp1960755947-13237) [n:127.0.0.1:49239_wze ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:49239_wze&wt=javabin&version=2} status=0 QTime=2367
[junit4] 2> 897302 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Waiting to see 1 active replicas in collection: control_collection
[junit4] 2> 897426 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 897434 INFO (zkConnectionManagerCallback-4086-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 897434 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 897485 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 897551 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49230/solr ready
[junit4] 2> 897551 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 897566 INFO (qtp1960755947-13233) [n:127.0.0.1:49239_wze ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 897583 INFO (OverseerThreadFactory-4559-thread-2-processing-n:127.0.0.1:49239_wze) [n:127.0.0.1:49239_wze ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 897584 INFO (OverseerCollectionConfigSetProcessor-72157166822817797-127.0.0.1:49239_wze-n_0000000000) [n:127.0.0.1:49239_wze ] 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> 897807 WARN (OverseerThreadFactory-4559-thread-2-processing-n:127.0.0.1:49239_wze) [n:127.0.0.1:49239_wze ] o.a.s.c.a.c.CreateCollectionCmd It is unusual to create a collection (collection1) without cores.
[junit4] 2> 897813 INFO (qtp1960755947-13233) [n:127.0.0.1:49239_wze ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 897818 INFO (qtp1960755947-13233) [n:127.0.0.1:49239_wze ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=1&wt=javabin&version=2} status=0 QTime=251
[junit4] 2> 897822 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected:2
[junit4] 2> 897822 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 897822 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected:2
[junit4] 2> 897822 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 897822 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected:2
[junit4] 2> 897822 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 897822 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating jetty instances pullReplicaCount=0 numOtherReplicas=4
[junit4] 2> 898612 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading_1CB8A3A034CE39A-001/shard-1-001 of type NRT for shard2
[junit4] 2> 898622 WARN (closeThreadPool-4087-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 898623 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 898624 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 898624 INFO (closeThreadPool-4087-thread-1) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.6+10
[junit4] 2> 898640 INFO (closeThreadPool-4087-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 898640 INFO (closeThreadPool-4087-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 898640 INFO (closeThreadPool-4087-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 898641 INFO (closeThreadPool-4087-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@49483d43{/wze,null,AVAILABLE}
[junit4] 2> 898646 INFO (closeThreadPool-4087-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2e247343{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:49286}
[junit4] 2> 898646 INFO (closeThreadPool-4087-thread-1) [ ] o.e.j.s.Server Started @898718ms
[junit4] 2> 898646 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/wze, solrconfig=solrconfig.xml, solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading_1CB8A3A034CE39A-001/tempDir-001/jetty1, hostPort=49286, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading_1CB8A3A034CE39A-001/shard-1-001/cores}
[junit4] 2> 898649 ERROR (closeThreadPool-4087-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 898649 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 898649 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.5.0
[junit4] 2> 898649 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 898649 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr
[junit4] 2> 898649 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-18T12:36:23.102648Z
[junit4] 2> 898651 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 898660 INFO (zkConnectionManagerCallback-4089-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 898660 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 898769 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 898769 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading_1CB8A3A034CE39A-001/shard-1-001/solr.xml
[junit4] 2> 898772 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 898772 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 898774 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@52bd2878, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 898991 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 898993 WARN (closeThreadPool-4087-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@25f24c02[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 898993 WARN (closeThreadPool-4087-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@25f24c02[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 899000 WARN (closeThreadPool-4087-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@17ecd473[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 899000 WARN (closeThreadPool-4087-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@17ecd473[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 899001 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49230/solr
[junit4] 2> 899002 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 899006 INFO (zkConnectionManagerCallback-4096-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 899006 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 899118 INFO (closeThreadPool-4087-thread-1) [n:127.0.0.1:49286_wze ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 899125 INFO (zkConnectionManagerCallback-4098-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 899125 INFO (closeThreadPool-4087-thread-1) [n:127.0.0.1:49286_wze ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 899150 INFO (closeThreadPool-4087-thread-1) [n:127.0.0.1:49286_wze ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 899170 INFO (closeThreadPool-4087-thread-1) [n:127.0.0.1:49286_wze ] o.a.s.c.ZkController Publish node=127.0.0.1:49286_wze as DOWN
[junit4] 2> 899175 INFO (closeThreadPool-4087-thread-1) [n:127.0.0.1:49286_wze ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 899175 INFO (closeThreadPool-4087-thread-1) [n:127.0.0.1:49286_wze ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49286_wze
[junit4] 2> 899181 INFO (zkCallback-4070-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 899181 INFO (zkCallback-4085-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 899182 INFO (zkCallback-4097-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 899188 INFO (closeThreadPool-4087-thread-1) [n:127.0.0.1:49286_wze ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 899188 WARN (closeThreadPool-4087-thread-1) [n:127.0.0.1:49286_wze ] 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> 899264 INFO (closeThreadPool-4087-thread-1) [n:127.0.0.1:49286_wze ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 899302 INFO (closeThreadPool-4087-thread-1) [n:127.0.0.1:49286_wze ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52bd2878
[junit4] 2> 899319 INFO (closeThreadPool-4087-thread-1) [n:127.0.0.1:49286_wze ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52bd2878
[junit4] 2> 899322 INFO (closeThreadPool-4087-thread-1) [n:127.0.0.1:49286_wze ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52bd2878
[junit4] 2> 899327 INFO (closeThreadPool-4087-thread-1) [n:127.0.0.1:49286_wze ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading_1CB8A3A034CE39A-001/shard-1-001/cores
[junit4] 2> 899406 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:49286_wze
[junit4] 2> 899472 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading_1CB8A3A034CE39A-001/shard-2-001 of type NRT for shard1
[junit4] 2> 899480 WARN (closeThreadPool-4087-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 899480 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 899480 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 899480 INFO (closeThreadPool-4087-thread-1) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.6+10
[junit4] 2> 899491 INFO (closeThreadPool-4087-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 899491 INFO (closeThreadPool-4087-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 899492 INFO (closeThreadPool-4087-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 899496 INFO (closeThreadPool-4087-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@469d1bad{/wze,null,AVAILABLE}
[junit4] 2> 899502 INFO (closeThreadPool-4087-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3174d872{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:49293}
[junit4] 2> 899502 INFO (closeThreadPool-4087-thread-1) [ ] o.e.j.s.Server Started @899575ms
[junit4] 2> 899502 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/wze, solrconfig=solrconfig.xml, solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading_1CB8A3A034CE39A-001/tempDir-001/jetty2, hostPort=49293, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading_1CB8A3A034CE39A-001/shard-2-001/cores}
[junit4] 2> 899505 ERROR (closeThreadPool-4087-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 899505 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 899506 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.5.0
[junit4] 2> 899506 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 899506 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr
[junit4] 2> 899508 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-18T12:36:23.961715Z
[junit4] 2> 899508 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 899517 INFO (zkConnectionManagerCallback-4104-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 899518 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 899593 INFO (OverseerCollectionConfigSetProcessor-72157166822817797-127.0.0.1:49239_wze-n_0000000000) [n:127.0.0.1:49239_wze ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 899625 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 899625 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading_1CB8A3A034CE39A-001/shard-2-001/solr.xml
[junit4] 2> 899629 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 899629 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 899631 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@52bd2878, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 899767 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 899769 WARN (closeThreadPool-4087-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@17b2c0be[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 899769 WARN (closeThreadPool-4087-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@17b2c0be[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 899774 WARN (closeThreadPool-4087-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4dd86d08[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 899775 WARN (closeThreadPool-4087-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4dd86d08[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 899776 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49230/solr
[junit4] 2> 899778 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 899781 INFO (zkConnectionManagerCallback-4111-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 899781 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 899898 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 899905 INFO (zkConnectionManagerCallback-4113-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 899905 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 899942 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 899966 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:49293_wze as DOWN
[junit4] 2> 899971 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 899971 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49293_wze
[junit4] 2> 899979 INFO (zkCallback-4070-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 899979 INFO (zkCallback-4112-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 899979 INFO (zkCallback-4085-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 899979 INFO (zkCallback-4097-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 899989 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 899991 WARN (closeThreadPool-4087-thread-1) [ ] 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> 900032 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 900062 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52bd2878
[junit4] 2> 900078 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52bd2878
[junit4] 2> 900078 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52bd2878
[junit4] 2> 900083 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading_1CB8A3A034CE39A-001/shard-2-001/cores
[junit4] 2> 900148 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:49293_wze
[junit4] 2> 900210 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading_1CB8A3A034CE39A-001/shard-3-001 of type NRT for shard2
[junit4] 2> 900223 WARN (closeThreadPool-4087-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 900223 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 900223 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 900223 INFO (closeThreadPool-4087-thread-1) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.6+10
[junit4] 2> 900233 INFO (closeThreadPool-4087-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 900233 INFO (closeThreadPool-4087-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 900233 INFO (closeThreadPool-4087-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 900234 INFO (closeThreadPool-4087-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@789f7cae{/wze,null,AVAILABLE}
[junit4] 2> 900245 INFO (closeThreadPool-4087-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@23b7f71c{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:49299}
[junit4] 2> 900245 INFO (closeThreadPool-4087-thread-1) [ ] o.e.j.s.Server Started @900317ms
[junit4] 2> 900245 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/wze, solrconfig=solrconfig.xml, solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading_1CB8A3A034CE39A-001/tempDir-001/jetty3, hostPort=49299, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading_1CB8A3A034CE39A-001/shard-3-001/cores}
[junit4] 2> 900246 ERROR (closeThreadPool-4087-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 900246 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 900248 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.5.0
[junit4] 2> 900248 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 900248 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr
[junit4] 2> 900248 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-18T12:36:24.701387Z
[junit4] 2> 900256 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 900259 INFO (zkConnectionManagerCallback-4119-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 900269 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 900376 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 900376 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading_1CB8A3A034CE39A-001/shard-3-001/solr.xml
[junit4] 2> 900379 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 900380 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 900383 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@52bd2878, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 900773 INFO (closeThreadPool-4087-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 900776 WARN (closeThreadPool-4087-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@567c70a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 900776 WARN (closeThreadPool-4087-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@567c70a[provider=null,keyStore=n
[...truncated too long message...]
ForServerDown: 127.0.0.1:51943
[junit4] 2> 88286 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:51943
[junit4] 2> 88286 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[1CB8A3A034CE39A]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 51943
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDynamicLoading -Dtests.method=testDynamicLoading -Dtests.seed=1CB8A3A034CE39A -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=en-HK -Dtests.timezone=Antarctica/Macquarie -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 80.0s J0 | TestDynamicLoading.testDynamicLoading <<<
[junit4] > Throwable #1: java.lang.AssertionError: {
[junit4] > "responseHeader":{
[junit4] > "status":500,
[junit4] > "QTime":30219},
[junit4] > "errorMessages":["3 out of 5 the property overlay to be of version 0 within 30 seconds! Failed cores: [https://127.0.0.1:52025/wze/collection1_shard2_replica_n1/, https://127.0.0.1:52065/wze/collection1_shard2_replica_n6/, https://127.0.0.1:52087/wze/collection1_shard1_replica_n5/]\n"],
[junit4] > "WARNING":"This response format is experimental. It is likely to change in the future.",
[junit4] > "error":{
[junit4] > "metadata":[
[junit4] > "error-class","org.apache.solr.common.SolrException",
[junit4] > "root-error-class","org.apache.solr.common.SolrException"],
[junit4] > "msg":"3 out of 5 the property overlay to be of version 0 within 30 seconds! Failed cores: [https://127.0.0.1:52025/wze/collection1_shard2_replica_n1/, https://127.0.0.1:52065/wze/collection1_shard2_replica_n6/, https://127.0.0.1:52087/wze/collection1_shard1_replica_n5/]",
[junit4] > "trace":"org.apache.solr.common.SolrException: 3 out of 5 the property overlay to be of version 0 within 30 seconds! Failed cores: [https://127.0.0.1:52025/wze/collection1_shard2_replica_n1/, https://127.0.0.1:52065/wze/collection1_shard2_replica_n6/, https://127.0.0.1:52087/wze/collection1_shard1_replica_n5/]\n\tat org.apache.solr.handler.SolrConfigHandler.waitForAllReplicasState(SolrConfigHandler.java:813)\n\tat org.apache.solr.handler.SolrConfigHandler.access$400(SolrConfigHandler.java:104)\n\tat org.apache.solr.handler.SolrConfigHandler$Command.handleCommands(SolrConfigHandler.java:522)\n\tat org.apache.solr.handler.SolrConfigHandler$Command.handlePOST(SolrConfigHandler.java:363)\n\tat org.apache.solr.handler.SolrConfigHandler$Command.access$100(SolrConfigHandler.java:161)\n\tat org.apache.solr.handler.SolrConfigHandler.handleRequestBody(SolrConfigHandler.java:139)\n\tat org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:211)\n\tat org.apache.solr.core.SolrCore.execute(SolrCore.java:2596)\n\tat org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:802)\n\tat org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:579)\n\tat org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:420)\n\tat org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:352)\n\tat org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)\n\tat org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166)\n\tat org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)\n\tat org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:545)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)\n\tat org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1607)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)\n\tat org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1297)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)\n\tat org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:485)\n\tat org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1577)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)\n\tat org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1212)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)\n\tat org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)\n\tat org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322)\n\tat org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:717)\n\tat org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)\n\tat org.eclipse.jetty.server.Server.handle(Server.java:500)\n\tat org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:383)\n\tat org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:547)\n\tat org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375)\n\tat org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:270)\n\tat org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)\n\tat org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)\n\tat org.eclipse.jetty.io.ssl.SslConnection$1.run(SslConnection.java:146)\n\tat org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:806)\n\tat org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:938)\n\tat java.base/java.lang.Thread.run(Thread.java:834)\n",
[junit4] > "code":500}}
[junit4] > expected null, but was:<[3 out of 5 the property overlay to be of version 0 within 30 seconds! Failed cores: [https://127.0.0.1:52025/wze/collection1_shard2_replica_n1/, https://127.0.0.1:52065/wze/collection1_shard2_replica_n6/, https://127.0.0.1:52087/wze/collection1_shard1_replica_n5/]
[junit4] > ]>
[junit4] > at __randomizedtesting.SeedInfo.seed([1CB8A3A034CE39A:D986A76DF491463A]:0)
[junit4] > at org.apache.solr.core.TestSolrConfigHandler.runConfigCommand(TestSolrConfigHandler.java:179)
[junit4] > at org.apache.solr.core.TestDynamicLoading.testDynamicLoading(TestDynamicLoading.java:65)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:566)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:834)
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_1CB8A3A034CE39A-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {}, docValues:{}, maxPointsInLeafNode=72, maxMBSortInHeap=7.467235330106131, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@fe12ba6), locale=en-HK, timezone=Antarctica/Macquarie
[junit4] 2> NOTE: Mac OS X 10.14.6 x86_64/AdoptOpenJDK 11.0.6 (64-bit)/cpus=6,threads=2,free=89944216,total=303562752
[junit4] 2> NOTE: All tests run in this JVM: [TestDynamicLoading]
[junit4] Completed [2/5 (2!)] on J0 in 84.73s, 1 test, 1 failure <<< FAILURES!
[...truncated 26 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/lucene/common-build.xml:1594: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX/lucene/common-build.xml:1121: There were test failures: 5 suites, 5 tests, 2 failures [seed: 1CB8A3A034CE39A]
Total time: 2 minutes 6 seconds
[repro] Setting last failure code to 256
[repro] Failures w/original seeds:
[repro] 2/5 failed: org.apache.solr.core.TestDynamicLoading
[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=/Users/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=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
No credentials specified
Setting ANT_1_8_2_HOME=/Users/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=1050e459027c35d15d4d1ea91a6c14cf2052594f, workspace=/Users/jenkins/workspace/Lucene-Solr-8.5-MacOSX
[WARNINGS] Computing warning deltas based on reference build #34
Recording test results
Setting ANT_1_8_2_HOME=/Users/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=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2