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
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
