You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2019/05/09 13:55:11 UTC
[JENKINS-EA] Lucene-Solr-8.x-Linux (64bit/jdk-13-ea+18) - Build #
535 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Linux/535/
Java: 64bit/jdk-13-ea+18 -XX:+UseCompressedOops -XX:+UseG1GC
3 tests failed.
FAILED: org.apache.solr.cloud.NestedShardedAtomicUpdateTest.test
Error Message:
Error from server at http://127.0.0.1:34617/collection1: non ok status: 500, message:Server Error
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:34617/collection1: non ok status: 500, message:Server Error
at __randomizedtesting.SeedInfo.seed([C81FA7980DE87FEB:404B9842A3141213]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:579)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:224)
at org.apache.solr.BaseDistributedSearchTestCase.add(BaseDistributedSearchTestCase.java:576)
at org.apache.solr.cloud.NestedShardedAtomicUpdateTest.indexDocAndRandomlyCommit(NestedShardedAtomicUpdateTest.java:221)
at org.apache.solr.cloud.NestedShardedAtomicUpdateTest.sendWrongRouteParam(NestedShardedAtomicUpdateTest.java:191)
at org.apache.solr.cloud.NestedShardedAtomicUpdateTest.test(NestedShardedAtomicUpdateTest.java:55)
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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:835)
FAILED: org.apache.solr.cloud.api.collections.ShardSplitTest.testSplitShardWithRule
Error Message:
Error from server at http://127.0.0.1:33309: Underlying core creation failed while creating collection: shardSplitWithRule_rewrite
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:33309: Underlying core creation failed while creating collection: shardSplitWithRule_rewrite
at __randomizedtesting.SeedInfo.seed([C81FA7980DE87FEB:4911762A901A24C0]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:649)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:368)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:296)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1068)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:837)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:769)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:224)
at org.apache.solr.cloud.api.collections.ShardSplitTest.doSplitShardWithRule(ShardSplitTest.java:650)
at org.apache.solr.cloud.api.collections.ShardSplitTest.testSplitShardWithRule(ShardSplitTest.java:628)
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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:835)
FAILED: org.apache.solr.update.processor.CategoryRoutedAliasUpdateProcessorTest.testMaxCardinality
Error Message:
Error from server at http://127.0.0.1:44749/solr: no core retrieved for testMaxCardinality
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteExecutionException: Error from server at http://127.0.0.1:44749/solr: no core retrieved for testMaxCardinality
at __randomizedtesting.SeedInfo.seed([C81FA7980DE87FEB:B9DE45177CCE536D]:0)
at org.apache.solr.client.solrj.impl.BaseHttpSolrClient$RemoteExecutionException.create(BaseHttpSolrClient.java:66)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:626)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:368)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:296)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1068)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:837)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:769)
at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1274)
at org.apache.solr.update.processor.RoutedAliasUpdateProcessorTest.createConfigSet(RoutedAliasUpdateProcessorTest.java:115)
at org.apache.solr.update.processor.CategoryRoutedAliasUpdateProcessorTest.testMaxCardinality(CategoryRoutedAliasUpdateProcessorTest.java:300)
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 13904 lines...]
[junit4] Suite: org.apache.solr.cloud.api.collections.ShardSplitTest
[junit4] 2> 439098 INFO (SUITE-ShardSplitTest-seed#[C81FA7980DE87FEB]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.ShardSplitTest_C81FA7980DE87FEB-001/init-core-data-001
[junit4] 2> 439099 WARN (SUITE-ShardSplitTest-seed#[C81FA7980DE87FEB]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 439099 INFO (SUITE-ShardSplitTest-seed#[C81FA7980DE87FEB]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 439099 INFO (SUITE-ShardSplitTest-seed#[C81FA7980DE87FEB]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl="https://issues.apache.org/jira/browse/SOLR-5776")
[junit4] 2> 439099 INFO (SUITE-ShardSplitTest-seed#[C81FA7980DE87FEB]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] IGNOR/A 0.00s J2 | ShardSplitTest.test
[junit4] > Assumption #1: 'nightly' test group is disabled (@Nightly())
[junit4] 2> 439102 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 439102 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 439102 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 439202 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.ZkTestServer start zk server on port:41073
[junit4] 2> 439202 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:41073
[junit4] 2> 439202 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 41073
[junit4] 2> 439205 INFO (zkConnectionManagerCallback-3132-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 439206 INFO (zkConnectionManagerCallback-3134-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 439207 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 439207 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml
[junit4] 2> 439208 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 439208 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 439209 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 439209 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 439209 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 439210 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 439210 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 439211 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 439211 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 439211 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise
[junit4] 2> 439300 WARN (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 439300 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 439300 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 439300 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 13-ea+18
[junit4] 2> 439301 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 439301 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 439301 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 439301 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1a83987a{/,null,AVAILABLE}
[junit4] 2> 439302 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@20616c74{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:46487}
[junit4] 2> 439302 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.e.j.s.Server Started @439333ms
[junit4] 2> 439302 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.ShardSplitTest_C81FA7980DE87FEB-001/tempDir-001/control/data, hostPort=46487, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.ShardSplitTest_C81FA7980DE87FEB-001/control-001/cores}
[junit4] 2> 439303 ERROR (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 439303 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 439303 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.2.0
[junit4] 2> 439303 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 439303 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 439303 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-09T13:12:09.513283Z
[junit4] 2> 439304 INFO (zkConnectionManagerCallback-3136-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 439304 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 439304 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.ShardSplitTest_C81FA7980DE87FEB-001/control-001/solr.xml
[junit4] 2> 439304 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x100952eaec50002, likely client has closed socket
[junit4] 2> 439306 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 439306 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 439307 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@5246efe3, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 439394 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 439395 WARN (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@5ce64f46[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 439398 WARN (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@7d9a0380[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 439398 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41073/solr
[junit4] 2> 439399 INFO (zkConnectionManagerCallback-3143-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 439401 INFO (zkConnectionManagerCallback-3145-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 439437 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:46487_
[junit4] 2> 439437 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [n:127.0.0.1:46487_ ] o.a.s.c.Overseer Overseer (id=72221621771108356-127.0.0.1:46487_-n_0000000000) starting
[junit4] 2> 439439 INFO (zkConnectionManagerCallback-3152-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 439440 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [n:127.0.0.1:46487_ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41073/solr ready
[junit4] 2> 439440 DEBUG (OverseerCollectionConfigSetProcessor-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
[junit4] 2> 439440 INFO (OverseerStateUpdate-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:46487_
[junit4] 2> 439440 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [n:127.0.0.1:46487_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46487_
[junit4] 2> 439441 DEBUG (OverseerCollectionConfigSetProcessor-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 0 #Completed tasks: 0
[junit4] 2> 439441 INFO (OverseerStateUpdate-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 439441 DEBUG (OverseerCollectionConfigSetProcessor-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor RunningTasks: []
[junit4] 2> 439441 INFO (zkCallback-3151-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 439441 DEBUG (OverseerCollectionConfigSetProcessor-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor BlockedTasks: []
[junit4] 2> 439441 DEBUG (OverseerCollectionConfigSetProcessor-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor CompletedTasks: []
[junit4] 2> 439441 INFO (OverseerCollectionConfigSetProcessor-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: []
[junit4] 2> 439453 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [n:127.0.0.1:46487_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 439464 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [n:127.0.0.1:46487_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5246efe3
[junit4] 2> 439471 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [n:127.0.0.1:46487_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5246efe3
[junit4] 2> 439471 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [n:127.0.0.1:46487_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5246efe3
[junit4] 2> 439472 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [n:127.0.0.1:46487_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.ShardSplitTest_C81FA7980DE87FEB-001/control-001/cores
[junit4] 2> 439483 INFO (zkConnectionManagerCallback-3158-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 439483 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 439484 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41073/solr ready
[junit4] 2> 439486 INFO (qtp326197635-8270) [n:127.0.0.1:46487_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:46487_&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 439487 DEBUG (OverseerCollectionConfigSetProcessor-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor Got 1 tasks from work-queue : [[org.apache.solr.cloud.OverseerTaskQueue$QueueEvent@2e684ecf]]
[junit4] 2> 439487 DEBUG (OverseerCollectionConfigSetProcessor-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor Marked task [/overseer/collection-queue-work/qn-0000000000] as running
[junit4] 2> 439487 DEBUG (OverseerCollectionConfigSetProcessor-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor Overseer Collection Message Handler: Get the message id:/overseer/collection-queue-work/qn-0000000000 message:{
[junit4] 2> "name":"control_collection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"1",
[junit4] 2> "createNodeSet":"127.0.0.1:46487_",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "operation":"create"}
[junit4] 2> 439488 DEBUG (OverseerThreadFactory-3289-thread-1-processing-n:127.0.0.1:46487_) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor Runner processing /overseer/collection-queue-work/qn-0000000000
[junit4] 2> 439488 DEBUG (OverseerThreadFactory-3289-thread-1-processing-n:127.0.0.1:46487_) [n:127.0.0.1:46487_ ] o.a.s.c.a.c.OverseerCollectionMessageHandler OverseerCollectionMessageHandler.processMessage : create , {
[junit4] 2> "name":"control_collection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"1",
[junit4] 2> "createNodeSet":"127.0.0.1:46487_",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "operation":"create"}
[junit4] 2> 439488 DEBUG (OverseerCollectionConfigSetProcessor-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 1 #Completed tasks: 0
[junit4] 2> 439488 DEBUG (OverseerCollectionConfigSetProcessor-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor RunningTasks: [/overseer/collection-queue-work/qn-0000000000]
[junit4] 2> 439488 DEBUG (OverseerCollectionConfigSetProcessor-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor BlockedTasks: []
[junit4] 2> 439488 DEBUG (OverseerCollectionConfigSetProcessor-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor CompletedTasks: []
[junit4] 2> 439488 INFO (OverseerCollectionConfigSetProcessor-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [/overseer/collection-queue-work/qn-0000000000]
[junit4] 2> 439488 INFO (OverseerThreadFactory-3289-thread-1-processing-n:127.0.0.1:46487_) [n:127.0.0.1:46487_ ] o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 439489 DEBUG (OverseerThreadFactory-3289-thread-1-processing-n:127.0.0.1:46487_) [n:127.0.0.1:46487_ ] o.a.s.c.a.c.OverseerCollectionMessageHandler creating collections conf node /collections/control_collection
[junit4] 2> 439489 DEBUG (OverseerThreadFactory-3289-thread-1-processing-n:127.0.0.1:46487_) [n:127.0.0.1:46487_ ] o.a.s.c.a.c.CreateCollectionCmd Check for collection zkNode:control_collection
[junit4] 2> 439489 DEBUG (OverseerThreadFactory-3289-thread-1-processing-n:127.0.0.1:46487_) [n:127.0.0.1:46487_ ] o.a.s.c.a.c.CreateCollectionCmd Collection zkNode exists
[junit4] 2> 439490 DEBUG (OverseerStateUpdate-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "name":"control_collection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"1",
[junit4] 2> "createNodeSet":"127.0.0.1:46487_",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 439490 DEBUG (OverseerStateUpdate-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
[junit4] 2> 439490 DEBUG (OverseerStateUpdate-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/control_collection/state.json
[junit4] 2> 439591 DEBUG (OverseerThreadFactory-3289-thread-1-processing-n:127.0.0.1:46487_) [n:127.0.0.1:46487_ ] o.a.s.c.a.c.CreateCollectionCmd Creating SolrCores for new collection control_collection, shardNames [shard1] , message : {
[junit4] 2> "name":"control_collection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"1",
[junit4] 2> "createNodeSet":"127.0.0.1:46487_",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "operation":"create"}
[junit4] 2> 439591 DEBUG (OverseerThreadFactory-3289-thread-1-processing-n:127.0.0.1:46487_) [n:127.0.0.1:46487_ ] o.a.s.c.a.c.CreateCollectionCmd Creating core control_collection_shard1_replica_n1 as part of shard shard1 of collection control_collection on 127.0.0.1:46487_
[junit4] 2> 439592 INFO (qtp326197635-8269) [n:127.0.0.1:46487_ x:control_collection_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 439593 INFO (qtp326197635-8269) [n:127.0.0.1:46487_ x:control_collection_shard1_replica_n1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 439594 DEBUG (OverseerStateUpdate-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"control_collection_shard1_replica_n1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:46487",
[junit4] 2> "node_name":"127.0.0.1:46487_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 439594 DEBUG (OverseerStateUpdate-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"control_collection_shard1_replica_n1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:46487",
[junit4] 2> "node_name":"127.0.0.1:46487_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"}
[junit4] 2> 439594 DEBUG (OverseerStateUpdate-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{"core":"control_collection_shard1_replica_n1","base_url":"http://127.0.0.1:46487","node_name":"127.0.0.1:46487_","state":"down","type":"NRT"}
[junit4] 2> 439594 DEBUG (OverseerStateUpdate-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(control_collection//collections/control_collection/state.json/0)={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":"control_collection_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:46487",
[junit4] 2> "node_name":"127.0.0.1:46487_",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 439695 DEBUG (OverseerStateUpdate-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/control_collection/state.json version: 0
[junit4] 2> 440599 INFO (qtp326197635-8269) [n:127.0.0.1:46487_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 440610 INFO (qtp326197635-8269) [n:127.0.0.1:46487_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.IndexSchema [control_collection_shard1_replica_n1] Schema name=test
[junit4] 2> 440654 INFO (qtp326197635-8269) [n:127.0.0.1:46487_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 440661 INFO (qtp326197635-8269) [n:127.0.0.1:46487_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1' using configuration from collection control_collection, trusted=true
[junit4] 2> 440661 INFO (qtp326197635-8269) [n:127.0.0.1:46487_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.control_collection.shard1.replica_n1' (registry 'solr.core.control_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5246efe3
[junit4] 2> 440661 INFO (qtp326197635-8269) [n:127.0.0.1:46487_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.ShardSplitTest_C81FA7980DE87FEB-001/control-001/cores/control_collection_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.ShardSplitTest_C81FA7980DE87FEB-001/control-001/cores/control_collection_shard1_replica_n1/data/]
[junit4] 2> 440663 INFO (qtp326197635-8269) [n:127.0.0.1:46487_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=31, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=63.173828125, floorSegmentMB=0.458984375, forceMergeDeletesPctAllowed=14.125234474175418, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=31.609633899343322
[junit4] 2> 440680 WARN (qtp326197635-8269) [n:127.0.0.1:46487_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 440710 INFO (qtp326197635-8269) [n:127.0.0.1:46487_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 440710 INFO (qtp326197635-8269) [n:127.0.0.1:46487_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 440711 INFO (qtp326197635-8269) [n:127.0.0.1:46487_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 440711 INFO (qtp326197635-8269) [n:127.0.0.1:46487_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 440712 INFO (qtp326197635-8269) [n:127.0.0.1:46487_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=16, maxMergedSegmentMB=88.599609375, floorSegmentMB=1.2607421875, forceMergeDeletesPctAllowed=20.948861681598743, segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2875317915676949, deletesPctAllowed=33.3923979010666
[junit4] 2> 440712 INFO (qtp326197635-8269) [n:127.0.0.1:46487_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@14b412c4[control_collection_shard1_replica_n1] main]
[junit4] 2> 440713 INFO (qtp326197635-8269) [n:127.0.0.1:46487_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 440713 INFO (qtp326197635-8269) [n:127.0.0.1:46487_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 440713 INFO (qtp326197635-8269) [n:127.0.0.1:46487_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 440713 INFO (qtp326197635-8269) [n:127.0.0.1:46487_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1633060159145115648
[junit4] 2> 440716 INFO (searcherExecutor-3294-thread-1-processing-n:127.0.0.1:46487_ x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:46487_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher Searcher@14b412c4[control_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 440717 INFO (qtp326197635-8269) [n:127.0.0.1:46487_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/control_collection/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 440717 INFO (qtp326197635-8269) [n:127.0.0.1:46487_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/control_collection/leaders/shard1
[junit4] 2> 440718 INFO (qtp326197635-8269) [n:127.0.0.1:46487_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 440718 INFO (qtp326197635-8269) [n:127.0.0.1:46487_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 440718 INFO (qtp326197635-8269) [n:127.0.0.1:46487_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:46487/control_collection_shard1_replica_n1/
[junit4] 2> 440719 INFO (qtp326197635-8269) [n:127.0.0.1:46487_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 440719 INFO (qtp326197635-8269) [n:127.0.0.1:46487_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:46487/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 440719 INFO (qtp326197635-8269) [n:127.0.0.1:46487_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/72221621771108356-core_node2-n_0000000000
[junit4] 2> 440719 INFO (qtp326197635-8269) [n:127.0.0.1:46487_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:46487/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 440719 DEBUG (OverseerStateUpdate-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:46487",
[junit4] 2> "core":"control_collection_shard1_replica_n1",
[junit4] 2> "state":"active"} current state version: 0
[junit4] 2> 440820 DEBUG (OverseerStateUpdate-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/control_collection/state.json version: 1
[junit4] 2> 440820 INFO (zkCallback-3144-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 440820 INFO (zkCallback-3144-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 440821 INFO (qtp326197635-8269) [n:127.0.0.1:46487_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 440821 DEBUG (OverseerStateUpdate-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"control_collection_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:46487",
[junit4] 2> "node_name":"127.0.0.1:46487_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 440821 DEBUG (OverseerStateUpdate-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"control_collection_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:46487",
[junit4] 2> "node_name":"127.0.0.1:46487_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"}
[junit4] 2> 440821 DEBUG (OverseerStateUpdate-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{"core":"control_collection_shard1_replica_n1","base_url":"http://127.0.0.1:46487","node_name":"127.0.0.1:46487_","state":"active","type":"NRT","leader":"true"}
[junit4] 2> 440821 DEBUG (OverseerStateUpdate-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(control_collection//collections/control_collection/state.json/2)={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":"control_collection_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:46487",
[junit4] 2> "node_name":"127.0.0.1:46487_",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true"}}}}}
[junit4] 2> 440822 INFO (qtp326197635-8269) [n:127.0.0.1:46487_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1229
[junit4] 2> 440822 DEBUG (OverseerThreadFactory-3289-thread-1-processing-n:127.0.0.1:46487_) [n:127.0.0.1:46487_ ] o.a.s.c.a.c.CreateCollectionCmd Finished create command on all shards for collection: control_collection
[junit4] 2> 440823 DEBUG (OverseerThreadFactory-3289-thread-1-processing-n:127.0.0.1:46487_) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor Completed task:[/overseer/collection-queue-work/qn-0000000000]
[junit4] 2> 440823 DEBUG (OverseerThreadFactory-3289-thread-1-processing-n:127.0.0.1:46487_) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor Marked task [/overseer/collection-queue-work/qn-0000000000] as completed.
[junit4] 2> 440823 DEBUG (OverseerThreadFactory-3289-thread-1-processing-n:127.0.0.1:46487_) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor RunningTasks: []
[junit4] 2> 440823 DEBUG (OverseerThreadFactory-3289-thread-1-processing-n:127.0.0.1:46487_) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor BlockedTasks: []
[junit4] 2> 440823 DEBUG (OverseerThreadFactory-3289-thread-1-processing-n:127.0.0.1:46487_) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor CompletedTasks: [/overseer/collection-queue-work/qn-0000000000]
[junit4] 2> 440823 INFO (OverseerThreadFactory-3289-thread-1-processing-n:127.0.0.1:46487_) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [/overseer/collection-queue-work/qn-0000000000]
[junit4] 2> 440823 DEBUG (OverseerThreadFactory-3289-thread-1-processing-n:127.0.0.1:46487_) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor Overseer Collection Message Handler: Message id:/overseer/collection-queue-work/qn-0000000000 complete, response:{success={127.0.0.1:46487_={responseHeader={status=0,QTime=1229},core=control_collection_shard1_replica_n1}}}
[junit4] 2> 440823 INFO (qtp326197635-8270) [n:127.0.0.1:46487_ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 440922 DEBUG (OverseerStateUpdate-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/control_collection/state.json version: 2
[junit4] 2> 440922 INFO (zkCallback-3144-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 440922 INFO (zkCallback-3144-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 440922 INFO (zkCallback-3144-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 440923 INFO (qtp326197635-8270) [n:127.0.0.1:46487_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:46487_&wt=javabin&version=2} status=0 QTime=1437
[junit4] 2> 440925 INFO (zkConnectionManagerCallback-3164-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 440926 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 440926 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41073/solr ready
[junit4] 2> 440926 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 440927 INFO (qtp326197635-8269) [n:127.0.0.1:46487_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 440928 DEBUG (OverseerCollectionConfigSetProcessor-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor Got 1 tasks from work-queue : [[org.apache.solr.cloud.OverseerTaskQueue$QueueEvent@2e684ed1]]
[junit4] 2> 440928 DEBUG (OverseerCollectionConfigSetProcessor-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor Marked task [/overseer/collection-queue-work/qn-0000000002] as running
[junit4] 2> 440928 DEBUG (OverseerCollectionConfigSetProcessor-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor Overseer Collection Message Handler: Get the message id:/overseer/collection-queue-work/qn-0000000002 message:{
[junit4] 2> "name":"collection1",
[junit4] 2> "fromApi":"true",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"2",
[junit4] 2> "createNodeSet":"",
[junit4] 2> "stateFormat":"1",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "operation":"create"}
[junit4] 2> 440929 DEBUG (OverseerThreadFactory-3289-thread-2-processing-n:127.0.0.1:46487_) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor Runner processing /overseer/collection-queue-work/qn-0000000002
[junit4] 2> 440929 DEBUG (OverseerThreadFactory-3289-thread-2-processing-n:127.0.0.1:46487_) [n:127.0.0.1:46487_ ] o.a.s.c.a.c.OverseerCollectionMessageHandler OverseerCollectionMessageHandler.processMessage : create , {
[junit4] 2> "name":"collection1",
[junit4] 2> "fromApi":"true",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"2",
[junit4] 2> "createNodeSet":"",
[junit4] 2> "stateFormat":"1",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "operation":"create"}
[junit4] 2> 440930 DEBUG (OverseerCollectionConfigSetProcessor-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 1 #Completed tasks: 1
[junit4] 2> 440930 INFO (OverseerThreadFactory-3289-thread-2-processing-n:127.0.0.1:46487_) [n:127.0.0.1:46487_ ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 440930 INFO (OverseerCollectionConfigSetProcessor-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] 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> 440930 DEBUG (OverseerCollectionConfigSetProcessor-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor RunningTasks: [/overseer/collection-queue-work/qn-0000000002]
[junit4] 2> 440930 DEBUG (OverseerCollectionConfigSetProcessor-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor BlockedTasks: []
[junit4] 2> 440930 DEBUG (OverseerCollectionConfigSetProcessor-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor CompletedTasks: []
[junit4] 2> 440930 INFO (OverseerCollectionConfigSetProcessor-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [/overseer/collection-queue-work/qn-0000000002]
[junit4] 2> 440930 DEBUG (OverseerThreadFactory-3289-thread-2-processing-n:127.0.0.1:46487_) [n:127.0.0.1:46487_ ] o.a.s.c.a.c.OverseerCollectionMessageHandler creating collections conf node /collections/collection1
[junit4] 2> 440930 DEBUG (OverseerThreadFactory-3289-thread-2-processing-n:127.0.0.1:46487_) [n:127.0.0.1:46487_ ] o.a.s.c.a.c.CreateCollectionCmd Check for collection zkNode:collection1
[junit4] 2> 440931 DEBUG (OverseerThreadFactory-3289-thread-2-processing-n:127.0.0.1:46487_) [n:127.0.0.1:46487_ ] o.a.s.c.a.c.CreateCollectionCmd Collection zkNode exists
[junit4] 2> 440931 DEBUG (OverseerStateUpdate-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "name":"collection1",
[junit4] 2> "fromApi":"true",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"2",
[junit4] 2> "createNodeSet":"",
[junit4] 2> "stateFormat":"1",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 440931 DEBUG (OverseerStateUpdate-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1
[junit4] 2> 441132 WARN (OverseerThreadFactory-3289-thread-2-processing-n:127.0.0.1:46487_) [n:127.0.0.1:46487_ ] o.a.s.c.a.c.CreateCollectionCmd It is unusual to create a collection (collection1) without cores.
[junit4] 2> 441132 DEBUG (OverseerThreadFactory-3289-thread-2-processing-n:127.0.0.1:46487_) [n:127.0.0.1:46487_ ] o.a.s.c.a.c.CreateCollectionCmd Finished create command for collection: collection1
[junit4] 2> 441132 DEBUG (OverseerThreadFactory-3289-thread-2-processing-n:127.0.0.1:46487_) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor Completed task:[/overseer/collection-queue-work/qn-0000000002]
[junit4] 2> 441132 DEBUG (OverseerThreadFactory-3289-thread-2-processing-n:127.0.0.1:46487_) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor Marked task [/overseer/collection-queue-work/qn-0000000002] as completed.
[junit4] 2> 441132 DEBUG (OverseerThreadFactory-3289-thread-2-processing-n:127.0.0.1:46487_) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor RunningTasks: []
[junit4] 2> 441132 DEBUG (OverseerThreadFactory-3289-thread-2-processing-n:127.0.0.1:46487_) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor BlockedTasks: []
[junit4] 2> 441132 DEBUG (OverseerThreadFactory-3289-thread-2-processing-n:127.0.0.1:46487_) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor CompletedTasks: [/overseer/collection-queue-work/qn-0000000002]
[junit4] 2> 441132 INFO (OverseerThreadFactory-3289-thread-2-processing-n:127.0.0.1:46487_) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [/overseer/collection-queue-work/qn-0000000002]
[junit4] 2> 441132 DEBUG (OverseerThreadFactory-3289-thread-2-processing-n:127.0.0.1:46487_) [n:127.0.0.1:46487_ ] o.a.s.c.OverseerTaskProcessor Overseer Collection Message Handler: Message id:/overseer/collection-queue-work/qn-0000000002 complete, response:{}
[junit4] 2> 441133 INFO (qtp326197635-8269) [n:127.0.0.1:46487_ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 441133 INFO (qtp326197635-8269) [n:127.0.0.1:46487_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=1&wt=javabin&version=2} status=0 QTime=205
[junit4] 2> 441134 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating jetty instances pullReplicaCount=0 numOtherReplicas=4
[junit4] 2> 441216 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.ShardSplitTest_C81FA7980DE87FEB-001/shard-1-001 of type NRT
[junit4] 2> 441216 WARN (closeThreadPool-3165-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 441216 INFO (closeThreadPool-3165-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 441216 INFO (closeThreadPool-3165-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 441216 INFO (closeThreadPool-3165-thread-1) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 13-ea+18
[junit4] 2> 441224 INFO (closeThreadPool-3165-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 441224 INFO (closeThreadPool-3165-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 441224 INFO (closeThreadPool-3165-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 441224 INFO (closeThreadPool-3165-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@29434d25{/,null,AVAILABLE}
[junit4] 2> 441224 INFO (closeThreadPool-3165-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7d30d8be{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:34007}
[junit4] 2> 441224 INFO (closeThreadPool-3165-thread-1) [ ] o.e.j.s.Server Started @441254ms
[junit4] 2> 441224 INFO (closeThreadPool-3165-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.ShardSplitTest_C81FA7980DE87FEB-001/tempDir-001/jetty1, hostPort=34007, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.ShardSplitTest_C81FA7980DE87FEB-001/shard-1-001/cores, replicaType=NRT}
[junit4] 2> 441224 ERROR (closeThreadPool-3165-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 441224 INFO (closeThreadPool-3165-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 441224 INFO (closeThreadPool-3165-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.2.0
[junit4] 2> 441224 INFO (closeThreadPool-3165-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 441224 INFO (closeThreadPool-3165-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 441224 INFO (closeThreadPool-3165-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-09T13:12:11.434784Z
[junit4] 2> 441225 INFO (zkConnectionManagerCallback-3167-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 441226 INFO (closeThreadPool-3165-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 441226 INFO (closeThreadPool-3165-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.ShardSplitTest_C81FA7980DE87FEB-001/shard-1-001/solr.xml
[junit4] 2> 441228 INFO (closeThreadPool-3165-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 441228 INFO (closeThreadPool-3165-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 441229 INFO (closeThreadPool-3165-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@5246efe3, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 441309 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.ShardSplitTest_C81FA7980DE87FEB-001/shard-2-001 of type NRT
[junit4] 2> 441309 WARN (closeThreadPool-3165-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 441310 INFO (closeThreadPool-3165-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 441310 INFO (closeThreadPool-3165-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 441310 INFO (closeThreadPool-3165-thread-2) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 13-ea+18
[junit4] 2> 441311 INFO (closeThreadPool-3165-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 441311 INFO (closeThreadPool-3165-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 441311 INFO (closeThreadPool-3165-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 441311 INFO (closeThreadPool-3165-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6a0e2b78{/,null,AVAILABLE}
[junit4] 2> 441311 INFO (closeThreadPool-3165-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2c42a5a5{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:40061}
[junit4] 2> 441311 INFO (closeThreadPool-3165-thread-2) [ ] o.e.j.s.Server Started @441341ms
[junit4] 2> 441311 INFO (closeThreadPool-3165-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.ShardSplitTest_C81FA7980DE87FEB-001/tempDir-001/jetty2, hostPort=40061, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.ShardSplitTest_C81FA7980DE87FEB-001/shard-2-001/cores, replicaType=NRT}
[junit4] 2> 441311 ERROR (closeThreadPool-3165-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 441311 INFO (closeThreadPool-3165-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 441311 INFO (closeThreadPool-3165-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.2.0
[junit4] 2> 441311 INFO (closeThreadPool-3165-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 441311 INFO (closeThreadPool-3165-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 441311 INFO (closeThreadPool-3165-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-09T13:12:11.521902Z
[junit4] 2> 441313 INFO (zkConnectionManagerCallback-3170-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 441313 INFO (closeThreadPool-3165-thread-2) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 441313 INFO (closeThreadPool-3165-thread-2) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.ShardSplitTest_C81FA7980DE87FEB-001/shard-2-001/solr.xml
[junit4] 2> 441315 INFO (closeThreadPool-3165-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 441315 INFO (closeThreadPool-3165-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 441315 INFO (closeThreadPool-3165-thread-2) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@5246efe3, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 441398 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.ShardSplitTest_C81FA7980DE87FEB-001/shard-3-001 of type NRT
[junit4] 2> 441399 WARN (closeThreadPool-3165-thread-3) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 441399 INFO (closeThreadPool-3165-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 441399 INFO (closeThreadPool-3165-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 441399 INFO (closeThreadPool-3165-thread-3) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 13-ea+18
[junit4] 2> 441410 INFO (closeThreadPool-3165-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 441413 INFO (closeThreadPool-3165-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 441413 INFO (closeThreadPool-3165-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 441413 INFO (closeThreadPool-3165-thread-3) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 441413 INFO (closeThreadPool-3165-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@162a8e4e{/,null,AVAILABLE}
[junit4] 2> 441414 INFO (closeThreadPool-3165-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@225320fa{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:42365}
[junit4] 2> 441414 INFO (closeThreadPool-3165-thread-3) [ ] o.e.j.s.Server Started @441444ms
[junit4] 2> 441414 INFO (closeThreadPool-3165-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.ShardSplitTest_C81FA7980DE87FEB-001/tempDir-001/jetty3, hostPort=42365, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.ShardSplitTest_C81FA7980DE87FEB-001/shard-3-001/cores, replicaType=NRT}
[junit4] 2> 441414 WARN (closeThreadPool-3165-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@7fbc55b8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 441414 ERROR (closeThreadPool-3165-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 441414 INFO (closeThreadPool-3165-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 441414 INFO (closeThreadPool-3165-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.2.0
[junit4] 2> 441414 INFO (closeThreadPool-3165-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 441414 INFO (closeThreadPool-3165-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 441414 INFO (closeThreadPool-3165-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-09T13:12:11.624545Z
[junit4] 2> 441418 INFO (zkConnectionManagerCallback-3174-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 441418 INFO (closeThreadPool-3165-thread-3) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 441418 INFO (closeThreadPool-3165-thread-3) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.ShardSplitTest_C81FA7980DE87FEB-001/shard-3-001/solr.xml
[junit4] 2> 441419 WARN (closeThreadPool-3165-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@40e12731[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 441420 INFO (closeThreadPool-3165-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41073/solr
[junit4] 2> 441420 INFO (closeThreadPool-3165-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 441420 INFO (closeThreadPool-3165-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 441421 INFO (closeThreadPool-3165-thread-3) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@5246efe3, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 441425 INFO (zkConnectionManagerCallback-3179-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 441437 INFO (zkConnectionManagerCallback-3182-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 441439 INFO (closeThreadPool-3165-thread-2) [n:127.0.0.1:40061_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 441442 INFO (closeThreadPool-3165-thread-2) [n:127.0.0.1:40061_ ] o.a.s.c.ZkController Publish node=127.0.0.1:40061_ as DOWN
[junit4] 2> 441443 INFO (closeThreadPool-3165-thread-2) [n:127.0.0.1:40061_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 441443 INFO (closeThreadPool-3165-thread-2) [n:127.0.0.1:40061_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40061_
[junit4] 2> 441443 DEBUG (OverseerStateUpdate-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:40061_"} current state version: 1
[junit4] 2> 441443 DEBUG (OverseerStateUpdate-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:40061_
[junit4] 2> 441443 INFO (zkCallback-3163-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 441443 INFO (zkCallback-3151-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 441443 INFO (zkCallback-3144-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 441445 INFO (zkCallback-3181-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 441450 INFO (zkConnectionManagerCallback-3189-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 441450 INFO (closeThreadPool-3165-thread-2) [n:127.0.0.1:40061_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 441451 INFO (closeThreadPool-3165-thread-2) [n:127.0.0.1:40061_ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41073/solr ready
[junit4] 2> 441452 INFO (closeThreadPool-3165-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 441454 WARN (closeThreadPool-3165-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@58292df3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 441464 WARN (closeThreadPool-3165-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@6c15daf5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 441464 INFO (closeThreadPool-3165-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41073/solr
[junit4] 2> 441468 INFO (closeThreadPool-3165-thread-2) [n:127.0.0.1:40061_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 441475 INFO (zkConnectionManagerCallback-3196-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 441481 INFO (zkConnectionManagerCallback-3198-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 441483 INFO (closeThreadPool-3165-thread-1) [n:127.0.0.1:34007_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 441485 INFO (closeThreadPool-3165-thread-1) [n:127.0.0.1:34007_ ] o.a.s.c.ZkController Publish node=127.0.0.1:34007_ as DOWN
[junit4] 2> 441485 INFO (closeThreadPool-3165-thread-1) [n:127.0.0.1:34007_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 441485 INFO (closeThreadPool-3165-thread-1) [n:127.0.0.1:34007_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34007_
[junit4] 2> 441485 INFO (closeThreadPool-3165-thread-2) [n:127.0.0.1:40061_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5246efe3
[junit4] 2> 441485 DEBUG (OverseerStateUpdate-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:34007_"} current state version: 1
[junit4] 2> 441485 DEBUG (OverseerStateUpdate-72221621771108356-127.0.0.1:46487_-n_0000000000) [n:127.0.0.1:46487_ ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:34007_
[junit4] 2> 441486 INFO (zkCallback-3163-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 441486 INFO (zkCallback-3151-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 441486 INFO (zkCallback-3181-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 441486 INFO (zkCallback-3144-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 441486 INFO (zkCallback-3188-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 441486 INFO (zkCallback-3197-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 441495 INFO (zkConnectionManagerCallback-3205-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 441496 INFO (closeThreadPool-3165-thread-1) [n:127.0.0.1:34007_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 441496 INFO (closeThreadPool-3165-thread-2) [n:127.0.0.1:40061_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5246efe3
[junit4] 2> 441496 INFO (closeThreadPool-3165-thread-2) [n:127.0.0.1:40061_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5246efe3
[junit4] 2> 441496 INFO (closeThreadPool-3165-thread-1) [n:127.0.0.1:34007_ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41073/solr ready
[junit4] 2> 441497 INFO (closeThreadPool-3165-thread-2) [n:127.0.0.1:40061_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.ShardSplitTest_C81FA7980DE87FEB-001/shard-2-001/cores
[junit4] 2> 441501 INFO (closeThreadPool-3165-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 441501 WARN (closeThreadPool-3165-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@79cc8713[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 441504 INFO (TEST-ShardSplitTest.testSplitMixedReplicaTypes-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.ShardSplitTest_C81FA7980DE87FEB-001/shard-4-001 of type NRT
[junit4] 2> 441504 WARN (closeThreadPool-3165-thread-4) [ ] o.e.j
[...truncated too long message...]
tion/terms/shard1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 12 /solr/clusterstate.json
[junit4] 2> 12 /solr/clusterprops.json
[junit4] 2> 7 /solr/collections/control_collection/state.json
[junit4] 2> 2 /solr/overseer_elect/election/72221665331773454-127.0.0.1:34617_-n_0000000002
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 12 /solr/live_nodes
[junit4] 2> 12 /solr/collections
[junit4] 2>
[junit4] 2> 1107799 INFO (TEST-NestedShardedAtomicUpdateTest.test-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:43545
[junit4] 2> 1107799 INFO (TEST-NestedShardedAtomicUpdateTest.test-seed#[C81FA7980DE87FEB]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 43545
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=NestedShardedAtomicUpdateTest -Dtests.method=test -Dtests.seed=C81FA7980DE87FEB -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ccp-BD -Dtests.timezone=Asia/Thimbu -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 4.00s J0 | NestedShardedAtomicUpdateTest.test <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:34617/collection1: non ok status: 500, message:Server Error
[junit4] > at __randomizedtesting.SeedInfo.seed([C81FA7980DE87FEB:404B9842A3141213]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:579)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:224)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.add(BaseDistributedSearchTestCase.java:576)
[junit4] > at org.apache.solr.cloud.NestedShardedAtomicUpdateTest.indexDocAndRandomlyCommit(NestedShardedAtomicUpdateTest.java:221)
[junit4] > at org.apache.solr.cloud.NestedShardedAtomicUpdateTest.sendWrongRouteParam(NestedShardedAtomicUpdateTest.java:191)
[junit4] > at org.apache.solr.cloud.NestedShardedAtomicUpdateTest.test(NestedShardedAtomicUpdateTest.java:55)
[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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:835)
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.NestedShardedAtomicUpdateTest_C81FA7980DE87FEB-001
[junit4] 2> May 09, 2019 1:23:18 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): {}, docValues:{}, maxPointsInLeafNode=277, maxMBSortInHeap=5.196423606350519, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@59f6062a), locale=ccp-BD, timezone=Asia/Thimbu
[junit4] 2> NOTE: Linux 4.18.0-17-generic amd64/Oracle Corporation 13-ea (64-bit)/cpus=8,threads=1,free=288833944,total=447741952
[junit4] 2> NOTE: All tests run in this JVM: [TestFieldSortValues, TestCloudPhrasesIdentificationComponent, SimpleCollectionCreateDeleteTest, TestCollapseQParserPlugin, HttpPartitionOnCommitTest, TestLegacyBM25SimilarityFactory, DOMUtilTest, TestRandomDVFaceting, TestConfigReload, GraphQueryTest, TestSortableTextField, BadComponentTest, HdfsDirectoryFactoryTest, HDFSCollectionsAPITest, SearchRateTriggerTest, SharedFSAutoReplicaFailoverTest, TestPayloadScoreQParserPlugin, SolrShardReporterTest, TestReplicationHandler, TriggerCooldownIntegrationTest, TestDeprecatedFilters, SolrCoreMetricManagerTest, SampleTest, PathHierarchyTokenizerFactoryTest, TestStressReorder, TestSQLHandlerNonCloud, DebugComponentTest, SuggesterTSTTest, TestStreamBody, SmileWriterTest, SuggestComponentContextFilterQueryTest, TestSolrJ, BitVectorTest, TestDirectoryFactory, TestJsonFacetsStatsParsing, TestSimTriggerIntegration, SimpleFacetsTest, FieldAnalysisRequestHandlerTest, TestRecovery, XmlUpdateRequestHandlerTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestMacroExpander, TestFunctionQuery, ClassificationUpdateProcessorIntegrationTest, TestLRUCache, UpdateLogTest, TestDistribDocBasedVersion, TestBulkSchemaAPI, TestManagedResource, ReplaceNodeTest, BlockJoinFacetDistribTest, ResponseLogComponentTest, AuditLoggerPluginTest, TestLogWatcher, TestScoreJoinQPNoScore, TestComponentsName, ChaosMonkeySafeLeaderWithPullReplicasTest, TestRequestStatusCollectionAPI, SolrCloudExampleTest, AddReplicaTest, DistributedVersionInfoTest, TestConfigSetsAPIZkFailure, DistributedFacetPivotSmallAdvancedTest, MoveReplicaHDFSFailoverTest, TestCloudConsistency, SyncSliceTest, RangeFacetCloudTest, HdfsUnloadDistributedZkTest, AutoscalingHistoryHandlerTest, LeaderVoteWaitTimeoutTest, TestLegacyFieldCache, LeaderElectionContextKeyTest, HLLSerializationTest, TestQueryUtils, EchoParamsTest, TestLeaderElectionZkExpiry, HdfsLockFactoryTest, TestSimExtremeIndexing, TestTrackingShardHandlerFactory, CloudExitableDirectoryReaderTest, RegexBoostProcessorTest, TestCloudJSONFacetSKG, DistributedMLTComponentTest, TestPKIAuthenticationPlugin, TestReloadAndDeleteDocs, NodeAddedTriggerTest, OverseerStatusTest, DeleteLastCustomShardedReplicaTest, TestExpandComponent, SearchHandlerTest, SOLR749Test, URLClassifyProcessorTest, TestConfigsApi, TestRTimerTree, TestClusterStateMutator, TestNestedUpdateProcessor, HdfsRecoverLeaseTest, TestStandardQParsers, TermVectorComponentTest, TestInPlaceUpdatesRequiredField, CustomCollectionTest, PolyFieldTest, TestFileDictionaryLookup, RankQueryTest, UnloadDistributedZkTest, HdfsChaosMonkeyNothingIsSafeTest, TestExactStatsCache, TestCloudRecovery, TestSolrIndexConfig, HttpSolrCallGetCoreTest, DistributedQueryElevationComponentTest, TestSchemaResource, FileBasedSpellCheckerTest, TestDelegationWithHadoopAuth, MetricsHistoryWithAuthIntegrationTest, CdcrBootstrapTest, TestJavabinTupleStreamParser, NestedAtomicUpdateTest, CdcrRequestHandlerTest, HighlighterTest, AlternateDirectoryTest, SolrJmxReporterTest, TriggerSetPropertiesIntegrationTest, PrimUtilsTest, HdfsAutoAddReplicasIntegrationTest, FieldMutatingUpdateProcessorTest, AtomicUpdatesTest, TestHighFrequencyDictionaryFactory, TestCrossCoreJoin, TestTolerantUpdateProcessorRandomCloud, TestSolrConfigHandler, ImplicitSnitchTest, SolrCmdDistributorTest, RAMDirectoryFactoryTest, TestSubQueryTransformer, TestIndexSearcher, TestOnReconnectListenerSupport, TestSegmentSorting, TestCollationFieldDocValues, DeleteShardTest, DeleteStatusTest, DistribCursorPagingTest, DistribDocExpirationUpdateProcessorTest, MissingSegmentRecoveryTest, MultiSolrCloudTestCaseTest, MultiThreadedOCPTest, NestedShardedAtomicUpdateTest]
[junit4] Completed [477/856 (3!)] on J0 in 4.76s, 1 test, 1 error <<< FAILURES!
[...truncated 44411 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Linux/535/consoleText
[repro] Revision: 214f70cb44d5ef3ff6f689c9cbe98fc6f986552b
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC"
[repro] Repro line: ant test -Dtestcase=ShardSplitTest -Dtests.method=testSplitShardWithRule -Dtests.seed=C81FA7980DE87FEB -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=et -Dtests.timezone=Pacific/Samoa -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] Repro line: ant test -Dtestcase=CategoryRoutedAliasUpdateProcessorTest -Dtests.method=testMaxCardinality -Dtests.seed=C81FA7980DE87FEB -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en-KN -Dtests.timezone=SystemV/HST10 -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] Repro line: ant test -Dtestcase=NestedShardedAtomicUpdateTest -Dtests.method=test -Dtests.seed=C81FA7980DE87FEB -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ccp-BD -Dtests.timezone=Asia/Thimbu -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] CategoryRoutedAliasUpdateProcessorTest
[repro] NestedShardedAtomicUpdateTest
[repro] ShardSplitTest
[repro] ant compile-test
[...truncated 3581 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=15 -Dtests.class="*.CategoryRoutedAliasUpdateProcessorTest|*.NestedShardedAtomicUpdateTest|*.ShardSplitTest" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC" -Dtests.seed=C81FA7980DE87FEB -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en-KN -Dtests.timezone=SystemV/HST10 -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 205 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.cloud.NestedShardedAtomicUpdateTest
[repro] 0/5 failed: org.apache.solr.cloud.api.collections.ShardSplitTest
[repro] 0/5 failed: org.apache.solr.update.processor.CategoryRoutedAliasUpdateProcessorTest
[repro] Exiting with code 0
[...truncated 43 lines...]
[JENKINS] Lucene-Solr-8.x-Linux (64bit/jdk1.8.0_201) - Build # 536
- Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Linux/536/
Java: 64bit/jdk1.8.0_201 -XX:+UseCompressedOops -XX:+UseParallelGC
6 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.RoutingToNodesWithPropertiesTest
Error Message:
expected:<us-west1> but was:<null>
Stack Trace:
java.lang.AssertionError: expected:<us-west1> but was:<null>
at __randomizedtesting.SeedInfo.seed([13525A4073A0EB3F]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotEquals(Assert.java:834)
at org.junit.Assert.assertEquals(Assert.java:118)
at org.junit.Assert.assertEquals(Assert.java:144)
at org.apache.solr.cloud.RoutingToNodesWithPropertiesTest.setupCluster(RoutingToNodesWithPropertiesTest.java:115)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:878)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.RoutingToNodesWithPropertiesTest
Error Message:
expected:<us-west1> but was:<null>
Stack Trace:
java.lang.AssertionError: expected:<us-west1> but was:<null>
at __randomizedtesting.SeedInfo.seed([13525A4073A0EB3F]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotEquals(Assert.java:834)
at org.junit.Assert.assertEquals(Assert.java:118)
at org.junit.Assert.assertEquals(Assert.java:144)
at org.apache.solr.cloud.RoutingToNodesWithPropertiesTest.setupCluster(RoutingToNodesWithPropertiesTest.java:115)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:878)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.RoutingToNodesWithPropertiesTest
Error Message:
expected:<us-west1> but was:<null>
Stack Trace:
java.lang.AssertionError: expected:<us-west1> but was:<null>
at __randomizedtesting.SeedInfo.seed([13525A4073A0EB3F]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotEquals(Assert.java:834)
at org.junit.Assert.assertEquals(Assert.java:118)
at org.junit.Assert.assertEquals(Assert.java:144)
at org.apache.solr.cloud.RoutingToNodesWithPropertiesTest.setupCluster(RoutingToNodesWithPropertiesTest.java:115)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:878)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.RoutingToNodesWithPropertiesTest
Error Message:
expected:<us-west1> but was:<null>
Stack Trace:
java.lang.AssertionError: expected:<us-west1> but was:<null>
at __randomizedtesting.SeedInfo.seed([13525A4073A0EB3F]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotEquals(Assert.java:834)
at org.junit.Assert.assertEquals(Assert.java:118)
at org.junit.Assert.assertEquals(Assert.java:144)
at org.apache.solr.cloud.RoutingToNodesWithPropertiesTest.setupCluster(RoutingToNodesWithPropertiesTest.java:115)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:878)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.cloud.PeerSyncReplicationTest.test
Error Message:
expected:<154> but was:<152>
Stack Trace:
java.lang.AssertionError: expected:<154> but was:<152>
at __randomizedtesting.SeedInfo.seed([13525A4073A0EB3F:9B06659ADD5C86C7]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotEquals(Assert.java:834)
at org.junit.Assert.assertEquals(Assert.java:645)
at org.junit.Assert.assertEquals(Assert.java:631)
at org.apache.solr.cloud.PeerSyncReplicationTest.test(PeerSyncReplicationTest.java:154)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.cloud.RoutingToNodesWithPropertiesTest.test
Error Message:
Hitting same zone after 10 queries
Stack Trace:
java.lang.AssertionError: Hitting same zone after 10 queries
at __randomizedtesting.SeedInfo.seed([13525A4073A0EB3F:9B06659ADD5C86C7]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.cloud.RoutingToNodesWithPropertiesTest.test(RoutingToNodesWithPropertiesTest.java:251)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 14516 lines...]
[junit4] Suite: org.apache.solr.cloud.RoutingToNodesWithPropertiesTest
[junit4] 2> 1138508 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/init-core-data-001
[junit4] 2> 1138509 WARN (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=5 numCloses=5
[junit4] 2> 1138509 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1138510 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 1138511 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001
[junit4] 2> 1138511 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1138511 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1138511 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1138611 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:36727
[junit4] 2> 1138611 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:36727
[junit4] 2> 1138611 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 36727
[junit4] 2> 1138613 INFO (zkConnectionManagerCallback-10174-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1138616 INFO (zkConnectionManagerCallback-10176-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1138617 INFO (zkConnectionManagerCallback-10178-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1138620 WARN (jetty-launcher-10179-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1138620 WARN (jetty-launcher-10179-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1138620 INFO (jetty-launcher-10179-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1138620 INFO (jetty-launcher-10179-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1138620 INFO (jetty-launcher-10179-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1138620 INFO (jetty-launcher-10179-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1138620 INFO (jetty-launcher-10179-thread-2) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_201-b09
[junit4] 2> 1138620 INFO (jetty-launcher-10179-thread-1) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_201-b09
[junit4] 2> 1138622 INFO (jetty-launcher-10179-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1138622 INFO (jetty-launcher-10179-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1138622 INFO (jetty-launcher-10179-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1138622 INFO (jetty-launcher-10179-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1138622 INFO (jetty-launcher-10179-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1138622 INFO (jetty-launcher-10179-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1138622 INFO (jetty-launcher-10179-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4d34efb{/solr,null,AVAILABLE}
[junit4] 2> 1138622 INFO (jetty-launcher-10179-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@53786a7a{/solr,null,AVAILABLE}
[junit4] 2> 1138624 WARN (jetty-launcher-10179-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@3d4e863c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1138624 WARN (jetty-launcher-10179-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@24f49596[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1138625 INFO (jetty-launcher-10179-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@60eeaf2{SSL,[ssl, http/1.1]}{127.0.0.1:35695}
[junit4] 2> 1138625 INFO (jetty-launcher-10179-thread-2) [ ] o.e.j.s.Server Started @1138654ms
[junit4] 2> 1138625 INFO (jetty-launcher-10179-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5ecb15d4{SSL,[ssl, http/1.1]}{127.0.0.1:38597}
[junit4] 2> 1138625 INFO (jetty-launcher-10179-thread-1) [ ] o.e.j.s.Server Started @1138654ms
[junit4] 2> 1138625 INFO (jetty-launcher-10179-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=35695}
[junit4] 2> 1138625 INFO (jetty-launcher-10179-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38597}
[junit4] 2> 1138625 ERROR (jetty-launcher-10179-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1138625 ERROR (jetty-launcher-10179-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1138625 INFO (jetty-launcher-10179-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1138625 INFO (jetty-launcher-10179-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1138625 INFO (jetty-launcher-10179-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.2.0
[junit4] 2> 1138625 INFO (jetty-launcher-10179-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.2.0
[junit4] 2> 1138625 INFO (jetty-launcher-10179-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1138625 INFO (jetty-launcher-10179-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1138625 INFO (jetty-launcher-10179-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1138625 INFO (jetty-launcher-10179-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1138625 INFO (jetty-launcher-10179-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-09T18:44:48.159Z
[junit4] 2> 1138625 INFO (jetty-launcher-10179-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-09T18:44:48.159Z
[junit4] 2> 1138626 INFO (zkConnectionManagerCallback-10182-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1138626 INFO (zkConnectionManagerCallback-10183-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1138626 INFO (jetty-launcher-10179-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1138626 INFO (jetty-launcher-10179-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1138628 INFO (jetty-launcher-10179-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1138628 INFO (jetty-launcher-10179-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1138629 INFO (jetty-launcher-10179-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1138629 INFO (jetty-launcher-10179-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1138629 INFO (jetty-launcher-10179-thread-2) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@24f9fd8c, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1138629 INFO (jetty-launcher-10179-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@24f9fd8c, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1138700 INFO (jetty-launcher-10179-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1138700 WARN (jetty-launcher-10179-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1138700 WARN (jetty-launcher-10179-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@47dca939[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1138702 WARN (jetty-launcher-10179-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1138703 WARN (jetty-launcher-10179-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@7be789e7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1138704 INFO (jetty-launcher-10179-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36727/solr
[junit4] 2> 1138705 INFO (zkConnectionManagerCallback-10191-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1138708 INFO (zkConnectionManagerCallback-10193-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1138737 INFO (jetty-launcher-10179-thread-1) [n:127.0.0.1:38597_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:38597_solr
[junit4] 2> 1138737 INFO (jetty-launcher-10179-thread-1) [n:127.0.0.1:38597_solr ] o.a.s.c.Overseer Overseer (id=72222929786634246-127.0.0.1:38597_solr-n_0000000000) starting
[junit4] 2> 1138739 INFO (zkConnectionManagerCallback-10200-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1138740 INFO (jetty-launcher-10179-thread-1) [n:127.0.0.1:38597_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36727/solr ready
[junit4] 2> 1138740 INFO (OverseerStateUpdate-72222929786634246-127.0.0.1:38597_solr-n_0000000000) [n:127.0.0.1:38597_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:38597_solr
[junit4] 2> 1138740 INFO (jetty-launcher-10179-thread-1) [n:127.0.0.1:38597_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38597_solr
[junit4] 2> 1138740 INFO (OverseerStateUpdate-72222929786634246-127.0.0.1:38597_solr-n_0000000000) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1138741 INFO (zkCallback-10199-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1138753 INFO (jetty-launcher-10179-thread-1) [n:127.0.0.1:38597_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1138762 INFO (jetty-launcher-10179-thread-1) [n:127.0.0.1:38597_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@24f9fd8c
[junit4] 2> 1138769 INFO (jetty-launcher-10179-thread-1) [n:127.0.0.1:38597_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@24f9fd8c
[junit4] 2> 1138769 INFO (jetty-launcher-10179-thread-1) [n:127.0.0.1:38597_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@24f9fd8c
[junit4] 2> 1138770 INFO (jetty-launcher-10179-thread-1) [n:127.0.0.1:38597_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node1/.
[junit4] 2> 1138794 INFO (jetty-launcher-10179-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1138794 WARN (jetty-launcher-10179-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1138794 WARN (jetty-launcher-10179-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@b72a423[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1138796 WARN (jetty-launcher-10179-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1138796 WARN (jetty-launcher-10179-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@ab6294f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1138797 INFO (jetty-launcher-10179-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36727/solr
[junit4] 2> 1138798 INFO (zkConnectionManagerCallback-10207-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1138800 INFO (zkConnectionManagerCallback-10209-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1138801 INFO (jetty-launcher-10179-thread-2) [n:127.0.0.1:35695_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1138802 INFO (jetty-launcher-10179-thread-2) [n:127.0.0.1:35695_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:35695_solr as DOWN
[junit4] 2> 1138802 INFO (jetty-launcher-10179-thread-2) [n:127.0.0.1:35695_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1138802 INFO (jetty-launcher-10179-thread-2) [n:127.0.0.1:35695_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35695_solr
[junit4] 2> 1138803 INFO (zkCallback-10192-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1138803 INFO (zkCallback-10199-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1138803 INFO (zkCallback-10208-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1138804 INFO (zkConnectionManagerCallback-10216-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1138804 INFO (jetty-launcher-10179-thread-2) [n:127.0.0.1:35695_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1138804 INFO (jetty-launcher-10179-thread-2) [n:127.0.0.1:35695_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36727/solr ready
[junit4] 2> 1138813 INFO (jetty-launcher-10179-thread-2) [n:127.0.0.1:35695_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1138823 INFO (jetty-launcher-10179-thread-2) [n:127.0.0.1:35695_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@24f9fd8c
[junit4] 2> 1138828 INFO (jetty-launcher-10179-thread-2) [n:127.0.0.1:35695_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@24f9fd8c
[junit4] 2> 1138829 INFO (jetty-launcher-10179-thread-2) [n:127.0.0.1:35695_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@24f9fd8c
[junit4] 2> 1138829 INFO (jetty-launcher-10179-thread-2) [n:127.0.0.1:35695_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node2/.
[junit4] 2> 1138845 INFO (zkConnectionManagerCallback-10222-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1138845 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1138845 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36727/solr ready
[junit4] 2> 1138848 WARN (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1138848 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1138848 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1138848 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_201-b09
[junit4] 2> 1138849 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1138849 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1138849 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1138850 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@dbbd0ac{/solr,null,AVAILABLE}
[junit4] 2> 1138850 WARN (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@f74b8be[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1138850 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@41faa8f9{SSL,[ssl, http/1.1]}{127.0.0.1:42877}
[junit4] 2> 1138850 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.e.j.s.Server Started @1138880ms
[junit4] 2> 1138850 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42877}
[junit4] 2> 1138851 ERROR (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1138851 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1138851 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.2.0
[junit4] 2> 1138851 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1138851 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1138851 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-09T18:44:48.385Z
[junit4] 2> 1138852 INFO (zkConnectionManagerCallback-10224-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1138852 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1138855 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1138855 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1138856 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@24f9fd8c, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1138942 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1138942 WARN (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1138943 WARN (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@7beb126c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1138944 WARN (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1138944 WARN (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@a3b4090[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1138945 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36727/solr
[junit4] 2> 1138946 INFO (zkConnectionManagerCallback-10231-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1138947 INFO (zkConnectionManagerCallback-10233-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1138949 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [n:127.0.0.1:42877_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1138950 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [n:127.0.0.1:42877_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:42877_solr as DOWN
[junit4] 2> 1138950 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [n:127.0.0.1:42877_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1138950 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [n:127.0.0.1:42877_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42877_solr
[junit4] 2> 1138950 INFO (zkCallback-10199-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1138950 INFO (zkCallback-10192-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1138950 INFO (zkCallback-10208-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1138950 INFO (zkCallback-10232-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1138951 INFO (zkCallback-10215-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1138951 INFO (zkCallback-10221-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1138951 INFO (zkConnectionManagerCallback-10240-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1138952 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [n:127.0.0.1:42877_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1138952 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [n:127.0.0.1:42877_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36727/solr ready
[junit4] 2> 1138961 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [n:127.0.0.1:42877_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1138971 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [n:127.0.0.1:42877_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@24f9fd8c
[junit4] 2> 1138977 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [n:127.0.0.1:42877_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@24f9fd8c
[junit4] 2> 1138977 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [n:127.0.0.1:42877_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@24f9fd8c
[junit4] 2> 1138978 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [n:127.0.0.1:42877_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node3/.
[junit4] 2> 1139000 WARN (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1139000 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1139000 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1139007 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_201-b09
[junit4] 2> 1139009 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1139009 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1139009 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1139010 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1f31cf8e{/solr,null,AVAILABLE}
[junit4] 2> 1139010 WARN (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@77f2006f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1139010 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@52c138a4{SSL,[ssl, http/1.1]}{127.0.0.1:45919}
[junit4] 2> 1139010 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.e.j.s.Server Started @1139040ms
[junit4] 2> 1139010 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45919}
[junit4] 2> 1139010 ERROR (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1139010 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1139010 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.2.0
[junit4] 2> 1139010 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1139010 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1139010 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-09T18:44:48.544Z
[junit4] 2> 1139011 INFO (zkConnectionManagerCallback-10243-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1139012 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1139014 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1139014 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1139014 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@24f9fd8c, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1139049 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1139050 WARN (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1139050 WARN (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@412746a5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1139052 WARN (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1139052 WARN (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@7adbe2b0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1139053 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36727/solr
[junit4] 2> 1139054 INFO (zkConnectionManagerCallback-10250-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1139055 INFO (zkConnectionManagerCallback-10252-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1139057 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1139058 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:45919_solr as DOWN
[junit4] 2> 1139058 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1139058 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45919_solr
[junit4] 2> 1139058 INFO (zkCallback-10215-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1139059 INFO (zkCallback-10192-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1139059 INFO (zkCallback-10221-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1139059 INFO (zkCallback-10208-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1139059 INFO (zkCallback-10199-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1139059 INFO (zkCallback-10251-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1139061 INFO (zkCallback-10232-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1139061 INFO (zkCallback-10239-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1139062 INFO (zkConnectionManagerCallback-10259-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1139062 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1139063 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36727/solr ready
[junit4] 2> 1139077 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1139089 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@24f9fd8c
[junit4] 2> 1139095 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@24f9fd8c
[junit4] 2> 1139095 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@24f9fd8c
[junit4] 2> 1139096 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node4/.
[junit4] 2> 1139160 INFO (qtp1524654658-26843) [n:127.0.0.1:35695_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.jvm:system.properties:zone} status=0 QTime=0
[junit4] 2> 1139161 INFO (qtp1524654658-26845) [n:127.0.0.1:35695_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1139170 INFO (qtp1841055002-26842) [n:127.0.0.1:38597_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.jvm:system.properties:zone} status=0 QTime=0
[junit4] 2> 1139171 INFO (qtp1841055002-26844) [n:127.0.0.1:38597_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1139178 INFO (qtp1512564958-26912) [n:127.0.0.1:42877_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.jvm:system.properties:zone} status=0 QTime=0
[junit4] 2> 1139180 INFO (qtp1512564958-26913) [n:127.0.0.1:42877_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1139189 INFO (qtp1678990264-26947) [n:127.0.0.1:45919_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.jvm:system.properties:zone} status=0 QTime=0
[junit4] 2> 1139190 INFO (qtp1678990264-26948) [n:127.0.0.1:45919_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1139192 INFO (qtp1524654658-26841) [n:127.0.0.1:35695_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=44
[junit4] 2> 1139193 INFO (qtp1524654658-26843) [n:127.0.0.1:35695_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&name=coll&nrtReplicas=2&action=CREATE&numShards=2&tlogReplicas=0&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1139193 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection coll
[junit4] 2> 1139194 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/coll.AUTOCREATED/protwords.txt
[junit4] 2> 1139194 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/coll.AUTOCREATED/managed-schema
[junit4] 2> 1139195 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/coll.AUTOCREATED/solrconfig.xml
[junit4] 2> 1139195 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/coll.AUTOCREATED/synonyms.txt
[junit4] 2> 1139196 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/coll.AUTOCREATED/stopwords.txt
[junit4] 2> 1139196 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/coll.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 1139197 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/coll.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 1139197 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/coll.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 1139198 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/coll.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 1139198 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/coll.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 1139198 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/coll.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 1139199 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/coll.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 1139199 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/coll.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 1139200 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/coll.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 1139200 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/coll.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 1139200 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/coll.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 1139201 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/coll.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 1139201 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/coll.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 1139202 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/coll.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 1139202 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/coll.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 1139202 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/coll.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 1139203 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/coll.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 1139203 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/coll.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 1139203 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/coll.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 1139204 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/coll.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 1139204 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/coll.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 1139205 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/coll.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 1139205 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/coll.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 1139205 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/coll.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 1139206 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/coll.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 1139206 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/coll.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 1139207 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/coll.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 1139207 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/coll.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 1139207 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/coll.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 1139208 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/coll.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 1139208 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/coll.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 1139209 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/coll.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 1139209 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/coll.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 1139209 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/coll.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 1139210 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/coll.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 1139210 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/coll.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 1139211 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/coll.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 1139211 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/coll.AUTOCREATED/lang/stopwords_gl.txt
[junit4] 2> 1139211 INFO (OverseerThreadFactory-8322-thread-1-processing-n:127.0.0.1:38597_solr) [n:127.0.0.1:38597_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/params.json to /configs/coll.AUTOCREATED/params.json
[junit4] 2> 1139321 INFO (qtp1678990264-26948) [n:127.0.0.1:45919_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.jvm:system.properties:zone} status=0 QTime=0
[junit4] 2> 1139322 INFO (qtp1678990264-26945) [n:127.0.0.1:45919_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1139324 INFO (qtp1841055002-26844) [n:127.0.0.1:38597_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.jvm:system.properties:zone} status=0 QTime=0
[junit4] 2> 1139324 INFO (qtp1841055002-26846) [n:127.0.0.1:38597_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1139326 INFO (qtp1524654658-26845) [n:127.0.0.1:35695_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.jvm:system.properties:zone} status=0 QTime=0
[junit4] 2> 1139327 INFO (qtp1524654658-26841) [n:127.0.0.1:35695_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1139329 INFO (qtp1512564958-26913) [n:127.0.0.1:42877_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.jvm:system.properties:zone} status=0 QTime=0
[junit4] 2> 1139330 INFO (qtp1512564958-26914) [n:127.0.0.1:42877_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1139349 INFO (OverseerStateUpdate-72222929786634246-127.0.0.1:38597_solr-n_0000000000) [n:127.0.0.1:38597_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"coll",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"coll_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:42877/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1139350 INFO (OverseerStateUpdate-72222929786634246-127.0.0.1:38597_solr-n_0000000000) [n:127.0.0.1:38597_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"coll",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"coll_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:35695/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1139358 INFO (OverseerStateUpdate-72222929786634246-127.0.0.1:38597_solr-n_0000000000) [n:127.0.0.1:38597_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"coll",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"coll_shard2_replica_n5",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:38597/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1139358 INFO (OverseerStateUpdate-72222929786634246-127.0.0.1:38597_solr-n_0000000000) [n:127.0.0.1:38597_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"coll",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"coll_shard2_replica_n6",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:45919/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1139555 INFO (qtp1524654658-26845) [n:127.0.0.1:35695_solr x:coll_shard1_replica_n2] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=coll.AUTOCREATED&newCollection=true&name=coll_shard1_replica_n2&action=CREATE&numShards=2&collection=coll&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1139555 INFO (qtp1512564958-26912) [n:127.0.0.1:42877_solr x:coll_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=coll.AUTOCREATED&newCollection=true&name=coll_shard1_replica_n1&action=CREATE&numShards=2&collection=coll&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1139555 INFO (qtp1841055002-26842) [n:127.0.0.1:38597_solr x:coll_shard2_replica_n5] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node7&collection.configName=coll.AUTOCREATED&newCollection=true&name=coll_shard2_replica_n5&action=CREATE&numShards=2&collection=coll&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1139556 INFO (qtp1841055002-26842) [n:127.0.0.1:38597_solr x:coll_shard2_replica_n5] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1139557 INFO (qtp1678990264-26947) [n:127.0.0.1:45919_solr x:coll_shard2_replica_n6] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node8&collection.configName=coll.AUTOCREATED&newCollection=true&name=coll_shard2_replica_n6&action=CREATE&numShards=2&collection=coll&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1140567 INFO (qtp1841055002-26842) [n:127.0.0.1:38597_solr c:coll s:shard2 r:core_node7 x:coll_shard2_replica_n5] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 1140567 INFO (qtp1841055002-26842) [n:127.0.0.1:38597_solr c:coll s:shard2 r:core_node7 x:coll_shard2_replica_n5] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 1140568 WARN (qtp1841055002-26842) [n:127.0.0.1:38597_solr c:coll s:shard2 r:core_node7 x:coll_shard2_replica_n5] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node1/coll_shard2_replica_n5/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node1/coll_shard2_replica_n5/../../../../contrib/extraction/lib
[junit4] 2> 1140568 WARN (qtp1841055002-26842) [n:127.0.0.1:38597_solr c:coll s:shard2 r:core_node7 x:coll_shard2_replica_n5] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node1/coll_shard2_replica_n5/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node1/coll_shard2_replica_n5/../../../../dist
[junit4] 2> 1140568 INFO (qtp1512564958-26912) [n:127.0.0.1:42877_solr c:coll s:shard1 r:core_node3 x:coll_shard1_replica_n1] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 1140568 INFO (qtp1512564958-26912) [n:127.0.0.1:42877_solr c:coll s:shard1 r:core_node3 x:coll_shard1_replica_n1] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 1140568 WARN (qtp1841055002-26842) [n:127.0.0.1:38597_solr c:coll s:shard2 r:core_node7 x:coll_shard2_replica_n5] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node1/coll_shard2_replica_n5/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node1/coll_shard2_replica_n5/../../../../contrib/clustering/lib
[junit4] 2> 1140568 WARN (qtp1841055002-26842) [n:127.0.0.1:38597_solr c:coll s:shard2 r:core_node7 x:coll_shard2_replica_n5] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node1/coll_shard2_replica_n5/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node1/coll_shard2_replica_n5/../../../../dist
[junit4] 2> 1140568 WARN (qtp1841055002-26842) [n:127.0.0.1:38597_solr c:coll s:shard2 r:core_node7 x:coll_shard2_replica_n5] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node1/coll_shard2_replica_n5/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node1/coll_shard2_replica_n5/../../../../contrib/langid/lib
[junit4] 2> 1140568 WARN (qtp1841055002-26842) [n:127.0.0.1:38597_solr c:coll s:shard2 r:core_node7 x:coll_shard2_replica_n5] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node1/coll_shard2_replica_n5/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node1/coll_shard2_replica_n5/../../../../dist
[junit4] 2> 1140568 WARN (qtp1841055002-26842) [n:127.0.0.1:38597_solr c:coll s:shard2 r:core_node7 x:coll_shard2_replica_n5] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node1/coll_shard2_replica_n5/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node1/coll_shard2_replica_n5/../../../../contrib/velocity/lib
[junit4] 2> 1140568 WARN (qtp1841055002-26842) [n:127.0.0.1:38597_solr c:coll s:shard2 r:core_node7 x:coll_shard2_replica_n5] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node1/coll_shard2_replica_n5/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node1/coll_shard2_replica_n5/../../../../dist
[junit4] 2> 1140568 WARN (qtp1841055002-26842) [n:127.0.0.1:38597_solr c:coll s:shard2 r:core_node7 x:coll_shard2_replica_n5] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node1/coll_shard2_replica_n5/../../../../dist filtered by solr-ltr-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node1/coll_shard2_replica_n5/../../../../dist
[junit4] 2> 1140568 WARN (qtp1512564958-26912) [n:127.0.0.1:42877_solr c:coll s:shard1 r:core_node3 x:coll_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node3/coll_shard1_replica_n1/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node3/coll_shard1_replica_n1/../../../../contrib/extraction/lib
[junit4] 2> 1140568 WARN (qtp1512564958-26912) [n:127.0.0.1:42877_solr c:coll s:shard1 r:core_node3 x:coll_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node3/coll_shard1_replica_n1/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node3/coll_shard1_replica_n1/../../../../dist
[junit4] 2> 1140568 INFO (qtp1841055002-26842) [n:127.0.0.1:38597_solr c:coll s:shard2 r:core_node7 x:coll_shard2_replica_n5] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 1140568 WARN (qtp1512564958-26912) [n:127.0.0.1:42877_solr c:coll s:shard1 r:core_node3 x:coll_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node3/coll_shard1_replica_n1/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node3/coll_shard1_replica_n1/../../../../contrib/clustering/lib
[junit4] 2> 1140568 WARN (qtp1512564958-26912) [n:127.0.0.1:42877_solr c:coll s:shard1 r:core_node3 x:coll_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node3/coll_shard1_replica_n1/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node3/coll_shard1_replica_n1/../../../../dist
[junit4] 2> 1140569 WARN (qtp1512564958-26912) [n:127.0.0.1:42877_solr c:coll s:shard1 r:core_node3 x:coll_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node3/coll_shard1_replica_n1/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node3/coll_shard1_replica_n1/../../../../contrib/langid/lib
[junit4] 2> 1140569 WARN (qtp1512564958-26912) [n:127.0.0.1:42877_solr c:coll s:shard1 r:core_node3 x:coll_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node3/coll_shard1_replica_n1/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node3/coll_shard1_replica_n1/../../../../dist
[junit4] 2> 1140569 WARN (qtp1512564958-26912) [n:127.0.0.1:42877_solr c:coll s:shard1 r:core_node3 x:coll_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node3/coll_shard1_replica_n1/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.RoutingToNodesWithPropertiesTest_13525A4073A0EB3F-001/tempDir-001/node3/coll_shard1_replica_n1/../../../../contrib/velocity/lib
[junit4] 2
[...truncated too long message...]
ser-706-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7d4eda58: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@e3c6f55
[junit4] 2> 108374 INFO (jetty-closer-706-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 108379 INFO (closeThreadPool-722-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72223060926136328-127.0.0.1:43591_solr-n_0000000000) closing
[junit4] 2> 108379 INFO (OverseerStateUpdate-72223060926136328-127.0.0.1:43591_solr-n_0000000000) [n:127.0.0.1:43591_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:43591_solr
[junit4] 2> 108380 INFO (OverseerAutoScalingTriggerThread-72223060926136328-127.0.0.1:43591_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 108382 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x100967dc318000e, likely client has closed socket
[junit4] 2> 108384 INFO (jetty-closer-706-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72223060926136328-127.0.0.1:43591_solr-n_0000000000) closing
[junit4] 2> 108384 INFO (closeThreadPool-722-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72223060926136328-127.0.0.1:43591_solr-n_0000000000) closing
[junit4] 2> 108384 INFO (jetty-closer-706-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@7c3c722e{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 108384 INFO (jetty-closer-706-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7c404357{/solr,null,UNAVAILABLE}
[junit4] 2> 108386 INFO (jetty-closer-706-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 108390 INFO (jetty-closer-706-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@255be868{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 108390 INFO (jetty-closer-706-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@c52af4{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 108391 INFO (jetty-closer-706-thread-4) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@657ea405{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 108391 INFO (jetty-closer-706-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1a5dc0df{/solr,null,UNAVAILABLE}
[junit4] 2> 108391 INFO (jetty-closer-706-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3afb9989{/solr,null,UNAVAILABLE}
[junit4] 2> 108391 INFO (jetty-closer-706-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 108391 INFO (jetty-closer-706-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6b0ab98b{/solr,null,UNAVAILABLE}
[junit4] 2> 108391 INFO (jetty-closer-706-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 108391 INFO (jetty-closer-706-thread-4) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 108394 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 108395 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> 9 /solr/aliases.json
[junit4] 2> 5 /solr/collections/coll/terms/shard1
[junit4] 2> 5 /solr/collections/coll/terms/shard2
[junit4] 2> 4 /solr/configs/coll.AUTOCREATED/managed-schema
[junit4] 2> 4 /solr/security.json
[junit4] 2> 4 /solr/configs/coll.AUTOCREATED
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 15 /solr/collections/coll/state.json
[junit4] 2> 9 /solr/clusterstate.json
[junit4] 2> 9 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 9 /solr/live_nodes
[junit4] 2> 9 /solr/collections
[junit4] 2>
[junit4] 2> 108397 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:37279
[junit4] 2> 108397 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 37279
[junit4] 2> 108397 INFO (SUITE-RoutingToNodesWithPropertiesTest-seed#[13525A4073A0EB3F]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
[junit4] 2> May 09, 2019 7:18:13 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=CheapBastard, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@6c05fcb2), locale=sr-Latn-BA, timezone=US/Eastern
[junit4] 2> NOTE: Linux 4.18.0-17-generic amd64/Oracle Corporation 1.8.0_201 (64-bit)/cpus=8,threads=1,free=204274536,total=522190848
[junit4] 2> NOTE: All tests run in this JVM: [RoutingToNodesWithPropertiesTest, RoutingToNodesWithPropertiesTest, RoutingToNodesWithPropertiesTest, PeerSyncReplicationTest, RoutingToNodesWithPropertiesTest, RoutingToNodesWithPropertiesTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=RoutingToNodesWithPropertiesTest -Dtests.seed=13525A4073A0EB3F -Dtests.multiplier=3 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=sr-Latn-BA -Dtests.timezone=US/Eastern -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J2 | RoutingToNodesWithPropertiesTest (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<us-west1> but was:<null>
[junit4] > at __randomizedtesting.SeedInfo.seed([13525A4073A0EB3F]:0)
[junit4] > at org.apache.solr.cloud.RoutingToNodesWithPropertiesTest.setupCluster(RoutingToNodesWithPropertiesTest.java:115)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] Completed [8/10 (4!)] on J2 in 4.76s, 0 tests, 1 failure <<< FAILURES!
[junit4]
[junit4] HEARTBEAT J1 PID(24878@serv1.sd-datasolutions.de): 2019-05-09T19:18:48, stalled for 61.1s at: PeerSyncReplicationTest.test
[junit4] HEARTBEAT J0 PID(24880@serv1.sd-datasolutions.de): 2019-05-09T19:18:48, stalled for 62.1s at: PeerSyncReplicationTest.test
[junit4] Suite: org.apache.solr.cloud.PeerSyncReplicationTest
[junit4] OK 63.0s J1 | PeerSyncReplicationTest.test
[junit4] Completed [9/10 (4!)] on J1 in 63.86s, 1 test
[junit4]
[junit4] Suite: org.apache.solr.cloud.PeerSyncReplicationTest
[junit4] OK 64.2s J0 | PeerSyncReplicationTest.test
[junit4] Completed [10/10 (4!)] on J0 in 64.84s, 1 test
[junit4]
[junit4]
[junit4] Tests with failures [seed: 13525A4073A0EB3F]:
[junit4] - org.apache.solr.cloud.RoutingToNodesWithPropertiesTest (suite)
[junit4] - org.apache.solr.cloud.RoutingToNodesWithPropertiesTest (suite)
[junit4] - org.apache.solr.cloud.RoutingToNodesWithPropertiesTest (suite)
[junit4] - org.apache.solr.cloud.RoutingToNodesWithPropertiesTest (suite)
[junit4]
[junit4]
[junit4] JVM J0: 0.67 .. 147.14 = 146.47s
[junit4] JVM J1: 0.67 .. 147.13 = 146.46s
[junit4] JVM J2: 0.68 .. 110.10 = 109.42s
[junit4] Execution time total: 2 minutes 27 seconds
[junit4] Tests summary: 10 suites, 6 tests, 4 suite-level errors
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-8.x-Linux/lucene/common-build.xml:1573: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-8.x-Linux/lucene/common-build.xml:1100: There were test failures: 10 suites, 6 tests, 4 suite-level errors [seed: 13525A4073A0EB3F]
Total time: 2 minutes 29 seconds
[repro] Setting last failure code to 256
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.cloud.PeerSyncReplicationTest
[repro] 4/5 failed: org.apache.solr.cloud.RoutingToNodesWithPropertiesTest
[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=726fb8facc15653fc3358521d226f10bd7dfff9c, workspace=/home/jenkins/workspace/Lucene-Solr-8.x-Linux
[WARNINGS] Computing warning deltas based on reference build #535
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