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 2019/10/19 12:08:44 UTC
[JENKINS] Lucene-Solr-master-Linux (64bit/jdk-11.0.4) - Build #
24923 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/24923/
Java: 64bit/jdk-11.0.4 -XX:+UseCompressedOops -XX:+UseParallelGC
5 tests failed.
FAILED: org.apache.solr.handler.component.DistributedTermsComponentTest.test
Error Message:
Error from server at https://127.0.0.1:33299/collection1: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[https://[ff01::114]:33332, https://127.0.0.1:33299/collection1, https://[ff01::083]:33332, https://[ff01::213]:33332]
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:33299/collection1: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[https://[ff01::114]:33332, https://127.0.0.1:33299/collection1, https://[ff01::083]:33332, https://[ff01::213]:33332]
at __randomizedtesting.SeedInfo.seed([61A1717234AEDCD5:E9F54EA89A52B12D]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:665)
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.SolrRequest.process(SolrRequest.java:207)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1003)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1018)
at org.apache.solr.BaseDistributedSearchTestCase.queryServer(BaseDistributedSearchTestCase.java:626)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:678)
at org.apache.solr.handler.component.DistributedTermsComponentTest.query(DistributedTermsComponentTest.java:111)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:656)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:635)
at org.apache.solr.handler.component.DistributedTermsComponentTest.query(DistributedTermsComponentTest.java:106)
at org.apache.solr.handler.component.DistributedTermsComponentTest.test(DistributedTermsComponentTest.java:82)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1108)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:834)
FAILED: org.apache.solr.handler.component.DistributedTermsComponentTest.test
Error Message:
Error from server at https://127.0.0.1:33625/collection1: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[https://[ff01::114]:33332, https://[ff01::083]:33332, https://127.0.0.1:33625/collection1, https://[ff01::213]:33332]
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:33625/collection1: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[https://[ff01::114]:33332, https://[ff01::083]:33332, https://127.0.0.1:33625/collection1, https://[ff01::213]:33332]
at __randomizedtesting.SeedInfo.seed([61A1717234AEDCD5:E9F54EA89A52B12D]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:665)
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.SolrRequest.process(SolrRequest.java:207)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1003)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1018)
at org.apache.solr.BaseDistributedSearchTestCase.queryServer(BaseDistributedSearchTestCase.java:626)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:678)
at org.apache.solr.handler.component.DistributedTermsComponentTest.query(DistributedTermsComponentTest.java:111)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:656)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:635)
at org.apache.solr.handler.component.DistributedTermsComponentTest.query(DistributedTermsComponentTest.java:106)
at org.apache.solr.handler.component.DistributedTermsComponentTest.test(DistributedTermsComponentTest.java:82)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1108)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:834)
FAILED: org.apache.solr.handler.component.DistributedTermsComponentTest.test
Error Message:
Error from server at https://127.0.0.1:46695/collection1: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[https://[ff01::114]:33332, https://127.0.0.1:46695/collection1, https://[ff01::083]:33332, https://[ff01::213]:33332]
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:46695/collection1: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[https://[ff01::114]:33332, https://127.0.0.1:46695/collection1, https://[ff01::083]:33332, https://[ff01::213]:33332]
at __randomizedtesting.SeedInfo.seed([61A1717234AEDCD5:E9F54EA89A52B12D]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:665)
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.SolrRequest.process(SolrRequest.java:207)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1003)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1018)
at org.apache.solr.BaseDistributedSearchTestCase.queryServer(BaseDistributedSearchTestCase.java:626)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:678)
at org.apache.solr.handler.component.DistributedTermsComponentTest.query(DistributedTermsComponentTest.java:111)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:656)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:635)
at org.apache.solr.handler.component.DistributedTermsComponentTest.query(DistributedTermsComponentTest.java:106)
at org.apache.solr.handler.component.DistributedTermsComponentTest.test(DistributedTermsComponentTest.java:82)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1108)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:834)
FAILED: org.apache.solr.handler.component.DistributedTermsComponentTest.test
Error Message:
Error from server at https://127.0.0.1:40337/collection1: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[https://[ff01::114]:33332, https://[ff01::083]:33332, https://[ff01::213]:33332, https://127.0.0.1:40337/collection1]
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:40337/collection1: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[https://[ff01::114]:33332, https://[ff01::083]:33332, https://[ff01::213]:33332, https://127.0.0.1:40337/collection1]
at __randomizedtesting.SeedInfo.seed([61A1717234AEDCD5:E9F54EA89A52B12D]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:665)
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.SolrRequest.process(SolrRequest.java:207)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1003)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1018)
at org.apache.solr.BaseDistributedSearchTestCase.queryServer(BaseDistributedSearchTestCase.java:626)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:678)
at org.apache.solr.handler.component.DistributedTermsComponentTest.query(DistributedTermsComponentTest.java:111)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:656)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:635)
at org.apache.solr.handler.component.DistributedTermsComponentTest.query(DistributedTermsComponentTest.java:106)
at org.apache.solr.handler.component.DistributedTermsComponentTest.test(DistributedTermsComponentTest.java:82)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1108)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:834)
FAILED: org.apache.solr.handler.component.DistributedTermsComponentTest.test
Error Message:
Error from server at https://127.0.0.1:44685/collection1: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[https://[ff01::114]:33332, https://[ff01::083]:33332, https://[ff01::213]:33332, https://127.0.0.1:44685/collection1]
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:44685/collection1: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[https://[ff01::114]:33332, https://[ff01::083]:33332, https://[ff01::213]:33332, https://127.0.0.1:44685/collection1]
at __randomizedtesting.SeedInfo.seed([61A1717234AEDCD5:E9F54EA89A52B12D]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:665)
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.SolrRequest.process(SolrRequest.java:207)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1003)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1018)
at org.apache.solr.BaseDistributedSearchTestCase.queryServer(BaseDistributedSearchTestCase.java:626)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:678)
at org.apache.solr.handler.component.DistributedTermsComponentTest.query(DistributedTermsComponentTest.java:111)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:656)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:635)
at org.apache.solr.handler.component.DistributedTermsComponentTest.query(DistributedTermsComponentTest.java:106)
at org.apache.solr.handler.component.DistributedTermsComponentTest.test(DistributedTermsComponentTest.java:82)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1108)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:834)
Build Log:
[...truncated 15146 lines...]
[junit4] Suite: org.apache.solr.handler.component.DistributedTermsComponentTest
[junit4] 2> 2268155 INFO (SUITE-DistributedTermsComponentTest-seed#[61A1717234AEDCD5]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 2268155 INFO (SUITE-DistributedTermsComponentTest-seed#[61A1717234AEDCD5]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedTermsComponentTest_61A1717234AEDCD5-001/data-dir-164-001
[junit4] 2> 2268155 WARN (SUITE-DistributedTermsComponentTest-seed#[61A1717234AEDCD5]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=8 numCloses=8
[junit4] 2> 2268155 INFO (SUITE-DistributedTermsComponentTest-seed#[61A1717234AEDCD5]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 2268156 INFO (SUITE-DistributedTermsComponentTest-seed#[61A1717234AEDCD5]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 2268157 INFO (SUITE-DistributedTermsComponentTest-seed#[61A1717234AEDCD5]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 2268320 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedTermsComponentTest_61A1717234AEDCD5-001/tempDir-001/control/cores/collection1
[junit4] 2> 2268327 WARN (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2268327 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 2268327 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2268327 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 11.0.4+11
[junit4] 2> 2268332 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2268332 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2268332 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2268339 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7e276d58{/,null,AVAILABLE}
[junit4] 2> 2268340 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5dd58e11{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:35821}
[junit4] 2> 2268340 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.e.j.s.Server Started @2268371ms
[junit4] 2> 2268340 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=35821, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedTermsComponentTest_61A1717234AEDCD5-001/tempDir-001/control/cores}
[junit4] 2> 2268340 ERROR (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2268340 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2268340 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 2268340 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 2268341 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2268341 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-10-19T11:43:29.324012Z
[junit4] 2> 2268341 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedTermsComponentTest_61A1717234AEDCD5-001/tempDir-001/control/solr.xml
[junit4] 2> 2268343 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2268343 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2268343 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@3e77bd4c, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2268445 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2268447 WARN (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@389c2242[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2268447 WARN (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@389c2242[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2268449 WARN (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1fdd149d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2268449 WARN (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1fdd149d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2268463 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2268463 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2268478 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3e77bd4c
[junit4] 2> 2268487 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3e77bd4c
[junit4] 2> 2268487 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3e77bd4c
[junit4] 2> 2268488 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedTermsComponentTest_61A1717234AEDCD5-001/tempDir-001/control/cores
[junit4] 2> 2268488 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 2268489 INFO (coreLoadExecutor-10453-thread-1) [ x:collection1 ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2268508 INFO (coreLoadExecutor-10453-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 2268527 INFO (coreLoadExecutor-10453-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 2268594 INFO (coreLoadExecutor-10453-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2268599 INFO (coreLoadExecutor-10453-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 2268599 INFO (coreLoadExecutor-10453-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3e77bd4c
[junit4] 2> 2268599 INFO (coreLoadExecutor-10453-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedTermsComponentTest_61A1717234AEDCD5-001/tempDir-001/control/cores/collection1/data/]
[junit4] 2> 2268600 INFO (coreLoadExecutor-10453-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=41, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 2268631 INFO (coreLoadExecutor-10453-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2268631 INFO (coreLoadExecutor-10453-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2268632 INFO (coreLoadExecutor-10453-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2268632 INFO (coreLoadExecutor-10453-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2268633 INFO (coreLoadExecutor-10453-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=66.3232421875, floorSegmentMB=0.2509765625, forceMergeDeletesPctAllowed=10.54651759954363, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=28.939946345181085
[junit4] 2> 2268633 INFO (coreLoadExecutor-10453-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@79f261c[collection1] main]
[junit4] 2> 2268633 INFO (coreLoadExecutor-10453-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 2268649 INFO (coreLoadExecutor-10453-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 2268702 INFO (coreLoadExecutor-10453-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2268702 INFO (coreLoadExecutor-10453-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1647821884945858560
[junit4] 2> 2268703 INFO (searcherExecutor-10454-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 2268703 INFO (searcherExecutor-10454-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 2268703 INFO (searcherExecutor-10454-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 2268703 INFO (searcherExecutor-10454-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 2268704 INFO (searcherExecutor-10454-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 2268704 INFO (searcherExecutor-10454-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 2268704 INFO (searcherExecutor-10454-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 2268704 INFO (searcherExecutor-10454-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 2268704 INFO (searcherExecutor-10454-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 2268705 INFO (searcherExecutor-10454-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@79f261c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2268810 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedTermsComponentTest_61A1717234AEDCD5-001/tempDir-001/shard0/cores/collection1
[junit4] 2> 2268811 WARN (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2268812 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 2268812 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2268812 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 11.0.4+11
[junit4] 2> 2268812 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2268812 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2268813 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2268813 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5457f5b2{/,null,AVAILABLE}
[junit4] 2> 2268813 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@116ee393{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:37901}
[junit4] 2> 2268813 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.e.j.s.Server Started @2268844ms
[junit4] 2> 2268813 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=37901, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedTermsComponentTest_61A1717234AEDCD5-001/tempDir-001/shard0/cores}
[junit4] 2> 2268814 ERROR (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2268814 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2268814 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 2268814 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 2268814 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2268814 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-10-19T11:43:29.797240Z
[junit4] 2> 2268814 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedTermsComponentTest_61A1717234AEDCD5-001/tempDir-001/shard0/solr.xml
[junit4] 2> 2268816 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2268816 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2268816 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@3e77bd4c, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2268968 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2268969 WARN (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@21fbafc5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2268969 WARN (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@21fbafc5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2268972 WARN (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@51d8d87e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2268972 WARN (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@51d8d87e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2268983 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2268983 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2268995 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3e77bd4c
[junit4] 2> 2269003 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3e77bd4c
[junit4] 2> 2269004 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3e77bd4c
[junit4] 2> 2269005 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedTermsComponentTest_61A1717234AEDCD5-001/tempDir-001/shard0/cores
[junit4] 2> 2269005 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 2269005 INFO (coreLoadExecutor-10467-thread-1) [ x:collection1 ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2269020 INFO (coreLoadExecutor-10467-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 2269041 INFO (coreLoadExecutor-10467-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 2269123 INFO (coreLoadExecutor-10467-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2269131 INFO (coreLoadExecutor-10467-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 2269131 INFO (coreLoadExecutor-10467-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3e77bd4c
[junit4] 2> 2269131 INFO (coreLoadExecutor-10467-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedTermsComponentTest_61A1717234AEDCD5-001/tempDir-001/shard0/cores/collection1/data/]
[junit4] 2> 2269132 INFO (coreLoadExecutor-10467-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=41, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 2269182 INFO (coreLoadExecutor-10467-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2269182 INFO (coreLoadExecutor-10467-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2269184 INFO (coreLoadExecutor-10467-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2269184 INFO (coreLoadExecutor-10467-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2269184 INFO (coreLoadExecutor-10467-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=66.3232421875, floorSegmentMB=0.2509765625, forceMergeDeletesPctAllowed=10.54651759954363, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=28.939946345181085
[junit4] 2> 2269185 INFO (coreLoadExecutor-10467-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@34cd6cc7[collection1] main]
[junit4] 2> 2269185 INFO (coreLoadExecutor-10467-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 2269198 INFO (coreLoadExecutor-10467-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 2269269 INFO (coreLoadExecutor-10467-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2269269 INFO (coreLoadExecutor-10467-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1647821885540401152
[junit4] 2> 2269271 INFO (searcherExecutor-10468-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 2269271 INFO (searcherExecutor-10468-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 2269271 INFO (searcherExecutor-10468-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 2269271 INFO (searcherExecutor-10468-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 2269271 INFO (searcherExecutor-10468-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 2269271 INFO (TEST-DistributedTermsComponentTest.test-seed#[61A1717234AEDCD5]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 2269272 INFO (searcherExecutor-10468-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 2269272 INFO (searcherExecutor-10468-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 2269272 INFO (searcherExecutor-10468-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 2269272 INFO (searcherExecutor-10468-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 2269273 INFO (searcherExecutor-10468-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@34cd6cc7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2269284 INFO (qtp207829275-37662) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1647821885555081216)} 0 0
[junit4] 2> 2269299 INFO (qtp1287623297-37683) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1647821885571858432)} 0 0
[junit4] 2> 2269300 INFO (qtp207829275-37664) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1033141506 (1647821885572907008)]} 0 0
[junit4] 2> 2269301 INFO (qtp1287623297-37685) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1033141506 (1647821885573955584)]} 0 0
[junit4] 2> 2269302 INFO (qtp207829275-37663) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[-1717758778 (1647821885573955584)]} 0 0
[junit4] 2> 2269303 INFO (qtp1287623297-37684) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[-1717758778 (1647821885575004160)]} 0 1
[junit4] 2> 2269303 INFO (qtp207829275-37662) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1618743844 (1647821885576052736)]} 0 0
[junit4] 2> 2269304 INFO (qtp1287623297-37683) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1618743844 (1647821885577101312)]} 0 0
[junit4] 2> 2269304 INFO (qtp207829275-37664) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[833889235 (1647821885577101312)]} 0 0
[junit4] 2> 2269304 INFO (qtp1287623297-37685) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[833889235 (1647821885577101313)]} 0 0
[junit4] 2> 2269305 INFO (qtp207829275-37663) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[-1335501470 (1647821885578149888)]} 0 0
[junit4] 2> 2269305 INFO (qtp1287623297-37684) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[-1335501470 (1647821885578149888)]} 0 0
[junit4] 2> 2269306 INFO (qtp207829275-37662) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[-262434121 (1647821885579198464)]} 0 0
[junit4] 2> 2269306 INFO (qtp1287623297-37683) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[-262434121 (1647821885579198464)]} 0 0
[junit4] 2> 2269306 INFO (qtp207829275-37664) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[717881970 (1647821885579198465)]} 0 0
[junit4] 2> 2269307 INFO (qtp1287623297-37685) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[717881970 (1647821885580247040)]} 0 0
[junit4] 2> 2269307 INFO (qtp207829275-37663) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[-1346649962 (1647821885580247040)]} 0 0
[junit4] 2> 2269308 INFO (qtp1287623297-37684) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[-1346649962 (1647821885581295616)]} 0 0
[junit4] 2> 2269308 INFO (qtp207829275-37662) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1647821885581295616,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2269308 INFO (qtp207829275-37662) [ x:collection1 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@683c6e86 commitCommandVersion:1647821885581295616
[junit4] 2> 2269311 INFO (qtp207829275-37662) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3c56d94f[collection1] main]
[junit4] 2> 2269311 INFO (qtp207829275-37662) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2269312 INFO (searcherExecutor-10454-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3c56d94f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):c8:[diagnostics={os=Linux, os.version=5.0.0-25-generic, java.runtime.version=11.0.4+11, os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=11.0.4+11, java.version=11.0.4, timestamp=1571485410293, java.vendor=AdoptOpenJDK}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 2269313 INFO (qtp207829275-37662) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 4
[junit4] 2> 2269313 INFO (qtp1287623297-37683) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1647821885586538496,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2269313 INFO (qtp1287623297-37683) [ x:collection1 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5ef9634 commitCommandVersion:1647821885586538496
[junit4] 2> 2269316 INFO (qtp1287623297-37683) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6be4a657[collection1] main]
[junit4] 2> 2269316 INFO (qtp1287623297-37683) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2269318 INFO (searcherExecutor-10468-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6be4a657[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):c8:[diagnostics={os=Linux, os.version=5.0.0-25-generic, java.runtime.version=11.0.4+11, os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=11.0.4+11, java.version=11.0.4, timestamp=1571485410297, java.vendor=AdoptOpenJDK}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 2269318 INFO (qtp1287623297-37683) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 5
[junit4] 2> 2269321 INFO (qtp207829275-37664) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={distrib=false&qt=/terms&terms=true&terms.fl=b_t&wt=javabin&version=2&shards.qt=/terms} status=0 QTime=1
[junit4] 2> 2269345 INFO (qtp1287623297-37684) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={df=text&distrib=false&qt=/terms&shards.purpose=1024&terms.sort=index&shard.url=[ff01::083]:33332/|[ff01::114]:33332/|[ff01::213]:33332/|https://127.0.0.1:37901/collection1&version=2&shards.qt=/terms&terms=true&omitHeader=false&terms.fl=b_t&terms.limit=-1&NOW=1571485410305&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 2269346 INFO (qtp1287623297-37685) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={shards=[ff01::083]:33332/|[ff01::114]:33332/|[ff01::213]:33332/|https://127.0.0.1:37901/collection1&qt=/terms&terms=true&terms.fl=b_t&wt=javabin&version=2&shards.qt=/terms} status=0 QTime=23
[junit4] 2> 2269347 INFO (qtp207829275-37663) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={distrib=false&qt=/terms&terms=true&terms.fl=b_t&wt=javabin&version=2&shards.qt=/terms} status=0 QTime=0
[junit4] 2> 2269348 INFO (qtp1287623297-37684) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={df=text&distrib=false&qt=/terms&shards.purpose=1024&terms.sort=index&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|https://127.0.0.1:37901/collection1&version=2&shards.qt=/terms&terms=true&omitHeader=false&terms.fl=b_t&terms.limit=-1&NOW=1571485410330&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 2269351 INFO (qtp1287623297-37683) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={shards=[ff01::114]:33332/|[ff01::213]:33332/|https://127.0.0.1:37901/collection1&qt=/terms&terms=true&terms.fl=b_t&wt=javabin&version=2&shards.qt=/terms} status=0 QTime=4
[junit4] 2> 2269371 INFO (qtp1287623297-37683) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={df=text&distrib=false&qt=/terms&shards.purpose=1024&terms.sort=index&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|https://127.0.0.1:37901/collection1&version=2&shards.qt=/terms&terms=true&omitHeader=false&terms.fl=b_t&terms.limit=-1&NOW=1571485410335&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 2269372 INFO (qtp207829275-37662) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={shards=[ff01::114]:33332/|[ff01::213]:33332/|https://127.0.0.1:37901/collection1&qt=/terms&terms=true&terms.fl=b_t&wt=json&version=1&shards.qt=/terms} status=0 QTime=19
[junit4] 2> 2269373 INFO (qtp1287623297-37684) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={df=text&distrib=false&qt=/terms&shards.purpose=1024&terms.sort=index&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|https://127.0.0.1:37901/collection1|[ff01::114]:33332/&version=2&shards.qt=/terms&terms=true&omitHeader=false&terms.fl=b_t&terms.limit=-1&NOW=1571485410355&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 2269373 INFO (qtp1287623297-37685) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={shards=[ff01::083]:33332/|[ff01::213]:33332/|https://127.0.0.1:37901/collection1|[ff01::114]:33332/&qt=/terms&terms=true&terms.fl=b_t&wt=json&version=1&shards.qt=/terms} status=0 QTime=0
[junit4] 2> 2269380 INFO (qtp1287623297-37683) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={df=text&distrib=false&qt=/terms&shards.purpose=1024&terms.sort=index&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|https://127.0.0.1:37901/collection1|[ff01::114]:33332/&version=2&shards.qt=/terms&terms=true&omitHeader=false&terms.fl=b_t&terms.limit=-1&NOW=1571485410357&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 2269381 INFO (qtp207829275-37664) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={shards=[ff01::083]:33332/|[ff01::213]:33332/|https://127.0.0.1:37901/collection1|[ff01::114]:33332/&qt=/terms&terms=true&terms.fl=b_t&wt=xml&version=2.2&shards.qt=/terms} status=0 QTime=6
[junit4] 2> 2269382 INFO (qtp1287623297-37685) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={df=text&distrib=false&qt=/terms&shards.purpose=1024&terms.sort=index&shard.url=https://127.0.0.1:37901/collection1|[ff01::213]:33332/|[ff01::083]:33332/&version=2&shards.qt=/terms&terms=true&omitHeader=false&terms.fl=b_t&terms.limit=-1&NOW=1571485410364&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 2269383 INFO (qtp1287623297-37684) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={shards=https://127.0.0.1:37901/collection1|[ff01::213]:33332/|[ff01::083]:33332/&qt=/terms&terms=true&terms.fl=b_t&wt=xml&version=2.2&shards.qt=/terms} status=0 QTime=1
[junit4] 2> 2269384 INFO (qtp207829275-37663) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={distrib=false&qt=/terms&terms=true&terms.fl=b_t&terms.limit=5&wt=javabin&version=2&shards.qt=/terms&terms.lower=s} status=0 QTime=0
[junit4] 2> 2269385 INFO (qtp1287623297-37685) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={df=text&distrib=false&qt=/terms&shards.purpose=1024&terms.sort=index&shard.url=[ff01::213]:33332/|[ff01::114]:33332/|[ff01::083]:33332/|https://127.0.0.1:37901/collection1&version=2&shards.qt=/terms&terms=true&omitHeader=false&terms.fl=b_t&terms.limit=-1&NOW=1571485410367&isShard=true&wt=javabin&terms.lower=s} status=0 QTime=0
[junit4] 2> 2269385 INFO (qtp1287623297-37683) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={shards=[ff01::213]:33332/|[ff01::114]:33332/|[ff01::083]:33332/|https://127.0.0.1:37901/collection1&qt=/terms&terms=true&terms.fl=b_t&terms.limit=5&wt=javabin&version=2&shards.qt=/terms&terms.lower=s} status=0 QTime=1
[junit4] 2> 2269386 INFO (qtp207829275-37662) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={distrib=false&qt=/terms&terms=true&terms.fl=b_t&terms.limit=5&wt=javabin&version=2&shards.qt=/terms&terms.lower=s} status=0 QTime=0
[junit4] 2> 2269387 INFO (qtp1287623297-37685) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={df=text&distrib=false&qt=/terms&shards.purpose=1024&terms.sort=index&shard.url=https://127.0.0.1:37901/collection1&version=2&shards.qt=/terms&terms=true&omitHeader=false&terms.fl=b_t&terms.limit=-1&NOW=1571485410369&isShard=true&wt=javabin&terms.lower=s} status=0 QTime=0
[junit4] 2> 2269387 INFO (qtp1287623297-37684) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={shards=https://127.0.0.1:37901/collection1&qt=/terms&terms=true&terms.fl=b_t&terms.limit=5&wt=javabin&version=2&shards.qt=/terms&terms.lower=s} status=0 QTime=1
[junit4] 2> 2269388 INFO (qtp1287623297-37683) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={df=text&distrib=false&qt=/terms&shards.purpose=1024&terms.sort=index&shard.url=https://127.0.0.1:37901/collection1&version=2&shards.qt=/terms&terms=true&omitHeader=false&terms.fl=b_t&terms.limit=-1&NOW=1571485410371&isShard=true&wt=javabin&terms.lower=s} status=0 QTime=0
[junit4] 2> 2269389 INFO (qtp207829275-37664) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={shards=https://127.0.0.1:37901/collection1&qt=/terms&terms=true&terms.fl=b_t&terms.limit=5&wt=json&version=1&shards.qt=/terms&terms.lower=s} status=0 QTime=0
[junit4] 2> 2269390 INFO (qtp1287623297-37684) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={df=text&distrib=false&qt=/terms&shards.purpose=1024&terms.sort=index&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|https://127.0.0.1:37901/collection1&version=2&shards.qt=/terms&terms=true&omitHeader=false&terms.fl=b_t&terms.limit=-1&NOW=1571485410372&isShard=true&wt=javabin&terms.lower=s} status=0 QTime=0
[junit4] 2> 2269390 INFO (qtp1287623297-37685) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={shards=[ff01::083]:33332/|[ff01::213]:33332/|https://127.0.0.1:37901/collection1&qt=/terms&terms=true&terms.fl=b_t&terms.limit=5&wt=json&version=1&shards.qt=/terms&terms.lower=s} status=0 QTime=0
[junit4] 2> 2269391 INFO (qtp1287623297-37683) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={df=text&distrib=false&qt=/terms&shards.purpose=1024&terms.sort=index&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|https://127.0.0.1:37901/collection1&version=2&shards.qt=/terms&terms=true&omitHeader=false&terms.fl=b_t&terms.limit=-1&NOW=1571485410374&isShard=true&wt=javabin&terms.lower=s} status=0 QTime=0
[junit4] 2> 2269392 INFO (qtp207829275-37663) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={shards=[ff01::083]:33332/|[ff01::213]:33332/|https://127.0.0.1:37901/collection1&qt=/terms&terms=true&terms.fl=b_t&terms.limit=5&wt=xml&version=2.2&shards.qt=/terms&terms.lower=s} status=0 QTime=1
[junit4] 2> 2269393 INFO (qtp1287623297-37685) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={df=text&distrib=false&qt=/terms&shards.purpose=1024&terms.sort=index&shard.url=https://127.0.0.1:37901/collection1|[ff01::213]:33332/&version=2&shards.qt=/terms&terms=true&omitHeader=false&terms.fl=b_t&terms.limit=-1&NOW=1571485410375&isShard=true&wt=javabin&terms.lower=s} status=0 QTime=0
[junit4] 2> 2269393 INFO (qtp1287623297-37684) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={shards=https://127.0.0.1:37901/collection1|[ff01::213]:33332/&qt=/terms&terms=true&terms.fl=b_t&terms.limit=5&wt=xml&version=2.2&shards.qt=/terms&terms.lower=s} status=0 QTime=0
[junit4] 2> 2269394 INFO (qtp207829275-37662) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={terms.prefix=sn&distrib=false&qt=/terms&terms=true&terms.mincount=2&terms.fl=b_t&terms.limit=5&terms.maxcount=1&wt=javabin&version=2&shards.qt=/terms&terms.lower=sn} status=0 QTime=0
[junit4] 2> 2269395 INFO (qtp1287623297-37685) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={df=text&distrib=false&qt=/terms&shards.purpose=1024&terms.sort=index&shard.url=https://127.0.0.1:37901/collection1&version=2&shards.qt=/terms&terms.prefix=sn&terms=true&omitHeader=false&terms.fl=b_t&terms.limit=-1&NOW=1571485410378&isShard=true&wt=javabin&terms.lower=sn} status=0 QTime=0
[junit4] 2> 2269396 INFO (qtp1287623297-37683) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={shards=https://127.0.0.1:37901/collection1&terms.prefix=sn&qt=/terms&terms=true&terms.mincount=2&terms.fl=b_t&terms.limit=5&terms.maxcount=1&wt=javabin&version=2&shards.qt=/terms&terms.lower=sn} status=0 QTime=0
[junit4] 2> 2269396 INFO (qtp207829275-37664) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={terms.prefix=sn&distrib=false&qt=/terms&terms=true&terms.mincount=2&terms.fl=b_t&terms.limit=5&terms.maxcount=1&wt=javabin&version=2&shards.qt=/terms&terms.lower=sn} status=0 QTime=0
[junit4] 2> 2269410 INFO (qtp1287623297-37685) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={df=text&distrib=false&qt=/terms&shards.purpose=1024&terms.sort=index&shard.url=[ff01::083]:33332/|https://127.0.0.1:37901/collection1&version=2&shards.qt=/terms&terms.prefix=sn&terms=true&omitHeader=false&terms.fl=b_t&terms.limit=-1&NOW=1571485410392&isShard=true&wt=javabin&terms.lower=sn} status=0 QTime=0
[junit4] 2> 2269410 INFO (qtp1287623297-37684) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={shards=[ff01::083]:33332/|https://127.0.0.1:37901/collection1&terms.prefix=sn&qt=/terms&terms=true&terms.mincount=2&terms.fl=b_t&terms.limit=5&terms.maxcount=1&wt=javabin&version=2&shards.qt=/terms&terms.lower=sn} status=0 QTime=1
[junit4] 2> 2269411 INFO (qtp1287623297-37683) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={df=text&distrib=false&qt=/terms&shards.purpose=1024&terms.sort=index&shard.url=[ff01::083]:33332/|https://127.0.0.1:37901/collection1&version=2&shards.qt=/terms&terms.prefix=sn&terms=true&omitHeader=false&terms.fl=b_t&terms.limit=-1&NOW=1571485410394&isShard=true&wt=javabin&terms.lower=sn} status=0 QTime=0
[junit4] 2> 2269412 INFO (qtp207829275-37663) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={shards=[ff01::083]:33332/|https://127.0.0.1:37901/collection1&terms.prefix=sn&qt=/terms&terms=true&terms.mincount=2&terms.fl=b_t&terms.limit=5&terms.maxcount=1&wt=json&version=1&shards.qt=/terms&terms.lower=sn} status=0 QTime=1
[junit4] 2> 2269413 INFO (qtp1287623297-37684) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={df=text&distrib=false&qt=/terms&shards.purpose=1024&terms.sort=index&shard.url=https://127.0.0.1:37901/collection1|[ff01::083]:33332/&version=2&shards.qt=/terms&terms.prefix=sn&terms=true&omitHeader=false&terms.fl=b_t&terms.limit=-1&NOW=1571485410395&isShard=true&wt=javabin&terms.lower=sn} status=0 QTime=0
[junit4] 2> 2269413 INFO (qtp1287623297-37685) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={shards=https://127.0.0.1:37901/collection1|[ff01::083]:33332/&terms.prefix=sn&qt=/terms&terms=true&terms.mincount=2&terms.fl=b_t&terms.limit=5&terms.maxcount=1&wt=json&version=1&shards.qt=/terms&terms.lower=sn} status=0 QTime=1
[junit4] 2> 2269415 INFO (qtp1287623297-37683) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={df=text&distrib=false&qt=/terms&shards.purpose=1024&terms.sort=index&shard.url=https://127.0.0.1:37901/collection1|[ff01::083]:33332/&version=2&shards.qt=/terms&terms.prefix=sn&terms=true&omitHeader=false&terms.fl=b_t&terms.limit=-1&NOW=1571485410397&isShard=true&wt=javabin&terms.lower=sn} status=0 QTime=0
[junit4] 2> 2269415 INFO (qtp207829275-37662) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={shards=https://127.0.0.1:37901/collection1|[ff01::083]:33332/&terms.prefix=sn&qt=/terms&terms=true&terms.mincount=2&terms.fl=b_t&terms.limit=5&terms.maxcount=1&wt=xml&version=2.2&shards.qt=/terms&terms.lower=sn} status=0 QTime=1
[junit4] 2> 2269416 INFO (qtp1287623297-37685) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={df=text&distrib=false&qt=/terms&shards.purpose=1024&terms.sort=index&shard.url=[ff01::213]:33332/|https://127.0.0.1:37901/collection1|[ff01::114]:33332/|[ff01::083]:33332/&version=2&shards.qt=/terms&terms.prefix=sn&terms=true&omitHeader=false&terms.fl=b_t&terms.limit=-1&NOW=1571485410399&isShard=true&wt=javabin&terms.lower=sn} status=0 QTime=0
[junit4] 2> 2269417 INFO (qtp1287623297-37684) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={shards=[ff01::213]:33332/|https://127.0.0.1:37901/collection1|[ff01::114]:33332/|[ff01::083]:33332/&terms.prefix=sn&qt=/terms&terms=true&terms.mincount=2&terms.fl=b_t&terms.limit=5&terms.maxcount=1&wt=xml&version=2.2&shards.qt=/terms&terms.lower=sn} status=0 QTime=0
[junit4] 2> 2269417 INFO (qtp207829275-37664) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={terms.prefix=s&distrib=false&qt=/terms&terms=true&terms.fl=b_t&terms.limit=5&wt=javabin&version=2&terms.upper=sn&shards.qt=/terms&terms.lower=s} status=0 QTime=0
[junit4] 2> 2269418 INFO (qtp1287623297-37685) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={df=text&distrib=false&qt=/terms&shards.purpose=1024&terms.sort=index&shard.url=[ff01::213]:33332/|[ff01::114]:33332/|https://127.0.0.1:37901/collection1&version=2&shards.qt=/terms&terms.prefix=s&terms=true&omitHeader=false&terms.fl=b_t&terms.limit=-1&NOW=1571485410401&isShard=true&wt=javabin&terms.upper=sn&terms.lower=s} status=0 QTime=0
[junit4] 2> 2269419 INFO (qtp1287623297-37683) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={shards=[ff01::213]:33332/|[ff01::114]:33332/|https://127.0.0.1:37901/collection1&terms.prefix=s&qt=/terms&terms=true&terms.fl=b_t&terms.limit=5&wt=javabin&version=2&terms.upper=sn&shards.qt=/terms&terms.lower=s} status=0 QTime=1
[junit4] 2> 2269420 INFO (qtp207829275-37663) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={terms.prefix=s&distrib=false&qt=/terms&terms=true&terms.fl=b_t&terms.limit=5&wt=javabin&version=2&terms.upper=sn&shards.qt=/terms&terms.lower=s} status=0 QTime=0
[junit4] 2> 2269421 INFO (qtp1287623297-37685) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={df=text&distrib=false&qt=/terms&shards.purpose=1024&terms.sort=index&shard.url=https://127.0.0.1:37901/collection1&version=2&shards.qt=/terms&terms.prefix=s&terms=true&omitHeader=false&terms.fl=b_t&terms.limit=-1&NOW=1571485410403&isShard=true&wt=javabin&terms.upper=sn&terms.lower=s} status=0 QTime=0
[junit4] 2> 2269421 INFO (qtp1287623297-37684) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={shards=https://127.0.0.1:37901/collection1&terms.prefix=s&qt=/terms&terms=true&terms.fl=b_t&terms.limit=5&wt=javabin&version=2&terms.upper=sn&shards.qt=/terms&terms.lower=s} status=0 QTime=0
[junit4] 2> 2269422 INFO (qtp1287623297-37683) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={df=text&distrib=false&qt=/terms&shards.purpose=1024&terms.sort=index&shard.url=https://127.0.0.1:37901/collection1&version=2&shards.qt=/terms&terms.prefix=s&terms=true&omitHeader=false&terms.fl=b_t&terms.limit=-1&NOW=1571485410404&isShard=true&wt=javabin&terms.upper=sn&terms.lower=s} status=0 QTime=0
[junit4] 2> 2269422 INFO (qtp207829275-37662) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={shards=https://127.0.0.1:37901/collection1&terms.prefix=s&qt=/terms&terms=true&terms.fl=b_t&terms.limit=5&wt=json&version=1&terms.upper=sn&shards.qt=/terms&terms.lower=s} status=0 QTime=0
[junit4] 2> 2269424 INFO (qtp1287623297-37684) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={df=text&distrib=false&qt=/terms&shards.purpose=1024&terms.sort=index&shard.url=https://127.0.0.1:37901/collection1|[ff01::083]:33332/&version=2&shards.qt=/terms&terms.prefix=s&terms=true&omitHeader=false&terms.fl=b_t&terms.limit=-1&NOW=1571485410406&isShard=true&wt=javabin&terms.upper=sn&terms.lower=s} status=0 QTime=0
[junit4] 2> 2269424 INFO (qtp1287623297-37685) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={shards=https://127.0.0.1:37901/collection1|[ff01::083]:33332/&terms.prefix=s&qt=/terms&terms=true&terms.fl=b_t&terms.limit=5&wt=json&version=1&terms.upper=sn&shards.qt=/terms&terms.lower=s} status=0 QTime=1
[junit4] 2> 2269426 INFO (qtp1287623297-37685) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={df=text&distrib=false&qt=/terms&shards.purpose=1024&terms.sort=index&shard.url=https://127.0.0.1:37901/collection1|[ff01::083]:33332/&version=2&shards.qt=/terms&terms.prefix=s&terms=true&omitHeader=false&terms.fl=b_t&terms.limit=-1&NOW=1571485410408&isShard=true&wt=javabin&terms.upper=sn&terms.lower=s} status=0 QTime=0
[junit4] 2> 2269426 INFO (qtp207829275-37664) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={shards=https://127.0.0.1:37901/collection1|[ff01::083]:33332/&terms.prefix=s&qt=/terms&terms=true&terms.fl=b_t&terms.limit=5&wt=xml&version=2.2&terms.upper=sn&shards.qt=/terms&terms.lower=s} status=0 QTime=1
[junit4] 2> 2269427 INFO (qtp1287623297-37683) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={df=text&distrib=false&qt=/terms&shards.purpose=1024&terms.sort=index&shard.url=https://127.0.0.1:37901/collection1&version=2&shards.qt=/terms&terms.prefix=s&terms=true&omitHeader=false&terms.fl=b_t&terms.limit=-1&NOW=1571485410410&isShard=true&wt=javabin&terms.upper=sn&terms.lower=s} status=0 QTime=0
[junit4] 2> 2269428 INFO (qtp1287623297-37684) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={shards=https://127.0.0.1:37901/collection1&terms.prefix=s&qt=/terms&terms=true&terms.fl=b_t&terms.limit=5&wt=xml&version=2.2&terms.upper=sn&shards.qt=/terms&terms.lower=s} status=0 QTime=1
[junit4] 2> 2269428 INFO (qtp207829275-37663) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={terms.prefix=s&distrib=false&qt=/terms&terms=true&terms.fl=b_t&terms.limit=5&terms.sort=index&terms.maxcount=-1&wt=javabin&version=2&shards.qt=/terms&terms.lower=s} status=0 QTime=0
[junit4] 2> 2269430 INFO (qtp1287623297-37683) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={df=text&distrib=false&qt=/terms&shards.purpose=1024&terms.sort=index&shard.url=https://127.0.0.1:37901/collection1|[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/&terms.maxcount=-1&version=2&shards.qt=/terms&terms.prefix=s&terms=true&omitHeader=false&terms.fl=b_t&terms.limit=5&NOW=1571485410412&isShard=true&wt=javabin&terms.lower=s} status=0 QTime=0
[junit4] 2> 2269431 INFO (qtp1287623297-37685) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={shards=https://127.0.0.1:37901/collection1|[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/&terms.prefix=s&qt=/terms&terms=true&terms.fl=b_t&terms.limit=5&terms.sort=index&terms.maxcount=-1&wt=javabin&version=2&shards.qt=/terms&terms.lower=s} status=0 QTime=2
[junit4] 2> 2269432 INFO (qtp207829275-37662) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={terms.prefix=s&distrib=false&qt=/terms&terms=true&terms.fl=b_t&terms.limit=5&terms.sort=index&terms.maxcount=-1&wt=javabin&version=2&shards.qt=/terms&terms.lower=s} status=0 QTime=0
[junit4] 2> 2269433 INFO (qtp1287623297-37683) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={df=text&distrib=false&qt=/terms&shards.purpose=1024&terms.sort=index&shard.url=[ff01::213]:33332/|[ff01::083]:33332/|https://127.0.0.1:37901/collection1|[ff01::114]:33332/&terms.maxcount=-1&version=2&shards.qt=/terms&terms.prefix=s&terms=true&omitHeader=false&terms.fl=b_t&terms.limit=5&NOW=1571485410416&isShard=true&wt=javabin&terms.lower=s} status=0 QTime=0
[junit4] 2> 2269434 INFO (qtp1287623297-37684) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={shards=[ff01::213]:33332/|[ff01::083]:33332/|https://127.0.0.1:37901/collection1|[ff01::114]:33332/&terms.prefix=s&qt=/terms&terms=true&terms.fl=b_t&terms.limit=5&terms.sort=index&terms.maxcount=-1&wt=javabin&version=2&shards.qt=/terms&terms.lower=s} status=0 QTime=1
[junit4] 2> 2269435 INFO (qtp1287623297-37685) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/terms params={df=text&distrib=false&qt=/terms&shards.purpose=1024&terms.sort=index&sh
[...truncated too long message...]
-6) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 40636 INFO (closeThreadPool-162-thread-6) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@686abe25: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@3d4eecb6
[junit4] 2> 40637 INFO (closeThreadPool-162-thread-6) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 40637 INFO (closeThreadPool-162-thread-6) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2e4c0c16: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@6b0af0cf
[junit4] 2> 40648 INFO (closeThreadPool-162-thread-8) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 40649 INFO (closeThreadPool-162-thread-8) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5c289ed: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@5415396e
[junit4] 2> 40652 INFO (closeThreadPool-162-thread-8) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 40652 INFO (closeThreadPool-162-thread-8) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@132daf9f: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@3d4eecb6
[junit4] 2> 40653 INFO (closeThreadPool-162-thread-8) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 40653 INFO (closeThreadPool-162-thread-8) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@19c4994f: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@6b0af0cf
[junit4] 2> 40677 INFO (closeThreadPool-162-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@104488be{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:0}
[junit4] 2> 40681 INFO (closeThreadPool-162-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@27b41b40{/,null,UNAVAILABLE}
[junit4] 2> 40681 INFO (closeThreadPool-162-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 40686 INFO (closeThreadPool-162-thread-7) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@2f593c10{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:0}
[junit4] 2> 40686 INFO (closeThreadPool-162-thread-7) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@68b36fe9{/,null,UNAVAILABLE}
[junit4] 2> 40689 INFO (closeThreadPool-162-thread-7) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 40693 INFO (closeThreadPool-162-thread-6) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@4e96b0e7{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:0}
[junit4] 2> 40693 INFO (closeThreadPool-162-thread-6) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@229a3ca{/,null,UNAVAILABLE}
[junit4] 2> 40694 INFO (closeThreadPool-162-thread-6) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 40703 INFO (closeThreadPool-162-thread-8) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@7ee0fd8b{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:0}
[junit4] 2> 40703 INFO (closeThreadPool-162-thread-8) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@494db111{/,null,UNAVAILABLE}
[junit4] 2> 40703 INFO (closeThreadPool-162-thread-8) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=DistributedTermsComponentTest -Dtests.method=test -Dtests.seed=61A1717234AEDCD5 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=saq-KE -Dtests.timezone=America/Rankin_Inlet -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 12.3s J1 | DistributedTermsComponentTest.test <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:40337/collection1: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[https://[ff01::114]:33332, https://[ff01::083]:33332, https://[ff01::213]:33332, https://127.0.0.1:40337/collection1]
[junit4] > at __randomizedtesting.SeedInfo.seed([61A1717234AEDCD5:E9F54EA89A52B12D]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:665)
[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.SolrRequest.process(SolrRequest.java:207)
[junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1003)
[junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1018)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.queryServer(BaseDistributedSearchTestCase.java:626)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:678)
[junit4] > at org.apache.solr.handler.component.DistributedTermsComponentTest.query(DistributedTermsComponentTest.java:111)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:656)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:635)
[junit4] > at org.apache.solr.handler.component.DistributedTermsComponentTest.query(DistributedTermsComponentTest.java:106)
[junit4] > at org.apache.solr.handler.component.DistributedTermsComponentTest.test(DistributedTermsComponentTest.java:82)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:566)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1108)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:834)
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedTermsComponentTest_61A1717234AEDCD5-002
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@17038c2b), locale=saq-KE, timezone=America/Rankin_Inlet
[junit4] 2> NOTE: Linux 5.0.0-25-generic amd64/AdoptOpenJDK 11.0.4 (64-bit)/cpus=8,threads=1,free=360197160,total=530055168
[junit4] 2> NOTE: All tests run in this JVM: [DistributedTermsComponentTest, DistributedTermsComponentTest]
[junit4] Completed [5/5 (5!)] on J1 in 12.52s, 1 test, 1 error <<< FAILURES!
[...truncated 15 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1510: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1037: There were test failures: 5 suites, 5 tests, 5 errors [seed: 61A1717234AEDCD5]
Total time: 43 seconds
[repro] Setting last failure code to 256
[repro] Failures:
[repro] 5/5 failed: org.apache.solr.handler.component.DistributedTermsComponentTest
[repro] Exiting with code 256
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
No credentials specified
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all warnings.
<Git Blamer> GIT_COMMIT=cabc125eefce10a8b021f1698371eabc6289ed60, workspace=/home/jenkins/workspace/Lucene-Solr-master-Linux
[WARNINGS] Computing warning deltas based on reference build #24922
Recording test results
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[JENKINS] Lucene-Solr-master-Linux (64bit/jdk-12.0.1) - Build #
24924 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/24924/
Java: 64bit/jdk-12.0.1 -XX:-UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest.testAsyncRequests
Error Message:
AddReplica did not complete expected same:<COMPLETED> was not:<RUNNING>
Stack Trace:
java.lang.AssertionError: AddReplica did not complete expected same:<COMPLETED> was not:<RUNNING>
at __randomizedtesting.SeedInfo.seed([6880840F2549F3D6:8CC4B8B883E1BD09]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotSame(Assert.java:828)
at org.junit.Assert.assertSame(Assert.java:771)
at org.apache.solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest.testAsyncRequests(CollectionsAPIAsyncDistributedZkTest.java:162)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:567)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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:835)
Build Log:
[...truncated 13933 lines...]
[junit4] Suite: org.apache.solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest
[junit4] 2> 436153 INFO (SUITE-CollectionsAPIAsyncDistributedZkTest-seed#[6880840F2549F3D6]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest_6880840F2549F3D6-001/data-dir-77-001
[junit4] 2> 436153 WARN (SUITE-CollectionsAPIAsyncDistributedZkTest-seed#[6880840F2549F3D6]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=14 numCloses=14
[junit4] 2> 436153 INFO (SUITE-CollectionsAPIAsyncDistributedZkTest-seed#[6880840F2549F3D6]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 436153 INFO (SUITE-CollectionsAPIAsyncDistributedZkTest-seed#[6880840F2549F3D6]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 436154 INFO (SUITE-CollectionsAPIAsyncDistributedZkTest-seed#[6880840F2549F3D6]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 436157 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdRaceCondition-seed#[6880840F2549F3D6]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testAsyncIdRaceCondition
[junit4] 2> 436158 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdRaceCondition-seed#[6880840F2549F3D6]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest_6880840F2549F3D6-001/tempDir-001
[junit4] 2> 436158 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdRaceCondition-seed#[6880840F2549F3D6]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 436159 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 436159 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 436259 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdRaceCondition-seed#[6880840F2549F3D6]) [ ] o.a.s.c.ZkTestServer start zk server on port:34337
[junit4] 2> 436259 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdRaceCondition-seed#[6880840F2549F3D6]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:34337
[junit4] 2> 436259 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdRaceCondition-seed#[6880840F2549F3D6]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:34337
[junit4] 2> 436259 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdRaceCondition-seed#[6880840F2549F3D6]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 34337
[junit4] 2> 436260 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdRaceCondition-seed#[6880840F2549F3D6]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 436263 INFO (zkConnectionManagerCallback-3096-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 436263 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdRaceCondition-seed#[6880840F2549F3D6]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 436265 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdRaceCondition-seed#[6880840F2549F3D6]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 436266 INFO (zkConnectionManagerCallback-3098-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 436266 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdRaceCondition-seed#[6880840F2549F3D6]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 436267 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdRaceCondition-seed#[6880840F2549F3D6]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 436268 INFO (zkConnectionManagerCallback-3100-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 436268 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdRaceCondition-seed#[6880840F2549F3D6]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 436375 WARN (jetty-launcher-3101-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 436375 WARN (jetty-launcher-3101-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 436375 INFO (jetty-launcher-3101-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 436375 INFO (jetty-launcher-3101-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 436375 INFO (jetty-launcher-3101-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 436375 INFO (jetty-launcher-3101-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 436375 INFO (jetty-launcher-3101-thread-1) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 12.0.1+12
[junit4] 2> 436375 INFO (jetty-launcher-3101-thread-2) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 12.0.1+12
[junit4] 2> 436378 INFO (jetty-launcher-3101-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 436378 INFO (jetty-launcher-3101-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 436378 INFO (jetty-launcher-3101-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 436378 INFO (jetty-launcher-3101-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 436378 INFO (jetty-launcher-3101-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 436378 INFO (jetty-launcher-3101-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 436378 INFO (jetty-launcher-3101-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@36350d0c{/solr,null,AVAILABLE}
[junit4] 2> 436378 INFO (jetty-launcher-3101-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@749505b0{/solr,null,AVAILABLE}
[junit4] 2> 436379 INFO (jetty-launcher-3101-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4b3047f7{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:33075}
[junit4] 2> 436379 INFO (jetty-launcher-3101-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3c426e97{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:41285}
[junit4] 2> 436379 INFO (jetty-launcher-3101-thread-2) [ ] o.e.j.s.Server Started @436421ms
[junit4] 2> 436379 INFO (jetty-launcher-3101-thread-1) [ ] o.e.j.s.Server Started @436421ms
[junit4] 2> 436379 INFO (jetty-launcher-3101-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33075}
[junit4] 2> 436379 INFO (jetty-launcher-3101-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=41285}
[junit4] 2> 436379 ERROR (jetty-launcher-3101-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 436379 ERROR (jetty-launcher-3101-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 436379 INFO (jetty-launcher-3101-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 436379 INFO (jetty-launcher-3101-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 436379 INFO (jetty-launcher-3101-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 436379 INFO (jetty-launcher-3101-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 436379 INFO (jetty-launcher-3101-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 436379 INFO (jetty-launcher-3101-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 436379 INFO (jetty-launcher-3101-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 436379 INFO (jetty-launcher-3101-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 436379 INFO (jetty-launcher-3101-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-10-19T17:08:01.122877Z
[junit4] 2> 436379 INFO (jetty-launcher-3101-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-10-19T17:08:01.122877Z
[junit4] 2> 436383 INFO (jetty-launcher-3101-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 436383 INFO (jetty-launcher-3101-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 436384 INFO (zkConnectionManagerCallback-3104-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 436384 INFO (jetty-launcher-3101-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 436384 INFO (jetty-launcher-3101-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 436384 INFO (zkConnectionManagerCallback-3105-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 436384 INFO (jetty-launcher-3101-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 436384 INFO (jetty-launcher-3101-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 436605 INFO (jetty-launcher-3101-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 436606 WARN (jetty-launcher-3101-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@35947ba9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 436606 WARN (jetty-launcher-3101-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@35947ba9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 436608 WARN (jetty-launcher-3101-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@555e337a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 436608 WARN (jetty-launcher-3101-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@555e337a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 436609 INFO (jetty-launcher-3101-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34337/solr
[junit4] 2> 436610 INFO (jetty-launcher-3101-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 436610 INFO (zkConnectionManagerCallback-3113-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 436610 INFO (jetty-launcher-3101-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 436712 INFO (jetty-launcher-3101-thread-1) [n:127.0.0.1:41285_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 436713 INFO (zkConnectionManagerCallback-3115-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 436713 INFO (jetty-launcher-3101-thread-1) [n:127.0.0.1:41285_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 436748 INFO (jetty-launcher-3101-thread-1) [n:127.0.0.1:41285_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:41285_solr
[junit4] 2> 436748 INFO (jetty-launcher-3101-thread-1) [n:127.0.0.1:41285_solr ] o.a.s.c.Overseer Overseer (id=72335612341452806-127.0.0.1:41285_solr-n_0000000000) starting
[junit4] 2> 436751 INFO (OverseerStateUpdate-72335612341452806-127.0.0.1:41285_solr-n_0000000000) [n:127.0.0.1:41285_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:41285_solr
[junit4] 2> 436751 INFO (jetty-launcher-3101-thread-1) [n:127.0.0.1:41285_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41285_solr
[junit4] 2> 436752 INFO (zkCallback-3114-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 436760 INFO (jetty-launcher-3101-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 436761 WARN (jetty-launcher-3101-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@23d9e915[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 436761 WARN (jetty-launcher-3101-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@23d9e915[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 436764 WARN (jetty-launcher-3101-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@73fd7c5e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 436764 WARN (jetty-launcher-3101-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@73fd7c5e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 436765 INFO (jetty-launcher-3101-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34337/solr
[junit4] 2> 436767 INFO (jetty-launcher-3101-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 436767 INFO (jetty-launcher-3101-thread-1) [n:127.0.0.1:41285_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 436768 INFO (zkConnectionManagerCallback-3124-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 436768 INFO (jetty-launcher-3101-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 436782 INFO (jetty-launcher-3101-thread-1) [n:127.0.0.1:41285_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41285.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e9aaf2e
[junit4] 2> 436788 INFO (jetty-launcher-3101-thread-1) [n:127.0.0.1:41285_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41285.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e9aaf2e
[junit4] 2> 436788 INFO (jetty-launcher-3101-thread-1) [n:127.0.0.1:41285_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41285.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e9aaf2e
[junit4] 2> 436789 INFO (jetty-launcher-3101-thread-1) [n:127.0.0.1:41285_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest_6880840F2549F3D6-001/tempDir-001/node1/.
[junit4] 2> 436870 INFO (jetty-launcher-3101-thread-2) [n:127.0.0.1:33075_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 436871 INFO (zkConnectionManagerCallback-3127-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 436871 INFO (jetty-launcher-3101-thread-2) [n:127.0.0.1:33075_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 436874 INFO (jetty-launcher-3101-thread-2) [n:127.0.0.1:33075_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 436876 INFO (jetty-launcher-3101-thread-2) [n:127.0.0.1:33075_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:33075_solr as DOWN
[junit4] 2> 436877 INFO (jetty-launcher-3101-thread-2) [n:127.0.0.1:33075_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 436877 INFO (jetty-launcher-3101-thread-2) [n:127.0.0.1:33075_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33075_solr
[junit4] 2> 436877 INFO (zkCallback-3114-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 436878 INFO (zkCallback-3126-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 436886 INFO (jetty-launcher-3101-thread-2) [n:127.0.0.1:33075_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 436897 INFO (jetty-launcher-3101-thread-2) [n:127.0.0.1:33075_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33075.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e9aaf2e
[junit4] 2> 436904 INFO (jetty-launcher-3101-thread-2) [n:127.0.0.1:33075_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33075.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e9aaf2e
[junit4] 2> 436904 INFO (jetty-launcher-3101-thread-2) [n:127.0.0.1:33075_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33075.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e9aaf2e
[junit4] 2> 436905 INFO (jetty-launcher-3101-thread-2) [n:127.0.0.1:33075_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest_6880840F2549F3D6-001/tempDir-001/node2/.
[junit4] 2> 436923 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdRaceCondition-seed#[6880840F2549F3D6]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 436924 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdRaceCondition-seed#[6880840F2549F3D6]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 436925 INFO (zkConnectionManagerCallback-3136-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 436925 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdRaceCondition-seed#[6880840F2549F3D6]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 436926 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdRaceCondition-seed#[6880840F2549F3D6]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 436926 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdRaceCondition-seed#[6880840F2549F3D6]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34337/solr ready
[junit4] 2> 436946 INFO (qtp1941476523-8874) [n:127.0.0.1:41285_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params async=82d605d8-9fdd-425c-8588-946bb76808b3&shards=shard1&collection.configName=conf1&name=testAsyncIdRaceCondition&router.name=implicit&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 436948 INFO (qtp1941476523-8874) [n:127.0.0.1:41285_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=82d605d8-9fdd-425c-8588-946bb76808b3&shards=shard1&collection.configName=conf1&name=testAsyncIdRaceCondition&router.name=implicit&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 436949 INFO (qtp1941476523-8870) [n:127.0.0.1:41285_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=82d605d8-9fdd-425c-8588-946bb76808b3&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 436949 INFO (qtp1941476523-8870) [n:127.0.0.1:41285_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=82d605d8-9fdd-425c-8588-946bb76808b3&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 436950 INFO (OverseerThreadFactory-2579-thread-1-processing-n:127.0.0.1:41285_solr) [n:127.0.0.1:41285_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testAsyncIdRaceCondition
[junit4] 2> 437053 INFO (OverseerStateUpdate-72335612341452806-127.0.0.1:41285_solr-n_0000000000) [n:127.0.0.1:41285_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testAsyncIdRaceCondition",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testAsyncIdRaceCondition_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:33075/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 437275 INFO (qtp80388901-8875) [n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&newCollection=true&collection=testAsyncIdRaceCondition&version=2&replicaType=NRT&async=82d605d8-9fdd-425c-8588-946bb76808b34242223503779153&coreNodeName=core_node2&name=testAsyncIdRaceCondition_shard1_replica_n1&action=CREATE&numShards=1&shard=shard1&wt=javabin} status=0 QTime=1
[junit4] 2> 437275 INFO (parallelCoreAdminExecutor-2587-thread-1-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 82d605d8-9fdd-425c-8588-946bb76808b34242223503779153 CREATE) [n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&collection=testAsyncIdRaceCondition&version=2&replicaType=NRT&async=82d605d8-9fdd-425c-8588-946bb76808b34242223503779153&coreNodeName=core_node2&name=testAsyncIdRaceCondition_shard1_replica_n1&action=CREATE&numShards=1&shard=shard1&wt=javabin
[junit4] 2> 437304 INFO (qtp80388901-8875) [n:127.0.0.1:33075_solr ] o.a.s.h.a.CoreAdminOperation Checking request status for : 82d605d8-9fdd-425c-8588-946bb76808b34242223503779153
[junit4] 2> 437304 INFO (qtp80388901-8875) [n:127.0.0.1:33075_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=82d605d8-9fdd-425c-8588-946bb76808b34242223503779153&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 437950 INFO (qtp1941476523-8872) [n:127.0.0.1:41285_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=82d605d8-9fdd-425c-8588-946bb76808b3&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 437951 INFO (qtp1941476523-8872) [n:127.0.0.1:41285_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=82d605d8-9fdd-425c-8588-946bb76808b3&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 438286 INFO (parallelCoreAdminExecutor-2587-thread-1-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 82d605d8-9fdd-425c-8588-946bb76808b34242223503779153 CREATE) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 438299 INFO (parallelCoreAdminExecutor-2587-thread-1-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 82d605d8-9fdd-425c-8588-946bb76808b34242223503779153 CREATE) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.s.IndexSchema [testAsyncIdRaceCondition_shard1_replica_n1] Schema name=minimal
[junit4] 2> 438301 INFO (parallelCoreAdminExecutor-2587-thread-1-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 82d605d8-9fdd-425c-8588-946bb76808b34242223503779153 CREATE) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 438301 INFO (parallelCoreAdminExecutor-2587-thread-1-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 82d605d8-9fdd-425c-8588-946bb76808b34242223503779153 CREATE) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'testAsyncIdRaceCondition_shard1_replica_n1' using configuration from collection testAsyncIdRaceCondition, trusted=true
[junit4] 2> 438301 INFO (parallelCoreAdminExecutor-2587-thread-1-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 82d605d8-9fdd-425c-8588-946bb76808b34242223503779153 CREATE) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33075.solr.core.testAsyncIdRaceCondition.shard1.replica_n1' (registry 'solr.core.testAsyncIdRaceCondition.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e9aaf2e
[junit4] 2> 438301 INFO (parallelCoreAdminExecutor-2587-thread-1-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 82d605d8-9fdd-425c-8588-946bb76808b34242223503779153 CREATE) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.c.SolrCore [[testAsyncIdRaceCondition_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest_6880840F2549F3D6-001/tempDir-001/node2/testAsyncIdRaceCondition_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest_6880840F2549F3D6-001/tempDir-001/node2/./testAsyncIdRaceCondition_shard1_replica_n1/data/]
[junit4] 2> 438306 INFO (qtp80388901-8873) [n:127.0.0.1:33075_solr ] o.a.s.h.a.CoreAdminOperation Checking request status for : 82d605d8-9fdd-425c-8588-946bb76808b34242223503779153
[junit4] 2> 438306 INFO (qtp80388901-8873) [n:127.0.0.1:33075_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=82d605d8-9fdd-425c-8588-946bb76808b34242223503779153&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 438388 INFO (parallelCoreAdminExecutor-2587-thread-1-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 82d605d8-9fdd-425c-8588-946bb76808b34242223503779153 CREATE) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 438388 INFO (parallelCoreAdminExecutor-2587-thread-1-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 82d605d8-9fdd-425c-8588-946bb76808b34242223503779153 CREATE) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 438395 INFO (parallelCoreAdminExecutor-2587-thread-1-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 82d605d8-9fdd-425c-8588-946bb76808b34242223503779153 CREATE) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 438395 INFO (parallelCoreAdminExecutor-2587-thread-1-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 82d605d8-9fdd-425c-8588-946bb76808b34242223503779153 CREATE) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 438397 INFO (parallelCoreAdminExecutor-2587-thread-1-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 82d605d8-9fdd-425c-8588-946bb76808b34242223503779153 CREATE) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@336a1298[testAsyncIdRaceCondition_shard1_replica_n1] main]
[junit4] 2> 438398 INFO (parallelCoreAdminExecutor-2587-thread-1-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 82d605d8-9fdd-425c-8588-946bb76808b34242223503779153 CREATE) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 438398 INFO (parallelCoreAdminExecutor-2587-thread-1-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 82d605d8-9fdd-425c-8588-946bb76808b34242223503779153 CREATE) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 438398 INFO (parallelCoreAdminExecutor-2587-thread-1-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 82d605d8-9fdd-425c-8588-946bb76808b34242223503779153 CREATE) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 438398 INFO (parallelCoreAdminExecutor-2587-thread-1-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 82d605d8-9fdd-425c-8588-946bb76808b34242223503779153 CREATE) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1647842304344457216
[junit4] 2> 438401 INFO (searcherExecutor-2591-thread-1-processing-x:testAsyncIdRaceCondition_shard1_replica_n1 82d605d8-9fdd-425c-8588-946bb76808b34242223503779153 r:core_node2 CREATE n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.c.SolrCore [testAsyncIdRaceCondition_shard1_replica_n1] Registered new searcher Searcher@336a1298[testAsyncIdRaceCondition_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 438402 INFO (parallelCoreAdminExecutor-2587-thread-1-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 82d605d8-9fdd-425c-8588-946bb76808b34242223503779153 CREATE) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testAsyncIdRaceCondition/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 438403 INFO (parallelCoreAdminExecutor-2587-thread-1-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 82d605d8-9fdd-425c-8588-946bb76808b34242223503779153 CREATE) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testAsyncIdRaceCondition/leaders/shard1
[junit4] 2> 438404 INFO (parallelCoreAdminExecutor-2587-thread-1-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 82d605d8-9fdd-425c-8588-946bb76808b34242223503779153 CREATE) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 438404 INFO (parallelCoreAdminExecutor-2587-thread-1-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 82d605d8-9fdd-425c-8588-946bb76808b34242223503779153 CREATE) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 438404 INFO (parallelCoreAdminExecutor-2587-thread-1-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 82d605d8-9fdd-425c-8588-946bb76808b34242223503779153 CREATE) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:33075/solr/testAsyncIdRaceCondition_shard1_replica_n1/
[junit4] 2> 438404 INFO (parallelCoreAdminExecutor-2587-thread-1-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 82d605d8-9fdd-425c-8588-946bb76808b34242223503779153 CREATE) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 438405 INFO (parallelCoreAdminExecutor-2587-thread-1-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 82d605d8-9fdd-425c-8588-946bb76808b34242223503779153 CREATE) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:33075/solr/testAsyncIdRaceCondition_shard1_replica_n1/ has no replicas
[junit4] 2> 438405 INFO (parallelCoreAdminExecutor-2587-thread-1-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 82d605d8-9fdd-425c-8588-946bb76808b34242223503779153 CREATE) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testAsyncIdRaceCondition/leaders/shard1/leader after winning as /collections/testAsyncIdRaceCondition/leader_elect/shard1/election/72335612341452808-core_node2-n_0000000000
[junit4] 2> 438406 INFO (parallelCoreAdminExecutor-2587-thread-1-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 82d605d8-9fdd-425c-8588-946bb76808b34242223503779153 CREATE) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:33075/solr/testAsyncIdRaceCondition_shard1_replica_n1/ shard1
[junit4] 2> 438507 INFO (zkCallback-3126-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testAsyncIdRaceCondition/state.json] for collection [testAsyncIdRaceCondition] has occurred - updating... (live nodes size: [2])
[junit4] 2> 438507 INFO (zkCallback-3126-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testAsyncIdRaceCondition/state.json] for collection [testAsyncIdRaceCondition] has occurred - updating... (live nodes size: [2])
[junit4] 2> 438507 INFO (parallelCoreAdminExecutor-2587-thread-1-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 82d605d8-9fdd-425c-8588-946bb76808b34242223503779153 CREATE) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 438609 INFO (zkCallback-3126-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testAsyncIdRaceCondition/state.json] for collection [testAsyncIdRaceCondition] has occurred - updating... (live nodes size: [2])
[junit4] 2> 438609 INFO (zkCallback-3126-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testAsyncIdRaceCondition/state.json] for collection [testAsyncIdRaceCondition] has occurred - updating... (live nodes size: [2])
[junit4] 2> 438609 INFO (zkCallback-3126-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testAsyncIdRaceCondition/state.json] for collection [testAsyncIdRaceCondition] has occurred - updating... (live nodes size: [2])
[junit4] 2> 438952 INFO (qtp1941476523-8874) [n:127.0.0.1:41285_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=82d605d8-9fdd-425c-8588-946bb76808b3&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 438953 INFO (qtp1941476523-8874) [n:127.0.0.1:41285_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=82d605d8-9fdd-425c-8588-946bb76808b3&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 439308 INFO (qtp80388901-8877) [n:127.0.0.1:33075_solr ] o.a.s.h.a.CoreAdminOperation Checking request status for : 82d605d8-9fdd-425c-8588-946bb76808b34242223503779153
[junit4] 2> 439308 INFO (qtp80388901-8877) [n:127.0.0.1:33075_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=82d605d8-9fdd-425c-8588-946bb76808b34242223503779153&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 439311 INFO (OverseerThreadFactory-2579-thread-1-processing-n:127.0.0.1:41285_solr) [n:127.0.0.1:41285_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 439954 INFO (qtp1941476523-8870) [n:127.0.0.1:41285_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=82d605d8-9fdd-425c-8588-946bb76808b3&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 439955 INFO (qtp1941476523-8870) [n:127.0.0.1:41285_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=82d605d8-9fdd-425c-8588-946bb76808b3&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 439956 INFO (qtp1941476523-8872) [n:127.0.0.1:41285_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :deletestatus with params requestid=82d605d8-9fdd-425c-8588-946bb76808b3&action=DELETESTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 439956 INFO (qtp1941476523-8872) [n:127.0.0.1:41285_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=82d605d8-9fdd-425c-8588-946bb76808b3&action=DELETESTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 439958 INFO (testAsyncIdRaceCondition-2596-thread-10) [ ] o.a.s.c.a.c.CollectionsAPIAsyncDistributedZkTest testAsyncIdRaceCondition-2596-thread-10 - Reloading Collection.
[junit4] 2> 439958 INFO (testAsyncIdRaceCondition-2596-thread-2) [ ] o.a.s.c.a.c.CollectionsAPIAsyncDistributedZkTest testAsyncIdRaceCondition-2596-thread-2 - Reloading Collection.
[junit4] 2> 439958 INFO (testAsyncIdRaceCondition-2596-thread-1) [ ] o.a.s.c.a.c.CollectionsAPIAsyncDistributedZkTest testAsyncIdRaceCondition-2596-thread-1 - Reloading Collection.
[junit4] 2> 439958 INFO (testAsyncIdRaceCondition-2596-thread-3) [ ] o.a.s.c.a.c.CollectionsAPIAsyncDistributedZkTest testAsyncIdRaceCondition-2596-thread-3 - Reloading Collection.
[junit4] 2> 439958 INFO (testAsyncIdRaceCondition-2596-thread-4) [ ] o.a.s.c.a.c.CollectionsAPIAsyncDistributedZkTest testAsyncIdRaceCondition-2596-thread-4 - Reloading Collection.
[junit4] 2> 439958 INFO (testAsyncIdRaceCondition-2596-thread-5) [ ] o.a.s.c.a.c.CollectionsAPIAsyncDistributedZkTest testAsyncIdRaceCondition-2596-thread-5 - Reloading Collection.
[junit4] 2> 439958 INFO (testAsyncIdRaceCondition-2596-thread-6) [ ] o.a.s.c.a.c.CollectionsAPIAsyncDistributedZkTest testAsyncIdRaceCondition-2596-thread-6 - Reloading Collection.
[junit4] 2> 439958 INFO (testAsyncIdRaceCondition-2596-thread-7) [ ] o.a.s.c.a.c.CollectionsAPIAsyncDistributedZkTest testAsyncIdRaceCondition-2596-thread-7 - Reloading Collection.
[junit4] 2> 439958 INFO (testAsyncIdRaceCondition-2596-thread-9) [ ] o.a.s.c.a.c.CollectionsAPIAsyncDistributedZkTest testAsyncIdRaceCondition-2596-thread-9 - Reloading Collection.
[junit4] 2> 439958 INFO (testAsyncIdRaceCondition-2596-thread-8) [ ] o.a.s.c.a.c.CollectionsAPIAsyncDistributedZkTest testAsyncIdRaceCondition-2596-thread-8 - Reloading Collection.
[junit4] 2> 439974 INFO (qtp80388901-8875) [n:127.0.0.1:33075_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :reload with params async=repeatedId&name=testAsyncIdRaceCondition&action=RELOAD&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 439974 INFO (qtp80388901-8877) [n:127.0.0.1:33075_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :reload with params async=repeatedId&name=testAsyncIdRaceCondition&action=RELOAD&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 439976 INFO (qtp80388901-8875) [n:127.0.0.1:33075_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=repeatedId&name=testAsyncIdRaceCondition&action=RELOAD&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 439979 INFO (qtp80388901-8956) [n:127.0.0.1:33075_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :reload with params async=repeatedId&name=testAsyncIdRaceCondition&action=RELOAD&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 439979 INFO (qtp80388901-8953) [n:127.0.0.1:33075_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :reload with params async=repeatedId&name=testAsyncIdRaceCondition&action=RELOAD&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 439980 INFO (OverseerThreadFactory-2579-thread-2-processing-n:127.0.0.1:41285_solr) [n:127.0.0.1:41285_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection Cmd=action=RELOAD, asyncId=repeatedId
[junit4] 2> 439980 INFO (OverseerCollectionConfigSetProcessor-72335612341452806-127.0.0.1:41285_solr-n_0000000000) [n:127.0.0.1:41285_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 439981 INFO (qtp80388901-8877) [n:127.0.0.1:33075_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=repeatedId&name=testAsyncIdRaceCondition&action=RELOAD&wt=javabin&version=2} status=0 QTime=6
[junit4] 2> 439981 INFO (testAsyncIdRaceCondition-2596-thread-1) [ ] o.a.s.c.a.c.CollectionsAPIAsyncDistributedZkTest Task with the same requestid already exists.
[junit4] 2> 439981 INFO (qtp80388901-8953) [n:127.0.0.1:33075_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=repeatedId&name=testAsyncIdRaceCondition&action=RELOAD&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 439983 INFO (qtp80388901-8877) [n:127.0.0.1:33075_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :reload with params async=repeatedId&name=testAsyncIdRaceCondition&action=RELOAD&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 439983 INFO (qtp80388901-8954) [n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={core=testAsyncIdRaceCondition_shard1_replica_n1&async=repeatedId4242226431629159&qt=/admin/cores&action=RELOAD&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 439983 INFO (qtp80388901-8954) [n:127.0.0.1:33075_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :reload with params async=repeatedId&name=testAsyncIdRaceCondition&action=RELOAD&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 439983 INFO (qtp80388901-8955) [n:127.0.0.1:33075_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :reload with params async=repeatedId&name=testAsyncIdRaceCondition&action=RELOAD&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 439983 INFO (qtp80388901-8875) [n:127.0.0.1:33075_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :reload with params async=repeatedId&name=testAsyncIdRaceCondition&action=RELOAD&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 439984 INFO (testAsyncIdRaceCondition-2596-thread-2) [ ] o.a.s.c.a.c.CollectionsAPIAsyncDistributedZkTest Task with the same requestid already exists.
[junit4] 2> 439984 INFO (qtp80388901-8877) [n:127.0.0.1:33075_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=repeatedId&name=testAsyncIdRaceCondition&action=RELOAD&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 439985 INFO (qtp80388901-8960) [n:127.0.0.1:33075_solr ] o.a.s.h.a.CoreAdminOperation Checking request status for : repeatedId4242226431629159
[junit4] 2> 439985 INFO (qtp80388901-8960) [n:127.0.0.1:33075_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=repeatedId4242226431629159&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 439985 INFO (qtp80388901-8875) [n:127.0.0.1:33075_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=repeatedId&name=testAsyncIdRaceCondition&action=RELOAD&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 439985 INFO (qtp80388901-8956) [n:127.0.0.1:33075_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=repeatedId&name=testAsyncIdRaceCondition&action=RELOAD&wt=javabin&version=2} status=0 QTime=6
[junit4] 2> 439985 INFO (testAsyncIdRaceCondition-2596-thread-6) [ ] o.a.s.c.a.c.CollectionsAPIAsyncDistributedZkTest Task with the same requestid already exists.
[junit4] 2> 439985 INFO (qtp80388901-8955) [n:127.0.0.1:33075_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=repeatedId&name=testAsyncIdRaceCondition&action=RELOAD&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 439985 INFO (testAsyncIdRaceCondition-2596-thread-7) [ ] o.a.s.c.a.c.CollectionsAPIAsyncDistributedZkTest Task with the same requestid already exists.
[junit4] 2> 439985 INFO (testAsyncIdRaceCondition-2596-thread-8) [ ] o.a.s.c.a.c.CollectionsAPIAsyncDistributedZkTest Task with the same requestid already exists.
[junit4] 2> 439986 INFO (testAsyncIdRaceCondition-2596-thread-3) [ ] o.a.s.c.a.c.CollectionsAPIAsyncDistributedZkTest Task with the same requestid already exists.
[junit4] 2> 439987 INFO (qtp80388901-8873) [n:127.0.0.1:33075_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :reload with params async=repeatedId&name=testAsyncIdRaceCondition&action=RELOAD&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 439987 INFO (qtp80388901-8954) [n:127.0.0.1:33075_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=repeatedId&name=testAsyncIdRaceCondition&action=RELOAD&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 439987 INFO (qtp80388901-8873) [n:127.0.0.1:33075_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=repeatedId&name=testAsyncIdRaceCondition&action=RELOAD&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 439987 INFO (testAsyncIdRaceCondition-2596-thread-5) [ ] o.a.s.c.a.c.CollectionsAPIAsyncDistributedZkTest Task with the same requestid already exists.
[junit4] 2> 439987 INFO (testAsyncIdRaceCondition-2596-thread-4) [ ] o.a.s.c.a.c.CollectionsAPIAsyncDistributedZkTest Task with the same requestid already exists.
[junit4] 2> 439989 INFO (parallelCoreAdminExecutor-2587-thread-2-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 repeatedId4242226431629159 RELOAD) [n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 439991 INFO (qtp80388901-8955) [n:127.0.0.1:33075_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :reload with params async=repeatedId&name=testAsyncIdRaceCondition&action=RELOAD&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 439992 INFO (qtp80388901-8955) [n:127.0.0.1:33075_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=repeatedId&name=testAsyncIdRaceCondition&action=RELOAD&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 439992 INFO (testAsyncIdRaceCondition-2596-thread-10) [ ] o.a.s.c.a.c.CollectionsAPIAsyncDistributedZkTest Task with the same requestid already exists.
[junit4] 2> 439995 INFO (parallelCoreAdminExecutor-2587-thread-2-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 repeatedId4242226431629159 RELOAD) [n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.s.IndexSchema [testAsyncIdRaceCondition_shard1_replica_n1] Schema name=minimal
[junit4] 2> 439996 INFO (parallelCoreAdminExecutor-2587-thread-2-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 repeatedId4242226431629159 RELOAD) [n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 439996 INFO (parallelCoreAdminExecutor-2587-thread-2-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 repeatedId4242226431629159 RELOAD) [n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.c.CoreContainer Reloading SolrCore 'testAsyncIdRaceCondition_shard1_replica_n1' using configuration from collection testAsyncIdRaceCondition
[junit4] 2> 440002 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdRaceCondition-seed#[6880840F2549F3D6]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testAsyncIdRaceCondition
[junit4] 2> 440021 INFO (parallelCoreAdminExecutor-2587-thread-2-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 repeatedId4242226431629159 RELOAD) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33075.solr.core.testAsyncIdRaceCondition.shard1.replica_n1' (registry 'solr.core.testAsyncIdRaceCondition.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e9aaf2e
[junit4] 2> 440021 INFO (parallelCoreAdminExecutor-2587-thread-2-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 repeatedId4242226431629159 RELOAD) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.c.SolrCore [[testAsyncIdRaceCondition_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest_6880840F2549F3D6-001/tempDir-001/node2/testAsyncIdRaceCondition_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest_6880840F2549F3D6-001/tempDir-001/node2/./testAsyncIdRaceCondition_shard1_replica_n1/data/]
[junit4] 2> 440052 INFO (parallelCoreAdminExecutor-2587-thread-2-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 repeatedId4242226431629159 RELOAD) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 440052 INFO (parallelCoreAdminExecutor-2587-thread-2-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 repeatedId4242226431629159 RELOAD) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 440054 INFO (parallelCoreAdminExecutor-2587-thread-2-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 repeatedId4242226431629159 RELOAD) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7d895496[testAsyncIdRaceCondition_shard1_replica_n1] main]
[junit4] 2> 440055 INFO (parallelCoreAdminExecutor-2587-thread-2-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 repeatedId4242226431629159 RELOAD) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 440055 INFO (parallelCoreAdminExecutor-2587-thread-2-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 repeatedId4242226431629159 RELOAD) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 440055 INFO (parallelCoreAdminExecutor-2587-thread-2-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 repeatedId4242226431629159 RELOAD) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 440055 INFO (parallelCoreAdminExecutor-2587-thread-2-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 repeatedId4242226431629159 RELOAD) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1647842306081947648
[junit4] 2> 440057 INFO (parallelCoreAdminExecutor-2587-thread-2-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 repeatedId4242226431629159 RELOAD) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 440059 INFO (searcherExecutor-2597-thread-1-processing-x:testAsyncIdRaceCondition_shard1_replica_n1 repeatedId4242226431629159 r:core_node2 RELOAD n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.c.SolrCore [testAsyncIdRaceCondition_shard1_replica_n1] Registered new searcher Searcher@7d895496[testAsyncIdRaceCondition_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 440059 INFO (parallelCoreAdminExecutor-2587-thread-2-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 repeatedId4242226431629159 RELOAD) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6c89ddbf[testAsyncIdRaceCondition_shard1_replica_n1] main]
[junit4] 2> 440059 INFO (parallelCoreAdminExecutor-2587-thread-2-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 repeatedId4242226431629159 RELOAD) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.c.SolrCore [testAsyncIdRaceCondition_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@11764557
[junit4] 2> 440059 INFO (parallelCoreAdminExecutor-2587-thread-2-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 repeatedId4242226431629159 RELOAD) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.testAsyncIdRaceCondition.shard1.replica_n1, tag=SolrCore@11764557
[junit4] 2> 440059 INFO (parallelCoreAdminExecutor-2587-thread-2-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 repeatedId4242226431629159 RELOAD) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@260ec15a: rootName = solr_33075, domain = solr.core.testAsyncIdRaceCondition.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.testAsyncIdRaceCondition.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@2fb68be7
[junit4] 2> 440060 INFO (searcherExecutor-2597-thread-1-processing-x:testAsyncIdRaceCondition_shard1_replica_n1 repeatedId4242226431629159 r:core_node2 RELOAD n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.c.SolrCore [testAsyncIdRaceCondition_shard1_replica_n1] Registered new searcher Searcher@6c89ddbf[testAsyncIdRaceCondition_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 440065 INFO (parallelCoreAdminExecutor-2587-thread-2-processing-n:127.0.0.1:33075_solr x:testAsyncIdRaceCondition_shard1_replica_n1 repeatedId4242226431629159 RELOAD) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.testAsyncIdRaceCondition.shard1.leader, tag=SolrCore@11764557
[junit4] 2> 440103 INFO (jetty-closer-3139-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1536310941
[junit4] 2> 440103 INFO (jetty-closer-3139-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:41285_solr
[junit4] 2> 440104 INFO (jetty-closer-3139-thread-1) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 440104 INFO (jetty-closer-3139-thread-1) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:41285_solr as DOWN
[junit4] 2> 440104 INFO (jetty-closer-3139-thread-2) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=524358828
[junit4] 2> 440104 INFO (jetty-closer-3139-thread-2) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:33075_solr
[junit4] 2> 440104 INFO (jetty-closer-3139-thread-2) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 440104 INFO (jetty-closer-3139-thread-2) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:33075_solr as DOWN
[junit4] 2> 440105 INFO (jetty-closer-3139-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 440105 INFO (jetty-closer-3139-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 440105 INFO (jetty-closer-3139-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@73031434: rootName = solr_41285, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@69471f78
[junit4] 2> 440105 INFO (coreCloseExecutor-2602-thread-1) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.c.SolrCore [testAsyncIdRaceCondition_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@51c8ae7
[junit4] 2> 440105 INFO (coreCloseExecutor-2602-thread-1) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.testAsyncIdRaceCondition.shard1.replica_n1, tag=SolrCore@51c8ae7
[junit4] 2> 440105 INFO (coreCloseExecutor-2602-thread-1) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1485f9a1: rootName = solr_33075, domain = solr.core.testAsyncIdRaceCondition.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.testAsyncIdRaceCondition.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@2fb68be7
[junit4] 2> 440110 INFO (jetty-closer-3139-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 440110 INFO (jetty-closer-3139-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6a5325fe: rootName = solr_41285, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@7bf4b319
[junit4] 2> 440112 INFO (coreCloseExecutor-2602-thread-1) [n:127.0.0.1:33075_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.testAsyncIdRaceCondition.shard1.leader, tag=SolrCore@51c8ae7
[junit4] 2> 440113 INFO (jetty-closer-3139-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 440113 INFO (jetty-closer-3139-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@777d913d: rootName = solr_33075, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@137b6f31
[junit4] 2> 440113 INFO (jetty-closer-3139-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 440113 INFO (jetty-closer-3139-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@177e252f: rootName = solr_41285, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@7836b5ed
[junit4] 2> 440113 INFO (jetty-closer-3139-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 440117 INFO (jetty-closer-3139-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 440117 INFO (jetty-closer-3139-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@387ad614: rootName = solr_33075, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@7bf4b319
[junit4] 2> 440118 INFO (jetty-closer-3139-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 440118 INFO (jetty-closer-3139-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@40d96b48: rootName = solr_33075, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@7836b5ed
[junit4] 2> 440118 INFO (jetty-closer-3139-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 440120 INFO (closeThreadPool-3146-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72335612341452806-127.0.0.1:41285_solr-n_0000000000) closing
[junit4] 2> 440120 INFO (OverseerStateUpdate-72335612341452806-127.0.0.1:41285_solr-n_0000000000) [n:127.0.0.1:41285_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:41285_solr
[junit4] 2> 440124 INFO (zkCallback-3126-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 440325 INFO (jetty-closer-3139-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@4b3047f7{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:0}
[junit4] 2> 440325 INFO (jetty-closer-3139-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@749505b0{/solr,null,UNAVAILABLE}
[junit4] 2> 440325 INFO (jetty-closer-3139-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 440986 ERROR (OverseerThreadFactory-2579-thread-2-processing-n:127.0.0.1:41285_solr) [n:127.0.0.1:41285_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Collection: testAsyncIdRaceCondition operation: reload failed:java.util.concurrent.RejectedExecutionException: Task org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$$Lambda$150/0x00007fe5f478ddb8@2d4a5256 rejected from org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@6848c99b[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 6]
[junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2055)
[junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:825)
[junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1355)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.execute(ExecutorUtil.java:195)
[junit4] 2> at com.codahale.metrics.InstrumentedExecutorService.execute(InstrumentedExecutorService.java:63)
[junit4] 2> at java.base/java.util.concurrent.ExecutorCompletionService.submit(ExecutorCompletionService.java:184)
[junit4] 2> at org.apache.solr.handler.component.HttpShardHandler.submit(HttpShardHandler.java:229)
[junit4] 2> at org.apache.solr.cloud.api.collections.OverseerCollectionMessageHandler.waitForCoreAdminAsyncCallToComplete(OverseerCollectionMessageHandler.java:831)
[junit4] 2> at org.apache.solr.cloud.api.collections.OverseerCollectionMessageHandler$ShardRequestTracker.waitForAsyncCallsToComplete(OverseerCollectionMessageHandler.java:1048)
[junit4] 2> at org.apache.solr.cloud.api.collections.OverseerCollectionMessageHandler$ShardRequestTracker.processResponses(OverseerCollectionMessageHandler.java:1038)
[junit4] 2> at org.apache.solr.cloud.api.collections.OverseerCollectionMessageHandler.collectionCmd(OverseerCollectionMessageHandler.java:767)
[junit4] 2> at org.apache.solr.cloud.api.collections.OverseerCollectionMessageHandler.collectionCmd(OverseerCollectionMessageHandler.java:745)
[junit4] 2> at org.apache.solr.cloud.api.collections.OverseerCollectionMessageHandler.reloadCollection(OverseerCollectionMessageHandler.java:309)
[junit4] 2> at org.apache.solr.cloud.api.collections.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:263)
[junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:505)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:210)
[junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
[junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:835)
[junit4] 2>
[junit4] 2> 440987 ERROR (OverseerThreadFactory-2579-thread-2-processing-n:127.0.0.1:41285_solr) [n:127.0.0.1:41285_solr ] o.a.s.c.OverseerTaskProcessor :org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer/collection-map-failure
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:134)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:2131)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:337)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:337)
[junit4] 2> at org.apache.solr.cloud.DistributedMap.size(DistributedMap.java:87)
[junit4] 2> at org.apache.solr.cloud.SizeLimitedDistributedMap.put(SizeLimitedDistributedMap.java:53)
[junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:514)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:210)
[junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
[junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:835)
[junit4] 2>
[junit4] 2> 440987 WARN (OverseerThreadFactory-2579-thread-2-processing-n:127.0.0.1:41285_solr) [n:127.0.0.1:41285_solr ] o.a.s.c.OverseerTaskProcessor Resetting task: /overseer/collection-queue-work/qn-0000000001, requestid: repeatedId, taskKey: testAsyncIdRaceCondition
[junit4] 2> 440987 ERROR (OverseerThreadFactory-2579-thread-2-processing-n:127.0.0.1:41285_solr) [n:127.0.0.1:41285_solr ] o.a.s.c.OverseerTaskProcessor :org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer/collection-map-running/mn-repeatedId
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:134)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.delete(ZooKeeper.java:1793)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$delete$1(SolrZkClient.java:262)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.delete(SolrZkClient.java:261)
[junit4] 2> at org.apache.solr.cloud.DistributedMap.remove(DistributedMap.java:98)
[junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.resetTaskWithException(OverseerTaskProcessor.java:580)
[junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:546)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:210)
[junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
[junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:835)
[junit4] 2>
[junit4] 2> 440988 INFO (OverseerAutoScalingTriggerThread-72335612341452806-127.0.0.1:41285_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 440988 INFO (jetty-closer-3139-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72335612341452806-127.0.0.1:41285_solr-n_0000000000) closing
[junit4] 2> 440988 INFO (closeThreadPool-3146-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72335612341452806-127.0.0.1:41285_solr-n_0000000000) closing
[junit4] 2> 440989 INFO (jetty-closer-3139-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@3c426e97{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:0}
[junit4] 2> 440989 INFO (jetty-closer-3139-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@36350d0c{/solr,null,UNAVAILABLE}
[junit4] 2> 440989 INFO (jetty-closer-3139-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 440989 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdRaceCondition-seed#[6880840F2549F3D6]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 441191 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> 3 /solr/aliases.json
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/collections/testAsyncIdRaceCondition/terms/shard1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 9 /solr/collections/testAsyncIdRaceCondition/state.json
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 14 /solr/overseer/queue
[junit4] 2> 7 /solr/live_nodes
[junit4] 2> 6 /solr/collections
[junit4] 2> 4 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 441193 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdRaceCondition-seed#[6880840F2549F3D6]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:34337
[junit4] 2> 441193 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdRaceCondition-seed#[6880840F2549F3D6]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:34337
[junit4] 2> 441193 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdRaceCondition-seed#[6880840F2549F3D6]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 34337
[junit4] 2> 441197 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6880840F2549F3D6]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testAsyncIdBackCompat
[junit4] 2> 441198 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6880840F2549F3D6]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest_6880840F2549F3D6-001/tempDir-002
[junit4] 2> 441198 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6880840F2549F3D6]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 441199 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 441199 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 441299 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6880840F2549F3D6]) [ ] o.a.s.c.ZkTestServer start zk server on port:39605
[junit4] 2> 441299 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6880840F2549F3D6]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:39605
[junit4] 2> 441299 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6880840F2549F3D6]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:39605
[junit4] 2> 441299 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6880840F2549F3D6]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 39605
[junit4] 2> 441300 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6880840F2549F3D6]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 441302 INFO (zkConnectionManagerCallback-3149-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 441302 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6880840F2549F3D6]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 441306 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6880840F2549F3D6]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 441309 INFO (zkConnectionManagerCallback-3151-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 441309 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6880840F2549F3D6]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 441311 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6880840F2549F3D6]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 441315 INFO (zkConnectionManagerCallback-3153-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 441315 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6880840F2549F3D6]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 441427 WARN (jetty-launcher-3154-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 441427 WARN (jetty-launcher-3154-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 441427 INFO (jetty-launcher-3154-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 441427 INFO (jetty-launcher-3154-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 441427 INFO (jetty-launcher-3154-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 441427 INFO (jetty-launcher-3154-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 441427 INFO (jetty-launcher-3154-thread-1) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 12.0.1+12
[junit4] 2> 441427 INFO (jetty-launcher-3154-thread-2) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 12.0.1+12
[junit4] 2> 441429 INFO (jetty-launcher-3154-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 441429 INFO (jetty-launcher-3154-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 441429 INFO (jetty-launcher-3154-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 441429 INFO (jetty-launcher-3154-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 441429 INFO (jetty-launcher-3154-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 441429 INFO (jetty-launcher-3154-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 441429 INFO (jetty-launcher-3154-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5a7fbff3{/solr,null,AVAILABLE}
[junit4] 2> 441429 INFO (jetty-launcher-3154-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5eba91ea{/solr,null,AVAILABLE}
[junit4] 2> 441430 INFO (jetty-launcher-3154-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1aab9cfd{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:38617}
[junit4] 2> 441430 INFO (jetty-launcher-3154-thread-1) [ ] o.e.j.s.Server Started @441472ms
[junit4] 2> 441430 INFO (jetty-launcher-3154-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38617}
[junit4] 2> 441430 INFO (jetty-launcher-3154-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7be298e1{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:44177}
[junit4] 2> 441430 INFO (jetty-launcher-3154-thread-2) [ ] o.e.j.s.Server Started @441472ms
[junit4] 2> 441430 INFO (jetty-launcher-3154-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44177}
[junit4] 2> 441430 ERROR (jetty-launcher-3154-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 441430 INFO (jetty-launcher-3154-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 441430 ERROR (jetty-launcher-3154-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 441430 INFO (jetty-launcher-3154-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 441430 INFO (jetty-launcher-3154-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 441430 INFO (jetty-launcher-3154-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 441430 INFO (jetty-launcher-3154-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 441430 INFO (jetty-launcher-3154-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 441430 INFO (jetty-launcher-3154-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 441430 INFO (jetty-launcher-3154-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 441430 INFO (jetty-launcher-3154-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-10-19T17:08:06.173959Z
[junit4] 2> 441430 INFO (jetty-launcher-3154-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-10-19T17:08:06.173959Z
[junit4] 2> 441439 INFO (jetty-launcher-3154-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 441443 INFO (zkConnectionManagerCallback-3158-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 441444 INFO (jetty-launcher-3154-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 441444 INFO (jetty-launcher-3154-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 441451 INFO (jetty-launcher-3154-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 441453 INFO (zkConnectionManagerCallback-3157-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 441453 INFO (jetty-launcher-3154-thread-1)
[...truncated too long message...]
cloud.ZkCollectionTerms.getShard(ZkCollectionTerms.java:45)
[junit4] 2> at org.apache.solr.cloud.ZkCollectionTerms.remove(ZkCollectionTerms.java:52)
[junit4] 2> at org.apache.solr.cloud.ZkController.unregister(ZkController.java:1643)
[junit4] 2> at org.apache.solr.cloud.ZkController.unregister(ZkController.java:1637)
[junit4] 2> at org.apache.solr.core.CoreContainer.create(CoreContainer.java:1175)
[junit4] 2> at org.apache.solr.handler.admin.CoreAdminOperation.lambda$static$0(CoreAdminOperation.java:93)
[junit4] 2> at org.apache.solr.handler.admin.CoreAdminOperation.execute(CoreAdminOperation.java:362)
[junit4] 2> at org.apache.solr.handler.admin.CoreAdminHandler$CallInfo.call(CoreAdminHandler.java:397)
[junit4] 2> at org.apache.solr.handler.admin.CoreAdminHandler.lambda$handleRequestBody$0(CoreAdminHandler.java:189)
[junit4] 2> at com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:181)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:210)
[junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
[junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:835)
[junit4] 2> Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:134)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java:1538)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$makePath$8(SolrZkClient.java:524)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:523)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:467)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:454)
[junit4] 2> at org.apache.solr.cloud.ZkShardTerms.ensureTermNodeExist(ZkShardTerms.java:322)
[junit4] 2> ... 15 more
[junit4] 2>
[junit4] 2> 1109802 INFO (jetty-closer-3304-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@2f253385{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:0}
[junit4] 2> 1109802 INFO (jetty-closer-3304-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5f3faebb{/solr,null,UNAVAILABLE}
[junit4] 2> 1109802 INFO (jetty-closer-3304-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1109803 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncRequests-seed#[6880840F2549F3D6]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1110005 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> 4 /solr/collections/testAsyncOperations/terms/shard2
[junit4] 2> 3 /solr/collections/testAsyncOperations/terms/shard1
[junit4] 2> 3 /solr/aliases.json
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/configs/conf1
[junit4] 2> 2 /solr/collections/testAsyncOperations/collectionprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 44 /solr/collections/testAsyncOperations/state.json
[junit4] 2> 6 /solr/clusterprops.json
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 26 /solr/overseer/queue
[junit4] 2> 10 /solr/overseer/collection-queue-work
[junit4] 2> 7 /solr/live_nodes
[junit4] 2> 6 /solr/collections
[junit4] 2>
[junit4] 2> 1110006 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncRequests-seed#[6880840F2549F3D6]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:44393
[junit4] 2> 1110006 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncRequests-seed#[6880840F2549F3D6]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:44393
[junit4] 2> 1110006 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncRequests-seed#[6880840F2549F3D6]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 44393
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=CollectionsAPIAsyncDistributedZkTest -Dtests.method=testAsyncRequests -Dtests.seed=6880840F2549F3D6 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ast-ES -Dtests.timezone=Europe/Ulyanovsk -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 652s J2 | CollectionsAPIAsyncDistributedZkTest.testAsyncRequests <<<
[junit4] > Throwable #1: java.lang.AssertionError: AddReplica did not complete expected same:<COMPLETED> was not:<RUNNING>
[junit4] > at __randomizedtesting.SeedInfo.seed([6880840F2549F3D6:8CC4B8B883E1BD09]:0)
[junit4] > at org.apache.solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest.testAsyncRequests(CollectionsAPIAsyncDistributedZkTest.java:162)
[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:567)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:835)
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest_6880840F2549F3D6-001
[junit4] 2> Oct 19, 2019 5:19:14 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene80): {_root_=FSTOrd50, id=PostingsFormat(name=MockRandom), _route_=BlockTreeOrds(blocksize=128)}, docValues:{}, maxPointsInLeafNode=116, maxMBSortInHeap=6.201784299378527, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@7ae8a68b), locale=ast-ES, timezone=Europe/Ulyanovsk
[junit4] 2> NOTE: Linux 5.0.0-25-generic amd64/AdoptOpenJDK 12.0.1 (64-bit)/cpus=8,threads=1,free=199743176,total=408944640
[junit4] 2> NOTE: All tests run in this JVM: [ReplicationFactorTest, RecoveryAfterSoftCommitTest, SolrIndexMetricsTest, LegacyCloudClusterPropTest, TestSimpleTextCodec, SuggestComponentContextFilterQueryTest, TestSolr4Spatial2, ZkShardTermsTest, ResponseHeaderTest, AuditLoggerIntegrationTest, TestConfigSetsAPIZkFailure, CoreAdminHandlerTest, TestClusterProperties, RequestLoggingTest, HttpPartitionTest, HLLUtilTest, AdminHandlersProxyTest, SliceStateTest, MigrateRouteKeyTest, TestBlendedInfixSuggestions, TestRequestForwarding, OutOfBoxZkACLAndCredentialsProvidersTest, TestReplicaProperties, NestedAtomicUpdateTest, TestDynamicURP, SignatureUpdateProcessorFactoryTest, TestReplicationHandlerDiskOverFlow, TestSurroundQueryParser, TestSchemaNameResource, JsonLoaderTest, TestSolrCoreSnapshots, TestDFRSimilarityFactory, TestCSVLoader, GraphQueryTest, TestFieldSortValues, ByteBuffersDirectoryFactoryTest, DistributedUpdateProcessorTest, VersionInfoTest, ShardRoutingCustomTest, ConfigSetsAPITest, BlockJoinFacetDistribTest, RangeFacetCloudTest, DeleteNodeTest, MetricUtilsTest, RemoteQueryErrorTest, SolrTestCaseJ4Test, TestDistribDocBasedVersion, SpatialFilterTest, TestReloadDeadlock, OverseerTaskQueueTest, ConnectionReuseTest, BasicAuthOnSingleNodeTest, TestSolrCloudWithDelegationTokens, NodeLostTriggerTest, TestEmbeddedSolrServerConstructors, TestMergePolicyConfig, TaggerTest, InfoHandlerTest, DateFieldTest, TestHdfsBackupRestoreCore, TestIndexingPerformance, SystemCollectionCompatTest, SolrMetricsIntegrationTest, SolrPluginUtilsTest, TestSchemaManager, TestScoreJoinQPNoScore, TestMacroExpander, CreateRoutedAliasTest, StatsReloadRaceTest, CollectionsAPIAsyncDistributedZkTest]
[junit4] Completed [325/887 (1!)] on J2 in 674.56s, 4 tests, 1 failure <<< FAILURES!
[...truncated 46730 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/24924/consoleText
[repro] Revision: cabc125eefce10a8b021f1698371eabc6289ed60
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseG1GC"
[repro] Repro line: ant test -Dtestcase=CollectionsAPIAsyncDistributedZkTest -Dtests.method=testAsyncRequests -Dtests.seed=6880840F2549F3D6 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ast-ES -Dtests.timezone=Europe/Ulyanovsk -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] CollectionsAPIAsyncDistributedZkTest
[repro] ant compile-test
[...truncated 3323 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.CollectionsAPIAsyncDistributedZkTest" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseG1GC" -Dtests.seed=6880840F2549F3D6 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ast-ES -Dtests.timezone=Europe/Ulyanovsk -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 90 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest
[repro] Exiting with code 0
[...truncated 43 lines...]