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/04/07 08:10:23 UTC
[JENKINS-EA] Lucene-Solr-8.x-Linux
(64bit/jdk-13-ea+shipilev-fastdebug) - Build # 359 - Failure!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Linux/359/
Java: 64bit/jdk-13-ea+shipilev-fastdebug -XX:-UseCompressedOops -XX:+UseParallelGC
2 tests failed.
FAILED: org.apache.solr.cloud.BasicDistributedZkTest.test
Error Message:
Could not load collection from ZK: multiunload2
Stack Trace:
org.apache.solr.common.SolrException: Could not load collection from ZK: multiunload2
at org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1366)
at org.apache.solr.common.cloud.ZkStateReader$LazyCollectionRef.get(ZkStateReader.java:748)
at org.apache.solr.common.cloud.ClusterState$CollectionRef.get(ClusterState.java:386)
at org.apache.solr.common.cloud.ZkStateReader.forceUpdateCollection(ZkStateReader.java:400)
at org.apache.solr.cloud.BasicDistributedZkTest.testStopAndStartCoresInOneInstance(BasicDistributedZkTest.java:624)
at org.apache.solr.cloud.BasicDistributedZkTest.test(BasicDistributedZkTest.java:427)
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)
Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/multiunload2/state.json
at org.apache.zookeeper.KeeperException.create(KeeperException.java:130)
at org.apache.zookeeper.KeeperException.create(KeeperException.java:54)
at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1221)
at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:358)
at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71)
at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:358)
at org.apache.solr.common.cloud.ZkStateReader.fetchCollectionState(ZkStateReader.java:1378)
at org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1364)
... 45 more
FAILED: org.apache.solr.cloud.ReplicationFactorTest.test
Error Message:
Didn't see all replicas for shard shard1 in repfacttest_c8n_1x3 come up within 30000 ms! ClusterState: { "control_collection":{ "pullReplicas":"0", "replicationFactor":"1", "shards":{"shard1":{ "range":"80000000-7fffffff", "state":"active", "replicas":{"core_node2":{ "core":"control_collection_shard1_replica_n1", "base_url":"http://127.0.0.1:41455", "node_name":"127.0.0.1:41455_", "state":"active", "type":"NRT", "leader":"true"}}}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"1", "autoAddReplicas":"false", "nrtReplicas":"1", "tlogReplicas":"0"}, "repfacttest_c8n_1x3":{ "pullReplicas":"0", "replicationFactor":"3", "shards":{"shard1":{ "range":"80000000-7fffffff", "state":"active", "replicas":{ "core_node4":{ "core":"repfacttest_c8n_1x3_shard1_replica_n2", "base_url":"http://127.0.0.1:41455", "node_name":"127.0.0.1:41455_", "state":"recovering", "type":"NRT"}, "core_node5":{ "core":"repfacttest_c8n_1x3_shard1_replica_n1", "base_url":"http://127.0.0.1:38261", "node_name":"127.0.0.1:38261_", "state":"active", "type":"NRT", "leader":"true"}, "core_node6":{ "core":"repfacttest_c8n_1x3_shard1_replica_n3", "base_url":"http://127.0.0.1:36075", "node_name":"127.0.0.1:36075_", "state":"recovering", "type":"NRT"}}}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"1", "autoAddReplicas":"false", "nrtReplicas":"3", "tlogReplicas":"0"}, "collection1":{ "pullReplicas":"0", "replicationFactor":"1", "shards":{ "shard1":{ "range":"80000000-d554ffff", "state":"active", "replicas":{"core_node5":{ "core":"collection1_shard1_replica_n2", "base_url":"http://127.0.0.1:38261", "node_name":"127.0.0.1:38261_", "state":"active", "type":"NRT", "leader":"true"}}}, "shard2":{ "range":"d5550000-2aa9ffff", "state":"active", "replicas":{"core_node4":{ "core":"collection1_shard2_replica_n1", "base_url":"http://127.0.0.1:36075", "node_name":"127.0.0.1:36075_", "state":"active", "type":"NRT", "leader":"true"}}}, "shard3":{ "range":"2aaa0000-7fffffff", "state":"active", "replicas":{"core_node6":{ "core":"collection1_shard3_replica_n3", "base_url":"http://127.0.0.1:42387", "node_name":"127.0.0.1:42387_", "state":"active", "type":"NRT", "leader":"true"}}}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"1", "autoAddReplicas":"false", "nrtReplicas":"1", "tlogReplicas":"0"}}
Stack Trace:
java.lang.AssertionError: Didn't see all replicas for shard shard1 in repfacttest_c8n_1x3 come up within 30000 ms! ClusterState: {
"control_collection":{
"pullReplicas":"0",
"replicationFactor":"1",
"shards":{"shard1":{
"range":"80000000-7fffffff",
"state":"active",
"replicas":{"core_node2":{
"core":"control_collection_shard1_replica_n1",
"base_url":"http://127.0.0.1:41455",
"node_name":"127.0.0.1:41455_",
"state":"active",
"type":"NRT",
"leader":"true"}}}},
"router":{"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"false",
"nrtReplicas":"1",
"tlogReplicas":"0"},
"repfacttest_c8n_1x3":{
"pullReplicas":"0",
"replicationFactor":"3",
"shards":{"shard1":{
"range":"80000000-7fffffff",
"state":"active",
"replicas":{
"core_node4":{
"core":"repfacttest_c8n_1x3_shard1_replica_n2",
"base_url":"http://127.0.0.1:41455",
"node_name":"127.0.0.1:41455_",
"state":"recovering",
"type":"NRT"},
"core_node5":{
"core":"repfacttest_c8n_1x3_shard1_replica_n1",
"base_url":"http://127.0.0.1:38261",
"node_name":"127.0.0.1:38261_",
"state":"active",
"type":"NRT",
"leader":"true"},
"core_node6":{
"core":"repfacttest_c8n_1x3_shard1_replica_n3",
"base_url":"http://127.0.0.1:36075",
"node_name":"127.0.0.1:36075_",
"state":"recovering",
"type":"NRT"}}}},
"router":{"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"false",
"nrtReplicas":"3",
"tlogReplicas":"0"},
"collection1":{
"pullReplicas":"0",
"replicationFactor":"1",
"shards":{
"shard1":{
"range":"80000000-d554ffff",
"state":"active",
"replicas":{"core_node5":{
"core":"collection1_shard1_replica_n2",
"base_url":"http://127.0.0.1:38261",
"node_name":"127.0.0.1:38261_",
"state":"active",
"type":"NRT",
"leader":"true"}}},
"shard2":{
"range":"d5550000-2aa9ffff",
"state":"active",
"replicas":{"core_node4":{
"core":"collection1_shard2_replica_n1",
"base_url":"http://127.0.0.1:36075",
"node_name":"127.0.0.1:36075_",
"state":"active",
"type":"NRT",
"leader":"true"}}},
"shard3":{
"range":"2aaa0000-7fffffff",
"state":"active",
"replicas":{"core_node6":{
"core":"collection1_shard3_replica_n3",
"base_url":"http://127.0.0.1:42387",
"node_name":"127.0.0.1:42387_",
"state":"active",
"type":"NRT",
"leader":"true"}}}},
"router":{"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"false",
"nrtReplicas":"1",
"tlogReplicas":"0"}}
at __randomizedtesting.SeedInfo.seed([2671E966BEBE7DB5:AE25D6BC1042104D]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.ensureAllReplicasAreActive(AbstractFullDistribZkTestBase.java:2130)
at org.apache.solr.cloud.ReplicationFactorTest.testRf3(ReplicationFactorTest.java:320)
at org.apache.solr.cloud.ReplicationFactorTest.test(ReplicationFactorTest.java:87)
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)
Build Log:
[...truncated 14421 lines...]
[junit4] Suite: org.apache.solr.cloud.ReplicationFactorTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ReplicationFactorTest_2671E966BEBE7DB5-001/init-core-data-001
[junit4] 2> 2632213 WARN (SUITE-ReplicationFactorTest-seed#[2671E966BEBE7DB5]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=10 numCloses=10
[junit4] 2> 2632214 INFO (SUITE-ReplicationFactorTest-seed#[2671E966BEBE7DB5]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 2632215 INFO (SUITE-ReplicationFactorTest-seed#[2671E966BEBE7DB5]-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> 2632215 INFO (SUITE-ReplicationFactorTest-seed#[2671E966BEBE7DB5]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 2632215 INFO (SUITE-ReplicationFactorTest-seed#[2671E966BEBE7DB5]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 2632219 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2632229 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2632229 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2632329 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.a.s.c.ZkTestServer start zk server on port:37351
[junit4] 2> 2632329 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:37351
[junit4] 2> 2632329 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 37351
[junit4] 2> 2632399 INFO (zkConnectionManagerCallback-12698-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2632451 INFO (zkConnectionManagerCallback-12700-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2632453 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] 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> 2632454 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 2632455 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] 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> 2632456 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] 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> 2632456 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] 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> 2632457 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] 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> 2632458 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] 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> 2632459 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] 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> 2632459 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] 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> 2632460 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] 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> 2632461 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] 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> 2632462 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise
[junit4] 2> 2632812 WARN (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2632812 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 2632812 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2632812 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 13-testing+0-builds.shipilev.net-openjdk-jdk-b769-20190316-jdk-1312
[junit4] 2> 2632819 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2632819 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2632819 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2632820 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@131df35e{/,null,AVAILABLE}
[junit4] 2> 2632853 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1c6a6a7{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:37957}
[junit4] 2> 2632853 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.e.j.s.Server Started @2633153ms
[junit4] 2> 2632853 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] 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.ReplicationFactorTest_2671E966BEBE7DB5-001/tempDir-001/control/data, hostPort=41455, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ReplicationFactorTest_2671E966BEBE7DB5-001/control-001/cores, replicaType=NRT}
[junit4] 2> 2632853 ERROR (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2632853 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2632853 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.1.0
[junit4] 2> 2632853 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2632854 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2632854 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-07T07:23:32.589028Z
[junit4] 2> 2632856 INFO (zkConnectionManagerCallback-12702-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2632857 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 2632857 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] 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.ReplicationFactorTest_2671E966BEBE7DB5-001/control-001/solr.xml
[junit4] 2> 2632857 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 0x100fa1fd22c0002, likely client has closed socket
[junit4] 2> 2632861 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2632861 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2632863 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@470b099b, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2633014 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2633015 WARN (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@5af3cc34[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2633018 WARN (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1ff31d85[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2633019 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37351/solr
[junit4] 2> 2633020 INFO (zkConnectionManagerCallback-12709-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2633024 INFO (zkConnectionManagerCallback-12711-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2633104 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [n:127.0.0.1:41455_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:41455_
[junit4] 2> 2633105 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [n:127.0.0.1:41455_ ] o.a.s.c.Overseer Overseer (id=72332608614957060-127.0.0.1:41455_-n_0000000000) starting
[junit4] 2> 2633168 INFO (zkConnectionManagerCallback-12718-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2633170 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [n:127.0.0.1:41455_ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37351/solr ready
[junit4] 2> 2633184 INFO (OverseerStateUpdate-72332608614957060-127.0.0.1:41455_-n_0000000000) [n:127.0.0.1:41455_ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:41455_
[junit4] 2> 2633205 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [n:127.0.0.1:41455_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41455_
[junit4] 2> 2633221 INFO (zkCallback-12717-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2633236 INFO (zkCallback-12710-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2633259 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [n:127.0.0.1:41455_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2633292 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [n:127.0.0.1:41455_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@470b099b
[junit4] 2> 2633324 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [n:127.0.0.1:41455_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@470b099b
[junit4] 2> 2633324 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [n:127.0.0.1:41455_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@470b099b
[junit4] 2> 2633327 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [n:127.0.0.1:41455_ ] 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.ReplicationFactorTest_2671E966BEBE7DB5-001/control-001/cores
[junit4] 2> 2633553 INFO (zkConnectionManagerCallback-12724-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2633555 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2633556 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37351/solr ready
[junit4] 2> 2633556 INFO (SocketProxy-Acceptor-41455) [ ] o.a.s.c.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=32996,localport=41455], receiveBufferSize:531000
[junit4] 2> 2633590 INFO (qtp980238489-27194) [n:127.0.0.1:41455_ ] 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:41455_&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2633616 INFO (SocketProxy-Acceptor-41455) [ ] o.a.s.c.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=37957,localport=55050], receiveBufferSize=530904
[junit4] 2> 2633627 INFO (OverseerThreadFactory-8541-thread-1-processing-n:127.0.0.1:41455_) [n:127.0.0.1:41455_ ] o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 2633746 INFO (SocketProxy-Acceptor-41455) [ ] o.a.s.c.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=33000,localport=41455], receiveBufferSize:531000
[junit4] 2> 2633759 INFO (qtp980238489-27193) [n:127.0.0.1:41455_ 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> 2633759 INFO (qtp980238489-27193) [n:127.0.0.1:41455_ x:control_collection_shard1_replica_n1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2633763 INFO (SocketProxy-Acceptor-41455) [ ] o.a.s.c.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=37957,localport=55054], receiveBufferSize=530904
[junit4] 2> 2634864 INFO (qtp980238489-27193) [n:127.0.0.1:41455_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 2634881 INFO (qtp980238489-27193) [n:127.0.0.1:41455_ 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> 2635081 INFO (qtp980238489-27193) [n:127.0.0.1:41455_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2635099 INFO (qtp980238489-27193) [n:127.0.0.1:41455_ 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> 2635099 INFO (qtp980238489-27193) [n:127.0.0.1:41455_ 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@470b099b
[junit4] 2> 2635099 INFO (qtp980238489-27193) [n:127.0.0.1:41455_ 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.ReplicationFactorTest_2671E966BEBE7DB5-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.ReplicationFactorTest_2671E966BEBE7DB5-001/control-001/cores/control_collection_shard1_replica_n1/data/]
[junit4] 2> 2635105 INFO (qtp980238489-27193) [n:127.0.0.1:41455_ 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=41, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=2.32421875, floorSegmentMB=1.421875, forceMergeDeletesPctAllowed=10.776299426127025, segmentsPerTier=44.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=35.434843989160875
[junit4] 2> 2635184 WARN (qtp980238489-27193) [n:127.0.0.1:41455_ 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> 2635280 INFO (qtp980238489-27193) [n:127.0.0.1:41455_ 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> 2635280 INFO (qtp980238489-27193) [n:127.0.0.1:41455_ 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> 2635284 INFO (qtp980238489-27193) [n:127.0.0.1:41455_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2635284 INFO (qtp980238489-27193) [n:127.0.0.1:41455_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2635287 INFO (qtp980238489-27193) [n:127.0.0.1:41455_ 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=43, maxMergedSegmentMB=66.6328125, floorSegmentMB=0.2216796875, forceMergeDeletesPctAllowed=28.1114669609539, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4749986506016355, deletesPctAllowed=49.79512058453382
[junit4] 2> 2635288 INFO (qtp980238489-27193) [n:127.0.0.1:41455_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@674a4158[control_collection_shard1_replica_n1] main]
[junit4] 2> 2635291 INFO (qtp980238489-27193) [n:127.0.0.1:41455_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 2635291 INFO (qtp980238489-27193) [n:127.0.0.1:41455_ 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> 2635292 INFO (qtp980238489-27193) [n:127.0.0.1:41455_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2635292 INFO (qtp980238489-27193) [n:127.0.0.1:41455_ 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 1630139124313751552
[junit4] 2> 2635296 INFO (searcherExecutor-8546-thread-1-processing-n:127.0.0.1:41455_ x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:41455_ 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@674a4158[control_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2635302 INFO (qtp980238489-27193) [n:127.0.0.1:41455_ 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> 2635302 INFO (qtp980238489-27193) [n:127.0.0.1:41455_ 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> 2635305 INFO (qtp980238489-27193) [n:127.0.0.1:41455_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2635305 INFO (qtp980238489-27193) [n:127.0.0.1:41455_ 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> 2635305 INFO (qtp980238489-27193) [n:127.0.0.1:41455_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:41455/control_collection_shard1_replica_n1/
[junit4] 2> 2635305 INFO (qtp980238489-27193) [n:127.0.0.1:41455_ 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> 2635305 INFO (qtp980238489-27193) [n:127.0.0.1:41455_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:41455/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 2635305 INFO (qtp980238489-27193) [n:127.0.0.1:41455_ 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/72332608614957060-core_node2-n_0000000000
[junit4] 2> 2635306 INFO (qtp980238489-27193) [n:127.0.0.1:41455_ 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:41455/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 2635409 INFO (zkCallback-12710-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> 2635427 INFO (zkCallback-12710-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> 2635440 INFO (qtp980238489-27193) [n:127.0.0.1:41455_ 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> 2635471 INFO (qtp980238489-27193) [n:127.0.0.1:41455_ 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=1712
[junit4] 2> 2635473 INFO (qtp980238489-27194) [n:127.0.0.1:41455_ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 2635541 INFO (zkCallback-12710-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> 2635542 INFO (zkCallback-12710-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> 2635542 INFO (qtp980238489-27194) [n:127.0.0.1:41455_ ] 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:41455_&wt=javabin&version=2} status=0 QTime=1952
[junit4] 2> 2635555 INFO (zkCallback-12710-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> 2635556 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 0x100fa1fd22c0006, likely client has closed socket
[junit4] 2> 2635629 INFO (OverseerCollectionConfigSetProcessor-72332608614957060-127.0.0.1:41455_-n_0000000000) [n:127.0.0.1:41455_ ] 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> 2635656 INFO (zkConnectionManagerCallback-12730-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2635657 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2635658 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37351/solr ready
[junit4] 2> 2635658 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 2635659 INFO (SocketProxy-Acceptor-41455) [ ] o.a.s.c.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=33006,localport=41455], receiveBufferSize:531000
[junit4] 2> 2635676 INFO (qtp980238489-27195) [n:127.0.0.1:41455_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=3&createNodeSet=&stateFormat=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2635696 INFO (SocketProxy-Acceptor-41455) [ ] o.a.s.c.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=37957,localport=55060], receiveBufferSize=530904
[junit4] 2> 2635718 INFO (OverseerThreadFactory-8541-thread-2-processing-n:127.0.0.1:41455_) [n:127.0.0.1:41455_ ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 2635921 WARN (OverseerThreadFactory-8541-thread-2-processing-n:127.0.0.1:41455_) [n:127.0.0.1:41455_ ] o.a.s.c.a.c.CreateCollectionCmd It is unusual to create a collection (collection1) without cores.
[junit4] 2> 2635933 INFO (qtp980238489-27195) [n:127.0.0.1:41455_ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 2635933 INFO (qtp980238489-27195) [n:127.0.0.1:41455_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=3&createNodeSet=&stateFormat=2&wt=javabin&version=2} status=0 QTime=257
[junit4] 2> 2635943 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating jetty instances pullReplicaCount=0 numOtherReplicas=3
[junit4] 2> 2636256 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] 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.ReplicationFactorTest_2671E966BEBE7DB5-001/shard-1-001 of type NRT
[junit4] 2> 2636262 WARN (closeThreadPool-12731-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2636262 INFO (closeThreadPool-12731-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 2636262 INFO (closeThreadPool-12731-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2636262 INFO (closeThreadPool-12731-thread-1) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 13-testing+0-builds.shipilev.net-openjdk-jdk-b769-20190316-jdk-1312
[junit4] 2> 2636409 INFO (closeThreadPool-12731-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2636409 INFO (closeThreadPool-12731-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2636409 INFO (closeThreadPool-12731-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2636418 INFO (closeThreadPool-12731-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@21222a83{/,null,AVAILABLE}
[junit4] 2> 2636418 INFO (closeThreadPool-12731-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@317348d6{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:33183}
[junit4] 2> 2636418 INFO (closeThreadPool-12731-thread-1) [ ] o.e.j.s.Server Started @2636718ms
[junit4] 2> 2636418 INFO (closeThreadPool-12731-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.ReplicationFactorTest_2671E966BEBE7DB5-001/tempDir-001/jetty1, hostPort=36075, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ReplicationFactorTest_2671E966BEBE7DB5-001/shard-1-001/cores}
[junit4] 2> 2636419 ERROR (closeThreadPool-12731-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2636419 INFO (closeThreadPool-12731-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2636419 INFO (closeThreadPool-12731-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.1.0
[junit4] 2> 2636419 INFO (closeThreadPool-12731-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2636419 INFO (closeThreadPool-12731-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2636419 INFO (closeThreadPool-12731-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-07T07:23:36.154382Z
[junit4] 2> 2636480 INFO (zkConnectionManagerCallback-12733-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2636484 INFO (closeThreadPool-12731-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 2636484 INFO (closeThreadPool-12731-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.ReplicationFactorTest_2671E966BEBE7DB5-001/shard-1-001/solr.xml
[junit4] 2> 2636488 INFO (closeThreadPool-12731-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2636488 INFO (closeThreadPool-12731-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2636489 INFO (closeThreadPool-12731-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@470b099b, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2636719 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] 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.ReplicationFactorTest_2671E966BEBE7DB5-001/shard-2-001 of type NRT
[junit4] 2> 2636734 WARN (closeThreadPool-12731-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2636734 INFO (closeThreadPool-12731-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 2636734 INFO (closeThreadPool-12731-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2636735 INFO (closeThreadPool-12731-thread-2) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 13-testing+0-builds.shipilev.net-openjdk-jdk-b769-20190316-jdk-1312
[junit4] 2> 2636860 INFO (closeThreadPool-12731-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2636860 INFO (closeThreadPool-12731-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2636860 INFO (closeThreadPool-12731-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2636880 INFO (closeThreadPool-12731-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@17a64a09{/,null,AVAILABLE}
[junit4] 2> 2636881 INFO (closeThreadPool-12731-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@51b8c971{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:36429}
[junit4] 2> 2636881 INFO (closeThreadPool-12731-thread-2) [ ] o.e.j.s.Server Started @2637181ms
[junit4] 2> 2636881 INFO (closeThreadPool-12731-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.ReplicationFactorTest_2671E966BEBE7DB5-001/tempDir-001/jetty2, hostPort=42387, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ReplicationFactorTest_2671E966BEBE7DB5-001/shard-2-001/cores}
[junit4] 2> 2636881 ERROR (closeThreadPool-12731-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2636881 INFO (closeThreadPool-12731-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2636881 INFO (closeThreadPool-12731-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.1.0
[junit4] 2> 2636881 INFO (closeThreadPool-12731-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2636881 INFO (closeThreadPool-12731-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2636882 INFO (closeThreadPool-12731-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-07T07:23:36.617002Z
[junit4] 2> 2636916 INFO (closeThreadPool-12731-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2637048 INFO (zkConnectionManagerCallback-12736-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2637052 WARN (closeThreadPool-12731-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1be2cbdb[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2637061 INFO (closeThreadPool-12731-thread-2) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 2637061 INFO (closeThreadPool-12731-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.ReplicationFactorTest_2671E966BEBE7DB5-001/shard-2-001/solr.xml
[junit4] 2> 2637065 WARN (closeThreadPool-12731-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1b2c3f76[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2637066 INFO (closeThreadPool-12731-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37351/solr
[junit4] 2> 2637071 INFO (closeThreadPool-12731-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2637071 INFO (closeThreadPool-12731-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2637075 INFO (closeThreadPool-12731-thread-2) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@470b099b, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2637121 INFO (zkConnectionManagerCallback-12742-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2637156 INFO (zkConnectionManagerCallback-12745-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2637159 INFO (closeThreadPool-12731-thread-1) [n:127.0.0.1:36075_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2637162 INFO (closeThreadPool-12731-thread-1) [n:127.0.0.1:36075_ ] o.a.s.c.ZkController Publish node=127.0.0.1:36075_ as DOWN
[junit4] 2> 2637162 INFO (closeThreadPool-12731-thread-1) [n:127.0.0.1:36075_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2637162 INFO (closeThreadPool-12731-thread-1) [n:127.0.0.1:36075_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36075_
[junit4] 2> 2637163 INFO (zkCallback-12717-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2637163 INFO (zkCallback-12710-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2637173 INFO (zkCallback-12729-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2637183 INFO (TEST-ReplicationFactorTest.test-seed#[2671E966BEBE7DB5]) [ ] 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.ReplicationFactorTest_2671E966BEBE7DB5-001/shard-3-001 of type NRT
[junit4] 2> 2637196 INFO (zkCallback-12744-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2637202 WARN (closeThreadPool-12731-thread-3) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2637202 INFO (closeThreadPool-12731-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 2637202 INFO (closeThreadPool-12731-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2637202 INFO (closeThreadPool-12731-thread-3) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 13-testing+0-builds.shipilev.net-openjdk-jdk-b769-20190316-jdk-1312
[junit4] 2> 2637212 INFO (closeThreadPool-12731-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2637224 WARN (closeThreadPool-12731-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@19bf4df8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2637240 INFO (zkConnectionManagerCallback-12752-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2637241 INFO (closeThreadPool-12731-thread-1) [n:127.0.0.1:36075_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2637241 INFO (closeThreadPool-12731-thread-1) [n:127.0.0.1:36075_ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37351/solr ready
[junit4] 2> 2637299 INFO (closeThreadPool-12731-thread-1) [n:127.0.0.1:36075_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2637384 WARN (closeThreadPool-12731-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@27debcdc[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2637386 INFO (closeThreadPool-12731-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37351/solr
[junit4] 2> 2637391 INFO (closeThreadPool-12731-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2637392 INFO (closeThreadPool-12731-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2637392 INFO (closeThreadPool-12731-thread-3) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2637400 INFO (closeThreadPool-12731-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@33202fb7{/,null,AVAILABLE}
[junit4] 2> 2637400 INFO (closeThreadPool-12731-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@53a7ae04{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:40649}
[junit4] 2> 2637400 INFO (closeThreadPool-12731-thread-3) [ ] o.e.j.s.Server Started @2637700ms
[junit4] 2> 2637400 INFO (closeThreadPool-12731-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.ReplicationFactorTest_2671E966BEBE7DB5-001/tempDir-001/jetty3, hostPort=38261, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ReplicationFactorTest_2671E966BEBE7DB5-001/shard-3-001/cores}
[junit4] 2> 2637400 ERROR (closeThreadPool-12731-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2637400 INFO (closeThreadPool-12731-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2637400 INFO (closeThreadPool-12731-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.1.0
[junit4] 2> 2637400 INFO (closeThreadPool-12731-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2637401 INFO (closeThreadPool-12731-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2637401 INFO (closeThreadPool-12731-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-07T07:23:37.136010Z
[junit4] 2> 2637408 INFO (closeThreadPool-12731-thread-1) [n:127.0.0.1:36075_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@470b099b
[junit4] 2> 2637415 INFO (zkConnectionManagerCallback-12759-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2637416 INFO (zkConnectionManagerCallback-12761-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2637417 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 0x100fa1fd22c000d, likely client has closed socket
[junit4] 2> 2637417 INFO (closeThreadPool-12731-thread-3) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 2637417 INFO (closeThreadPool-12731-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.ReplicationFactorTest_2671E966BEBE7DB5-001/shard-3-001/solr.xml
[junit4] 2> 2637422 INFO (closeThreadPool-12731-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2637422 INFO (closeThreadPool-12731-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2637423 INFO (closeThreadPool-12731-thread-3) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@470b099b, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2637437 INFO (closeThreadPool-12731-thread-1) [n:127.0.0.1:36075_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@470b099b
[junit4] 2> 2637437 INFO (closeThreadPool-12731-thread-1) [n:127.0.0.1:36075_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@470b099b
[junit4] 2> 2637440 INFO (closeThreadPool-12731-thread-1) [n:127.0.0.1:36075_ ] 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.ReplicationFactorTest_2671E966BEBE7DB5-001/shard-1-001/cores
[junit4] 2> 2637457 INFO (zkConnectionManagerCallback-12763-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2637460 INFO (closeThreadPool-12731-thread-2) [n:127.0.0.1:42387_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2637462 INFO (closeThreadPool-12731-thread-2) [n:127.0.0.1:42387_ ] o.a.s.c.ZkController Publish node=127.0.0.1:42387_ as DOWN
[junit4] 2> 2637464 INFO (closeThreadPool-12731-thread-2) [n:127.0.0.1:42387_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2637464 INFO (closeThreadPool-12731-thread-2) [n:127.0.0.1:42387_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42387_
[junit4] 2> 2637465 INFO (zkCallback-12710-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2637467 INFO (zkCallback-12729-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2637467 INFO (zkCallback-12744-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2637467 INFO (zkCallback-12717-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2637480 INFO (zkCallback-12762-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2637488 INFO (zkCallback-12751-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2637489 INFO (closeThreadPool-12731-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2637524 WARN (closeThreadPool-12731-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@2b92cf62[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2637525 INFO (zkConnectionManagerCallback-12771-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2637526 INFO (closeThreadPool-12731-thread-2) [n:127.0.0.1:42387_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 2637526 INFO (closeThreadPool-12731-thread-2) [n:127.0.0.1:42387_ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37351/solr ready
[junit4] 2> 2637546 INFO (closeThreadPool-12731-thread-2) [n:127.0.0.1:42387_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2637552 WARN (closeThreadPool-12731-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@114febbe[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2637553 INFO (closeThreadPool-12731-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37351/solr
[junit4] 2> 2637568 INFO (zkConnectionManagerCallback-12778-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2637572 INFO (closeThreadPool-12731-thread-2) [n:127.0.0.1:42387_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@470b099b
[junit4] 2> 2637588 INFO (zkConnectionManagerCallback-12780-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2637591 INFO (closeThreadPool-12731-thread-3) [n:127.0.0.1:38261_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 2637594 INFO (closeThreadPool-12731-thread-3) [n:127.0.0.1:38261_ ] o.a.s.c.ZkController Publish node=127.0.0.1:38261_ as DOWN
[junit4] 2> 2637594 INFO (closeThreadPool-12731-thread-3) [n:127.0.0.1:38261_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2637594 INFO (closeThreadPool-12731-thread-3) [n:127.0.0.1:38261_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38261_
[junit4] 2> 2637596 INFO (zkCallback-12762-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2637596 INFO (zkCallback-12729-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2637596 INFO (zkCallback-12751-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2637596 INFO (zkCallback-12744-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2637596 INFO (zkCallback-12717-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2637596 INFO (zkCallback-12710-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2637597 INFO (closeThreadPool-12731-thread-2) [n:127.0.0.1:42387_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@470b099b
[junit4] 2> 2637598 INFO (closeThreadPool-12731-thread-2) [n:127.0.0.1:42387_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@470b099b
[junit4] 2> 2637600 INFO (closeThreadPool-12731-thread-2) [n:127.0.0.1:42387_ ] 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.ReplicationFactorTest_2671E966BEBE7DB5-001/shard-2-001/cores
[junit4] 2> 2637604 INFO (zkCallback-12770-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2637612 INFO (zkCallback-12779-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2637628 INFO (zkConnectionManagerCallback-12787-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2637628 INFO (closeThreadPool-12731-thread-3) [n:127.0.0.1:38261_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 2637629 INFO (closeThreadPool-12731-thread-3) [n:127.0.0.1:38261_ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37351/solr ready
[junit4] 2> 2637635 INFO (closeThreadPool-12731-thread-3) [n:127.0.0.1:38261_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2637663 INFO (closeThreadPool-12731-thread-3) [n:127.0.0.1:38261_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@470b099b
[junit4] 2> 2637687 INFO (closeThreadPool-12731-thread-3) [n:127.0.0.1:38261_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@470b099b
[junit4] 2> 2637687 INFO (closeThreadPool-12731-thread-3) [n:127.0.0.1:38261_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@470b099b
[junit4] 2> 2637689 INFO (closeThreadPool-12731-thread-3) [n:127.0.0.1:38261_ ] 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.ReplicationFactorTest_2671E966BEBE7DB5-001/shard-3-001/cores
[junit4] 2> 2637719 INFO (OverseerCollectionConfigSetProcessor-72332608614957060-127.0.0.1:41455_-n_0000000000) [n:127.0.0.1:41455_ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 2637992 INFO (SocketProxy-Acceptor-36075) [ ] o.a.s.c.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=44222,localport=36075], receiveBufferSize:531000
[junit4] 2> 2638033 INFO (qtp1111910352-27261) [n:127.0.0.1:36075_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:36075_&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2638040 INFO (SocketProxy-Acceptor-36075) [ ] o.a.s.c.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=33183,localport=34166], receiveBufferSize=530904
[junit4] 2> 2638040 INFO (SocketProxy-Acceptor-36075) [ ] o.a.s.c.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=44226,localport=36075], receiveBufferSize:531000
[junit4] 2> 2638045 INFO (OverseerThreadFactory-8541-thread-3-processing-n:127.0.0.1:41455_) [n:127.0.0.1:41455_ c:collection1 s:shard2 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:36075_ for creating new replica of shard shard2 for collection collection1
[junit4] 2> 2638046 INFO (OverseerThreadFactory-8541-thread-3-processing-n:127.0.0.1:41455_) [n:127.0.0.1:41455_ c:collection1 s:shard2 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 2638068 INFO (qtp1111910352-27260) [n:127.0.0.1:36075_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:38261_&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2638071 INFO (SocketProxy-Acceptor-36075) [ ] o.a.s.c.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=33183,localport=34172], receiveBufferSize=530904
[junit4] 2> 2638072 INFO (SocketProxy-Acceptor-36075) [ ] o.a.s.c.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=44228,localport=36075], receiveBufferSize:531000
[junit4] 2> 2638082 INFO (OverseerThreadFactory-8541-thread-4-processing-n:127.0.0.1:41455_) [n:127.0.0.1:41455_ c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:38261_ for creating new replica of shard shard1 for collection collection1
[junit4] 2> 2638083 INFO (OverseerThreadFactory-8541-thread-4-processing-n:127.0.0.1:41455_) [n:127.0.0.1:41455_ c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 2638086 INFO (SocketProxy-Acceptor-36075) [ ] o.a.s.c.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=33183,localport=34174], receiveBufferSize=530904
[junit4] 2> 2638086 INFO (SocketProxy-Acceptor-36075) [ ] o.a.s.c.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=44234,localport=36075], receiveBufferSize:531000
[junit4] 2> 2638116 INFO (qtp1111910352-27262) [n:127.0.0.1:36075_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:42387_&action=ADDREPLICA&collection=collection1&shard=shard3&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2638131 INFO (SocketProxy-Acceptor-36075) [ ] o.a.s.c.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=33183,localport=34178], receiveBufferSize=530904
[junit4] 2> 2638136 INFO (SocketProxy-Acceptor-38261) [ ] o.a.s.c.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=52612,localport=38261], receiveBufferSize:531000
[junit4] 2> 2638141 INFO (OverseerThreadFactory-8541-thread-5-processing-n:127.0.0.1:41455_) [n:127.0.0.1:41455_ c:collection1 s:shard3 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:42387_ for creating new replica of shard shard3 for collection collection1
[junit4] 2> 2638142 INFO (OverseerThreadFactory-8541-thread-5-processing-n:127.0.0.1:41455_) [n:127.0.0.1:41455_ c:collection1 s:shard3 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 2638145 INFO (qtp1111910352-27377) [n:127.0.0.1:36075_ x:collection1_shard2_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n1&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2638172 INFO (SocketProxy-Acceptor-38261) [ ] o.a.s.c.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=40649,localport=40894], receiveBufferSize=530904
[junit4] 2> 2638176 INFO (SocketProxy-Acceptor-42387) [ ] o.a.s.c.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=45116,localport=42387], receiveBufferSize:531000
[junit4] 2> 2638187 INFO (SocketProxy-Acceptor-42387) [ ] o.a.s.c.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=36429,localport=38318], receiveBufferSize=530904
[junit4] 2> 2638228 INFO (qtp54714500-27311) [n:127.0.0.1:38261_ x:collection1_shard1_replica_n2] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n2&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2638232 INFO (qtp1989485193-27275) [n:127.0.0.1:42387_ x:collection1_shard3_replica_n3] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard3_replica_n3&action=CREATE&collection=collection1&shard=shard3&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2639201 INFO (qtp1111910352-27377) [n:127.0.0.1:36075_ c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 2639238 INFO (qtp1111910352-27377) [n:127.0.0.1:36075_ c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.s.IndexSchema [collection1_shard2_replica_n1] Schema name=test
[junit4] 2> 2639313 INFO (qtp54714500-27311) [n:127.0.0.1:38261_ c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 2639314 INFO (qtp1989485193-27275) [n:127.0.0.1:42387_ c:collection1 s:shard3 x:collection1_shard3_replica_n3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 2639329 INFO (qtp1989485193-27275) [n:127.0.0.1:42387_ c:collection1 s:shard3 x:collection1_shard3_replica_n3] o.a.s.s.IndexSchema [collection1_shard3_replica_n3] Schema name=test
[junit4] 2> 2639330 INFO (qtp54714500-27311) [n:127.0.0.1:38261_ c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.s.IndexSchema [collection1_shard1_replica_n2] Schema name=test
[junit4] 2> 2639577 INFO (qtp1111910352-27377) [n:127.0.0.1:36075_ c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2639637 INFO (qtp1111910352-27377) [n:127.0.0.1:36075_ c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n1' using configuration from collection collection1, trusted=true
[junit4] 2> 2639638 INFO (qtp1111910352-27377) [n:127.0.0.1:36075_ c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1.shard2.replica_n1' (registry 'solr.core.collection1.shard2.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@470b099b
[junit4] 2> 2639638 INFO (qtp1111910352-27377) [n:127.0.0.1:36075_ c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.SolrCore [[collection1_shard2_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ReplicationFactorTest_2671E966BEBE7DB5-001/shard-1-001/cores/collection1_shard2_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ReplicationFactorTest_2671E966BEBE7DB5-001/shard-1-001/cores/collection1_shard2_replica_n1/data/]
[junit4] 2> 2639639 INFO (qtp54714500-27311) [n:127.0.0.1:38261_ c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2639644 INFO (qtp1111910352-27377) [n:127.0.0.1:36075_ c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=2.32421875, floorSegmentMB=1.421875, forceMergeDeletesPctAllowed=10.776299426127025, segmentsPerTier=44.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=35.434843989160875
[junit4] 2> 2639647 WARN (qtp1111910352-27377) [n:127.0.0.1:36075_ c:collection1 s:shard2 x:collection1_shard2_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> 2639652 INFO (qtp1989485193-27275) [n:127.0.0.1:42387_ c:collection1 s:shard3 x:collection1_shard3_replica_n3] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2639659 INFO (qtp54714500-27311) [n:127.0.0.1:38261_ c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n2' using configuration from collection collection1, trusted=true
[junit4] 2> 2639659 INFO (qtp54714500-27311) [n:127.0.0.1:38261_ c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1.shard1.replica_n2' (registry 'solr.core.collection1.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@470b099b
[junit4] 2> 2639660 INFO (qtp54714500-27311) [n:127.0.0.1:38261_ c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.c.SolrCore [[collection1_shard1_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ReplicationFactorTest_2671E966BEBE7DB5-001/shard-3-001/cores/collection1_shard1_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ReplicationFactorTest_2671E966BEBE7DB5-001/shard-3-001/cores/collection1_shard1_replica_n2/data/]
[junit4] 2> 2639664 INFO (qtp54714500-27311) [n:127.0.0.1:38261_ c:collection1 s:shard1 x:collection1_shard1_replica_n2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=2.32421875, floorSegmentMB=1.421875, forceMergeDeletesPctAllowed=10.776299426127025, segmentsPerTier=44.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=35.434843989160875
[junit4] 2> 2639671 INFO (qtp1989485193-27275) [n:127.0.0.1:42387_ c:collection1 s:shard3 x:collection1_shard3_replica_n3] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard3_replica_n3' using configuration from collection collection1, trusted=true
[junit4] 2> 2639672 INFO (qtp1989485193-27275) [n:127.0.0.1:42387_ c:collection1 s:shard3 x:collection1_shard3_replica_n3] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1.shard3.replica_n3' (registry 'solr.core.collection1.shard3.replica_n3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@470b099b
[junit4] 2> 2639672 INFO (qtp1989485193-27275) [n:127.0.0.1:42387_ c:collection1 s:shard3 x:collection1_shard3_replica_n3] o.a.s.c.SolrCore [[collection1_shard3_replica_n3] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ReplicationFactorTest_2671E966BEBE7DB5-001/shard-2-001/cores/collection1_shard3_replica_n3], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ReplicationFactorTest_2671E966BEBE7DB5-001/shard-2-001/cores/collection1_shard3_replica_n3/data/]
[junit4] 2> 2639677 INFO (qtp1989485193-27275) [n:127.0.0.1:42387_ c:collection1 s:shard3 x:collection1_shard3_replica_n3] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=2.32421875, floorSegmentMB=1.421875, forceMergeDeletesPctAllowed=10.776299426127025, segmentsPerTier=44.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=35.434843989160875
[junit4] 2> 2639681 WARN (qtp54714500-27311) [n:127.0.0.1:38261_ c:collection1 s:shard1 x:collection1_shard1_replica_n2] 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> 2639685 WARN (qtp1989485193-27275) [n:127.0.0.1:42387_ c:collection1 s:shard3 x:collection1_shard3_replica_n3] 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> 2639780 INFO (qtp1111910352-27377) [n:127.0.0.1:36075_ c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2639780 INFO (qtp1111910352-27377) [n:127.0.0.1:36075_ c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2639785 INFO (qtp1111910352-27377) [n:127.0.0.1:36075_ c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2639785 INFO (qtp1111910352-27377) [n:127.0.0.1:36075_ c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2639788 INFO (qtp1111910352-27377) [n:127.0.0.1:36075_ c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=31, maxMergeAtOnceExplicit=43, maxMergedSegmentMB=66.6328125, floorSegmentMB=0.2216796875, forceMergeDeletesPctAllowed=28.1114669609539, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4749986506016355, deletesPctAllowed=49.79512058453382
[junit4] 2> 2639788 INFO (qtp1111910352-27377) [n:127.0.0.1:36075_ c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5b8c5760[collection1_shard2_replica_n1] main]
[junit4] 2> 2639791 INFO (qtp1111910352-27377) [n:127.0.0.1:36075_ c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 2639792 INFO (qtp1111910352-27377) [n:127.0.0.1:36075_ c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2639793 INFO (qtp1111910352-27377) [n:127.0.0.1:36075_ c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2639793 INFO (qtp1111910352-27377) [n:127.0.0.1:36075_ c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1630139129033392128
[junit4] 2> 2639801 INFO (searcherExecutor-8578-thread-1-processing-n:127.0.0.1:36075_ x:collection1_shard2_replica_n1 c:collection1 s:shard2) [n:127.0.0.1:36075_ c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.SolrCore [collection1_shard2_replica_n1] Registered new searcher Searcher@5b8c5760[collection1_shard2_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2639806 INFO (qtp1111910352-27377) [n:127.0.0.1:36075_ c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard2 to Terms{values={core_node4=0}, version=0}
[junit4] 2> 2639807 INFO (qtp1111910352-27377) [n:127.0.0.1:36075_ c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard2
[junit4] 2> 2639809 INFO (qtp1111910352-27377) [n:127.0.0.1:36075_ c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 263980
[...truncated too long message...]
run(Thread.java:835)Throwable #4: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=26181, name=testExecutor-7361-thread-3, state=RUNNABLE, group=TGRP-BasicDistributedZkTest]
[junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:33593/c_w: ADDREPLICA failed to create replica
[junit4] > at __randomizedtesting.SeedInfo.seed([2671E966BEBE7DB5]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:649)
[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.cloud.BasicDistributedZkTest.lambda$createCollectionInOneInstance$1(BasicDistributedZkTest.java:656)
[junit4] > at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
[junit4] > at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
[junit4] > at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
[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/J1/temp/solr.cloud.BasicDistributedZkTest_2671E966BEBE7DB5-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene80): {regex_dup_A_s=FST50, regex_dup_B_s=BlockTreeOrds(blocksize=128), SubjectTerms_mfacet=BlockTreeOrds(blocksize=128), multiDefault=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), _root_=BlockTreeOrds(blocksize=128), genre_s=FST50, author_t=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), series_t=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), rnd_b=Lucene50(blocksize=128), oddField_s=FST50, a_t=BlockTreeOrds(blocksize=128), cat=BlockTreeOrds(blocksize=128), foo_b=FST50, name=Lucene50(blocksize=128), inStock=FST50, id=Lucene50(blocksize=128), text=FST50}, docValues:{regex_dup_A_s=DocValuesFormat(name=Asserting), other_tl1=DocValuesFormat(name=Lucene80), regex_dup_B_s=DocValuesFormat(name=Lucene80), range_facet_l_dv=DocValuesFormat(name=Direct), SubjectTerms_mfacet=DocValuesFormat(name=Lucene80), n_l1=DocValuesFormat(name=Lucene80), multiDefault=DocValuesFormat(name=Lucene80), intDefault=DocValuesFormat(name=Lucene80), genre_s=DocValuesFormat(name=Asserting), n_td1=DocValuesFormat(name=Lucene80), n_d1=DocValuesFormat(name=Lucene80), range_facet_l=DocValuesFormat(name=Lucene80), n_f1=DocValuesFormat(name=Lucene80), series_t=DocValuesFormat(name=Lucene80), n_tl1=DocValuesFormat(name=Lucene80), oddField_s=DocValuesFormat(name=Asserting), n_tf1=DocValuesFormat(name=Lucene80), price=DocValuesFormat(name=Asserting), a_t=DocValuesFormat(name=Lucene80), cat=DocValuesFormat(name=Lucene80), sequence_i=DocValuesFormat(name=Asserting), inStock=DocValuesFormat(name=Asserting), id=DocValuesFormat(name=Direct), text=DocValuesFormat(name=Asserting), intDvoDefault=DocValuesFormat(name=Asserting), timestamp=DocValuesFormat(name=Lucene80), foo_i=DocValuesFormat(name=Lucene80), val_i=DocValuesFormat(name=Direct), _root_=DocValuesFormat(name=Lucene80), n_dt1=DocValuesFormat(name=Lucene80), author_t=DocValuesFormat(name=Lucene80), a_i1=DocValuesFormat(name=Lucene80), n_ti1=DocValuesFormat(name=Direct), rnd_b=DocValuesFormat(name=Direct), _version_=DocValuesFormat(name=Lucene80), n_tdt1=DocValuesFormat(name=Lucene80), foo_b=DocValuesFormat(name=Asserting), name=DocValuesFormat(name=Direct), id_i1=DocValuesFormat(name=Lucene80), foo_d=DocValuesFormat(name=Direct), range_facet_i_dv=DocValuesFormat(name=Lucene80), foo_f=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=734, maxMBSortInHeap=6.202337476986315, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@7abe153f), locale=nds, timezone=Asia/Seoul
[junit4] 2> NOTE: Linux 4.18.0-15-generic amd64/Aleksey Shipilev 13-testing (64-bit)/cpus=8,threads=1,free=108453320,total=477626368
[junit4] 2> NOTE: All tests run in this JVM: [TestSolrCloudSnapshots, ZkShardTermsTest, EnumFieldTest, TestInitQParser, V2ApiIntegrationTest, TestImpersonationWithHadoopAuth, TestMultiValuedNumericRangeQuery, TestDistribIDF, TestPullReplicaErrorHandling, SaslZkACLProviderTest, TestUnInvertedFieldException, TestRandomFaceting, TestTolerantUpdateProcessorRandomCloud, TestCloudPhrasesIdentificationComponent, TestCloudConsistency, TestInPlaceUpdatesDistrib, ShardsWhitelistTest, SyncSliceTest, TestManagedSynonymGraphFilterFactory, SystemLogListenerTest, NodeLostTriggerIntegrationTest, ZkControllerTest, RequestLoggingTest, TestLMDirichletSimilarityFactory, CurrencyFieldTypeTest, TestSmileRequest, TestSimNodeLostTrigger, ReplicaListTransformerTest, TestRecoveryHdfs, BasicFunctionalityTest, ConcurrentCreateRoutedAliasTest, TestSolrQueryParser, SecurityConfHandlerTest, SolrSlf4jReporterTest, TestAnalyzedSuggestions, TestIndexingPerformance, ScheduledTriggerTest, LoggingHandlerTest, TestSweetSpotSimilarityFactory, TestNumericTerms32, TestTrieFacet, TestCoreBackup, ProtectedTermFilterFactoryTest, InfixSuggestersTest, TestBadConfig, FileUtilsTest, SpellPossibilityIteratorTest, XsltUpdateRequestHandlerTest, BasicAuthIntegrationTest, MaxSizeAutoCommitTest, SolrIndexConfigTest, MetricTriggerTest, TermVectorComponentDistributedTest, DistributedSuggestComponentTest, CdcrOpsAndBoundariesTest, SolrCloudExampleTest, ReplaceNodeNoTargetTest, TestBinaryResponseWriter, UnloadDistributedZkTest, TestSystemIdResolver, TestCopyFieldCollectionResource, PluginInfoTest, TestUnifiedSolrHighlighter, CollectionsAPIDistributedZkTest, HdfsRecoveryZkTest, TestCollectionAPI, SolrCoreCheckLockOnStartupTest, TestRTGBase, SolrGraphiteReporterTest, DisMaxRequestHandlerTest, Tagger2Test, TestRTimerTree, TestLeaderElectionZkExpiry, TestFieldTypeResource, TestBinaryField, CreateCollectionCleanupTest, TestDistributedSearch, TestReplicationHandlerBackup, TestSolrCloudWithSecureImpersonation, TriggerEventQueueTest, TestClusterProperties, TestGeoJSONResponseWriter, BaseCdcrDistributedZkTest, SpatialHeatmapFacetsTest, DistributedFacetPivotLongTailTest, MinimalSchemaTest, TestCollectionAPIs, TestRebalanceLeaders, TestStressUserVersions, TestFieldCacheVsDocValues, JsonLoaderTest, DeleteLastCustomShardedReplicaTest, TestLuceneMatchVersion, BlobRepositoryCloudTest, TestTolerantSearch, ChaosMonkeyNothingIsSafeTest, TestSolrCoreParser, TestShardHandlerFactory, HighlighterMaxOffsetTest, TestReRankQParserPlugin, SuggesterTest, TestCloudNestedDocsSort, DocExpirationUpdateProcessorFactoryTest, SolrRrdBackendFactoryTest, CoreAdminRequestStatusTest, TestFieldCollectionResource, CollectionReloadTest, ZookeeperStatusHandlerTest, LeaderVoteWaitTimeoutTest, TestMacros, StatsReloadRaceTest, TestSubQueryTransformerCrossCore, TestConfigOverlay, TestOverriddenPrefixQueryForCustomFieldType, CSVRequestHandlerTest, TestPartialUpdateDeduplication, WrapperMergePolicyFactoryTest, ForceLeaderWithTlogReplicasTest, HdfsWriteToMultipleCollectionsTest, BufferStoreTest, IndexSchemaTest, TestJsonFacetRefinement, HdfsThreadLeakTest, DOMUtilTest, TestScoreJoinQPScore, OverseerTest, TestCorePropertiesReload, DistributedExpandComponentTest, JavabinLoaderTest, ChaosMonkeySafeLeaderWithPullReplicasTest, OverriddenZkACLAndCredentialsProvidersTest, TestDynamicLoading, DistributedVersionInfoTest, BasicDistributedZk2Test, TestConfigSetsAPIZkFailure, TestEmbeddedSolrServerConstructors, HdfsBasicDistributedZk2Test, TestSimClusterStateProvider, BasicDistributedZkTest]
[junit4] Completed [495/851 (2!)] on J1 in 695.84s, 1 test, 1 error <<< FAILURES!
[...truncated 1272 lines...]
[junit4] JVM J1: stdout was not empty, see: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/temp/junit4-J1-20190407_063938_62110328672958436904007.sysout
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] java.lang.OutOfMemoryError: GC overhead limit exceeded
[junit4] Dumping heap to /home/jenkins/workspace/Lucene-Solr-8.x-Linux/heapdumps/java_pid11210.hprof ...
[junit4] Heap dump file created [478201148 bytes in 32.977 secs]
[junit4] <<< JVM J1: EOF ----
[...truncated 8981 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-8.x-Linux/build.xml:633: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-8.x-Linux/build.xml:585: Some of the tests produced a heap dump, but did not fail. Maybe a suppressed OutOfMemoryError? Dumps created:
* java_pid11210.hprof
Total time: 159 minutes 17 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
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