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/05 03:51:20 UTC
[JENKINS] Lucene-Solr-8.x-Windows (64bit/jdk1.8.0_201) - Build #
847 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/847/
Java: 64bit/jdk1.8.0_201 -XX:+UseCompressedOops -XX:+UseSerialGC
5 tests failed.
FAILED: org.apache.solr.cloud.HttpPartitionWithTlogReplicasTest.test
Error Message:
Timeout occurred while waiting response from server at: http://127.0.0.1:55109/t_
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: http://127.0.0.1:55109/t_
at __randomizedtesting.SeedInfo.seed([888488DB980A9750:D0B70136F6FAA8]: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:55106/t_
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: http://127.0.0.1:55106/t_
at __randomizedtesting.SeedInfo.seed([888488DB980A9750:D0B70136F6FAA8]: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:55110/t_
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: http://127.0.0.1:55110/t_
at __randomizedtesting.SeedInfo.seed([888488DB980A9750:D0B70136F6FAA8]: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:55107/t_
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: http://127.0.0.1:55107/t_
at __randomizedtesting.SeedInfo.seed([888488DB980A9750:D0B70136F6FAA8]: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:59833/t_
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: http://127.0.0.1:59833/t_
at __randomizedtesting.SeedInfo.seed([888488DB980A9750:D0B70136F6FAA8]: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 13346 lines...]
[junit4] Suite: org.apache.solr.cloud.HttpPartitionWithTlogReplicasTest
[junit4] 2> 13504 INFO (SUITE-HttpPartitionWithTlogReplicasTest-seed#[888488DB980A9750]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of 'C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\server\solr\configsets\_default\conf'
[junit4] 2> 13593 INFO (SUITE-HttpPartitionWithTlogReplicasTest-seed#[888488DB980A9750]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.HttpPartitionWithTlogReplicasTest_888488DB980A9750-001\data-dir-1-001
[junit4] 2> 14161 INFO (SUITE-HttpPartitionWithTlogReplicasTest-seed#[888488DB980A9750]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 14482 INFO (SUITE-HttpPartitionWithTlogReplicasTest-seed#[888488DB980A9750]-worker) [ ] o.e.j.u.log Logging initialized @14501ms to org.eclipse.jetty.util.log.Slf4jLog
[junit4] 2> 14514 INFO (SUITE-HttpPartitionWithTlogReplicasTest-seed#[888488DB980A9750]-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)
[junit4] 2> 14653 INFO (SUITE-HttpPartitionWithTlogReplicasTest-seed#[888488DB980A9750]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 14653 INFO (SUITE-HttpPartitionWithTlogReplicasTest-seed#[888488DB980A9750]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /t_/
[junit4] 2> 14745 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 14786 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 14786 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 15879 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer start zk server on port:59762
[junit4] 2> 15879 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:59762
[junit4] 2> 15897 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:59762
[junit4] 2> 15897 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 59762
[junit4] 2> 16417 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 16731 INFO (zkConnectionManagerCallback-3-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 16733 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 16986 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 16998 INFO (zkConnectionManagerCallback-5-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 17004 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 17153 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 17210 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4] 2> 17222 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 17229 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 17234 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 17246 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
[junit4] 2> 17255 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 17260 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 17265 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 17283 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 17295 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 17298 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use TLOG replicas unless explicitly asked otherwise
[junit4] 2> 21100 WARN (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 21100 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 21100 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 21125 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] 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> 21162 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 21162 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 21171 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 21257 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@db82a2c{/t_,null,AVAILABLE}
[junit4] 2> 21313 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4b7fd559{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:59849}
[junit4] 2> 21313 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.e.j.s.Server Started @21348ms
[junit4] 2> 21313 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.HttpPartitionWithTlogReplicasTest_888488DB980A9750-001\tempDir-001/control/data, replicaType=NRT, hostContext=/t_, hostPort=59833, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.HttpPartitionWithTlogReplicasTest_888488DB980A9750-001\control-001\cores}
[junit4] 2> 21808 ERROR (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 21808 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 21808 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 21820 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 21820 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 21821 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-05T02:43:42.483Z
[junit4] 2> 21970 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 21974 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 21981 INFO (zkConnectionManagerCallback-7-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 21981 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 22094 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 22100 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.HttpPartitionWithTlogReplicasTest_888488DB980A9750-001\control-001\solr.xml
[junit4] 2> 22191 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 22191 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 22256 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@33488b8e, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 24784 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 25466 WARN (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@72dc7a94[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 25466 WARN (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@72dc7a94[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 26687 WARN (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@70e27407[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 26687 WARN (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@70e27407[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 26822 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59762/solr
[junit4] 2> 26914 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 26919 INFO (zkConnectionManagerCallback-14-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 26919 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 27063 INFO (zkConnectionManagerCallback-16-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 27071 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [n:127.0.0.1:59833_t_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 27071 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [n:127.0.0.1:59833_t_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 27563 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [n:127.0.0.1:59833_t_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:59833_t_
[junit4] 2> 27563 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [n:127.0.0.1:59833_t_ ] o.a.s.c.Overseer Overseer (id=72075734080225284-127.0.0.1:59833_t_-n_0000000000) starting
[junit4] 2> 28121 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [n:127.0.0.1:59833_t_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59833_t_
[junit4] 2> 28131 INFO (OverseerStateUpdate-72075734080225284-127.0.0.1:59833_t_-n_0000000000) [n:127.0.0.1:59833_t_ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:59833_t_
[junit4] 2> 28142 INFO (OverseerStateUpdate-72075734080225284-127.0.0.1:59833_t_-n_0000000000) [n:127.0.0.1:59833_t_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 28480 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [n:127.0.0.1:59833_t_ ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 28579 WARN (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [n:127.0.0.1:59833_t_ ] 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> 29227 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [n:127.0.0.1:59833_t_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 29668 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [n:127.0.0.1:59833_t_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@33488b8e
[junit4] 2> 29674 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [n:127.0.0.1:59833_t_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@33488b8e
[junit4] 2> 29675 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [n:127.0.0.1:59833_t_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@33488b8e
[junit4] 2> 29897 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [n:127.0.0.1:59833_t_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.HttpPartitionWithTlogReplicasTest_888488DB980A9750-001\control-001\cores
[junit4] 2> 30225 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 30227 INFO (zkConnectionManagerCallback-25-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 30227 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 30230 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 30232 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:59762/solr ready
[junit4] 2> 30734 INFO (SocketProxy-Acceptor-59833) [ ] o.a.s.c.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=59982,localport=59833], receiveBufferSize:65536
[junit4] 2> 30743 INFO (SocketProxy-Acceptor-59833) [ ] o.a.s.c.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=59849,localport=59983], receiveBufferSize=65536
[junit4] 2> 31274 INFO (qtp732300188-84) [ ] o.e.j.u.TypeUtil JVM Runtime does not support Modules
[junit4] 2> 31863 INFO (qtp732300188-84) [n:127.0.0.1:59833_t_ ] 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:59833_t_&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 31919 INFO (OverseerThreadFactory-10-thread-1-processing-n:127.0.0.1:59833_t_) [n:127.0.0.1:59833_t_ ] o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 32149 INFO (SocketProxy-Acceptor-59833) [ ] o.a.s.c.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=59990,localport=59833], receiveBufferSize:65536
[junit4] 2> 32150 INFO (SocketProxy-Acceptor-59833) [ ] o.a.s.c.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=59849,localport=59991], receiveBufferSize=65536
[junit4] 2> 32181 INFO (qtp732300188-89) [n:127.0.0.1:59833_t_ 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> 32186 INFO (qtp732300188-89) [n:127.0.0.1:59833_t_ x:control_collection_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 33305 INFO (qtp732300188-89) [n:127.0.0.1:59833_t_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 33750 INFO (qtp732300188-89) [n:127.0.0.1:59833_t_ 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> 36588 WARN (qtp732300188-89) [n:127.0.0.1:59833_t_ 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> 36768 WARN (qtp732300188-89) [n:127.0.0.1:59833_t_ 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> 36806 WARN (qtp732300188-89) [n:127.0.0.1:59833_t_ 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> 36867 WARN (qtp732300188-89) [n:127.0.0.1:59833_t_ 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> 38880 INFO (qtp732300188-89) [n:127.0.0.1:59833_t_ 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> 39783 INFO (qtp732300188-89) [n:127.0.0.1:59833_t_ 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> 39853 INFO (qtp732300188-89) [n:127.0.0.1:59833_t_ 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@33488b8e
[junit4] 2> 40000 INFO (qtp732300188-89) [n:127.0.0.1:59833_t_ 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 [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.HttpPartitionWithTlogReplicasTest_888488DB980A9750-001\control-001\cores\control_collection_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.HttpPartitionWithTlogReplicasTest_888488DB980A9750-001\control-001\cores\control_collection_shard1_replica_n1\data\]
[junit4] 2> 40278 INFO (qtp732300188-89) [n:127.0.0.1:59833_t_ 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=10, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=104.765625, floorSegmentMB=1.9990234375, forceMergeDeletesPctAllowed=18.39817070926764, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.34131966088268967, deletesPctAllowed=45.401624665589
[junit4] 2> 42781 WARN (qtp732300188-89) [n:127.0.0.1:59833_t_ 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> 46718 INFO (qtp732300188-89) [n:127.0.0.1:59833_t_ 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> 46718 INFO (qtp732300188-89) [n:127.0.0.1:59833_t_ 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> 46746 INFO (qtp732300188-89) [n:127.0.0.1:59833_t_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 46746 INFO (qtp732300188-89) [n:127.0.0.1:59833_t_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 46750 INFO (qtp732300188-89) [n:127.0.0.1:59833_t_ 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=37, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=87.0185546875, floorSegmentMB=1.755859375, forceMergeDeletesPctAllowed=20.275208870158618, segmentsPerTier=45.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6974029826773205, deletesPctAllowed=48.52187109184448
[junit4] 2> 46874 INFO (qtp732300188-89) [n:127.0.0.1:59833_t_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@34820ff3[control_collection_shard1_replica_n1] main]
[junit4] 2> 46921 INFO (qtp732300188-89) [n:127.0.0.1:59833_t_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 47041 INFO (qtp732300188-89) [n:127.0.0.1:59833_t_ 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> 47269 INFO (qtp732300188-89) [n:127.0.0.1:59833_t_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 47324 INFO (qtp732300188-89) [n:127.0.0.1:59833_t_ 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 1660290332608167936
[junit4] 2> 47344 INFO (searcherExecutor-15-thread-1-processing-n:127.0.0.1:59833_t_ x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:59833_t_ 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@34820ff3[control_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 47377 INFO (qtp732300188-89) [n:127.0.0.1:59833_t_ 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> 47397 INFO (qtp732300188-89) [n:127.0.0.1:59833_t_ 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> 47424 INFO (qtp732300188-89) [n:127.0.0.1:59833_t_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 47424 INFO (qtp732300188-89) [n:127.0.0.1:59833_t_ 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> 47424 INFO (qtp732300188-89) [n:127.0.0.1:59833_t_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:59833/t_/control_collection_shard1_replica_n1/
[junit4] 2> 47429 INFO (qtp732300188-89) [n:127.0.0.1:59833_t_ 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> 47430 INFO (qtp732300188-89) [n:127.0.0.1:59833_t_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:59833/t_/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 47452 INFO (qtp732300188-89) [n:127.0.0.1:59833_t_ 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/72075734080225284-core_node2-n_0000000000
[junit4] 2> 47477 INFO (qtp732300188-89) [n:127.0.0.1:59833_t_ 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:59833/t_/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 47487 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> 47497 INFO (qtp732300188-89) [n:127.0.0.1:59833_t_ 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> 47525 INFO (qtp732300188-89) [n:127.0.0.1:59833_t_ 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=15372
[junit4] 2> 47622 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> 47622 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> 47622 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> 47924 INFO (qtp732300188-84) [n:127.0.0.1:59833_t_ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 47925 INFO (qtp732300188-84) [n:127.0.0.1:59833_t_ ] 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:59833_t_&wt=javabin&version=2} status=0 QTime=16295
[junit4] 2> 47964 INFO (closeThreadPool-28-thread-2) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=251867793
[junit4] 2> 47964 INFO (closeThreadPool-28-thread-2) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:59833_t_
[junit4] 2> 47968 INFO (closeThreadPool-28-thread-2) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 47968 INFO (closeThreadPool-28-thread-2) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:59833_t_ as DOWN
[junit4] 2> 47988 INFO (OverseerCollectionConfigSetProcessor-72075734080225284-127.0.0.1:59833_t_-n_0000000000) [n:127.0.0.1:59833_t_ ] 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> 47989 INFO (coreCloseExecutor-20-thread-1) [n:127.0.0.1:59833_t_ 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@2f3153d5
[junit4] 2> 47989 INFO (coreCloseExecutor-20-thread-1) [n:127.0.0.1:59833_t_ 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@2f3153d5
[junit4] 2> 47990 INFO (coreCloseExecutor-20-thread-1) [n:127.0.0.1:59833_t_ 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@9542a68: 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@7c1b76a6
[junit4] 2> 48041 INFO (coreCloseExecutor-20-thread-1) [n:127.0.0.1:59833_t_ 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@2f3153d5
[junit4] 2> 48056 INFO (coreCloseExecutor-20-thread-1) [n:127.0.0.1:59833_t_ 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> 48067 INFO (closeThreadPool-28-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 48068 INFO (closeThreadPool-28-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1177979f: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@602a6239
[junit4] 2> 48076 INFO (closeThreadPool-28-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 48077 INFO (closeThreadPool-28-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2f2d167d: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@75d97d9d
[junit4] 2> 48081 INFO (closeThreadPool-28-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 48082 INFO (closeThreadPool-28-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@24baac89: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@49b9bb7e
[junit4] 2> 48082 INFO (closeThreadPool-28-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 48096 INFO (closeThreadPool-32-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72075734080225284-127.0.0.1:59833_t_-n_0000000000) closing
[junit4] 2> 48096 INFO (OverseerStateUpdate-72075734080225284-127.0.0.1:59833_t_-n_0000000000) [n:127.0.0.1:59833_t_ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:59833_t_
[junit4] 2> 48102 INFO (OverseerAutoScalingTriggerThread-72075734080225284-127.0.0.1:59833_t_-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 48106 INFO (closeThreadPool-32-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72075734080225284-127.0.0.1:59833_t_-n_0000000000) closing
[junit4] 2> 48206 INFO (closeThreadPool-28-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72075734080225284-127.0.0.1:59833_t_-n_0000000000) closing
[junit4] 2> 48216 INFO (closeThreadPool-28-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@4b7fd559{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 48228 INFO (closeThreadPool-28-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@db82a2c{/t_,null,UNAVAILABLE}
[junit4] 2> 48228 INFO (closeThreadPool-28-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 48229 WARN (closeThreadPool-28-thread-2) [ ] o.a.s.c.s.c.SocketProxy Closing 2 connections to: http://127.0.0.1:59833/t_, target: http://127.0.0.1:59849/t_
[junit4] 2> 48229 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 48444 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> 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> 48455 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:59762
[junit4] 2> 48455 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:59762
[junit4] 2> 48455 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 59762
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=HttpPartitionWithTlogReplicasTest -Dtests.method=test -Dtests.seed=888488DB980A9750 -Dtests.slow=true -Dtests.locale=de-CH -Dtests.timezone=AET -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 36.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:59833/t_
[junit4] > at __randomizedtesting.SeedInfo.seed([888488DB980A9750:D0B70136F6FAA8]: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: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.HttpPartitionWithTlogReplicasTest_888488DB980A9750-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {}, docValues:{}, maxPointsInLeafNode=816, maxMBSortInHeap=5.1950022864948995, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@24a15228), locale=de-CH, timezone=AET
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_201 (64-bit)/cpus=6,threads=2,free=137114584,total=194772992
[junit4] 2> NOTE: All tests run in this JVM: [HttpPartitionWithTlogReplicasTest]
[junit4] Completed [8/901 (1!)] on J0 in 48.82s, 1 test, 1 error <<< FAILURES!
[...truncated 55728 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/847/consoleText
[repro] Revision: 1573d3d2d83c5008dd179736feb7218906830fb0
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC"
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr\core
[repro] HttpPartitionWithTlogReplicasTest
[repro] ant compile-test
[...truncated 3440 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.HttpPartitionWithTlogReplicasTest" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=888488DB980A9750 -Dtests.slow=true -Dtests.locale=de-CH -Dtests.timezone=AET -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 35 lines...]
[junit4] Suite: org.apache.solr.cloud.HttpPartitionWithTlogReplicasTest
[junit4] 2> 14473 INFO (SUITE-HttpPartitionWithTlogReplicasTest-seed#[888488DB980A9750]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of 'C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\server\solr\configsets\_default\conf'
[junit4] 2> 14635 INFO (SUITE-HttpPartitionWithTlogReplicasTest-seed#[888488DB980A9750]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.HttpPartitionWithTlogReplicasTest_888488DB980A9750-001\data-dir-1-001
[junit4] 2> 15328 INFO (SUITE-HttpPartitionWithTlogReplicasTest-seed#[888488DB980A9750]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 15713 INFO (SUITE-HttpPartitionWithTlogReplicasTest-seed#[888488DB980A9750]-worker) [ ] o.e.j.u.log Logging initialized @15736ms to org.eclipse.jetty.util.log.Slf4jLog
[junit4] 2> 15761 INFO (SUITE-HttpPartitionWithTlogReplicasTest-seed#[888488DB980A9750]-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)
[junit4] 2> 15959 INFO (SUITE-HttpPartitionWithTlogReplicasTest-seed#[888488DB980A9750]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 15959 INFO (SUITE-HttpPartitionWithTlogReplicasTest-seed#[888488DB980A9750]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /t_/
[junit4] 2> 16139 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 16147 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 16151 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 16789 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer start zk server on port:55048
[junit4] 2> 16789 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:55048
[junit4] 2> 16811 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:55048
[junit4] 2> 16813 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 55048
[junit4] 2> 17755 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 17983 INFO (zkConnectionManagerCallback-3-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 17983 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 18124 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 18129 INFO (zkConnectionManagerCallback-5-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 18129 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 18326 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 18390 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4] 2> 18394 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 18398 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 18402 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 18413 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
[junit4] 2> 18483 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 18486 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 18491 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 18496 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 18500 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 18502 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use TLOG replicas unless explicitly asked otherwise
[junit4] 2> 21926 WARN (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 21927 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 21927 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 21950 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] 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> 21992 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 21992 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 22002 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 22085 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7b193f63{/t_,null,AVAILABLE}
[junit4] 2> 22147 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@59eee4dc{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:55111}
[junit4] 2> 22147 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.e.j.s.Server Started @22191ms
[junit4] 2> 22148 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.HttpPartitionWithTlogReplicasTest_888488DB980A9750-001\tempDir-001/control/data, replicaType=NRT, hostContext=/t_, hostPort=55107, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.HttpPartitionWithTlogReplicasTest_888488DB980A9750-001\control-001\cores}
[junit4] 2> 22693 ERROR (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 22693 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 22693 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 22701 INFO (TEST-HttpPartitionWithTlo
[...truncated too long message...]
[junit4] 2> 54872 INFO (closeThreadPool-27-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@50801be3{/t_,null,UNAVAILABLE}
[junit4] 2> 54872 INFO (closeThreadPool-27-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 54872 WARN (closeThreadPool-27-thread-2) [ ] o.a.s.c.s.c.SocketProxy Closing 2 connections to: http://127.0.0.1:55110/t_, target: http://127.0.0.1:55115/t_
[junit4] 2> 54872 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 55078 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> 5 /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> 55084 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:55053
[junit4] 2> 55084 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:55053
[junit4] 2> 55084 INFO (TEST-HttpPartitionWithTlogReplicasTest.test-seed#[888488DB980A9750]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 55053
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=HttpPartitionWithTlogReplicasTest -Dtests.method=test -Dtests.seed=888488DB980A9750 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=de-CH -Dtests.timezone=AET -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 41.2s J3 | HttpPartitionWithTlogReplicasTest.test <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: http://127.0.0.1:55110/t_
[junit4] > at __randomizedtesting.SeedInfo.seed([888488DB980A9750:D0B70136F6FAA8]: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: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.HttpPartitionWithTlogReplicasTest_888488DB980A9750-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {}, docValues:{}, maxPointsInLeafNode=816, maxMBSortInHeap=5.1950022864948995, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@42fe6c46), locale=de-CH, timezone=AET
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_201 (64-bit)/cpus=6,threads=2,free=137326840,total=194772992
[junit4] 2> NOTE: All tests run in this JVM: [HttpPartitionWithTlogReplicasTest]
[junit4] Completed [5/5 (5!)] on J3 in 54.96s, 1 test, 1 error <<< FAILURES!
[...truncated 17 lines...]
BUILD FAILED
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\common-build.xml:1594: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\common-build.xml:1121: There were test failures: 5 suites, 5 tests, 5 errors [seed: 888488DB980A9750]
Total time: 1 minute 0 seconds
[repro] Setting last failure code to 1
[repro] Failures w/original seeds:
[repro] 5/5 failed: org.apache.solr.cloud.HttpPartitionWithTlogReplicasTest
[repro] Exiting with code 1
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows>IF EXIST lucene\build move /y lucene\build lucene\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows>IF EXIST solr\build move /y solr\build solr\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows>IF EXIST lucene\build.orig move /y lucene\build.orig lucene\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows>IF EXIST solr\build.orig move /y solr\build.orig solr\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows>exit 0
Archiving artifacts
Setting ANT_1_8_2_HOME=C:\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=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
No credentials specified
Setting ANT_1_8_2_HOME=C:\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=1573d3d2d83c5008dd179736feb7218906830fb0, workspace=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows
[WARNINGS] Computing warning deltas based on reference build #846
Recording test results
Setting ANT_1_8_2_HOME=C:\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=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
[JENKINS-Experimental-GC] Lucene-Solr-8.x-Windows
(64bit/jdk-15-ea+9) - Build # 848 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/848/
Java: 64bit/jdk-15-ea+9 -XX:-UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseZGC
2 tests failed.
FAILED: org.apache.solr.cloud.RollingRestartTest.test
Error Message:
No overseer designate as leader found after restart #1: 127.0.0.1:53803_
Stack Trace:
java.lang.AssertionError: No overseer designate as leader found after restart #1: 127.0.0.1:53803_
at __randomizedtesting.SeedInfo.seed([74BFD65BBA9B6DC:8F1FC2BF1555DB24]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.apache.solr.cloud.RollingRestartTest.restartWithRolesTest(RollingRestartTest.java:101)
at org.apache.solr.cloud.RollingRestartTest.test(RollingRestartTest.java:53)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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:832)
FAILED: org.apache.solr.cloud.autoscaling.sim.TestSimScenario.testAutoAddReplicas
Error Message:
Timed out waiting for trigger .auto_add_replicas to fire after simulated 60s (real 1200ms).
Stack Trace:
java.io.IOException: Timed out waiting for trigger .auto_add_replicas to fire after simulated 60s (real 1200ms).
at __randomizedtesting.SeedInfo.seed([74BFD65BBA9B6DC:8EEB0E19CFA27AF5]:0)
at org.apache.solr.cloud.autoscaling.sim.SimScenario$SimWaitListener.wait(SimScenario.java:693)
at org.apache.solr.cloud.autoscaling.sim.SimScenario$WaitEvent.execute(SimScenario.java:758)
at org.apache.solr.cloud.autoscaling.sim.SimScenario.run(SimScenario.java:1096)
at org.apache.solr.cloud.autoscaling.sim.TestSimScenario.testAutoAddReplicas(TestSimScenario.java:57)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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 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:832)
Build Log:
[...truncated 14087 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.sim.TestSimScenario
[junit4] 2> 731844 INFO (SUITE-TestSimScenario-seed#[74BFD65BBA9B6DC]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of 'C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\server\solr\configsets\_default\conf'
[junit4] 2> 731872 INFO (SUITE-TestSimScenario-seed#[74BFD65BBA9B6DC]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.sim.TestSimScenario_74BFD65BBA9B6DC-001\data-dir-93-001
[junit4] 2> 731872 WARN (SUITE-TestSimScenario-seed#[74BFD65BBA9B6DC]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=31 numCloses=31
[junit4] 2> 731872 INFO (SUITE-TestSimScenario-seed#[74BFD65BBA9B6DC]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 731872 INFO (SUITE-TestSimScenario-seed#[74BFD65BBA9B6DC]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 731877 INFO (SUITE-TestSimScenario-seed#[74BFD65BBA9B6DC]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 731897 INFO (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testAutoAddReplicas
[junit4] 2> 731936 INFO (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario 1. CreateCluster numNodes=2
[junit4] 2> 731938 INFO (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario CreateCluster numNodes=2
[junit4] 2> 732197 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 732197 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimClusterStateProvider --- new Overseer leader: 127.0.0.1:10000_solr
[junit4] 2> 732198 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 732198 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=0
[junit4] 2> 732199 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 0
[junit4] 2> 732199 INFO (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 732200 INFO (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario 2. LoadAutoscaling json={'cluster-policy'+:+[{'replica'+:+'<3',+'shard'+:+'#EACH',+'collection'+:+'testCollection','node':'#ANY'}]}&defaultWaitFor=10
[junit4] 2> 732200 INFO (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario LoadAutoscaling json={'cluster-policy'+:+[{'replica'+:+'<3',+'shard'+:+'#EACH',+'collection'+:+'testCollection','node':'#ANY'}]}&defaultWaitFor=10
[junit4] 2> 732202 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 732202 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 732202 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:10001_solr, 127.0.0.1:10000_solr]
[junit4] 2> 732202 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 732202 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 732202 DEBUG (ScheduledTrigger-2509-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 732202 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 732202 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 732202 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:10001_solr, 127.0.0.1:10000_solr]
[junit4] 2> 732202 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 732202 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 732202 INFO (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario 3. RunSolrRequest path=/admin/collections&action=CREATE&autoAddReplicas=true&name=testCollection&numShards=2&replicationFactor=2&maxShardsPerNode=2
[junit4] 2> 732202 INFO (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario RunSolrRequest path=/admin/collections&action=CREATE&autoAddReplicas=true&name=testCollection&numShards=2&replicationFactor=2&maxShardsPerNode=2
[junit4] 2> 732209 DEBUG (ScheduledTrigger-2509-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 732212 DEBUG (simCloudManagerPool-2508-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider -- simCreateCollection testCollection, currentVersion=1
[junit4] 2> 732213 DEBUG (simCloudManagerPool-2508-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=1
[junit4] 2> 732213 DEBUG (simCloudManagerPool-2508-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 1
[junit4] 2> 732236 DEBUG (simCloudManagerPool-2508-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=2
[junit4] 2> 732237 DEBUG (ScheduledTrigger-2509-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 732241 DEBUG (simCloudManagerPool-2508-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 2
[junit4] 2> 732253 DEBUG (simCloudManagerPool-2508-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider -- finished createCollection testCollection, currentVersion=3
[junit4] 2> 732253 INFO (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario 4. WaitCollection collection=testCollection&shards=2&replicas=2
[junit4] 2> 732253 INFO (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario WaitCollection collection=testCollection&shards=2&replicas=2
[junit4] 2> 732255 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=3
[junit4] 2> 732255 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 3
[junit4] 2> 732255 INFO (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.CloudUtil -- shards without leaders: [shard2, shard1]
[junit4] 2> 732256 DEBUG (ScheduledTrigger-2509-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 732276 DEBUG (ScheduledTrigger-2509-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 732278 DEBUG (simCloudManagerPool-2508-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=4
[junit4] 2> 732278 DEBUG (simCloudManagerPool-2508-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 4
[junit4] 2> 732278 DEBUG (simCloudManagerPool-2508-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider Running leader election (testCollection / shard2)
[junit4] 2> 732278 DEBUG (simCloudManagerPool-2508-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testCollection / shard2 (currentVersion=5): {"core_node3":{
[junit4] 2> "core":"testCollection_shard2_replica_n3",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10001_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 732300 DEBUG (ScheduledTrigger-2509-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 732300 DEBUG (simCloudManagerPool-2508-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=5
[junit4] 2> 732300 DEBUG (simCloudManagerPool-2508-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 5
[junit4] 2> 732323 DEBUG (simCloudManagerPool-2508-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=6
[junit4] 2> 732324 DEBUG (simCloudManagerPool-2508-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 6
[junit4] 2> 732324 DEBUG (simCloudManagerPool-2508-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider Running leader election (testCollection / shard1)
[junit4] 2> 732324 DEBUG (simCloudManagerPool-2508-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testCollection / shard1 (currentVersion=7): {"core_node1":{
[junit4] 2> "core":"testCollection_shard1_replica_n1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10000_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 732326 DEBUG (ScheduledTrigger-2509-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 732334 DEBUG (simCloudManagerPool-2508-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=7
[junit4] 2> 732335 DEBUG (simCloudManagerPool-2508-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 7
[junit4] 2> 732336 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=8
[junit4] 2> 732336 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 8
[junit4] 2> 732336 INFO (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario 5. SetEventListener trigger=.auto_add_replicas&stage=SUCCEEDED
[junit4] 2> 732336 INFO (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario SetEventListener trigger=.auto_add_replicas&stage=SUCCEEDED
[junit4] 2> 732338 INFO (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario 6. KillNodes node=${_random_node_}
[junit4] 2> 732338 INFO (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario KillNodes node=127.0.0.1:10000_solr
[junit4] 2> 732339 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimClusterStateProvider -- created marker: /autoscaling/nodeLost/127.0.0.1:10000_solr
[junit4] 2> 732339 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimClusterStateProvider --- new Overseer leader: 127.0.0.1:10001_solr
[junit4] 2> 732339 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=9
[junit4] 2> 732340 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 9
[junit4] 2> 732340 INFO (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario 7. WaitEvent trigger=.auto_add_replicas&wait=60
[junit4] 2> 732340 INFO (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario WaitEvent trigger=.auto_add_replicas&wait=60
[junit4] 2> 732348 DEBUG (simCloudManagerPool-2508-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=10
[junit4] 2> 732348 DEBUG (ScheduledTrigger-2509-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 2
[junit4] 2> 732348 DEBUG (ScheduledTrigger-2509-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Tracking lost node: 127.0.0.1:10000_solr
[junit4] 2> 732348 DEBUG (simCloudManagerPool-2508-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 10
[junit4] 2> 732348 DEBUG (simCloudManagerPool-2508-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider Running leader election (testCollection / shard1)
[junit4] 2> 732348 INFO (simCloudManagerPool-2508-thread-7) [ ] o.a.s.c.ActionThrottle Throttling leader attempts - waiting for 3723ms
[junit4] 2> 732390 DEBUG (ScheduledTrigger-2509-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 732416 DEBUG (ScheduledTrigger-2509-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 732424 DEBUG (simCloudManagerPool-2508-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testCollection / shard1 (currentVersion=11): {"core_node2":{
[junit4] 2> "core":"testCollection_shard1_replica_n2",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10001_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 732437 DEBUG (ScheduledTrigger-2509-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 732460 DEBUG (ScheduledTrigger-2509-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 732481 DEBUG (ScheduledTrigger-2509-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 732505 DEBUG (ScheduledTrigger-2509-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 732536 DEBUG (ScheduledTrigger-2509-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 732584 DEBUG (ScheduledTrigger-2509-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 732584 DEBUG (ScheduledTrigger-2509-thread-4) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger firing registered processor for lost nodes: [127.0.0.1:10000_solr]
[junit4] 2> 732584 DEBUG (ScheduledTrigger-2509-thread-4) [ ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
[junit4] 2> "id":"10c0eaaeeac44Tcb40i30mtvbgjm51w4ur1ioi",
[junit4] 2> "source":".auto_add_replicas",
[junit4] 2> "eventTime":294732113554500,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[294732113554500],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "nodeNames":["127.0.0.1:10000_solr"]}}
[junit4] 2> 732584 DEBUG (ScheduledTrigger-2509-thread-4) [ ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: [.auto_add_replicas, .scheduled_maintenance]
[junit4] 2> 732584 DEBUG (ScheduledTrigger-2509-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=11
[junit4] 2> 732586 DEBUG (ScheduledTrigger-2509-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 11
[junit4] 2> 732586 DEBUG (ScheduledTrigger-2509-thread-4) [ ] o.a.s.c.a.SystemLogListener Collection .system missing, skip sending event {
[junit4] 2> "id":"10c0eaaeeac44Tcb40i30mtvbgjm51w4ur1ioi",
[junit4] 2> "source":".auto_add_replicas",
[junit4] 2> "eventTime":294732113554500,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[294732113554500],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":294743938929500,
[junit4] 2> "nodeNames":["127.0.0.1:10000_solr"]}}
[junit4] 2> 732616 DEBUG (AutoscalingActionExecutor-2510-thread-1) [ ] o.a.s.c.a.ScheduledTriggers -- processing actions for {
[junit4] 2> "id":"10c0eaaeeac44Tcb40i30mtvbgjm51w4ur1ioi",
[junit4] 2> "source":".auto_add_replicas",
[junit4] 2> "eventTime":294732113554500,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[294732113554500],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":294743938929500,
[junit4] 2> "nodeNames":["127.0.0.1:10000_solr"]}}
[junit4] 2> 732616 DEBUG (AutoscalingActionExecutor-2510-thread-1) [ ] o.a.s.c.a.ComputePlanAction -- processing event: {
[junit4] 2> "id":"10c0eaaeeac44Tcb40i30mtvbgjm51w4ur1ioi",
[junit4] 2> "source":".auto_add_replicas",
[junit4] 2> "eventTime":294732113554500,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[294732113554500],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":294743938929500,
[junit4] 2> "nodeNames":["127.0.0.1:10000_solr"]}} with context properties: {BEFORE_ACTION=[auto_add_replicas_plan]}
[junit4] 2> 732624 DEBUG (AutoscalingActionExecutor-2510-thread-1) [ ] o.a.s.c.a.ComputePlanAction Computed Plan: action=MOVEREPLICA&collection=testCollection&targetNode=127.0.0.1:10001_solr&inPlaceMove=true&replica=core_node1
[junit4] 2> 732624 DEBUG (AutoscalingActionExecutor-2510-thread-1) [ ] o.a.s.c.a.ComputePlanAction Computed Plan: action=MOVEREPLICA&collection=testCollection&targetNode=127.0.0.1:10001_solr&inPlaceMove=true&replica=core_node4
[junit4] 2> 732624 DEBUG (AutoscalingActionExecutor-2510-thread-1) [ ] o.a.s.c.a.ExecutePlanAction -- processing event: {
[junit4] 2> "id":"10c0eaaeeac44Tcb40i30mtvbgjm51w4ur1ioi",
[junit4] 2> "source":".auto_add_replicas",
[junit4] 2> "eventTime":294732113554500,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[294732113554500],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":294743938929500,
[junit4] 2> "nodeNames":["127.0.0.1:10000_solr"]}} with context properties: {operations=[{
[junit4] 2> "class":"org.apache.solr.client.solrj.request.CollectionAdminRequest$MoveReplica",
[junit4] 2> "method":"GET",
[junit4] 2> "params.action":"MOVEREPLICA",
[junit4] 2> "params.collection":"testCollection",
[junit4] 2> "params.targetNode":"127.0.0.1:10001_solr",
[junit4] 2> "params.inPlaceMove":"true",
[junit4] 2> "params.replica":"core_node1"}, {
[junit4] 2> "class":"org.apache.solr.client.solrj.request.CollectionAdminRequest$MoveReplica",
[junit4] 2> "method":"GET",
[junit4] 2> "params.action":"MOVEREPLICA",
[junit4] 2> "params.collection":"testCollection",
[junit4] 2> "params.targetNode":"127.0.0.1:10001_solr",
[junit4] 2> "params.inPlaceMove":"true",
[junit4] 2> "params.replica":"core_node4"}], AFTER_ACTION=[auto_add_replicas_plan], BEFORE_ACTION=[auto_add_replicas_plan, execute_plan]}
[junit4] 2> 732624 DEBUG (AutoscalingActionExecutor-2510-thread-1) [ ] o.a.s.c.a.ExecutePlanAction Executing operation: action=MOVEREPLICA&collection=testCollection&targetNode=127.0.0.1:10001_solr&inPlaceMove=true&replica=core_node1
[junit4] 2> 732792 DEBUG (simCloudManagerPool-2508-thread-8) [ ] o.a.s.c.a.s.SimClusterStateProvider -- new replica: {"core_node6":{
[junit4] 2> "core":"testCollection_shard1_replica_n5",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10001_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"down",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 732912 DEBUG (simCloudManagerPool-2508-thread-8) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=12
[junit4] 2> 732916 DEBUG (simCloudManagerPool-2508-thread-8) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 12
[junit4] 2> 732917 DEBUG (AutoscalingActionExecutor-2510-thread-1) [ ] o.a.s.c.a.ExecutePlanAction Executing operation: action=MOVEREPLICA&collection=testCollection&targetNode=127.0.0.1:10001_solr&inPlaceMove=true&replica=core_node4
[junit4] 2> 732917 DEBUG (simCloudManagerPool-2508-thread-11) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=13
[junit4] 2> 732917 DEBUG (simCloudManagerPool-2508-thread-11) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 13
[junit4] 2> 733091 DEBUG (simCloudManagerPool-2508-thread-11) [ ] o.a.s.c.a.s.SimClusterStateProvider -- new replica: {"core_node8":{
[junit4] 2> "core":"testCollection_shard2_replica_n7",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10001_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"down",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 733373 DEBUG (simCloudManagerPool-2508-thread-11) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=14
[junit4] 2> 733373 DEBUG (simCloudManagerPool-2508-thread-11) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 14
[junit4] 2> 733506 DEBUG (MetricsHistoryHandler-2512-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=15
[junit4] 2> 733506 DEBUG (MetricsHistoryHandler-2512-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 15
[junit4] 2> 733584 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 733584 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 733584 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 733584 INFO (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 733588 ERROR (AutoscalingActionExecutor-2510-thread-1) [ ] o.a.s.c.a.ExecutePlanAction Unexpected Exception while querying status of requestId=.auto_add_replicas/10c0eaaeeac44Tcb40i30mtvbgjm51w4ur1ioi/1
[junit4] 2> => java.io.IOException: java.lang.InterruptedException
[junit4] 2> at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.request(SimCloudManager.java:732)
[junit4] 2> java.io.IOException: java.lang.InterruptedException
[junit4] 2> at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.request(SimCloudManager.java:732) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.autoscaling.ExecutePlanAction.waitForTaskToFinish(ExecutePlanAction.java:196) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.autoscaling.ExecutePlanAction.process(ExecutePlanAction.java:113) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$add$3(ScheduledTriggers.java:326) ~[java/:?]
[junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) ~[?:?]
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:264) ~[?:?]
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:210) ~[java/:?]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) ~[?:?]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:630) ~[?:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:832) [?:?]
[junit4] 2> Caused by: java.lang.InterruptedException
[junit4] 2> at java.util.concurrent.FutureTask.awaitDone(FutureTask.java:418) ~[?:?]
[junit4] 2> at java.util.concurrent.FutureTask.get(FutureTask.java:203) ~[?:?]
[junit4] 2> at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.request(SimCloudManager.java:730) ~[java/:?]
[junit4] 2> ... 9 more
[junit4] 2> 733589 WARN (AutoscalingActionExecutor-2510-thread-1) [ ] o.a.s.c.a.SystemLogListener Exception sending event. Collection: .system, event: {
[junit4] 2> "id":"10c0eaaeeac44Tcb40i30mtvbgjm51w4ur1ioi",
[junit4] 2> "source":".auto_add_replicas",
[junit4] 2> "eventTime":294732113554500,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[294732113554500],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":294743938929500,
[junit4] 2> "nodeNames":["127.0.0.1:10000_solr"]}}, exception: java.io.IOException: already closed
[junit4] 2> 733589 WARN (AutoscalingActionExecutor-2510-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Exception executing actions
[junit4] 2> => org.apache.solr.cloud.autoscaling.TriggerActionException: Error processing action for trigger event: {
[junit4] 2> "id":"10c0eaaeeac44Tcb40i30mtvbgjm51w4ur1ioi",
[junit4] 2> "source":".auto_add_replicas",
[junit4] 2> "eventTime":294732113554500,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[294732113554500],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":294743938929500,
[junit4] 2> "nodeNames":["127.0.0.1:10000_solr"]}}
[junit4] 2> at org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$add$3(ScheduledTriggers.java:329)
[junit4] 2> org.apache.solr.cloud.autoscaling.TriggerActionException: Error processing action for trigger event: {
[junit4] 2> "id":"10c0eaaeeac44Tcb40i30mtvbgjm51w4ur1ioi",
[junit4] 2> "source":".auto_add_replicas",
[junit4] 2> "eventTime":294732113554500,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[294732113554500],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":294743938929500,
[junit4] 2> "nodeNames":["127.0.0.1:10000_solr"]}}
[junit4] 2> at org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$add$3(ScheduledTriggers.java:329) ~[java/:?]
[junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) ~[?:?]
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:264) ~[?:?]
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:210) ~[java/:?]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) ~[?:?]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:630) ~[?:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:832) [?:?]
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Unexpected exception while processing event: {
[junit4] 2> "id":"10c0eaaeeac44Tcb40i30mtvbgjm51w4ur1ioi",
[junit4] 2> "source":".auto_add_replicas",
[junit4] 2> "eventTime":294732113554500,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[294732113554500],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":294743938929500,
[junit4] 2> "nodeNames":["127.0.0.1:10000_solr"]}}
[junit4] 2> at org.apache.solr.cloud.autoscaling.ExecutePlanAction.process(ExecutePlanAction.java:180) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$add$3(ScheduledTriggers.java:326) ~[java/:?]
[junit4] 2> ... 6 more
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Unexpected exception executing operation: action=MOVEREPLICA&async=.auto_add_replicas/10c0eaaeeac44Tcb40i30mtvbgjm51w4ur1ioi/1&waitForFinalState=true&collection=testCollection&targetNode=127.0.0.1:10001_solr&inPlaceMove=true&replica=core_node4
[junit4] 2> at org.apache.solr.cloud.autoscaling.ExecutePlanAction.process(ExecutePlanAction.java:170) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$add$3(ScheduledTriggers.java:326) ~[java/:?]
[junit4] 2> ... 6 more
[junit4] 2> Caused by: java.io.IOException: java.lang.InterruptedException
[junit4] 2> at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.request(SimCloudManager.java:732) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.autoscaling.ExecutePlanAction.waitForTaskToFinish(ExecutePlanAction.java:196) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.autoscaling.ExecutePlanAction.process(ExecutePlanAction.java:113) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$add$3(ScheduledTriggers.java:326) ~[java/:?]
[junit4] 2> ... 6 more
[junit4] 2> Caused by: java.lang.InterruptedException
[junit4] 2> at java.util.concurrent.FutureTask.awaitDone(FutureTask.java:418) ~[?:?]
[junit4] 2> at java.util.concurrent.FutureTask.get(FutureTask.java:203) ~[?:?]
[junit4] 2> at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.request(SimCloudManager.java:730) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.autoscaling.ExecutePlanAction.waitForTaskToFinish(ExecutePlanAction.java:196) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.autoscaling.ExecutePlanAction.process(ExecutePlanAction.java:113) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$add$3(ScheduledTriggers.java:326) ~[java/:?]
[junit4] 2> ... 6 more
[junit4] 2> 733589 DEBUG (AutoscalingActionExecutor-2510-thread-1) [ ] o.a.s.c.a.ScheduledTriggers -- processing took 48635 ms for event id=10c0eaaeeac44Tcb40i30mtvbgjm51w4ur1ioi
[junit4] 2> 733589 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 733589 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 733589 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 733590 INFO (TEST-TestSimScenario.testAutoAddReplicas-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testAutoAddReplicas
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSimScenario -Dtests.method=testAutoAddReplicas -Dtests.seed=74BFD65BBA9B6DC -Dtests.slow=true -Dtests.locale=en-MG -Dtests.timezone=SystemV/AST4 -Dtests.asserts=true -Dtests.file.encoding=Cp1252
[junit4] ERROR 1.71s J1 | TestSimScenario.testAutoAddReplicas <<<
[junit4] > Throwable #1: java.io.IOException: Timed out waiting for trigger .auto_add_replicas to fire after simulated 60s (real 1200ms).
[junit4] > at __randomizedtesting.SeedInfo.seed([74BFD65BBA9B6DC:8EEB0E19CFA27AF5]:0)
[junit4] > at org.apache.solr.cloud.autoscaling.sim.SimScenario$SimWaitListener.wait(SimScenario.java:693)
[junit4] > at org.apache.solr.cloud.autoscaling.sim.SimScenario$WaitEvent.execute(SimScenario.java:758)
[junit4] > at org.apache.solr.cloud.autoscaling.sim.SimScenario.run(SimScenario.java:1096)
[junit4] > at org.apache.solr.cloud.autoscaling.sim.TestSimScenario.testAutoAddReplicas(TestSimScenario.java:57)
[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:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:832)
[junit4] 2> 733607 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSuggestions
[junit4] 2> 733611 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario 1. CreateCluster numNodes=2
[junit4] 2> 733611 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario CreateCluster numNodes=2
[junit4] 2> 733668 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimClusterStateProvider --- new Overseer leader: 127.0.0.1:10002_solr
[junit4] 2> 733668 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=0
[junit4] 2> 733668 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 0
[junit4] 2> 733668 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 733668 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario 2. RunSolrRequest path=/admin/collections&action=CREATE&autoAddReplicas=true&name=testCollection&numShards=2&replicationFactor=2&maxShardsPerNode=2
[junit4] 2> 733668 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario RunSolrRequest path=/admin/collections&action=CREATE&autoAddReplicas=true&name=testCollection&numShards=2&replicationFactor=2&maxShardsPerNode=2
[junit4] 2> 733668 DEBUG (simCloudManagerPool-2514-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider -- simCreateCollection testCollection, currentVersion=1
[junit4] 2> 733668 DEBUG (simCloudManagerPool-2514-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=1
[junit4] 2> 733668 DEBUG (simCloudManagerPool-2514-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 1
[junit4] 2> 733682 DEBUG (simCloudManagerPool-2514-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=2
[junit4] 2> 733682 DEBUG (simCloudManagerPool-2514-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 2
[junit4] 2> 733697 DEBUG (simCloudManagerPool-2514-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=3
[junit4] 2> 733697 DEBUG (simCloudManagerPool-2514-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 3
[junit4] 2> 733697 DEBUG (simCloudManagerPool-2514-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider Running leader election (testCollection / shard1)
[junit4] 2> 733697 DEBUG (simCloudManagerPool-2514-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testCollection / shard1 (currentVersion=4): {"core_node1":{
[junit4] 2> "core":"testCollection_shard1_replica_n1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10002_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 733697 DEBUG (simCloudManagerPool-2514-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider -- finished createCollection testCollection, currentVersion=4
[junit4] 2> 733698 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario 3. WaitCollection collection=testCollection&shards=2&replicas=2
[junit4] 2> 733699 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario WaitCollection collection=testCollection&shards=2&replicas=2
[junit4] 2> 733708 DEBUG (simCloudManagerPool-2514-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=4
[junit4] 2> 733709 DEBUG (simCloudManagerPool-2514-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 4
[junit4] 2> 733709 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=5
[junit4] 2> 733709 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 5
[junit4] 2> 733709 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.CloudUtil -- shards without leaders: [shard2]
[junit4] 2> 733709 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 733710 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 733713 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.CloudUtil -- shards without leaders: [shard2]
[junit4] 2> 733716 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.CloudUtil -- shards without leaders: [shard2]
[junit4] 2> 733716 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 733716 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 733720 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.CloudUtil -- shards without leaders: [shard2]
[junit4] 2> 733721 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:10002_solr, 127.0.0.1:10003_solr]
[junit4] 2> 733721 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 733721 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 733723 DEBUG (ScheduledTrigger-2515-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 733732 DEBUG (simCloudManagerPool-2514-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=6
[junit4] 2> 733732 DEBUG (simCloudManagerPool-2514-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 6
[junit4] 2> 733732 DEBUG (simCloudManagerPool-2514-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider Running leader election (testCollection / shard2)
[junit4] 2> 733732 DEBUG (simCloudManagerPool-2514-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testCollection / shard2 (currentVersion=7): {"core_node3":{
[junit4] 2> "core":"testCollection_shard2_replica_n3",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10002_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 733744 DEBUG (ScheduledTrigger-2515-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 733744 DEBUG (simCloudManagerPool-2514-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=7
[junit4] 2> 733744 DEBUG (simCloudManagerPool-2514-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 7
[junit4] 2> 733744 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=8
[junit4] 2> 733746 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 8
[junit4] 2> 733746 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario 4. CtxSet key=myNode&value=${_random_node_}
[junit4] 2> 733746 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario CtxSet key=myNode&value=127.0.0.1:10002_solr
[junit4] 2> 733746 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario 5. RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=${myNode}
[junit4] 2> 733746 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10002_solr
[junit4] 2> 733748 INFO (simCloudManagerPool-2514-thread-6) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10002_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 733748 INFO (simCloudManagerPool-2514-thread-6) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 733766 DEBUG (ScheduledTrigger-2515-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 733766 DEBUG (simCloudManagerPool-2514-thread-6) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=9
[junit4] 2> 733770 DEBUG (simCloudManagerPool-2514-thread-6) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 9
[junit4] 2> 733770 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario 6. RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=${myNode}
[junit4] 2> 733770 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10002_solr
[junit4] 2> 733780 DEBUG (simCloudManagerPool-2514-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=10
[junit4] 2> 733788 DEBUG (ScheduledTrigger-2515-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 733788 DEBUG (simCloudManagerPool-2514-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 10
[junit4] 2> 733788 INFO (simCloudManagerPool-2514-thread-7) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10002_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 733788 INFO (simCloudManagerPool-2514-thread-7) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 733800 DEBUG (simCloudManagerPool-2514-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=11
[junit4] 2> 733815 DEBUG (simCloudManagerPool-2514-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 11
[junit4] 2> 733816 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario 7. LoopOp iterations=${iterative}
[junit4] 2> 733816 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario LoopOp iterations=0
[junit4] 2> 733816 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario 8. LoopOp iterations=${justCalc}
[junit4] 2> 733816 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario LoopOp iterations=1
[junit4] 2> 733816 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario * iter 1:
[junit4] 2> 733816 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario - CalculateSuggestions
[junit4] 2> 733820 DEBUG (ScheduledTrigger-2515-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 733839 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=12
[junit4] 2> 733840 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 12
[junit4] 2> 733848 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario - 1 suggestions
[junit4] 2> 733849 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario - SaveSnapshot path=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.sim.TestSimScenario_74BFD65BBA9B6DC-001\tempDir-002/snapshot/0
[junit4] 2> 733849 DEBUG (ScheduledTrigger-2515-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 733856 WARN (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimUtils V2 action SET_CLUSTER_PROPERTY_OBJ has no equivalent V1 action
[junit4] 2> 733856 WARN (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimUtils V2 action GET_NODES has no equivalent V1 action
[junit4] 2> 733860 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SnapshotDistribStateManager - copying 22 resources from SimDistribStateManager
[junit4] 2> 733908 DEBUG (ScheduledTrigger-2515-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 733940 DEBUG (ScheduledTrigger-2515-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 733972 DEBUG (ScheduledTrigger-2515-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 733974 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario 9. Dump redact=true
[junit4] 2> 733974 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario Dump redact=true
[junit4] 2> 733976 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SnapshotDistribStateManager - copying 22 resources from SimDistribStateManager
[junit4] 2> 733990 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 733990 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 733990 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 733990 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 733990 INFO (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 733990 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 733990 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 733990 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario 1. CreateCluster numNodes=2
[junit4] 2> 733990 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario CreateCluster numNodes=2
[junit4] 2> 734008 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimClusterStateProvider --- new Overseer leader: 127.0.0.1:10004_solr
[junit4] 2> 734010 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=0
[junit4] 2> 734010 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 0
[junit4] 2> 734010 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 734010 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario 2. RunSolrRequest path=/admin/collections&action=CREATE&autoAddReplicas=true&name=testCollection&numShards=2&replicationFactor=2&maxShardsPerNode=2
[junit4] 2> 734010 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario RunSolrRequest path=/admin/collections&action=CREATE&autoAddReplicas=true&name=testCollection&numShards=2&replicationFactor=2&maxShardsPerNode=2
[junit4] 2> 734011 DEBUG (simCloudManagerPool-2520-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider -- simCreateCollection testCollection, currentVersion=1
[junit4] 2> 734011 DEBUG (simCloudManagerPool-2520-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=1
[junit4] 2> 734011 DEBUG (simCloudManagerPool-2520-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 1
[junit4] 2> 734016 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 734016 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 734017 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 734017 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 734017 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:10004_solr, 127.0.0.1:10005_solr]
[junit4] 2> 734017 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 734017 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 734020 DEBUG (ScheduledTrigger-2521-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 734021 DEBUG (simCloudManagerPool-2520-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=2
[junit4] 2> 734021 DEBUG (simCloudManagerPool-2520-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 2
[junit4] 2> 734024 DEBUG (simCloudManagerPool-2520-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider -- finished createCollection testCollection, currentVersion=3
[junit4] 2> 734024 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario 3. WaitCollection collection=testCollection&shards=2&replicas=2
[junit4] 2> 734024 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario WaitCollection collection=testCollection&shards=2&replicas=2
[junit4] 2> 734024 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=3
[junit4] 2> 734024 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 3
[junit4] 2> 734024 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.CloudUtil -- shards without leaders: [shard2, shard1]
[junit4] 2> 734040 DEBUG (simCloudManagerPool-2520-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=4
[junit4] 2> 734040 DEBUG (simCloudManagerPool-2520-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 4
[junit4] 2> 734040 DEBUG (simCloudManagerPool-2520-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider Running leader election (testCollection / shard1)
[junit4] 2> 734040 DEBUG (simCloudManagerPool-2520-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testCollection / shard1 (currentVersion=5): {"core_node1":{
[junit4] 2> "core":"testCollection_shard1_replica_n1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10004_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 734040 DEBUG (ScheduledTrigger-2521-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 734051 DEBUG (simCloudManagerPool-2520-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=5
[junit4] 2> 734051 DEBUG (simCloudManagerPool-2520-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 5
[junit4] 2> 734051 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=6
[junit4] 2> 734051 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 6
[junit4] 2> 734051 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.CloudUtil -- shards without leaders: [shard2]
[junit4] 2> 734063 DEBUG (ScheduledTrigger-2521-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 734063 DEBUG (simCloudManagerPool-2520-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=7
[junit4] 2> 734063 DEBUG (simCloudManagerPool-2520-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 7
[junit4] 2> 734063 DEBUG (simCloudManagerPool-2520-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider Running leader election (testCollection / shard2)
[junit4] 2> 734063 DEBUG (simCloudManagerPool-2520-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testCollection / shard2 (currentVersion=8): {"core_node3":{
[junit4] 2> "core":"testCollection_shard2_replica_n3",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10004_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 734075 DEBUG (simCloudManagerPool-2520-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=8
[junit4] 2> 734075 DEBUG (simCloudManagerPool-2520-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 8
[junit4] 2> 734075 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=9
[junit4] 2> 734075 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 9
[junit4] 2> 734075 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario 4. CtxSet key=myNode&value=${_random_node_}
[junit4] 2> 734075 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario CtxSet key=myNode&value=127.0.0.1:10004_solr
[junit4] 2> 734075 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario 5. RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=${myNode}
[junit4] 2> 734075 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10004_solr
[junit4] 2> 734076 INFO (simCloudManagerPool-2520-thread-6) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10004_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 734076 INFO (simCloudManagerPool-2520-thread-6) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 734084 DEBUG (ScheduledTrigger-2521-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 734091 DEBUG (simCloudManagerPool-2520-thread-6) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=10
[junit4] 2> 734091 DEBUG (simCloudManagerPool-2520-thread-6) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 10
[junit4] 2> 734091 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario 6. RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=${myNode}
[junit4] 2> 734091 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10004_solr
[junit4] 2> 734093 DEBUG (simCloudManagerPool-2520-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=11
[junit4] 2> 734093 DEBUG (simCloudManagerPool-2520-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 11
[junit4] 2> 734093 INFO (simCloudManagerPool-2520-thread-7) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10004_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 734093 INFO (simCloudManagerPool-2520-thread-7) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 734105 DEBUG (simCloudManagerPool-2520-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=12
[junit4] 2> 734105 DEBUG (simCloudManagerPool-2520-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 12
[junit4] 2> 734106 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario 7. LoopOp iterations=${iterative}
[junit4] 2> 734106 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario LoopOp iterations=10
[junit4] 2> 734106 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario * iter 1:
[junit4] 2> 734106 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario - CalculateSuggestions
[junit4] 2> 734106 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=13
[junit4] 2> 734106 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 13
[junit4] 2> 734108 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario - 1 suggestions
[junit4] 2> 734108 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario - ApplySuggestions
[junit4] 2> 734120 DEBUG (ScheduledTrigger-2521-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 734140 DEBUG (ScheduledTrigger-2521-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 734163 DEBUG (ScheduledTrigger-2521-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 734208 DEBUG (ScheduledTrigger-2521-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 734231 DEBUG (ScheduledTrigger-2521-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 734236 DEBUG (simCloudManagerPool-2520-thread-8) [ ] o.a.s.c.a.s.SimClusterStateProvider -- new replica: {"core_node10":{
[junit4] 2> "core":"testCollection_shard2_replica_n9",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10005_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 734252 DEBUG (ScheduledTrigger-2521-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 734274 DEBUG (ScheduledTrigger-2521-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 734295 DEBUG (ScheduledTrigger-2521-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 734316 DEBUG (ScheduledTrigger-2521-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 734336 DEBUG (ScheduledTrigger-2521-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 734354 DEBUG (simCloudManagerPool-2520-thread-8) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=14
[junit4] 2> 734355 DEBUG (simCloudManagerPool-2520-thread-8) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 14
[junit4] 2> 734355 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario - RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10004_solr
[junit4] 2> 734356 DEBUG (simCloudManagerPool-2520-thread-9) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=15
[junit4] 2> 734356 DEBUG (simCloudManagerPool-2520-thread-9) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 15
[junit4] 2> 734356 INFO (simCloudManagerPool-2520-thread-9) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10004_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 734356 INFO (simCloudManagerPool-2520-thread-9) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 734361 DEBUG (ScheduledTrigger-2521-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 734371 DEBUG (simCloudManagerPool-2520-thread-9) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=16
[junit4] 2> 734371 DEBUG (simCloudManagerPool-2520-thread-9) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 16
[junit4] 2> 734372 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario - RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10004_solr
[junit4] 2> 734377 DEBUG (simCloudManagerPool-2520-thread-10) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=17
[junit4] 2> 734377 DEBUG (simCloudManagerPool-2520-thread-10) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 17
[junit4] 2> 734377 INFO (simCloudManagerPool-2520-thread-10) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10004_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 734378 INFO (simCloudManagerPool-2520-thread-10) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 734383 DEBUG (ScheduledTrigger-2521-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 734389 DEBUG (simCloudManagerPool-2520-thread-10) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=18
[junit4] 2> 734389 DEBUG (simCloudManagerPool-2520-thread-10) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 18
[junit4] 2> 734389 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario * iter 2:
[junit4] 2> 734389 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario - CalculateSuggestions
[junit4] 2> 734389 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=19
[junit4] 2> 734389 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 19
[junit4] 2> 734392 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario - 1 suggestions
[junit4] 2> 734392 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario - ApplySuggestions
[junit4] 2> 734404 DEBUG (ScheduledTrigger-2521-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 734428 DEBUG (ScheduledTrigger-2521-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 734448 DEBUG (ScheduledTrigger-2521-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 734471 DEBUG (ScheduledTrigger-2521-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 734492 DEBUG (ScheduledTrigger-2521-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 734512 DEBUG (simCloudManagerPool-2520-thread-11) [ ] o.a.s.c.a.s.SimClusterStateProvider -- new replica: {"core_node16":{
[junit4] 2> "core":"testCollection_shard1_replica_n15",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10005_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 734517 DEBUG (ScheduledTrigger-2521-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 734544 DEBUG (ScheduledTrigger-2521-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 734572 DEBUG (ScheduledTrigger-2521-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 734593 DEBUG (ScheduledTrigger-2521-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 734627 DEBUG (ScheduledTrigger-2521-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 734629 DEBUG (simCloudManagerPool-2520-thread-11) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=20
[junit4] 2> 734629 DEBUG (simCloudManagerPool-2520-thread-11) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 20
[junit4] 2> 734629 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario - RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10004_solr
[junit4] 2> 734648 DEBUG (ScheduledTrigger-2521-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 734669 DEBUG (ScheduledTrigger-2521-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 734672 DEBUG (simCloudManagerPool-2520-thread-12) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=21
[junit4] 2> 734672 DEBUG (simCloudManagerPool-2520-thread-12) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 21
[junit4] 2> 734672 INFO (simCloudManagerPool-2520-thread-12) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10004_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 734672 INFO (simCloudManagerPool-2520-thread-12) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 734692 DEBUG (simCloudManagerPool-2520-thread-12) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=22
[junit4] 2> 734692 DEBUG (simCloudManagerPool-2520-thread-12) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 22
[junit4] 2> 734692 INFO (TEST-TestSimScenario.testSuggestions-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.a.s.SimScenario - RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10004_solr
[junit4] 2> 734696 DEBUG (ScheduledTrigger-2521-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 734710 DEBUG (simCloudManagerPool-2520-thread-13) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=23
[junit4] 2> 734710 DEBUG (simCloudManagerPool-2520-thread-13) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 23
[junit4] 2> 734710 INFO (simCloudManagerPool-2520-thread-13) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10004_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 734710 INFO (simCloudManagerPool-2520-thread-13) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 734718 DEBUG (ScheduledTrigger-2521-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 734724 DEBUG (simCloudManagerPool-2520-thread-13) [ ] o.a.s.c
[...truncated too long message...]
b0f: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@37391f77
[junit4] 2> 1116874 INFO (closeThreadPool-4144-thread-5) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3280adfd: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@37391f77
[junit4] 2> 1116874 INFO (closeThreadPool-4144-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 1116874 INFO (closeThreadPool-4144-thread-5) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 1116878 INFO (closeThreadPool-4151-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72076928660733956-127.0.0.1:53803_-n_0000000848) closing
[junit4] 2> 1116878 INFO (OverseerStateUpdate-72076928660733956-127.0.0.1:53803_-n_0000000848) [n:127.0.0.1:53803_ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:53803_
[junit4] 2> 1116878 INFO (OverseerAutoScalingTriggerThread-72076928660733956-127.0.0.1:53803_-n_0000000848) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 1116880 INFO (closeThreadPool-4151-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72076928660733956-127.0.0.1:53803_-n_0000000848) closing
[junit4] 2> 1116978 INFO (closeThreadPool-4144-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72076928660733956-127.0.0.1:53803_-n_0000000848) closing
[junit4] 2> 1116980 INFO (closeThreadPool-4144-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@55184893{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 1116981 INFO (closeThreadPool-4144-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4aa22c6d{/,null,UNAVAILABLE}
[junit4] 2> 1116981 INFO (closeThreadPool-4144-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1117081 INFO (closeThreadPool-4144-thread-5) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@752c59f5{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 1117081 INFO (closeThreadPool-4144-thread-5) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@76d4f92b{/,null,UNAVAILABLE}
[junit4] 2> 1117082 INFO (closeThreadPool-4144-thread-5) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1117083 INFO (TEST-RollingRestartTest.test-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1117292 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> 12 /solr/collections/collection1/state.json
[junit4] 2> 5 /solr/aliases.json
[junit4] 2> 3 /solr/packages.json
[junit4] 2> 3 /solr/security.json
[junit4] 2> 3 /solr/configs/conf1
[junit4] 2> 2 /solr/collections/collection1/terms/shard1
[junit4] 2> 2 /solr/collections/collection1/terms/shard2
[junit4] 2> 2 /solr/collections/control_collection/terms/shard1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 26 /solr/clusterstate.json
[junit4] 2> 12 /solr/collections/control_collection/state.json
[junit4] 2> 5 /solr/clusterprops.json
[junit4] 2> 3 /solr/overseer_elect/election/72076928660733964-127.0.0.1:53937_-n_0000000005
[junit4] 2> 2 /solr/overseer_elect/election/72076928660733961-127.0.0.1:53910_-n_0000000001
[junit4] 2> 2 /solr/overseer_elect/election/72076928660733956-127.0.0.1:53803_-n_0000000000
[junit4] 2> 2 /solr/autoscaling.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 27 /solr/overseer/queue
[junit4] 2> 21 /solr/overseer/collection-queue-work
[junit4] 2> 16 /solr/live_nodes
[junit4] 2> 5 /solr/collections
[junit4] 2> 3 /solr/autoscaling/events/.auto_add_replicas
[junit4] 2> 2 /solr/autoscaling/events/.scheduled_maintenance
[junit4] 2> 2 /solr/overseer/queue-work
[junit4] 2>
[junit4] 2> 1117299 INFO (TEST-RollingRestartTest.test-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:53685
[junit4] 2> 1117299 INFO (TEST-RollingRestartTest.test-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:53685
[junit4] 2> 1117299 INFO (TEST-RollingRestartTest.test-seed#[74BFD65BBA9B6DC]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 53685
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=RollingRestartTest -Dtests.method=test -Dtests.seed=74BFD65BBA9B6DC -Dtests.slow=true -Dtests.locale=en-PG -Dtests.timezone=Africa/Conakry -Dtests.asserts=true -Dtests.file.encoding=Cp1252
[junit4] FAILURE 76.2s J0 | RollingRestartTest.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: No overseer designate as leader found after restart #1: 127.0.0.1:53803_
[junit4] > at __randomizedtesting.SeedInfo.seed([74BFD65BBA9B6DC:8F1FC2BF1555DB24]:0)
[junit4] > at org.apache.solr.cloud.RollingRestartTest.restartWithRolesTest(RollingRestartTest.java:101)
[junit4] > at org.apache.solr.cloud.RollingRestartTest.test(RollingRestartTest.java:53)
[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:564)
[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:832)
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.RollingRestartTest_74BFD65BBA9B6DC-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {}, docValues:{}, maxPointsInLeafNode=1696, maxMBSortInHeap=5.097510160568676, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@2cf0d1a0), locale=en-PG, timezone=Africa/Conakry
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 15-ea (64-bit)/cpus=6,threads=1,free=398458880,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [IndexSizeTriggerTest, BlockJoinFacetRandomTest, PeerSyncWithLeaderAndIndexFingerprintCachingTest, TestSolrCloudWithKerberosAlt, TestTlogReplayVsRecovery, OverseerSolrResponseTest, TestJoin, TestScoreJoinQPNoScore, CloneFieldUpdateProcessorFactoryTest, DeleteReplicaTest, TestNonDefinedSimilarityFactory, BaseCdcrDistributedZkTest, HighlighterTest, ManagedSchemaRoundRobinCloudTest, TestAnalyzeInfixSuggestions, TestPayloadCheckQParserPlugin, TestConfigsApi, TestGeoJSONResponseWriter, CreateCollectionCleanupTest, TestDistributedMissingSort, TestDynamicFieldCollectionResource, OutOfBoxZkACLAndCredentialsProvidersTest, TestInitQParser, SolrXmlInZkTest, ScheduledTriggerIntegrationTest, SolrShardReporterTest, TestSimPolicyCloud, NodeLostTriggerIntegrationTest, TestElisionMultitermQuery, CustomHighlightComponentTest, SpatialHeatmapFacetsTest, CircularListTest, TestLegacyField, TestRTimerTree, CoreAdminRequestStatusTest, UUIDUpdateProcessorFallbackTest, TestSearcherReuse, TestSimExtremeIndexing, TestValueSourceCache, TestDFRSimilarityFactory, SuggestComponentTest, TestRequestStatusCollectionAPI, AddBlockUpdateTest, TestConfigSets, LeaderFailureAfterFreshStartTest, HttpPartitionWithTlogReplicasTest, AuditLoggerIntegrationTest, SpellCheckCollatorTest, ZkNodePropsTest, TestMinHashQParser, UpdateParamsTest, TestHdfsUpdateLog, TaggingAttributeTest, SignatureUpdateProcessorFactoryTest, ExecutePlanActionTest, TestRawResponseWriter, TestWaitForStateWithJettyShutdowns, NumberUtilsTest, ConfigureRecoveryStrategyTest, CloudMLTQParserTest, MoreLikeThisComponentTest, PKIAuthenticationIntegrationTest, RollingRestartTest]
[junit4] Completed [374/901 (2!)] on J0 in 76.39s, 1 test, 1 failure <<< FAILURES!
[...truncated 46688 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/848/consoleText
[repro] Revision: 1573d3d2d83c5008dd179736feb7218906830fb0
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseZGC"
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr\core
[repro] TestSimScenario
[repro] RollingRestartTest
[repro] ant compile-test
[...truncated 3445 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=10 -Dtests.class="*.TestSimScenario|*.RollingRestartTest" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseZGC" -Dtests.seed=74BFD65BBA9B6DC -Dtests.slow=true -Dtests.locale=en-MG -Dtests.timezone=SystemV/AST4 -Dtests.asserts=true -Dtests.file.encoding=Cp1252
[...truncated 117 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.cloud.RollingRestartTest
[repro] 0/5 failed: org.apache.solr.cloud.autoscaling.sim.TestSimScenario
[repro] Exiting with code 0
[...truncated 78 lines...]