Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-master/1582/
3 tests failed.
FAILED: org.apache.solr.cloud.SharedFSAutoReplicaFailoverTest.test
Error Message:
Expected numSlices=5 numReplicas=1 but found
DocCollection(solrj_collection3//collections/solrj_collection3/state.json/28)={
"pullReplicas":"0", "replicationFactor":"1", "shards":{ "shard1":{
"range":"80000000-b332ffff", "state":"active",
"replicas":{"core_node9":{
"dataDir":"hdfs://lucene2-us-west.apache.org:34630/solr_hdfs_home/solrj_collection3/core_node9/data/",
"base_url":"http://127.0.0.1:42112/u_/m";,
"node_name":"127.0.0.1:42112_u_%2Fm", "type":"NRT",
"ulogDir":"hdfs://lucene2-us-west.apache.org:34630/solr_hdfs_home/solrj_collection3/core_node9/data/tlog",
"core":"solrj_collection3_shard1_replica_n1",
"shared_storage":"true", "state":"active",
"leader":"true"}}}, "shard2":{ "range":"b3330000-e665ffff",
"state":"active", "replicas":{"core_node5":{
"dataDir":"hdfs://lucene2-us-west.apache.org:34630/solr_hdfs_home/solrj_collection3/core_node5/data/",
"base_url":"http://127.0.0.1:43778/u_/m";,
"node_name":"127.0.0.1:43778_u_%2Fm", "type":"NRT",
"ulogDir":"hdfs://lucene2-us-west.apache.org:34630/solr_hdfs_home/solrj_collection3/core_node5/data/tlog",
"core":"solrj_collection3_shard2_replica_n2",
"shared_storage":"true", "state":"active",
"leader":"true"}}}, "shard3":{ "range":"e6660000-1998ffff",
"state":"active", "replicas":{"core_node7":{
"dataDir":"hdfs://lucene2-us-west.apache.org:34630/solr_hdfs_home/solrj_collection3/core_node7/data/",
"base_url":"http://127.0.0.1:43778/u_/m";,
"node_name":"127.0.0.1:43778_u_%2Fm", "type":"NRT",
"ulogDir":"hdfs://lucene2-us-west.apache.org:34630/solr_hdfs_home/solrj_collection3/core_node7/data/tlog",
"core":"solrj_collection3_shard3_replica_n3",
"shared_storage":"true", "state":"active",
"leader":"true"}}}, "shard4":{ "range":"19990000-4ccbffff",
"state":"active", "replicas":{"core_node8":{
"core":"solrj_collection3_shard4_replica_n4",
"base_url":"http://127.0.0.1:43778/u_/m";,
"node_name":"127.0.0.1:43778_u_%2Fm", "state":"down",
"type":"NRT", "leader":"true"}}}, "shard5":{
"range":"4ccc0000-7fffffff", "state":"active",
"replicas":{"core_node10":{
"dataDir":"hdfs://lucene2-us-west.apache.org:34630/solr_hdfs_home/solrj_collection3/core_node10/data/",
"base_url":"http://127.0.0.1:43778/u_/m";,
"node_name":"127.0.0.1:43778_u_%2Fm", "type":"NRT",
"ulogDir":"hdfs://lucene2-us-west.apache.org:34630/solr_hdfs_home/solrj_collection3/core_node10/data/tlog",
"core":"solrj_collection3_shard5_replica_n6",
"shared_storage":"true", "state":"active",
"leader":"true"}}}}, "router":{ "field":"myOwnField",
"name":"compositeId"}, "maxShardsPerNode":"1", "autoAddReplicas":"true",
"nrtReplicas":"1", "tlogReplicas":"0"} with /live_nodes:
[127.0.0.1:42112_u_%2Fm, 127.0.0.1:43778_u_%2Fm, 127.0.0.1:45011_u_%2Fm,
127.0.0.1:45426_u_%2Fm]
Stack Trace:
java.lang.AssertionError: Expected numSlices=5 numReplicas=1 but found
DocCollection(solrj_collection3//collections/solrj_collection3/state.json/28)={
"pullReplicas":"0",
"replicationFactor":"1",
"shards":{
"shard1":{
"range":"80000000-b332ffff",
"state":"active",
"replicas":{"core_node9":{
"dataDir":"hdfs://lucene2-us-west.apache.org:34630/solr_hdfs_home/solrj_collection3/core_node9/data/",
"base_url":"http://127.0.0.1:42112/u_/m";,
"node_name":"127.0.0.1:42112_u_%2Fm",
"type":"NRT",
"ulogDir":"hdfs://lucene2-us-west.apache.org:34630/solr_hdfs_home/solrj_collection3/core_node9/data/tlog",
"core":"solrj_collection3_shard1_replica_n1",
"shared_storage":"true",
"state":"active",
"leader":"true"}}},
"shard2":{
"range":"b3330000-e665ffff",
"state":"active",
"replicas":{"core_node5":{
"dataDir":"hdfs://lucene2-us-west.apache.org:34630/solr_hdfs_home/solrj_collection3/core_node5/data/",
"base_url":"http://127.0.0.1:43778/u_/m";,
"node_name":"127.0.0.1:43778_u_%2Fm",
"type":"NRT",
"ulogDir":"hdfs://lucene2-us-west.apache.org:34630/solr_hdfs_home/solrj_collection3/core_node5/data/tlog",
"core":"solrj_collection3_shard2_replica_n2",
"shared_storage":"true",
"state":"active",
"leader":"true"}}},
"shard3":{
"range":"e6660000-1998ffff",
"state":"active",
"replicas":{"core_node7":{
"dataDir":"hdfs://lucene2-us-west.apache.org:34630/solr_hdfs_home/solrj_collection3/core_node7/data/",
"base_url":"http://127.0.0.1:43778/u_/m";,
"node_name":"127.0.0.1:43778_u_%2Fm",
"type":"NRT",
"ulogDir":"hdfs://lucene2-us-west.apache.org:34630/solr_hdfs_home/solrj_collection3/core_node7/data/tlog",
"core":"solrj_collection3_shard3_replica_n3",
"shared_storage":"true",
"state":"active",
"leader":"true"}}},
"shard4":{
"range":"19990000-4ccbffff",
"state":"active",
"replicas":{"core_node8":{
"core":"solrj_collection3_shard4_replica_n4",
"base_url":"http://127.0.0.1:43778/u_/m";,
"node_name":"127.0.0.1:43778_u_%2Fm",
"state":"down",
"type":"NRT",
"leader":"true"}}},
"shard5":{
"range":"4ccc0000-7fffffff",
"state":"active",
"replicas":{"core_node10":{
"dataDir":"hdfs://lucene2-us-west.apache.org:34630/solr_hdfs_home/solrj_collection3/core_node10/data/",
"base_url":"http://127.0.0.1:43778/u_/m";,
"node_name":"127.0.0.1:43778_u_%2Fm",
"type":"NRT",
"ulogDir":"hdfs://lucene2-us-west.apache.org:34630/solr_hdfs_home/solrj_collection3/core_node10/data/tlog",
"core":"solrj_collection3_shard5_replica_n6",
"shared_storage":"true",
"state":"active",
"leader":"true"}}}},
"router":{
"field":"myOwnField",
"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"true",
"nrtReplicas":"1",
"tlogReplicas":"0"} with /live_nodes: [127.0.0.1:42112_u_%2Fm,
127.0.0.1:43778_u_%2Fm, 127.0.0.1:45011_u_%2Fm, 127.0.0.1:45426_u_%2Fm]
at
__randomizedtesting.SeedInfo.seed([E49AD33B821B12EB:6CCEECE12CE77F13]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.cloud.SharedFSAutoReplicaFailoverTest.assertSliceAndReplicaCount(SharedFSAutoReplicaFailoverTest.java:415)
at
org.apache.solr.cloud.SharedFSAutoReplicaFailoverTest.testBasics(SharedFSAutoReplicaFailoverTest.java:257)
at
org.apache.solr.cloud.SharedFSAutoReplicaFailoverTest.test(SharedFSAutoReplicaFailoverTest.java:145)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1008)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:983)
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:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.cloud.TestStressLiveNodes.testStress
Error Message:
KeeperErrorCode = Session expired for /live_nodes
Stack Trace:
org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode =
Session expired for /live_nodes
at
__randomizedtesting.SeedInfo.seed([E49AD33B821B12EB:F181AEFA01ECEA90]:0)
at org.apache.zookeeper.KeeperException.create(KeeperException.java:130)
at org.apache.zookeeper.KeeperException.create(KeeperException.java:54)
at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1535)
at
org.apache.solr.common.cloud.SolrZkClient.lambda$getChildren$4(SolrZkClient.java:329)
at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
at
org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:329)
at
org.apache.solr.common.cloud.ZkStateReader.refreshLiveNodes(ZkStateReader.java:762)
at
org.apache.solr.common.cloud.ZkStateReader.updateLiveNodes(ZkStateReader.java:405)
at
org.apache.solr.cloud.TestStressLiveNodes.testStress(TestStressLiveNodes.java:145)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
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:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.test
Error Message:
Captured an uncaught exception in thread: Thread[id=21233, name=Connection
evictor, state=RUNNABLE, group=TGRP-FullSolrCloudDistribCmdsTest]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=21233, name=Connection evictor, state=RUNNABLE,
group=TGRP-FullSolrCloudDistribCmdsTest]
Caused by: java.lang.OutOfMemoryError: GC overhead limit exceeded
Build Log:
[...truncated 14864 lines...]
[junit4] Suite: org.apache.solr.cloud.SharedFSAutoReplicaFailoverTest
[junit4] 2> Creating dataDir:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_E49AD33B821B12EB-001/init-core-data-001
[junit4] 2> 4691107 WARN
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[E49AD33B821B12EB]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=91 numCloses=91
[junit4] 2> 4691107 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[E49AD33B821B12EB]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 4691121 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[E49AD33B821B12EB]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
[junit4] 2> 4691121 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[E49AD33B821B12EB]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 4691122 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[E49AD33B821B12EB]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /u_/m
[junit4] 1> Formatting using clusterid: testClusterID
[junit4] 2> 4691437 WARN
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[E49AD33B821B12EB]-worker) [ ]
o.a.h.m.i.MetricsConfig Cannot locate configuration: tried
hadoop-metrics2-namenode.properties,hadoop-metrics2.properties
[junit4] 2> 4691560 WARN
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[E49AD33B821B12EB]-worker) [ ]
o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 4691562 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[E49AD33B821B12EB]-worker) [ ]
o.m.log jetty-6.1.26
[junit4] 2> 4691739 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[E49AD33B821B12EB]-worker) [ ]
o.m.log Extract
jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/hdfs
to ./temp/Jetty_lucene2.us.west_apache_org_45977_hdfs____nxbjtu/webapp
[junit4] 2> 4693596 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[E49AD33B821B12EB]-worker) [ ]
o.m.log Started
HttpServer2$selectchannelconnectorwithsafestar...@lucene2-us-west.apache.org:45977
[junit4] 2> 4695091 WARN
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[E49AD33B821B12EB]-worker) [ ]
o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 4695141 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[E49AD33B821B12EB]-worker) [ ]
o.m.log jetty-6.1.26
[junit4] 2> 4695419 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[E49AD33B821B12EB]-worker) [ ]
o.m.log Extract
jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/datanode
to ./temp/Jetty_localhost_38672_datanode____4kmnsj/webapp
[junit4] 2> 4696942 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[E49AD33B821B12EB]-worker) [ ]
o.m.log Started
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:38672
[junit4] 2> 4698107 WARN
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[E49AD33B821B12EB]-worker) [ ]
o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 4698108 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[E49AD33B821B12EB]-worker) [ ]
o.m.log jetty-6.1.26
[junit4] 2> 4698372 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[E49AD33B821B12EB]-worker) [ ]
o.m.log Extract
jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/datanode
to ./temp/Jetty_localhost_33029_datanode____.nxm83m/webapp
[junit4] 2> 4699729 ERROR (DataNode:
[[[DISK]file:/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_E49AD33B821B12EB-001/tempDir-001/hdfsBaseDir/data/data1/,
[DISK]file:/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_E49AD33B821B12EB-001/tempDir-001/hdfsBaseDir/data/data2/]]
heartbeating to lucene2-us-west.apache.org/127.0.0.1:34630) [ ]
o.a.h.h.s.d.DirectoryScanner
dfs.datanode.directoryscan.throttle.limit.ms.per.sec set to value below 1
ms/sec. Assuming default value of 1000
[junit4] 2> 4700328 INFO (Block report processor) [ ] BlockStateChange
BLOCK* processReport 0x30741a2faa4577: from storage
DS-e2405150-1b2e-4f97-a31b-ccdb96e0062a node
DatanodeRegistration(127.0.0.1:33261,
datanodeUuid=d38f06a2-23a7-408b-b9a3-aa45760ca0e8, infoPort=40547,
infoSecurePort=0, ipcPort=40071,
storageInfo=lv=-56;cid=testClusterID;nsid=362930972;c=0), blocks: 0,
hasStaleStorage: true, processing time: 0 msecs
[junit4] 2> 4700328 INFO (Block report processor) [ ] BlockStateChange
BLOCK* processReport 0x30741a2faa4577: from storage
DS-4c15b0ff-12b4-4f74-beca-006d55bfe5ec node
DatanodeRegistration(127.0.0.1:33261,
datanodeUuid=d38f06a2-23a7-408b-b9a3-aa45760ca0e8, infoPort=40547,
infoSecurePort=0, ipcPort=40071,
storageInfo=lv=-56;cid=testClusterID;nsid=362930972;c=0), blocks: 0,
hasStaleStorage: false, processing time: 0 msecs
[junit4] 2> 4700876 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[E49AD33B821B12EB]-worker) [ ]
o.m.log Started
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:33029
[junit4] 2> 4702689 ERROR (DataNode:
[[[DISK]file:/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_E49AD33B821B12EB-001/tempDir-001/hdfsBaseDir/data/data3/,
[DISK]file:/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_E49AD33B821B12EB-001/tempDir-001/hdfsBaseDir/data/data4/]]
heartbeating to lucene2-us-west.apache.org/127.0.0.1:34630) [ ]
o.a.h.h.s.d.DirectoryScanner
dfs.datanode.directoryscan.throttle.limit.ms.per.sec set to value below 1
ms/sec. Assuming default value of 1000
[junit4] 2> 4702819 INFO (Block report processor) [ ] BlockStateChange
BLOCK* processReport 0x30741ac42b16b1: from storage
DS-8862805b-c6da-44e9-a89b-6b1d0b1285b7 node
DatanodeRegistration(127.0.0.1:43230,
datanodeUuid=2ecb2f15-dc97-4743-adb5-adb71ad90815, infoPort=36725,
infoSecurePort=0, ipcPort=41205,
storageInfo=lv=-56;cid=testClusterID;nsid=362930972;c=0), blocks: 0,
hasStaleStorage: true, processing time: 0 msecs
[junit4] 2> 4702819 INFO (Block report processor) [ ] BlockStateChange
BLOCK* processReport 0x30741ac42b16b1: from storage
DS-f5411200-0bb1-4da8-bf93-812cd4faea5b node
DatanodeRegistration(127.0.0.1:43230,
datanodeUuid=2ecb2f15-dc97-4743-adb5-adb71ad90815, infoPort=36725,
infoSecurePort=0, ipcPort=41205,
storageInfo=lv=-56;cid=testClusterID;nsid=362930972;c=0), blocks: 0,
hasStaleStorage: false, processing time: 0 msecs
[junit4] 2> 4703768 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 4703800 INFO (Thread-51927) [ ] o.a.s.c.ZkTestServer
client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 4703800 INFO (Thread-51927) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 4703839 ERROR (Thread-51927) [ ] o.a.z.s.ZooKeeperServer
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action
on ERROR or SHUTDOWN server state changes
[junit4] 2> 4703896 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.ZkTestServer start zk server on port:44994
[junit4] 2> 4704024 INFO (zkConnectionManagerCallback-4832-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4704150 INFO (zkConnectionManagerCallback-4834-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4704153 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 4704167 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/schema15.xml
to /configs/conf1/schema.xml
[junit4] 2> 4704168 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 4704169 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 4704207 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 4704208 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 4704209 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 4704210 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 4704223 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 4704224 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 4704225 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 4704226 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly
asked otherwise
[junit4] 2> 4704994 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git:
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 4705095 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4705095 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4705095 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 4705136 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1a971065{/u_/m,null,AVAILABLE}
[junit4] 2> 4705136 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@20c3dce0{HTTP/1.1,[http/1.1]}{127.0.0.1:45011}
[junit4] 2> 4705136 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.e.j.s.Server Started @4705273ms
[junit4] 2> 4705136 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {replicaType=NRT,
hostContext=/u_/m, hostPort=45011,
coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_E49AD33B821B12EB-001/control-001/cores}
[junit4] 2> 4705136 ERROR
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 4705136 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4705137 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 4705137 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4705137 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4705137 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-07-11T09:24:18.572Z
[junit4] 2> 4705243 INFO (zkConnectionManagerCallback-4836-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4705337 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 4705337 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_E49AD33B821B12EB-001/control-001/solr.xml
[junit4] 2> 4705341 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay
is ignored
[junit4] 2> 4705341 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 4705374 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@7434cd46, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 4706377 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44994/solr
[junit4] 2> 4706410 INFO (zkConnectionManagerCallback-4840-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4706467 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
0x1032cea5b610003, likely client has closed socket
[junit4] 2> 4706544 INFO (zkConnectionManagerCallback-4842-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4707027 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB])
[n:127.0.0.1:45011_u_%2Fm ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 4707027 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB])
[n:127.0.0.1:45011_u_%2Fm ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:45011_u_%2Fm
[junit4] 2> 4707028 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB])
[n:127.0.0.1:45011_u_%2Fm ] o.a.s.c.Overseer Overseer
(id=72951404035112964-127.0.0.1:45011_u_%2Fm-n_0000000000) starting
[junit4] 2> 4707216 INFO (zkConnectionManagerCallback-4849-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4707342 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB])
[n:127.0.0.1:45011_u_%2Fm ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:44994/solr ready
[junit4] 2> 4707436 DEBUG
(OverseerAutoScalingTriggerThread-72951404035112964-127.0.0.1:45011_u_%2Fm-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 4707451 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB])
[n:127.0.0.1:45011_u_%2Fm ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:45011_u_%2Fm
[junit4] 2> 4707472 DEBUG
(OverseerAutoScalingTriggerThread-72951404035112964-127.0.0.1:45011_u_%2Fm-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 1
[junit4] 2> 4707472 DEBUG
(OverseerAutoScalingTriggerThread-72951404035112964-127.0.0.1:45011_u_%2Fm-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1,
lastZnodeVersion -1
[junit4] 2> 4707472 DEBUG
(OverseerAutoScalingTriggerThread-72951404035112964-127.0.0.1:45011_u_%2Fm-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 1
[junit4] 2> 4707550 INFO
(OverseerStateUpdate-72951404035112964-127.0.0.1:45011_u_%2Fm-n_0000000000) [
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4707647 INFO (zkCallback-4848-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4707669 DEBUG
(OverseerAutoScalingTriggerThread-72951404035112964-127.0.0.1:45011_u_%2Fm-n_0000000000)
[ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial
livenodes: [127.0.0.1:45011_u_%2Fm]
[junit4] 2> 4707699 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB])
[n:127.0.0.1:45011_u_%2Fm ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 4707756 DEBUG
(OverseerAutoScalingTriggerThread-72951404035112964-127.0.0.1:45011_u_%2Fm-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 4707757 DEBUG
(OverseerAutoScalingTriggerThread-72951404035112964-127.0.0.1:45011_u_%2Fm-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1,
lastZnodeVersion 1
[junit4] 2> 4707769 DEBUG (ScheduledTrigger-6792-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 4707843 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB])
[n:127.0.0.1:45011_u_%2Fm ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7434cd46
[junit4] 2> 4707899 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB])
[n:127.0.0.1:45011_u_%2Fm ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7434cd46
[junit4] 2> 4707899 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB])
[n:127.0.0.1:45011_u_%2Fm ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7434cd46
[junit4] 2> 4707901 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB])
[n:127.0.0.1:45011_u_%2Fm ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_E49AD33B821B12EB-001/control-001/cores
[junit4] 2> 4708098 INFO (zkConnectionManagerCallback-4855-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4708155 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4708160 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44994/solr ready
[junit4] 2> 4708192 INFO (qtp1280717846-66272) [n:127.0.0.1:45011_u_%2Fm
] 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:45011_u_%252Fm&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 4708291 INFO (OverseerThreadFactory-6794-thread-1) [ ]
o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 4708448 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
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> 4708449 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
x:control_collection_shard1_replica_n1] o.a.s.c.TransientSolrCoreCacheDefault
Allocating transient cache for 4 transient cores
[junit4] 2> 4708944 DEBUG (ScheduledTrigger-6792-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 4709951 DEBUG (ScheduledTrigger-6792-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 4710401 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 4710523 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
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> 4711034 DEBUG (ScheduledTrigger-6792-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 4711129 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 4711199 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
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> 4711232 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
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@7434cd46
[junit4] 2> 4711232 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.HdfsDirectoryFactory
solr.hdfs.home=hdfs://lucene2-us-west.apache.org:34630/solr_hdfs_home
[junit4] 2> 4711232 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled
[junit4] 2> 4711232 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 4711233 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
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/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_E49AD33B821B12EB-001/control-001/cores/control_collection_shard1_replica_n1],
dataDir=[hdfs://lucene2-us-west.apache.org:34630/solr_hdfs_home/control_collection/core_node2/data/]
[junit4] 2> 4711234 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://lucene2-us-west.apache.org:34630/solr_hdfs_home/control_collection/core_node2/data/snapshot_metadata
[junit4] 2> 4711307 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 4711307 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[4194304] will allocate [1] slabs and use ~[4194304] bytes
[junit4] 2> 4711365 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 4711398 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://lucene2-us-west.apache.org:34630/solr_hdfs_home/control_collection/core_node2/data
[junit4] 2> 4711582 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://lucene2-us-west.apache.org:34630/solr_hdfs_home/control_collection/core_node2/data/index
[junit4] 2> 4711635 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 4711635 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[4194304] will allocate [1] slabs and use ~[4194304] bytes
[junit4] 2> 4711727 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 4711728 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
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=14, maxMergeAtOnceExplicit=44, maxMergedSegmentMB=0.5244140625,
floorSegmentMB=1.6767578125, forceMergeDeletesPctAllowed=2.476511852767734,
segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0,
reclaimDeletesWeight=0.3100030032171759
[junit4] 2> 4712036 DEBUG (ScheduledTrigger-6792-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 4712201 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43230 is added to
blk_1073741825_1001{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-4c15b0ff-12b4-4f74-beca-006d55bfe5ec:NORMAL:127.0.0.1:33261|RBW],
ReplicaUC[[DISK]DS-f5411200-0bb1-4da8-bf93-812cd4faea5b:NORMAL:127.0.0.1:43230|FINALIZED]]}
size 0
[junit4] 2> 4712206 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:33261 is added to
blk_1073741825_1001{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-f5411200-0bb1-4da8-bf93-812cd4faea5b:NORMAL:127.0.0.1:43230|FINALIZED],
ReplicaUC[[DISK]DS-e2405150-1b2e-4f97-a31b-ccdb96e0062a:NORMAL:127.0.0.1:33261|FINALIZED]]}
size 0
[junit4] 2> 4712313 WARN (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
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> 4712657 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.HdfsUpdateLog
[junit4] 2> 4712657 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
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> 4712657 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.HdfsUpdateLog Initializing HdfsUpdateLog: tlogDfsReplication=2
[junit4] 2> 4712756 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 4712756 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 4712771 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=31, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=1.0]
[junit4] 2> 4712898 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@45b32c47[control_collection_shard1_replica_n1] main]
[junit4] 2> 4712899 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 4712899 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
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> 4712900 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 4712901 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
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 1605685546934337536
[junit4] 2> 4712932 INFO
(searcherExecutor-6799-thread-1-processing-n:127.0.0.1:45011_u_%2Fm
x:control_collection_shard1_replica_n1 c:control_collection s:shard1)
[n:127.0.0.1:45011_u_%2Fm 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@45b32c47[control_collection_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4712937 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
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> 4712952 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 4712952 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
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> 4712952 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:45011/u_/m/control_collection_shard1_replica_n1/
[junit4] 2> 4712953 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
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> 4712953 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SyncStrategy
http://127.0.0.1:45011/u_/m/control_collection_shard1_replica_n1/ has no
replicas
[junit4] 2> 4712953 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 4712988 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
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:45011/u_/m/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 4713044 DEBUG (ScheduledTrigger-6792-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 4713045 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
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> 4713047 INFO (qtp1280717846-66267) [n:127.0.0.1:45011_u_%2Fm
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=4599
[junit4] 2> 4713084 INFO (qtp1280717846-66272) [n:127.0.0.1:45011_u_%2Fm
] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at
most 30 seconds. Check all shard replicas
[junit4] 2> 4713155 INFO (zkCallback-4841-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> 4714045 DEBUG (ScheduledTrigger-6792-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 4714089 INFO (qtp1280717846-66272) [n:127.0.0.1:45011_u_%2Fm
] 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:45011_u_%252Fm&wt=javabin&version=2}
status=0 QTime=5897
[junit4] 2> 4714292 INFO (zkConnectionManagerCallback-4860-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4714330 INFO
(OverseerCollectionConfigSetProcessor-72951404035112964-127.0.0.1:45011_u_%2Fm-n_0000000000)
[ ] 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> 4714330 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4714331 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44994/solr ready
[junit4] 2> 4714331 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
loss:false
[junit4] 2> 4714332 INFO (qtp1280717846-66268) [n:127.0.0.1:45011_u_%2Fm
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=2&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 4714382 INFO (OverseerThreadFactory-6794-thread-2) [ ]
o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 4714383 WARN (OverseerThreadFactory-6794-thread-2) [ ]
o.a.s.c.a.c.CreateCollectionCmd It is unusual to create a collection
(collection1) without cores.
[junit4] 2> 4714639 INFO (qtp1280717846-66268) [n:127.0.0.1:45011_u_%2Fm
] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at
most 30 seconds. Check all shard replicas
[junit4] 2> 4714640 INFO (qtp1280717846-66268) [n:127.0.0.1:45011_u_%2Fm
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=2&wt=javabin&version=2}
status=0 QTime=308
[junit4] 2> 4715056 DEBUG (ScheduledTrigger-6792-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 4715517 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_E49AD33B821B12EB-001/shard-1-001
of type NRT
[junit4] 2> 4715530 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git:
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 4715531 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4715531 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4715531 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 4715532 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@421a53a6{/u_/m,null,AVAILABLE}
[junit4] 2> 4715532 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@35ce03e0{HTTP/1.1,[http/1.1]}{127.0.0.1:43778}
[junit4] 2> 4715532 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.e.j.s.Server Started @4715669ms
[junit4] 2> 4715532 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {replicaType=NRT,
solrconfig=solrconfig.xml, hostContext=/u_/m, hostPort=43778,
coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_E49AD33B821B12EB-001/shard-1-001/cores}
[junit4] 2> 4715532 ERROR
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 4715532 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4715532 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 4715532 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4715532 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4715532 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-07-11T09:24:28.967Z
[junit4] 2> 4715551 INFO (zkConnectionManagerCallback-4862-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4715552 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 4715552 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_E49AD33B821B12EB-001/shard-1-001/solr.xml
[junit4] 2> 4715568 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay
is ignored
[junit4] 2> 4715568 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 4715569 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@7434cd46, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 4715938 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44994/solr
[junit4] 2> 4715973 INFO (zkConnectionManagerCallback-4866-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4715975 INFO (zkConnectionManagerCallback-4868-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4715992 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB])
[n:127.0.0.1:43778_u_%2Fm ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 4716010 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB])
[n:127.0.0.1:43778_u_%2Fm ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 4716011 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB])
[n:127.0.0.1:43778_u_%2Fm ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 4 transient cores
[junit4] 2> 4716012 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB])
[n:127.0.0.1:43778_u_%2Fm ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:43778_u_%2Fm
[junit4] 2> 4716037 INFO (zkCallback-4841-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 4716049 INFO (zkCallback-4848-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 4716173 INFO (zkCallback-4867-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 4716173 DEBUG (ScheduledTrigger-6792-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 4716179 INFO (zkCallback-4859-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 4716403 INFO
(OverseerCollectionConfigSetProcessor-72951404035112964-127.0.0.1:45011_u_%2Fm-n_0000000000)
[ ] 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> 4716479 INFO (zkConnectionManagerCallback-4875-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4716513 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB])
[n:127.0.0.1:43778_u_%2Fm ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 4716528 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB])
[n:127.0.0.1:43778_u_%2Fm ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:44994/solr ready
[junit4] 2> 4716565 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB])
[n:127.0.0.1:43778_u_%2Fm ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 4716639 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB])
[n:127.0.0.1:43778_u_%2Fm ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7434cd46
[junit4] 2> 4716678 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB])
[n:127.0.0.1:43778_u_%2Fm ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7434cd46
[junit4] 2> 4716678 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB])
[n:127.0.0.1:43778_u_%2Fm ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7434cd46
[junit4] 2> 4716712 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB])
[n:127.0.0.1:43778_u_%2Fm ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_E49AD33B821B12EB-001/shard-1-001/cores
[junit4] 2> 4716987 INFO (qtp323678340-66337) [n:127.0.0.1:43778_u_%2Fm
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with
params
node=127.0.0.1:43778_u_%252Fm&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 4717020 INFO (OverseerThreadFactory-6794-thread-3) [
c:collection1 s:shard2 ] o.a.s.c.a.c.AddReplicaCmd Node Identified
127.0.0.1:43778_u_%2Fm for creating new replica
[junit4] 2> 4717032 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
x:collection1_shard2_replica_n41] o.a.s.h.a.CoreAdminOperation core create
command
qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n41&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 4717186 DEBUG (ScheduledTrigger-6792-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 4717217 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.0.0
[junit4] 2> 4717299 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.s.IndexSchema
[collection1_shard2_replica_n41] Schema name=test
[junit4] 2> 4717900 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.s.IndexSchema
Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 4717982 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard2_replica_n41' using configuration from
collection collection1, trusted=true
[junit4] 2> 4717983 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1.shard2.replica_n41' (registry
'solr.core.collection1.shard2.replica_n41') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7434cd46
[junit4] 2> 4717983 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.HdfsDirectoryFactory
solr.hdfs.home=hdfs://lucene2-us-west.apache.org:34630/solr_hdfs_home
[junit4] 2> 4717983 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled
[junit4] 2> 4717983 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 4717983 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SolrCore
[[collection1_shard2_replica_n41] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_E49AD33B821B12EB-001/shard-1-001/cores/collection1_shard2_replica_n41],
dataDir=[hdfs://lucene2-us-west.apache.org:34630/solr_hdfs_home/collection1/core_node42/data/]
[junit4] 2> 4717985 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://lucene2-us-west.apache.org:34630/solr_hdfs_home/collection1/core_node42/data/snapshot_metadata
[junit4] 2> 4718057 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 4718057 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[4194304] will allocate [1] slabs and use ~[4194304] bytes
[junit4] 2> 4718081 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 4718082 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://lucene2-us-west.apache.org:34630/solr_hdfs_home/collection1/core_node42/data
[junit4] 2> 4718190 DEBUG (ScheduledTrigger-6792-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 4718190 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://lucene2-us-west.apache.org:34630/solr_hdfs_home/collection1/core_node42/data/index
[junit4] 2> 4718208 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 4718208 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[4194304] will allocate [1] slabs and use ~[4194304] bytes
[junit4] 2> 4718232 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 4718232 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=14, maxMergeAtOnceExplicit=44, maxMergedSegmentMB=0.5244140625,
floorSegmentMB=1.6767578125, forceMergeDeletesPctAllowed=2.476511852767734,
segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0,
reclaimDeletesWeight=0.3100030032171759
[junit4] 2> 4718604 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:33261 is added to
blk_1073741826_1002{UCState=COMMITTED, truncateBlock=null, primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-e2405150-1b2e-4f97-a31b-ccdb96e0062a:NORMAL:127.0.0.1:33261|RBW],
ReplicaUC[[DISK]DS-f5411200-0bb1-4da8-bf93-812cd4faea5b:NORMAL:127.0.0.1:43230|RBW]]}
size 69
[junit4] 2> 4718604 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43230 is added to
blk_1073741826_1002 size 69
[junit4] 2> 4719057 WARN (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
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> 4719190 DEBUG (ScheduledTrigger-6792-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 4719621 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.HdfsUpdateLog
[junit4] 2> 4719621 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 4719621 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.HdfsUpdateLog
Initializing HdfsUpdateLog: tlogDfsReplication=2
[junit4] 2> 4719674 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 4719674 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 4719711 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=31, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=1.0]
[junit4] 2> 4719890 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@1e73c65f[collection1_shard2_replica_n41] main]
[junit4] 2> 4719911 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 4719911 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4719912 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 4719913 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1605685554286952448
[junit4] 2> 4719938 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.ZkShardTerms
Successful update of terms at /collections/collection1/terms/shard2 to
Terms{values={core_node42=0}, version=0}
[junit4] 2> 4719948 INFO
(searcherExecutor-6813-thread-1-processing-n:127.0.0.1:43778_u_%2Fm
x:collection1_shard2_replica_n41 c:collection1 s:shard2)
[n:127.0.0.1:43778_u_%2Fm c:collection1 s:shard2
x:collection1_shard2_replica_n41] o.a.s.c.SolrCore
[collection1_shard2_replica_n41] Registered new searcher
Searcher@1e73c65f[collection1_shard2_replica_n41]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4719973 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 4719973 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 4719973 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:43778/u_/m/collection1_shard2_replica_n41/
[junit4] 2> 4719973 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 4719973 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SyncStrategy
http://127.0.0.1:43778/u_/m/collection1_shard2_replica_n41/ has no replicas
[junit4] 2> 4719973 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 4719993 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:43778/u_/m/collection1_shard2_replica_n41/ shard2
[junit4] 2> 4720045 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.ZkController
I am the leader, no recovery necessary
[junit4] 2> 4720047 INFO (qtp323678340-66332) [n:127.0.0.1:43778_u_%2Fm
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n41&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=3015
[junit4] 2> 4720167 INFO (qtp323678340-66337) [n:127.0.0.1:43778_u_%2Fm
c:collection1 ] o.a.s.s.HttpSolrCall [admin] webapp=null
path=/admin/collections
params={node=127.0.0.1:43778_u_%252Fm&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2}
status=0 QTime=3179
[junit4] 2> 4720167 INFO (zkCallback-4867-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 4720191 DEBUG (ScheduledTrigger-6792-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 4720823 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_E49AD33B821B12EB-001/shard-2-001
of type NRT
[junit4] 2> 4720823 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git:
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 4720840 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4720840 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4720840 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 4720841 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@378cf1cd{/u_/m,null,AVAILABLE}
[junit4] 2> 4720842 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@23e5e0e5{HTTP/1.1,[http/1.1]}{127.0.0.1:44614}
[junit4] 2> 4720842 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.e.j.s.Server Started @4720980ms
[junit4] 2> 4720842 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {replicaType=NRT,
solrconfig=solrconfig.xml, hostContext=/u_/m, hostPort=44614,
coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_E49AD33B821B12EB-001/shard-2-001/cores}
[junit4] 2> 4720843 ERROR
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 4720843 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4720843 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 4720843 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4720843 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4720843 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-07-11T09:24:34.278Z
[junit4] 2> 4720857 INFO (zkConnectionManagerCallback-4878-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4720858 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
0x1032cea5b61000c, likely client has closed socket
[junit4] 2> 4720858 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 4720858 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_E49AD33B821B12EB-001/shard-2-001/solr.xml
[junit4] 2> 4720878 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay
is ignored
[junit4] 2> 4720878 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 4720879 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@7434cd46, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 4721068 INFO
(OverseerCollectionConfigSetProcessor-72951404035112964-127.0.0.1:45011_u_%2Fm-n_0000000000)
[ ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000004 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 4721194 DEBUG (ScheduledTrigger-6792-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 4721903 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44994/solr
[junit4] 2> 4722024 INFO (zkConnectionManagerCallback-4882-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4722077 INFO (zkConnectionManagerCallback-4884-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4722102 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB])
[n:127.0.0.1:44614_u_%2Fm ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 4722103 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB])
[n:127.0.0.1:44614_u_%2Fm ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 4722105 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB])
[n:127.0.0.1:44614_u_%2Fm ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 4 transient cores
[junit4] 2> 4722130 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB])
[n:127.0.0.1:44614_u_%2Fm ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:44614_u_%2Fm
[junit4] 2> 4722268 INFO (zkCallback-4883-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4722296 INFO (zkCallback-4859-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4722305 INFO (zkCallback-4848-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4722305 DEBUG (ScheduledTrigger-6792-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 3
[junit4] 2> 4722305 INFO (zkCallback-4841-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4722306 INFO (zkCallback-4867-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4722341 INFO (zkCallback-4874-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4722390 INFO (zkConnectionManagerCallback-4891-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4722414 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB])
[n:127.0.0.1:44614_u_%2Fm ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 4722415 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB])
[n:127.0.0.1:44614_u_%2Fm ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:44994/solr ready
[junit4] 2> 4722446 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB])
[n:127.0.0.1:44614_u_%2Fm ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 4722574 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB])
[n:127.0.0.1:44614_u_%2Fm ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7434cd46
[junit4] 2> 4722649 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB])
[n:127.0.0.1:44614_u_%2Fm ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7434cd46
[junit4] 2> 4722666 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB])
[n:127.0.0.1:44614_u_%2Fm ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7434cd46
[junit4] 2> 4722667 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[E49AD33B821B12EB])
[n:127.0.0.1:44614_u_%2Fm ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_E49AD33B821B12EB-001/shard-2-001/cores
[junit4] 2> 4722980 INFO (qtp323678340-66333) [n:127.0.0.1:43778_u_%2Fm
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with
params
node=127.0.0.1:44614_u_%252Fm&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 4722998 INFO (OverseerThreadFactory-6794-thread-4) [
c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Node Identified
127.0.0.1:44614_u_%2Fm for creating new replica
[junit4] 2> 4723018 INFO (qtp1951039649-66384) [n:127.0.0.1:44614_u_%2Fm
x:collection1_shard1_replica_n43] o.a.s.h.a.CoreAdminOperation core create
command
qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n43&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 4723044 INFO (zkCallback-4867-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 4723092 INFO (qtp1951039649-66384) [n:127.0.0.1:44614_u_%2Fm
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.0.0
[junit4] 2> 4723145 INFO (qtp1951039649-66
[...truncated too long message...]
gsFormat(name=MockRandom), 5child1_s=PostingsFormat(name=MockRandom),
14grand0_s=Lucene50(blocksize=128), 33parent_f2_s=Lucene50(blocksize=128),
13grand3_s=Lucene50(blocksize=128), 22parent_f2_s=Lucene50(blocksize=128),
4child0_s=BlockTreeOrds(blocksize=128), 11grand5_s=Lucene50(blocksize=128),
12grand2_s=Lucene50(blocksize=128), 12parent_f2_s=BlockTreeOrds(blocksize=128),
52parent_f2_s=BlockTreeOrds(blocksize=128),
42parent_f2_s=PostingsFormat(name=Asserting),
10grand4_s=Lucene50(blocksize=128),
32parent_f2_s=PostingsFormat(name=MockRandom),
21parent_f1_s=Lucene50(blocksize=128),
8grand2_s=PostingsFormat(name=MockRandom),
9grand1_s=PostingsFormat(name=MockRandom),
41parent_f1_s=PostingsFormat(name=Asserting),
12grand8_s=PostingsFormat(name=Asserting),
40parent_f2_s=Lucene50(blocksize=128), 47parent_f1_s=Lucene50(blocksize=128),
13grand7_s=Lucene50(blocksize=128), 14parent_f1_s=Lucene50(blocksize=128),
14grand10_s=BlockTreeOrds(blocksize=128),
20parent_f2_s=PostingsFormat(name=Asserting),
34parent_f1_s=PostingsFormat(name=Asserting),
6child4_s=PostingsFormat(name=MockRandom), 15grand9_s=Lucene50(blocksize=128),
6grand0_s=PostingsFormat(name=MockRandom),
7child3_s=PostingsFormat(name=MockRandom),
15grand0_s=BlockTreeOrds(blocksize=128),
8child6_s=PostingsFormat(name=MockRandom), 5child0_s=Lucene50(blocksize=128),
14grand1_s=BlockTreeOrds(blocksize=128),
8parent_f1_s=BlockTreeOrds(blocksize=128),
15grand13_s=PostingsFormat(name=MockRandom),
27parent_f2_s=BlockTreeOrds(blocksize=128),
2parent_f2_s=Lucene50(blocksize=128), 4child1_s=PostingsFormat(name=Asserting),
11grand4_s=BlockTreeOrds(blocksize=128),
16parent_f2_s=BlockTreeOrds(blocksize=128),
10grand5_s=BlockTreeOrds(blocksize=128),
36parent_f2_s=PostingsFormat(name=MockRandom),
13grand10_s=PostingsFormat(name=Asserting),
7grand6_s=PostingsFormat(name=Asserting),
18parent_f1_s=PostingsFormat(name=Asserting),
9grand8_s=Lucene50(blocksize=128), 8grand3_s=PostingsFormat(name=Asserting),
6parent_f1_s=PostingsFormat(name=MockRandom),
25parent_f1_s=Lucene50(blocksize=128),
12grand9_s=PostingsFormat(name=MockRandom),
0parent_f1_s=BlockTreeOrds(blocksize=128),
3grand2_s=PostingsFormat(name=Asserting), 9grand0_s=Lucene50(blocksize=128),
4parent_f2_s=PostingsFormat(name=Asserting),
47parent_f2_s=PostingsFormat(name=MockRandom),
13grand6_s=BlockTreeOrds(blocksize=128), 6child5_s=Lucene50(blocksize=128),
7child2_s=Lucene50(blocksize=128),
38parent_f1_s=PostingsFormat(name=Asserting),
5grand4_s=PostingsFormat(name=Asserting),
6grand1_s=PostingsFormat(name=Asserting),
15grand8_s=BlockTreeOrds(blocksize=128), 8child7_s=Lucene50(blocksize=128),
16parent_f1_s=Lucene50(blocksize=128),
14grand2_s=PostingsFormat(name=Asserting),
8parent_f2_s=PostingsFormat(name=Asserting),
15grand14_s=Lucene50(blocksize=128), 13grand5_s=PostingsFormat(name=Asserting),
29parent_f2_s=PostingsFormat(name=MockRandom),
2parent_f1_s=PostingsFormat(name=MockRandom),
4child2_s=PostingsFormat(name=MockRandom),
rnd_b=PostingsFormat(name=Asserting),
11grand3_s=PostingsFormat(name=Asserting),
27parent_f1_s=PostingsFormat(name=Asserting),
45parent_f1_s=PostingsFormat(name=Asserting),
49parent_f2_s=BlockTreeOrds(blocksize=128),
12grand0_s=PostingsFormat(name=Asserting),
10grand6_s=PostingsFormat(name=Asserting),
2child0_s=PostingsFormat(name=MockRandom),
13grand11_s=PostingsFormat(name=MockRandom),
36parent_f1_s=Lucene50(blocksize=128)},
docValues:{_version_=DocValuesFormat(name=Asserting),
id=DocValuesFormat(name=Lucene70), text_t=DocValuesFormat(name=Asserting)},
maxPointsInLeafNode=1850, maxMBSortInHeap=7.589652052991062,
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@7040ee3b),
locale=es-PY, timezone=America/Panama
[junit4] 2> NOTE: Linux 4.4.0-112-generic amd64/Oracle Corporation
1.8.0_172 (64-bit)/cpus=4,threads=1,free=136302824,total=477626368
[junit4] 2> NOTE: All tests run in this JVM: [VersionInfoTest,
CoreAdminCreateDiscoverTest, UninvertDocValuesMergePolicyTest, TestRTimerTree,
SpellingQueryConverterTest, TestGeoJSONResponseWriter, HdfsRecoveryZkTest,
DistributedQueryComponentCustomSortTest, MoveReplicaHDFSTest,
MoreLikeThisHandlerTest, TestMiniSolrCloudClusterSSL, LoggingHandlerTest,
PeerSyncWithLeaderAndIndexFingerprintCachingTest, RollingRestartTest,
TestGroupingSearch, FileUtilsTest, ChangedSchemaMergeTest,
DistributedDebugComponentTest, MultiThreadedOCPTest,
TestTrackingShardHandlerFactory, SuggesterWFSTTest, RestoreTriggerStateTest,
TestGraphTermsQParserPlugin, TestNamedUpdateProcessors, TestNodeLostTrigger,
BlobRepositoryCloudTest, TestReqParamsAPI, MetricUtilsTest, DOMUtilTest,
ZkControllerTest, TestSizeLimitedDistributedMap, AnalyticsMergeStrategyTest,
PluginInfoTest, HttpPartitionTest, TestCustomSort, TestTrieFacet,
TestTolerantUpdateProcessorCloud, CollectionsAPIDistributedZkTest,
TestLuceneMatchVersion, TestSystemIdResolver, HdfsDirectoryTest,
TestSearchPerf, ExitableDirectoryReaderTest, TestDFISimilarityFactory,
TestAuthorizationFramework, BitVectorTest, SpatialFilterTest,
TestExtendedDismaxParser, BadCopyFieldTest, TestCorePropertiesReload,
TestXmlQParserPlugin, TestManagedSchemaThreadSafety, TestMultiWordSynonyms,
TestWriterPerf, TestSchemaNameResource, TestExceedMaxTermLength,
HdfsCollectionsAPIDistributedZkTest, TestStressLucene,
AnalysisErrorHandlingTest, TestRestManager, TestLocalFSCloudBackupRestore,
TestShardHandlerFactory, SolrCmdDistributorTest,
TestLegacyNumericRangeQueryBuilder, RegexBytesRefFilterTest,
TestSchemaVersionResource, DistributedFacetPivotLargeTest,
TestExactSharedStatsCache, DateFieldTest, DocumentAnalysisRequestHandlerTest,
ZkShardTermsTest, RegexBoostProcessorTest, TestPHPSerializedResponseWriter,
RuleEngineTest, MigrateRouteKeyTest, TestComplexPhraseLeadingWildcard,
TestCloudPseudoReturnFields, NodeLostTriggerIntegrationTest, TestCrossCoreJoin,
TestSchemaManager, TestSolrCoreSnapshots, TransactionLogTest, ReplaceNodeTest,
TestSmileRequest, TestHdfsBackupRestoreCore, DistanceFunctionTest,
ShardRoutingCustomTest, TestConfigOverlay, ChaosMonkeyNothingIsSafeTest,
TestConfigSetsAPIZkFailure, TestCloudSchemaless, FacetPivotSmallTest,
RecoveryZkTest, CdcrRequestHandlerTest, TestFieldCacheSort, TestInitQParser,
TestNumericRangeQuery32, DistribDocExpirationUpdateProcessorTest,
IgnoreLargeDocumentProcessorFactoryTest, TestBlendedInfixSuggestions,
ShufflingReplicaListTransformerTest, CreateCollectionCleanupTest,
TestRawTransformer, SolrMetricsIntegrationTest, SolrJmxReporterCloudTest,
PrimUtilsTest, TestLeaderElectionZkExpiry, ResponseHeaderTest,
TestCloudJSONFacetJoinDomain, SuggesterFSTTest, SolrIndexSplitterTest,
TestDocTermOrdsUninvertLimit, TestGenericDistributedQueue,
TestMinMaxOnMultiValuedField, BlockJoinFacetRandomTest,
TestStressInPlaceUpdates, DistributedSpellCheckComponentTest,
TestRandomCollapseQParserPlugin, RequiredFieldsTest, EchoParamsTest,
PeerSyncTest, TimeZoneUtilsTest, ZkStateReaderTest, CollectionReloadTest,
ExternalFileFieldSortTest, SolrXmlInZkTest, HdfsBasicDistributedZk2Test,
TestFieldResource, SolrCoreCheckLockOnStartupTest, TestCloudNestedDocsSort,
AnalysisAfterCoreReloadTest, CursorPagingTest, MinimalSchemaTest,
OutputWriterTest, SampleTest, SolrInfoBeanTest, SolrTestCaseJ4Test,
TestCursorMarkWithoutUniqueKey, TestDistributedGrouping,
TestDistributedMissingSort, TestHighlightDedupGrouping, TestJoin,
TestRandomDVFaceting, TestSolrCoreProperties, TestTolerantSearch, TestTrie,
PathHierarchyTokenizerFactoryTest, TokenizerChainTest,
TestLuceneIndexBackCompat, TestEmbeddedSolrServerAdminHandler,
TestEmbeddedSolrServerConstructors, ConnectionReuseTest, ActionThrottleTest,
AddReplicaTest, AliasIntegrationTest, AssignBackwardCompatibilityTest,
AsyncCallRequestStatusResponseTest, BasicDistributedZk2Test,
ChaosMonkeyNothingIsSafeWithPullReplicasTest, ChaosMonkeySafeLeaderTest,
ChaosMonkeySafeLeaderWithPullReplicasTest, CleanupOldIndexTest,
CloudExitableDirectoryReaderTest, CollectionPropsTest,
ConcurrentCreateRoutedAliasTest, ConfigSetsAPITest, ConnectionManagerTest,
CreateRoutedAliasTest, DeleteInactiveReplicaTest,
DeleteLastCustomShardedReplicaTest, DeleteShardTest,
DistributedVersionInfoTest, DocValuesNotIndexedTest, ForceLeaderTest,
FullSolrCloudDistribCmdsTest]
[junit4] Completed [526/823 (3!)] on J0 in 2280.24s, 1 test, 1 error <<<
FAILURES!
[...truncated 1053 lines...]
[junit4] JVM J0: stdout was not empty, see:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/temp/junit4-J0-20180711_080553_2185510846247313525781.sysout
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] java.lang.OutOfMemoryError: GC overhead limit exceeded
[junit4] Dumping heap to
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/heapdumps/java_pid10160.hprof
...
[junit4] Heap dump file created [549103506 bytes in 40.500 secs]
[junit4] <<< JVM J0: EOF ----
[junit4] JVM J0: stderr was not empty, see:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/temp/junit4-J0-20180711_080553_2182953064586134752412.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4]
[junit4] Exception: java.lang.IllegalArgumentException thrown from the
UncaughtExceptionHandler in thread "Connection evictor"
[junit4] <<< JVM J0: EOF ----
[...truncated 8726 lines...]
BUILD FAILED
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/build.xml:651:
The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/build.xml:585:
Some of the tests produced a heap dump, but did not fail. Maybe a suppressed
OutOfMemoryError? Dumps created:
* java_pid10160.hprof
Total time: 519 minutes 42 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
