You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2017/10/13 08:40:32 UTC
[JENKINS] Lucene-Solr-NightlyTests-7.1 - Build # 1 - Unstable
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-7.1/1/
7 tests failed.
FAILED: org.apache.lucene.spatial3d.TestGeo3DPoint.testRandomBig
Error Message:
Test abandoned because suite timeout was reached.
Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
at __randomizedtesting.SeedInfo.seed([FDF3A65FB537AD36]:0)
FAILED: junit.framework.TestSuite.org.apache.lucene.spatial3d.TestGeo3DPoint
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([FDF3A65FB537AD36]:0)
FAILED: org.apache.solr.cloud.CdcrReplicationHandlerTest.testReplicationWithBufferedUpdates
Error Message:
Timeout while trying to assert number of documents @ source_collection
Stack Trace:
java.lang.AssertionError: Timeout while trying to assert number of documents @ source_collection
at __randomizedtesting.SeedInfo.seed([271B22973D729D3E:F412728978E101A9]:0)
at org.apache.solr.cloud.BaseCdcrDistributedZkTest.assertNumDocs(BaseCdcrDistributedZkTest.java:282)
at org.apache.solr.cloud.CdcrReplicationHandlerTest.testReplicationWithBufferedUpdates(CdcrReplicationHandlerTest.java:233)
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:993)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
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)
Caused by: java.lang.AssertionError: expected:<150> but was:<160>
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at org.apache.solr.cloud.BaseCdcrDistributedZkTest.assertNumDocs(BaseCdcrDistributedZkTest.java:273)
... 42 more
FAILED: org.apache.solr.cloud.HttpPartitionTest.test
Error Message:
Didn't see all replicas for shard shard1 in c8n_1x2 come up within 90000 ms! ClusterState: { "collMinRf_1x3":{ "pullReplicas":"0", "replicationFactor":"1", "shards":{"shard1":{ "range":"80000000-7fffffff", "state":"active", "replicas":{ "core_node3":{ "core":"collMinRf_1x3_shard1_replica_t1", "base_url":"http://127.0.0.1:41721/x_uo/my", "node_name":"127.0.0.1:41721_x_uo%2Fmy", "state":"active", "type":"TLOG"}, "core_node5":{ "core":"collMinRf_1x3_shard1_replica_t2", "base_url":"http://127.0.0.1:33108/x_uo/my", "node_name":"127.0.0.1:33108_x_uo%2Fmy", "state":"active", "type":"TLOG"}, "core_node6":{ "core":"collMinRf_1x3_shard1_replica_t4", "base_url":"http://127.0.0.1:34960/x_uo/my", "node_name":"127.0.0.1:34960_x_uo%2Fmy", "state":"active", "type":"TLOG", "leader":"true"}}}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"1", "autoAddReplicas":"false", "nrtReplicas":"0", "tlogReplicas":"3"}, "collection1":{ "pullReplicas":"0", "replicationFactor":"1", "shards":{ "shard1":{ "range":"80000000-ffffffff", "state":"active", "replicas":{"core_node44":{ "core":"collection1_shard1_replica_t43", "base_url":"http://127.0.0.1:33108/x_uo/my", "node_name":"127.0.0.1:33108_x_uo%2Fmy", "state":"active", "type":"TLOG", "leader":"true"}}}, "shard2":{ "range":"0-7fffffff", "state":"active", "replicas":{ "core_node42":{ "core":"collection1_shard2_replica_t41", "base_url":"http://127.0.0.1:41721/x_uo/my", "node_name":"127.0.0.1:41721_x_uo%2Fmy", "state":"active", "type":"TLOG", "leader":"true"}, "core_node46":{ "core":"collection1_shard2_replica_t45", "base_url":"http://127.0.0.1:34960/x_uo/my", "node_name":"127.0.0.1:34960_x_uo%2Fmy", "state":"active", "type":"TLOG"}}}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"1", "autoAddReplicas":"false", "nrtReplicas":"1", "tlogReplicas":"0"}, "control_collection":{ "pullReplicas":"0", "replicationFactor":"1", "shards":{"shard1":{ "range":"80000000-7fffffff", "state":"active", "replicas":{"core_node2":{ "core":"control_collection_shard1_replica_n1", "base_url":"http://127.0.0.1:45217/x_uo/my", "node_name":"127.0.0.1:45217_x_uo%2Fmy", "state":"active", "type":"NRT", "leader":"true"}}}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"1", "autoAddReplicas":"false", "nrtReplicas":"1", "tlogReplicas":"0"}, "c8n_1x2":{ "pullReplicas":"0", "replicationFactor":"1", "shards":{"shard1":{ "range":"80000000-7fffffff", "state":"active", "replicas":{ "core_node3":{ "core":"c8n_1x2_shard1_replica_t1", "base_url":"http://127.0.0.1:34960/x_uo/my", "node_name":"127.0.0.1:34960_x_uo%2Fmy", "state":"active", "type":"TLOG", "leader":"true"}, "core_node4":{ "state":"down", "base_url":"http://127.0.0.1:45217/x_uo/my", "core":"c8n_1x2_shard1_replica_t2", "node_name":"127.0.0.1:45217_x_uo%2Fmy", "type":"TLOG"}}}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"1", "autoAddReplicas":"false", "nrtReplicas":"0", "tlogReplicas":"2"}}
Stack Trace:
java.lang.AssertionError: Didn't see all replicas for shard shard1 in c8n_1x2 come up within 90000 ms! ClusterState: {
"collMinRf_1x3":{
"pullReplicas":"0",
"replicationFactor":"1",
"shards":{"shard1":{
"range":"80000000-7fffffff",
"state":"active",
"replicas":{
"core_node3":{
"core":"collMinRf_1x3_shard1_replica_t1",
"base_url":"http://127.0.0.1:41721/x_uo/my",
"node_name":"127.0.0.1:41721_x_uo%2Fmy",
"state":"active",
"type":"TLOG"},
"core_node5":{
"core":"collMinRf_1x3_shard1_replica_t2",
"base_url":"http://127.0.0.1:33108/x_uo/my",
"node_name":"127.0.0.1:33108_x_uo%2Fmy",
"state":"active",
"type":"TLOG"},
"core_node6":{
"core":"collMinRf_1x3_shard1_replica_t4",
"base_url":"http://127.0.0.1:34960/x_uo/my",
"node_name":"127.0.0.1:34960_x_uo%2Fmy",
"state":"active",
"type":"TLOG",
"leader":"true"}}}},
"router":{"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"false",
"nrtReplicas":"0",
"tlogReplicas":"3"},
"collection1":{
"pullReplicas":"0",
"replicationFactor":"1",
"shards":{
"shard1":{
"range":"80000000-ffffffff",
"state":"active",
"replicas":{"core_node44":{
"core":"collection1_shard1_replica_t43",
"base_url":"http://127.0.0.1:33108/x_uo/my",
"node_name":"127.0.0.1:33108_x_uo%2Fmy",
"state":"active",
"type":"TLOG",
"leader":"true"}}},
"shard2":{
"range":"0-7fffffff",
"state":"active",
"replicas":{
"core_node42":{
"core":"collection1_shard2_replica_t41",
"base_url":"http://127.0.0.1:41721/x_uo/my",
"node_name":"127.0.0.1:41721_x_uo%2Fmy",
"state":"active",
"type":"TLOG",
"leader":"true"},
"core_node46":{
"core":"collection1_shard2_replica_t45",
"base_url":"http://127.0.0.1:34960/x_uo/my",
"node_name":"127.0.0.1:34960_x_uo%2Fmy",
"state":"active",
"type":"TLOG"}}}},
"router":{"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"false",
"nrtReplicas":"1",
"tlogReplicas":"0"},
"control_collection":{
"pullReplicas":"0",
"replicationFactor":"1",
"shards":{"shard1":{
"range":"80000000-7fffffff",
"state":"active",
"replicas":{"core_node2":{
"core":"control_collection_shard1_replica_n1",
"base_url":"http://127.0.0.1:45217/x_uo/my",
"node_name":"127.0.0.1:45217_x_uo%2Fmy",
"state":"active",
"type":"NRT",
"leader":"true"}}}},
"router":{"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"false",
"nrtReplicas":"1",
"tlogReplicas":"0"},
"c8n_1x2":{
"pullReplicas":"0",
"replicationFactor":"1",
"shards":{"shard1":{
"range":"80000000-7fffffff",
"state":"active",
"replicas":{
"core_node3":{
"core":"c8n_1x2_shard1_replica_t1",
"base_url":"http://127.0.0.1:34960/x_uo/my",
"node_name":"127.0.0.1:34960_x_uo%2Fmy",
"state":"active",
"type":"TLOG",
"leader":"true"},
"core_node4":{
"state":"down",
"base_url":"http://127.0.0.1:45217/x_uo/my",
"core":"c8n_1x2_shard1_replica_t2",
"node_name":"127.0.0.1:45217_x_uo%2Fmy",
"type":"TLOG"}}}},
"router":{"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"false",
"nrtReplicas":"0",
"tlogReplicas":"2"}}
at __randomizedtesting.SeedInfo.seed([271B22973D729D3E:AF4F1D4D938EF0C6]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.ensureAllReplicasAreActive(AbstractFullDistribZkTestBase.java:1996)
at org.apache.solr.cloud.HttpPartitionTest.testRf2(HttpPartitionTest.java:370)
at org.apache.solr.cloud.HttpPartitionTest.test(HttpPartitionTest.java:132)
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:993)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
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.SharedFSAutoReplicaFailoverTest.test
Error Message:
Expected numSlices=5 numReplicas=1 but found DocCollection(solrj_collection3//collections/solrj_collection3/state.json/27)={ "pullReplicas":"0", "replicationFactor":"1", "shards":{ "shard1":{ "range":"80000000-b332ffff", "state":"active", "replicas":{"core_node3":{ "dataDir":"hdfs://lucene2-us-west.apache.org:46116/solr_hdfs_home/solrj_collection3/core_node3/data/", "base_url":"http://127.0.0.1:37305/a_f", "node_name":"127.0.0.1:37305_a_f", "type":"NRT", "ulogDir":"hdfs://lucene2-us-west.apache.org:46116/solr_hdfs_home/solrj_collection3/core_node3/data/tlog", "core":"solrj_collection3_shard1_replica_n1", "state":"active", "leader":"true"}}}, "shard2":{ "range":"b3330000-e665ffff", "state":"active", "replicas":{"core_node5":{ "dataDir":"hdfs://lucene2-us-west.apache.org:46116/solr_hdfs_home/solrj_collection3/core_node5/data/", "base_url":"http://127.0.0.1:36505/a_f", "node_name":"127.0.0.1:36505_a_f", "type":"NRT", "ulogDir":"hdfs://lucene2-us-west.apache.org:46116/solr_hdfs_home/solrj_collection3/core_node5/data/tlog", "core":"solrj_collection3_shard2_replica_n2", "state":"active", "leader":"true"}}}, "shard3":{ "range":"e6660000-1998ffff", "state":"active", "replicas":{"core_node7":{ "dataDir":"hdfs://lucene2-us-west.apache.org:46116/solr_hdfs_home/solrj_collection3/core_node7/data/", "base_url":"http://127.0.0.1:36505/a_f", "node_name":"127.0.0.1:36505_a_f", "type":"NRT", "ulogDir":"hdfs://lucene2-us-west.apache.org:46116/solr_hdfs_home/solrj_collection3/core_node7/data/tlog", "core":"solrj_collection3_shard3_replica_n4", "state":"active", "leader":"true"}}}, "shard4":{ "range":"19990000-4ccbffff", "state":"active", "replicas":{"core_node9":{ "core":"solrj_collection3_shard4_replica_n6", "base_url":"http://127.0.0.1:36505/a_f", "node_name":"127.0.0.1:36505_a_f", "state":"down", "type":"NRT"}}}, "shard5":{ "range":"4ccc0000-7fffffff", "state":"active", "replicas":{"core_node10":{ "dataDir":"hdfs://lucene2-us-west.apache.org:46116/solr_hdfs_home/solrj_collection3/core_node10/data/", "base_url":"http://127.0.0.1:37305/a_f", "node_name":"127.0.0.1:37305_a_f", "type":"NRT", "ulogDir":"hdfs://lucene2-us-west.apache.org:46116/solr_hdfs_home/solrj_collection3/core_node10/data/tlog", "core":"solrj_collection3_shard5_replica_n8", "state":"active", "leader":"true"}}}}, "router":{ "field":"myOwnField", "name":"compositeId"}, "maxShardsPerNode":"1", "autoAddReplicas":"true", "nrtReplicas":"1", "tlogReplicas":"0"} with /live_nodes: [127.0.0.1:36505_a_f, 127.0.0.1:37305_a_f, 127.0.0.1:39007_a_f, 127.0.0.1:39575_a_f, 127.0.0.1:45402_a_f]
Stack Trace:
java.lang.AssertionError: Expected numSlices=5 numReplicas=1 but found DocCollection(solrj_collection3//collections/solrj_collection3/state.json/27)={
"pullReplicas":"0",
"replicationFactor":"1",
"shards":{
"shard1":{
"range":"80000000-b332ffff",
"state":"active",
"replicas":{"core_node3":{
"dataDir":"hdfs://lucene2-us-west.apache.org:46116/solr_hdfs_home/solrj_collection3/core_node3/data/",
"base_url":"http://127.0.0.1:37305/a_f",
"node_name":"127.0.0.1:37305_a_f",
"type":"NRT",
"ulogDir":"hdfs://lucene2-us-west.apache.org:46116/solr_hdfs_home/solrj_collection3/core_node3/data/tlog",
"core":"solrj_collection3_shard1_replica_n1",
"state":"active",
"leader":"true"}}},
"shard2":{
"range":"b3330000-e665ffff",
"state":"active",
"replicas":{"core_node5":{
"dataDir":"hdfs://lucene2-us-west.apache.org:46116/solr_hdfs_home/solrj_collection3/core_node5/data/",
"base_url":"http://127.0.0.1:36505/a_f",
"node_name":"127.0.0.1:36505_a_f",
"type":"NRT",
"ulogDir":"hdfs://lucene2-us-west.apache.org:46116/solr_hdfs_home/solrj_collection3/core_node5/data/tlog",
"core":"solrj_collection3_shard2_replica_n2",
"state":"active",
"leader":"true"}}},
"shard3":{
"range":"e6660000-1998ffff",
"state":"active",
"replicas":{"core_node7":{
"dataDir":"hdfs://lucene2-us-west.apache.org:46116/solr_hdfs_home/solrj_collection3/core_node7/data/",
"base_url":"http://127.0.0.1:36505/a_f",
"node_name":"127.0.0.1:36505_a_f",
"type":"NRT",
"ulogDir":"hdfs://lucene2-us-west.apache.org:46116/solr_hdfs_home/solrj_collection3/core_node7/data/tlog",
"core":"solrj_collection3_shard3_replica_n4",
"state":"active",
"leader":"true"}}},
"shard4":{
"range":"19990000-4ccbffff",
"state":"active",
"replicas":{"core_node9":{
"core":"solrj_collection3_shard4_replica_n6",
"base_url":"http://127.0.0.1:36505/a_f",
"node_name":"127.0.0.1:36505_a_f",
"state":"down",
"type":"NRT"}}},
"shard5":{
"range":"4ccc0000-7fffffff",
"state":"active",
"replicas":{"core_node10":{
"dataDir":"hdfs://lucene2-us-west.apache.org:46116/solr_hdfs_home/solrj_collection3/core_node10/data/",
"base_url":"http://127.0.0.1:37305/a_f",
"node_name":"127.0.0.1:37305_a_f",
"type":"NRT",
"ulogDir":"hdfs://lucene2-us-west.apache.org:46116/solr_hdfs_home/solrj_collection3/core_node10/data/tlog",
"core":"solrj_collection3_shard5_replica_n8",
"state":"active",
"leader":"true"}}}},
"router":{
"field":"myOwnField",
"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"true",
"nrtReplicas":"1",
"tlogReplicas":"0"} with /live_nodes: [127.0.0.1:36505_a_f, 127.0.0.1:37305_a_f, 127.0.0.1:39007_a_f, 127.0.0.1:39575_a_f, 127.0.0.1:45402_a_f]
at __randomizedtesting.SeedInfo.seed([271B22973D729D3E:AF4F1D4D938EF0C6]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.SharedFSAutoReplicaFailoverTest.assertSliceAndReplicaCount(SharedFSAutoReplicaFailoverTest.java:413)
at org.apache.solr.cloud.SharedFSAutoReplicaFailoverTest.testBasics(SharedFSAutoReplicaFailoverTest.java:255)
at org.apache.solr.cloud.SharedFSAutoReplicaFailoverTest.test(SharedFSAutoReplicaFailoverTest.java:143)
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:993)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
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.hdfs.HdfsUnloadDistributedZkTest.test
Error Message:
Timeout occured while waiting response from server at: http://127.0.0.1:46007
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:46007
at __randomizedtesting.SeedInfo.seed([271B22973D729D3E:AF4F1D4D938EF0C6]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:637)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:253)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:242)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413)
at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1121)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:862)
at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:793)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:178)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:195)
at org.apache.solr.cloud.UnloadDistributedZkTest.testCoreUnloadAndLeaders(UnloadDistributedZkTest.java:305)
at org.apache.solr.cloud.UnloadDistributedZkTest.test(UnloadDistributedZkTest.java:68)
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:993)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
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)
Caused by: java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
at java.net.SocketInputStream.read(SocketInputStream.java:171)
at java.net.SocketInputStream.read(SocketInputStream.java:141)
at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:282)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:165)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185)
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:111)
at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:525)
... 52 more
FAILED: org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest.testHistory
Error Message:
replica never fully recovered
Stack Trace:
java.lang.AssertionError: replica never fully recovered
at __randomizedtesting.SeedInfo.seed([271B22973D729D3E:4AE7866A873A6239]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest.waitForRecovery(AutoscalingHistoryHandlerTest.java:303)
at org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest.testHistory(AutoscalingHistoryHandlerTest.java:255)
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)
Build Log:
[...truncated 10893 lines...]
[junit4] Suite: org.apache.lucene.spatial3d.TestGeo3DPoint
[junit4] 2> lis 13, 2017 9:57:25 AM com.carrotsearch.randomizedtesting.ThreadLeakControl$2 evaluate
[junit4] 2> WARNING: Suite execution timed out: org.apache.lucene.spatial3d.TestGeo3DPoint
[junit4] 2> 1) Thread[id=13, name=SUITE-TestGeo3DPoint-seed#[FDF3A65FB537AD36], state=RUNNABLE, group=TGRP-TestGeo3DPoint]
[junit4] 2> at java.lang.Thread.getStackTrace(Thread.java:1559)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$4.run(ThreadLeakControl.java:696)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$4.run(ThreadLeakControl.java:693)
[junit4] 2> at java.security.AccessController.doPrivileged(Native Method)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.getStackTrace(ThreadLeakControl.java:693)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.getThreadsWithTraces(ThreadLeakControl.java:709)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.formatThreadStacksFull(ThreadLeakControl.java:689)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.access$1000(ThreadLeakControl.java:65)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$2.evaluate(ThreadLeakControl.java:415)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSuite(RandomizedRunner.java:705)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.access$200(RandomizedRunner.java:139)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$2.run(RandomizedRunner.java:626)
[junit4] 2> 2) Thread[id=9, name=JUnit4-serializer-daemon, state=TIMED_WAITING, group=main]
[junit4] 2> at java.lang.Thread.sleep(Native Method)
[junit4] 2> at com.carrotsearch.ant.tasks.junit4.events.Serializer$1.run(Serializer.java:50)
[junit4] 2> 3) Thread[id=1, name=main, state=WAITING, group=main]
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> at java.lang.Thread.join(Thread.java:1252)
[junit4] 2> at java.lang.Thread.join(Thread.java:1326)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSuite(RandomizedRunner.java:636)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.run(RandomizedRunner.java:493)
[junit4] 2> at com.carrotsearch.ant.tasks.junit4.slave.SlaveMain.execute(SlaveMain.java:251)
[junit4] 2> at com.carrotsearch.ant.tasks.junit4.slave.SlaveMain.main(SlaveMain.java:368)
[junit4] 2> at com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe.main(SlaveMainSafe.java:13)
[junit4] 2> 4) Thread[id=14, name=TEST-TestGeo3DPoint.testRandomBig-seed#[FDF3A65FB537AD36], state=RUNNABLE, group=TGRP-TestGeo3DPoint]
[junit4] 2> at org.apache.lucene.spatial3d.geom.Plane.findIntersections(Plane.java:650)
[junit4] 2> at org.apache.lucene.spatial3d.geom.StandardXYZSolid.<init>(StandardXYZSolid.java:177)
[junit4] 2> at org.apache.lucene.spatial3d.geom.XYZSolidFactory.makeXYZSolid(XYZSolidFactory.java:64)
[junit4] 2> at org.apache.lucene.spatial3d.TestGeo3DPoint.verify(TestGeo3DPoint.java:847)
[junit4] 2> at org.apache.lucene.spatial3d.TestGeo3DPoint.doTestRandom(TestGeo3DPoint.java:548)
[junit4] 2> at org.apache.lucene.spatial3d.TestGeo3DPoint.testRandomBig(TestGeo3DPoint.java:480)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
[junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestGeo3DPoint -Dtests.method=testRandomBig -Dtests.seed=FDF3A65FB537AD36 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/test-data/enwiki.random.lines.txt -Dtests.locale=hr -Dtests.timezone=Asia/Rangoon -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 7105s J1 | TestGeo3DPoint.testRandomBig <<<
[junit4] > Throwable #1: java.lang.Exception: Test abandoned because suite timeout was reached.
[junit4] > at __randomizedtesting.SeedInfo.seed([FDF3A65FB537AD36]:0)
[junit4] 2> lis 13, 2017 9:57:26 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> lis 13, 2017 9:57:46 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> SEVERE: 1 thread leaked from SUITE scope at org.apache.lucene.spatial3d.TestGeo3DPoint:
[junit4] 2> 1) Thread[id=14, name=TEST-TestGeo3DPoint.testRandomBig-seed#[FDF3A65FB537AD36], state=RUNNABLE, group=TGRP-TestGeo3DPoint]
[junit4] 2> at org.apache.lucene.spatial3d.geom.Plane.findIntersections(Plane.java:650)
[junit4] 2> at org.apache.lucene.spatial3d.geom.GeoComplexPolygon.isWithin(GeoComplexPolygon.java:208)
[junit4] 2> at org.apache.lucene.spatial3d.geom.GeoBaseMembershipShape.isWithin(GeoBaseMembershipShape.java:36)
[junit4] 2> at org.apache.lucene.spatial3d.geom.GeoBaseShape.getBounds(GeoBaseShape.java:43)
[junit4] 2> at org.apache.lucene.spatial3d.geom.GeoComplexPolygon.getBounds(GeoComplexPolygon.java:441)
[junit4] 2> at org.apache.lucene.spatial3d.TestGeo3DPoint.verify(TestGeo3DPoint.java:846)
[junit4] 2> at org.apache.lucene.spatial3d.TestGeo3DPoint.doTestRandom(TestGeo3DPoint.java:548)
[junit4] 2> at org.apache.lucene.spatial3d.TestGeo3DPoint.testRandomBig(TestGeo3DPoint.java:480)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
[junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> lis 13, 2017 9:57:46 AM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=14, name=TEST-TestGeo3DPoint.testRandomBig-seed#[FDF3A65FB537AD36], state=RUNNABLE, group=TGRP-TestGeo3DPoint]
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/spatial3d/test/J1/temp/lucene.spatial3d.TestGeo3DPoint_FDF3A65FB537AD36-001
[junit4] 2> Oct 13, 2017 3:27:46 AM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: All leaked threads terminated.
[junit4] 2> NOTE: test params are: codec=FastDecompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=2698, maxDocsPerChunk=685, blockSize=801), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=2698, blockSize=801)), sim=RandomSimilarity(queryNorm=true): {}, locale=hr, timezone=Asia/Rangoon
[junit4] 2> NOTE: Linux 4.4.0-83-generic amd64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=4,threads=1,free=201126616,total=480247808
[junit4] 2> NOTE: All tests run in this JVM: [TestGeo3DDocValues, TestGeo3DPoint]
[junit4] 2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestGeo3DPoint -Dtests.seed=FDF3A65FB537AD36 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/test-data/enwiki.random.lines.txt -Dtests.locale=hr -Dtests.timezone=Asia/Rangoon -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J1 | TestGeo3DPoint (suite) <<<
[junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
[junit4] > at __randomizedtesting.SeedInfo.seed([FDF3A65FB537AD36]:0)
[junit4] Completed [15/15 (1!)] on J1 in 7220.63s, 6 tests, 2 errors <<< FAILURES!
[...truncated 1964 lines...]
[junit4] Suite: org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest
[junit4] 2> 554598 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[271B22973D729D3E]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_271B22973D729D3E-001/init-core-data-001
[junit4] 2> 554599 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[271B22973D729D3E]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 554689 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[271B22973D729D3E]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 554690 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[271B22973D729D3E]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 3 servers in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_271B22973D729D3E-001/tempDir-001
[junit4] 2> 554690 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[271B22973D729D3E]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 554719 INFO (Thread-323) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 554722 INFO (Thread-323) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 554782 ERROR (Thread-323) [ ] 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> 554828 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[271B22973D729D3E]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:40096
[junit4] 2> 555038 INFO (jetty-launcher-83-thread-1) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 555073 INFO (jetty-launcher-83-thread-2) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 555132 INFO (jetty-launcher-83-thread-3) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 555231 INFO (jetty-launcher-83-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4aa5f34c{/solr,null,AVAILABLE}
[junit4] 2> 555232 INFO (jetty-launcher-83-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@500da532{HTTP/1.1,[http/1.1]}{127.0.0.1:46787}
[junit4] 2> 555232 INFO (jetty-launcher-83-thread-3) [ ] o.e.j.s.Server Started @567881ms
[junit4] 2> 555232 INFO (jetty-launcher-83-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46787}
[junit4] 2> 555233 ERROR (jetty-launcher-83-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 555233 INFO (jetty-launcher-83-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.1.0
[junit4] 2> 555233 INFO (jetty-launcher-83-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 555233 INFO (jetty-launcher-83-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 555233 INFO (jetty-launcher-83-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-10-13T04:08:23.131Z
[junit4] 2> 555305 INFO (jetty-launcher-83-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1097445f{/solr,null,AVAILABLE}
[junit4] 2> 555306 INFO (jetty-launcher-83-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4dbec6d7{HTTP/1.1,[http/1.1]}{127.0.0.1:38095}
[junit4] 2> 555306 INFO (jetty-launcher-83-thread-1) [ ] o.e.j.s.Server Started @567955ms
[junit4] 2> 555306 INFO (jetty-launcher-83-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38095}
[junit4] 2> 555306 ERROR (jetty-launcher-83-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 555306 INFO (jetty-launcher-83-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.1.0
[junit4] 2> 555306 INFO (jetty-launcher-83-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 555306 INFO (jetty-launcher-83-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 555306 INFO (jetty-launcher-83-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-10-13T04:08:23.204Z
[junit4] 2> 555341 INFO (jetty-launcher-83-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 555528 INFO (jetty-launcher-83-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@40d13492{/solr,null,AVAILABLE}
[junit4] 2> 555529 INFO (jetty-launcher-83-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@302f118a{HTTP/1.1,[http/1.1]}{127.0.0.1:46831}
[junit4] 2> 555529 INFO (jetty-launcher-83-thread-2) [ ] o.e.j.s.Server Started @568178ms
[junit4] 2> 555529 INFO (jetty-launcher-83-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46831}
[junit4] 2> 555529 ERROR (jetty-launcher-83-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 555529 INFO (jetty-launcher-83-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.1.0
[junit4] 2> 555529 INFO (jetty-launcher-83-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 555529 INFO (jetty-launcher-83-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 555529 INFO (jetty-launcher-83-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-10-13T04:08:23.427Z
[junit4] 2> 555633 INFO (jetty-launcher-83-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40096/solr
[junit4] 2> 555638 INFO (jetty-launcher-83-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 555928 INFO (jetty-launcher-83-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 556044 INFO (jetty-launcher-83-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40096/solr
[junit4] 2> 556401 INFO (jetty-launcher-83-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40096/solr
[junit4] 2> 557160 INFO (jetty-launcher-83-thread-2) [n:127.0.0.1:46831_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 557189 INFO (jetty-launcher-83-thread-2) [n:127.0.0.1:46831_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:46831_solr
[junit4] 2> 557191 INFO (jetty-launcher-83-thread-2) [n:127.0.0.1:46831_solr ] o.a.s.c.Overseer Overseer (id=98819617761067017-127.0.0.1:46831_solr-n_0000000000) starting
[junit4] 2> 557490 DEBUG (OverseerStateUpdate-98819617761067017-127.0.0.1:46831_solr-n_0000000000) [n:127.0.0.1:46831_solr ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 557491 INFO (jetty-launcher-83-thread-2) [n:127.0.0.1:46831_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46831_solr
[junit4] 2> 557491 DEBUG (OverseerAutoScalingTriggerThread-98819617761067017-127.0.0.1:46831_solr-n_0000000000) [n:127.0.0.1:46831_solr ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger
[junit4] 2> 557596 DEBUG (OverseerAutoScalingTriggerThread-98819617761067017-127.0.0.1:46831_solr-n_0000000000) [n:127.0.0.1:46831_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 557616 INFO (OverseerStateUpdate-98819617761067017-127.0.0.1:46831_solr-n_0000000000) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 557669 DEBUG (OverseerAutoScalingTriggerThread-98819617761067017-127.0.0.1:46831_solr-n_0000000000) [n:127.0.0.1:46831_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:46831_solr]
[junit4] 2> 557669 DEBUG (OverseerAutoScalingTriggerThread-98819617761067017-127.0.0.1:46831_solr-n_0000000000) [n:127.0.0.1:46831_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion -1
[junit4] 2> 557669 DEBUG (OverseerAutoScalingTriggerThread-98819617761067017-127.0.0.1:46831_solr-n_0000000000) [n:127.0.0.1:46831_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 557815 DEBUG (OverseerAutoScalingTriggerThread-98819617761067017-127.0.0.1:46831_solr-n_0000000000) [n:127.0.0.1:46831_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 557836 DEBUG (OverseerAutoScalingTriggerThread-98819617761067017-127.0.0.1:46831_solr-n_0000000000) [n:127.0.0.1:46831_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 557852 DEBUG (ScheduledTrigger-164-thread-1) [n:127.0.0.1:46831_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: [127.0.0.1:46831_solr]
[junit4] 2> 559095 DEBUG (ScheduledTrigger-164-thread-1) [n:127.0.0.1:46831_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: [127.0.0.1:46831_solr]
[junit4] 2> 559222 INFO (jetty-launcher-83-thread-1) [n:127.0.0.1:38095_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 559223 INFO (jetty-launcher-83-thread-1) [n:127.0.0.1:38095_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 559224 INFO (jetty-launcher-83-thread-1) [n:127.0.0.1:38095_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38095_solr
[junit4] 2> 559294 INFO (zkCallback-101-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 559404 INFO (zkCallback-99-thread-1-processing-n:127.0.0.1:38095_solr) [n:127.0.0.1:38095_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 559420 DEBUG (OverseerStateUpdate-98819617761067017-127.0.0.1:46831_solr-n_0000000000) [n:127.0.0.1:46831_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:38095_solr"} current state version: 0
[junit4] 2> 559420 DEBUG (OverseerStateUpdate-98819617761067017-127.0.0.1:46831_solr-n_0000000000) [n:127.0.0.1:46831_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:38095_solr
[junit4] 2> 559588 INFO (jetty-launcher-83-thread-3) [n:127.0.0.1:46787_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 559662 INFO (jetty-launcher-83-thread-3) [n:127.0.0.1:46787_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 559698 INFO (jetty-launcher-83-thread-3) [n:127.0.0.1:46787_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46787_solr
[junit4] 2> 559699 INFO (zkCallback-99-thread-1-processing-n:127.0.0.1:38095_solr) [n:127.0.0.1:38095_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 559713 DEBUG (OverseerStateUpdate-98819617761067017-127.0.0.1:46831_solr-n_0000000000) [n:127.0.0.1:46831_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:46787_solr"} current state version: 0
[junit4] 2> 559713 DEBUG (OverseerStateUpdate-98819617761067017-127.0.0.1:46831_solr-n_0000000000) [n:127.0.0.1:46831_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:46787_solr
[junit4] 2> 559713 INFO (zkCallback-101-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 559787 INFO (zkCallback-95-thread-1-processing-n:127.0.0.1:46787_solr) [n:127.0.0.1:46787_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 559789 INFO (jetty-launcher-83-thread-2) [n:127.0.0.1:46831_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46831.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@70f580a
[junit4] 2> 560146 INFO (jetty-launcher-83-thread-2) [n:127.0.0.1:46831_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46831.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@70f580a
[junit4] 2> 560146 INFO (jetty-launcher-83-thread-2) [n:127.0.0.1:46831_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46831.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@70f580a
[junit4] 2> 560166 INFO (jetty-launcher-83-thread-2) [n:127.0.0.1:46831_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_271B22973D729D3E-001/tempDir-001/node2/.
[junit4] 2> 560363 DEBUG (ScheduledTrigger-164-thread-2) [n:127.0.0.1:46831_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: [127.0.0.1:38095_solr, 127.0.0.1:46787_solr, 127.0.0.1:46831_solr]
[junit4] 2> 561272 INFO (jetty-launcher-83-thread-1) [n:127.0.0.1:38095_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38095.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@70f580a
[junit4] 2> 561398 INFO (jetty-launcher-83-thread-3) [n:127.0.0.1:46787_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46787.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@70f580a
[junit4] 2> 561415 DEBUG (ScheduledTrigger-164-thread-1) [n:127.0.0.1:46831_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: [127.0.0.1:38095_solr, 127.0.0.1:46787_solr, 127.0.0.1:46831_solr]
[junit4] 2> 561618 INFO (jetty-launcher-83-thread-3) [n:127.0.0.1:46787_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46787.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@70f580a
[junit4] 2> 561651 INFO (jetty-launcher-83-thread-3) [n:127.0.0.1:46787_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46787.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@70f580a
[junit4] 2> 561652 INFO (jetty-launcher-83-thread-3) [n:127.0.0.1:46787_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_271B22973D729D3E-001/tempDir-001/node3/.
[junit4] 2> 561725 INFO (jetty-launcher-83-thread-1) [n:127.0.0.1:38095_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38095.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@70f580a
[junit4] 2> 561726 INFO (jetty-launcher-83-thread-1) [n:127.0.0.1:38095_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38095.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@70f580a
[junit4] 2> 561727 INFO (jetty-launcher-83-thread-1) [n:127.0.0.1:38095_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_271B22973D729D3E-001/tempDir-001/node1/.
[junit4] 2> 562424 DEBUG (ScheduledTrigger-164-thread-1) [n:127.0.0.1:46831_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: [127.0.0.1:38095_solr, 127.0.0.1:46787_solr, 127.0.0.1:46831_solr]
[junit4] 2> 562977 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[271B22973D729D3E]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 562978 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[271B22973D729D3E]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:40096/solr ready
[junit4] 2> 563387 INFO (qtp1505590192-749) [n:127.0.0.1:46787_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=3&name=AutoscalingHistoryHandlerTest_collection&nrtReplicas=3&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 563424 DEBUG (ScheduledTrigger-164-thread-1) [n:127.0.0.1:46831_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: [127.0.0.1:38095_solr, 127.0.0.1:46787_solr, 127.0.0.1:46831_solr]
[junit4] 2> 563452 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.CreateCollectionCmd Create collection AutoscalingHistoryHandlerTest_collection
[junit4] 2> 563452 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.CreateCollectionCmd Only _default config set found, using it.
[junit4] 2> 563453 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/AutoscalingHistoryHandlerTest_collection/protwords.txt
[junit4] 2> 563467 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/AutoscalingHistoryHandlerTest_collection/managed-schema
[junit4] 2> 563504 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/AutoscalingHistoryHandlerTest_collection/solrconfig.xml
[junit4] 2> 563506 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/AutoscalingHistoryHandlerTest_collection/synonyms.txt
[junit4] 2> 563507 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/AutoscalingHistoryHandlerTest_collection/stopwords.txt
[junit4] 2> 563529 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/params.json to /configs/AutoscalingHistoryHandlerTest_collection/params.json
[junit4] 2> 563530 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/contractions_it.txt
[junit4] 2> 563545 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_no.txt
[junit4] 2> 563546 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_hy.txt
[junit4] 2> 563547 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stemdict_nl.txt
[junit4] 2> 563567 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/contractions_ca.txt
[junit4] 2> 563568 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_id.txt
[junit4] 2> 563602 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_ga.txt
[junit4] 2> 563603 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_da.txt
[junit4] 2> 563604 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_hi.txt
[junit4] 2> 563638 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_ja.txt
[junit4] 2> 563639 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_pt.txt
[junit4] 2> 563640 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_hu.txt
[junit4] 2> 563656 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_el.txt
[junit4] 2> 563657 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_ru.txt
[junit4] 2> 563756 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_tr.txt
[junit4] 2> 563757 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_ar.txt
[junit4] 2> 563758 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/userdict_ja.txt
[junit4] 2> 563793 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_it.txt
[junit4] 2> 563794 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_eu.txt
[junit4] 2> 563815 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_cz.txt
[junit4] 2> 563816 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stoptags_ja.txt
[junit4] 2> 563869 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/contractions_fr.txt
[junit4] 2> 563904 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_fa.txt
[junit4] 2> 563995 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_de.txt
[junit4] 2> 563997 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/contractions_ga.txt
[junit4] 2> 563998 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_ca.txt
[junit4] 2> 563999 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_nl.txt
[junit4] 2> 564012 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_bg.txt
[junit4] 2> 564013 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_es.txt
[junit4] 2> 564015 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_en.txt
[junit4] 2> 564032 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_lv.txt
[junit4] 2> 564033 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_th.txt
[junit4] 2> 564034 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_ro.txt
[junit4] 2> 564035 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_fr.txt
[junit4] 2> 564050 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_sv.txt
[junit4] 2> 564051 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_fi.txt
[junit4] 2> 564086 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/hyphenations_ga.txt
[junit4] 2> 564087 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:46831_solr) [n:127.0.0.1:46831_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/AutoscalingHistoryHandlerTest_collection/lang/stopwords_gl.txt
[junit4] 2> 564195 DEBUG (OverseerStateUpdate-98819617761067017-127.0.0.1:46831_solr-n_0000000000) [n:127.0.0.1:46831_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "name":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"3",
[junit4] 2> "numShards":"1",
[junit4] 2> "nrtReplicas":"3",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 564195 DEBUG (OverseerStateUpdate-98819617761067017-127.0.0.1:46831_solr-n_0000000000) [n:127.0.0.1:46831_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: AutoscalingHistoryHandlerTest_collection
[junit4] 2> 564216 DEBUG (OverseerStateUpdate-98819617761067017-127.0.0.1:46831_solr-n_0000000000) [n:127.0.0.1:46831_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/AutoscalingHistoryHandlerTest_collection/state.json
[junit4] 2> 564234 DEBUG (OverseerStateUpdate-98819617761067017-127.0.0.1:46831_solr-n_0000000000) [n:127.0.0.1:46831_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:46787/solr",
[junit4] 2> "type":"NRT"} current state version: 0
[junit4] 2> 564234 INFO (OverseerStateUpdate-98819617761067017-127.0.0.1:46831_solr-n_0000000000) [n:127.0.0.1:46831_solr ] o.a.s.c.
[...truncated too long message...]
ud.SharedFSAutoReplicaFailoverTest_271B22973D729D3E-001/tempDir-001/hdfsBaseDir/data/data4/]] heartbeating to lucene2-us-west.apache.org/127.0.0.1:46116) [ ] o.a.h.h.s.d.IncrementalBlockReportManager IncrementalBlockReportManager interrupted
[junit4] 2> 10313227 WARN (DataNode: [[[DISK]file:/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_271B22973D729D3E-001/tempDir-001/hdfsBaseDir/data/data3/, [DISK]file:/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_271B22973D729D3E-001/tempDir-001/hdfsBaseDir/data/data4/]] heartbeating to lucene2-us-west.apache.org/127.0.0.1:46116) [ ] o.a.h.h.s.d.DataNode Ending block pool service for: Block pool BP-1912684746-127.0.0.1-1507876037459 (Datanode Uuid 25782654-d045-4a4a-a886-c952a046d742) service to lucene2-us-west.apache.org/127.0.0.1:46116
[junit4] 2> 10313279 WARN (SUITE-SharedFSAutoReplicaFailoverTest-seed#[271B22973D729D3E]-worker) [ ] o.a.h.h.s.d.DirectoryScanner DirectoryScanner: shutdown has been called
[junit4] 2> 10313319 INFO (SUITE-SharedFSAutoReplicaFailoverTest-seed#[271B22973D729D3E]-worker) [ ] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:0
[junit4] 2> 10313329 WARN (1260240741@qtp-1904127970-1 - Acceptor0 HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:41343) [ ] o.a.h.h.HttpServer2 HttpServer Acceptor: isRunning is false. Rechecking.
[junit4] 2> 10313330 WARN (1260240741@qtp-1904127970-1 - Acceptor0 HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:41343) [ ] o.a.h.h.HttpServer2 HttpServer Acceptor: isRunning is false
[junit4] 2> 10313432 WARN (DataNode: [[[DISK]file:/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_271B22973D729D3E-001/tempDir-001/hdfsBaseDir/data/data1/, [DISK]file:/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_271B22973D729D3E-001/tempDir-001/hdfsBaseDir/data/data2/]] heartbeating to lucene2-us-west.apache.org/127.0.0.1:46116) [ ] o.a.h.h.s.d.IncrementalBlockReportManager IncrementalBlockReportManager interrupted
[junit4] 2> 10313432 WARN (DataNode: [[[DISK]file:/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_271B22973D729D3E-001/tempDir-001/hdfsBaseDir/data/data1/, [DISK]file:/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_271B22973D729D3E-001/tempDir-001/hdfsBaseDir/data/data2/]] heartbeating to lucene2-us-west.apache.org/127.0.0.1:46116) [ ] o.a.h.h.s.d.DataNode Ending block pool service for: Block pool BP-1912684746-127.0.0.1-1507876037459 (Datanode Uuid c0e722db-f116-4614-8e29-69e0d36189c2) service to lucene2-us-west.apache.org/127.0.0.1:46116
[junit4] 2> 10313473 INFO (SUITE-SharedFSAutoReplicaFailoverTest-seed#[271B22973D729D3E]-worker) [ ] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@lucene2-us-west.apache.org:0
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_271B22973D729D3E-001
[junit4] 2> Oct 13, 2017 6:51:00 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 34 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {id=Lucene50(blocksize=128), text=PostingsFormat(name=Memory)}, docValues:{_version_=DocValuesFormat(name=Lucene70), id=DocValuesFormat(name=Lucene70), text=DocValuesFormat(name=Memory)}, maxPointsInLeafNode=207, maxMBSortInHeap=6.595772894011303, sim=RandomSimilarity(queryNorm=true): {}, locale=es-CL, timezone=Indian/Comoro
[junit4] 2> NOTE: Linux 4.4.0-83-generic amd64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=4,threads=2,free=195390456,total=527433728
[junit4] 2> NOTE: All tests run in this JVM: [TestCloudInspectUtil, OverseerModifyCollectionTest, TestFreeTextSuggestions, CollectionReloadTest, GraphQueryTest, IndexSchemaRuntimeFieldTest, TestStressCloudBlindAtomicUpdates, CustomCollectionTest, HLLSerializationTest, DirectSolrConnectionTest, SolrCloudReportersTest, ZkStateWriterTest, TestDynamicFieldCollectionResource, HdfsRestartWhileUpdatingTest, TestRTGBase, TestSolrConfigHandlerCloud, DocValuesTest, TestBM25SimilarityFactory, OpenCloseCoreStressTest, TestCoreAdminApis, TestApiFramework, TestSolrCloudSnapshots, SecurityConfHandlerTest, RulesTest, SolrCmdDistributorTest, HdfsLockFactoryTest, FacetPivotSmallTest, HdfsChaosMonkeyNothingIsSafeTest, HdfsTlogReplayBufferedWhileIndexingTest, TestNumericTerms32, TestRebalanceLeaders, TestSha256AuthenticationProvider, IgnoreCommitOptimizeUpdateProcessorFactoryTest, TestAnalyzeInfixSuggestions, SolrMetricsIntegrationTest, TestInPlaceUpdatesDistrib, ZkControllerTest, LoggingHandlerTest, ResponseHeaderTest, DistributedQueryComponentCustomSortTest, CdcrVersionReplicationTest, TestTlogReplica, SuggestComponentTest, TestJettySolrRunner, AtomicUpdatesTest, TestMaxTokenLenTokenizer, CacheHeaderTest, TestSystemIdResolver, ResourceLoaderTest, OutOfBoxZkACLAndCredentialsProvidersTest, TestFieldResource, ZkFailoverTest, SaslZkACLProviderTest, BitVectorTest, TestLegacyNumericUtils, TestOnReconnectListenerSupport, CheckHdfsIndexTest, HdfsAutoAddReplicasIntegrationTest, TestStressRecovery, TestManagedSchemaThreadSafety, JavabinLoaderTest, QueryParsingTest, TestSmileRequest, TriggerIntegrationTest, TestShortCircuitedRequests, TestJavabinTupleStreamParser, TestXIncludeConfig, TestPostingsSolrHighlighter, BlockDirectoryTest, TestMacroExpander, DistributedTermsComponentTest, SliceStateTest, DisMaxRequestHandlerTest, CachingDirectoryFactoryTest, PrimUtilsTest, TlogReplayBufferedWhileIndexingTest, DistributedFacetPivotSmallAdvancedTest, ClusterStateUpdateTest, EnumFieldTest, TestFastLRUCache, BadCopyFieldTest, TestCloudManagedSchema, ShardRoutingCustomTest, TestLocalFSCloudBackupRestore, ConfigSetsAPITest, TestDistributedGrouping, StressHdfsTest, TestSolrCloudWithDelegationTokens, RemoteQueryErrorTest, TestConfigSetsAPI, TestLeaderElectionWithEmptyReplica, TestCorePropertiesReload, TestCoreDiscovery, AddBlockUpdateTest, TestPartialUpdateDeduplication, TestHdfsBackupRestoreCore, TestHttpShardHandlerFactory, TestAuthorizationFramework, InfoHandlerTest, TestDownShardTolerantSearch, TestTrieFacet, HighlighterTest, TestAtomicUpdateErrorCases, TestSweetSpotSimilarityFactory, AnalysisAfterCoreReloadTest, CSVRequestHandlerTest, TestFieldSortValues, ShardRoutingTest, TestStressVersions, TestExactSharedStatsCache, TestCryptoKeys, SimpleCollectionCreateDeleteTest, BasicAuthIntegrationTest, DirectSolrSpellCheckerTest, TestSchemalessBufferedUpdates, CloudMLTQParserTest, CdcrReplicationDistributedZkTest, ScriptEngineTest, CoreAdminHandlerTest, TestSimpleTrackingShardHandler, TermVectorComponentTest, SolrJmxReporterCloudTest, CollectionsAPIDistributedZkTest, RecoveryZkTest, LeaderElectionTest, ZkSolrClientTest, TestZkChroot, ZkCLITest, AutoCommitTest, TestFaceting, TestHashPartitioner, TestCoreContainer, SimpleFacetsTest, SpellCheckComponentTest, QueryElevationComponentTest, PeerSyncTest, ConvertedLegacyTest, TestFiltering, SoftAutoCommitTest, SpellCheckCollatorTest, TestFoldingMultitermQuery, TestTrie, TestCSVLoader, SchemaVersionSpecificBehaviorTest, TestUpdate, QueryEqualityTest, DocValuesMultiTest, DistanceFunctionTest, SolrInfoBeanTest, DebugComponentTest, TestSurroundQueryParser, LukeRequestHandlerTest, PrimitiveFieldTypeTest, XmlUpdateRequestHandlerTest, DocumentBuilderTest, TestIndexingPerformance, RequiredFieldsTest, TestSolrQueryParser, RegexBoostProcessorTest, IndexSchemaTest, ReturnFieldsTest, MBeansHandlerTest, SearchHandlerTest, HighlighterConfigTest, SOLR749Test, TestQuerySenderListener, UpdateParamsTest, AlternateDirectoryTest, TestSolrIndexConfig, TestQuerySenderNoQuery, BadComponentTest, TestMergePolicyConfig, MultiTermTest, SampleTest, TestSearchPerf, NumericFieldsTest, MinimalSchemaTest, TestElisionMultitermQuery, TestFuzzyAnalyzedSuggestions, ExternalFileFieldSortTest, TestPhraseSuggestions, TestCharFilters, TestCodecSupport, EchoParamsTest, TestPerFieldSimilarity, TestLMDirichletSimilarityFactory, TestLMJelinekMercerSimilarityFactory, TestIBSimilarityFactory, TestFastWriter, TimeZoneUtilsTest, OpenExchangeRatesOrgProviderTest, ChaosMonkeyNothingIsSafeTest, PreAnalyzedFieldTest, SpellingQueryConverterTest, DOMUtilTest, TestSolrJ, TestLRUCache, TestUtils, ZkNodePropsTest, SystemInfoHandlerTest, FileUtilsTest, DistributedMLTComponentTest, CursorPagingTest, SolrTestCaseJ4Test, TestCrossCoreJoin, TestCursorMarkWithoutUniqueKey, TestHighlightDedupGrouping, TestEmbeddedSolrServerAdminHandler, TestEmbeddedSolrServerSchemaAPI, ActionThrottleTest, AliasIntegrationTest, AsyncCallRequestStatusResponseTest, CloudExitableDirectoryReaderTest, CollectionTooManyReplicasTest, CollectionsAPIAsyncDistributedZkTest, DeleteStatusTest, DistribDocExpirationUpdateProcessorTest, DistribJoinFromCollectionTest, DistributedVersionInfoTest, LeaderElectionContextKeyTest, LeaderInitiatedRecoveryOnShardRestartTest, MissingSegmentRecoveryTest, MoveReplicaHDFSFailoverTest, NodeMutatorTest, OverseerStatusTest, OverseerTaskQueueTest, ReplaceNodeTest, ReplicationFactorTest, RestartWhileUpdatingTest, ShardSplitTest, SharedFSAutoReplicaFailoverTest]
[junit4] Completed [570/744 (5!)] on J1 in 1432.76s, 1 test, 1 failure <<< FAILURES!
[...truncated 47594 lines...]
[JENKINS] Lucene-Solr-NightlyTests-7.1 - Build # 2 - Still Unstable
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-7.1/2/
8 tests failed.
FAILED: org.apache.lucene.index.TestIndexWriterOnVMError.testCheckpoint
Error Message:
Test abandoned because suite timeout was reached.
Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
at __randomizedtesting.SeedInfo.seed([7562A2CCB9DE1578]:0)
FAILED: junit.framework.TestSuite.org.apache.lucene.index.TestIndexWriterOnVMError
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([7562A2CCB9DE1578]:0)
FAILED: org.apache.solr.cloud.CollectionTooManyReplicasTest.testAddTooManyReplicas
Error Message:
Could not load collection from ZK: TooManyReplicasInSeveralFlavors
Stack Trace:
org.apache.solr.common.SolrException: Could not load collection from ZK: TooManyReplicasInSeveralFlavors
at __randomizedtesting.SeedInfo.seed([E60AEFD9FDBF8E38:6B589B01B359B189]:0)
at org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1170)
at org.apache.solr.common.cloud.ZkStateReader$LazyCollectionRef.get(ZkStateReader.java:690)
at org.apache.solr.common.cloud.ClusterState.getCollectionOrNull(ClusterState.java:130)
at org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:110)
at org.apache.solr.cloud.SolrCloudTestCase.getCollectionState(SolrCloudTestCase.java:247)
at org.apache.solr.cloud.CollectionTooManyReplicasTest.getAllNodeNames(CollectionTooManyReplicasTest.java:217)
at org.apache.solr.cloud.CollectionTooManyReplicasTest.testAddTooManyReplicas(CollectionTooManyReplicasTest.java:62)
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)
Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/TooManyReplicasInSeveralFlavors/state.json
at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1212)
at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:357)
at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:354)
at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:354)
at org.apache.solr.common.cloud.ZkStateReader.fetchCollectionState(ZkStateReader.java:1182)
at org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1168)
... 45 more
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.MultiThreadedOCPTest
Error Message:
1 thread leaked from SUITE scope at org.apache.solr.cloud.MultiThreadedOCPTest: 1) Thread[id=1357, name=zkCallback-145-thread-1, state=TIMED_WAITING, group=TGRP-MultiThreadedOCPTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.cloud.MultiThreadedOCPTest:
1) Thread[id=1357, name=zkCallback-145-thread-1, state=TIMED_WAITING, group=TGRP-MultiThreadedOCPTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460)
at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362)
at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([E60AEFD9FDBF8E38]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.MultiThreadedOCPTest
Error Message:
There are still zombie threads that couldn't be terminated: 1) Thread[id=1357, name=zkCallback-145-thread-1, state=TIMED_WAITING, group=TGRP-MultiThreadedOCPTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
1) Thread[id=1357, name=zkCallback-145-thread-1, state=TIMED_WAITING, group=TGRP-MultiThreadedOCPTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460)
at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362)
at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([E60AEFD9FDBF8E38]:0)
FAILED: org.apache.solr.cloud.hdfs.HdfsTlogReplayBufferedWhileIndexingTest.test
Error Message:
There are still nodes recoverying - waited for 600 seconds
Stack Trace:
java.lang.AssertionError: There are still nodes recoverying - waited for 600 seconds
at __randomizedtesting.SeedInfo.seed([E60AEFD9FDBF8E38:6E5ED0035343E3C0]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:185)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:914)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForThingsToLevelOut(AbstractFullDistribZkTestBase.java:1471)
at org.apache.solr.cloud.TlogReplayBufferedWhileIndexingTest.test(TlogReplayBufferedWhileIndexingTest.java:99)
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:993)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
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.hdfs.StressHdfsTest.test
Error Message:
Could not find collection:delete_data_dir
Stack Trace:
java.lang.AssertionError: Could not find collection:delete_data_dir
at __randomizedtesting.SeedInfo.seed([E60AEFD9FDBF8E38:6E5ED0035343E3C0]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNotNull(Assert.java:526)
at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:155)
at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:140)
at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:135)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:908)
at org.apache.solr.cloud.hdfs.StressHdfsTest.createAndDeleteCollection(StressHdfsTest.java:160)
at org.apache.solr.cloud.hdfs.StressHdfsTest.test(StressHdfsTest.java:104)
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:993)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
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.handler.TestReplicationHandler.doTestDetails
Error Message:
Expected to see that the slave has replicated1: {indexSize=120 bytes,indexPath=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_E60AEFD9FDBF8E38-001/solr-instance-023/./collection1/data/index/,commits=[{indexVersion=1508053005810,generation=2,filelist=[segments_2]}],isMaster=false,isSlave=true,indexVersion=1508053005810,generation=2,slave={masterDetails={indexSize=1.6 KB,indexPath=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_E60AEFD9FDBF8E38-001/solr-instance-022/./collection1/data/index/,commits=[{indexVersion=0,generation=1,filelist=[segments_1]}, {indexVersion=1508053005810,generation=2,filelist=[_0.cfe, _0.cfs, _0.si, segments_2]}],isMaster=true,isSlave=false,indexVersion=1508053005810,generation=2,master={confFiles=schema.xml,xslt/dummy.xsl,replicateAfter=[commit],replicationEnabled=true,replicableVersion=1508053005810,replicableGeneration=2}},masterUrl=http://127.0.0.1:55003/solr/collection1,pollInterval=00:00:01,nextExecutionAt=Sun Oct 15 10:37:25 EAT 2017,currentDate=Sun Oct 15 10:37:25 EAT 2017,isPollingDisabled=false,isReplicating=false}}
Stack Trace:
java.lang.AssertionError: Expected to see that the slave has replicated1: {indexSize=120 bytes,indexPath=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_E60AEFD9FDBF8E38-001/solr-instance-023/./collection1/data/index/,commits=[{indexVersion=1508053005810,generation=2,filelist=[segments_2]}],isMaster=false,isSlave=true,indexVersion=1508053005810,generation=2,slave={masterDetails={indexSize=1.6 KB,indexPath=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_E60AEFD9FDBF8E38-001/solr-instance-022/./collection1/data/index/,commits=[{indexVersion=0,generation=1,filelist=[segments_1]}, {indexVersion=1508053005810,generation=2,filelist=[_0.cfe, _0.cfs, _0.si, segments_2]}],isMaster=true,isSlave=false,indexVersion=1508053005810,generation=2,master={confFiles=schema.xml,xslt/dummy.xsl,replicateAfter=[commit],replicationEnabled=true,replicableVersion=1508053005810,replicableGeneration=2}},masterUrl=http://127.0.0.1:55003/solr/collection1,pollInterval=00:00:01,nextExecutionAt=Sun Oct 15 10:37:25 EAT 2017,currentDate=Sun Oct 15 10:37:25 EAT 2017,isPollingDisabled=false,isReplicating=false}}
at __randomizedtesting.SeedInfo.seed([E60AEFD9FDBF8E38:9C57CCAC6BE7A7B0]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNotNull(Assert.java:526)
at org.apache.solr.handler.TestReplicationHandler.doTestDetails(TestReplicationHandler.java:332)
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)
Build Log:
[...truncated 11 lines...]
ERROR: Error cloning remote repo 'origin'
hudson.plugins.git.GitException: Command "git fetch --tags --progress git://git.apache.org/lucene-solr.git +refs/heads/*:refs/remotes/origin/*" returned status code 128:
stdout:
stderr: remote: Counting objects: 76690
remote: Counting objects: 251419
remote: Counting objects: 379403
remote: Counting objects: 505133
remote: Counting objects: 654262
remote: Counting objects: 820279
remote: Counting objects: 917474, done.
remote: Compressing objects: 0% (1/179021)
remote: Compressing objects: 1% (1791/179021)
remote: Compressing objects: 2% (3581/179021)
remote: Compressing objects: 3% (5371/179021)
remote: Compressing objects: 4% (7161/179021)
remote: Compressing objects: 5% (8952/179021)
remote: Compressing objects: 6% (10742/179021)
remote: Compressing objects: 7% (12532/179021)
remote: Compressing objects: 8% (14322/179021)
remote: Compressing objects: 9% (16112/179021)
remote: Compressing objects: 10% (17903/179021)
remote: Compressing objects: 11% (19693/179021)
remote: Compressing objects: 12% (21483/179021)
remote: Compressing objects: 13% (23273/179021)
remote: Compressing objects: 14% (25063/179021)
remote: Compressing objects: 15% (26854/179021)
remote: Compressing objects: 16% (28644/179021)
remote: Compressing objects: 17% (30434/179021)
remote: Compressing objects: 18% (32224/179021)
remote: Compressing objects: 19% (34014/179021)
remote: Compressing objects: 20% (35805/179021)
remote: Compressing objects: 21% (37595/179021)
remote: Compressing objects: 22% (39385/179021)
remote: Compressing objects: 23% (41175/179021)
remote: Compressing objects: 24% (42966/179021)
remote: Compressing objects: 25% (44756/179021)
remote: Compressing objects: 26% (46546/179021)
remote: Compressing objects: 27% (48336/179021)
remote: Compressing objects: 28% (50126/179021)
remote: Compressing objects: 29% (51917/179021)
remote: Compressing objects: 30% (53707/179021)
remote: Compressing objects: 31% (55497/179021)
remote: Compressing objects: 32% (57287/179021)
remote: Compressing objects: 33% (59077/179021)
remote: Compressing objects: 34% (60868/179021)
remote: Compressing objects: 35% (62658/179021)
remote: Compressing objects: 36% (64448/179021)
remote: Compressing objects: 37% (66238/179021)
remote: Compressing objects: 38% (68028/179021)
remote: Compressing objects: 39% (69819/179021)
remote: Compressing objects: 40% (71609/179021)
remote: Compressing objects: 41% (73399/179021)
remote: Compressing objects: 42% (75189/179021)
remote: Compressing objects: 43% (76980/179021)
remote: Compressing objects: 44% (78770/179021)
remote: Compressing objects: 45% (80560/179021)
remote: Compressing objects: 46% (82350/179021)
remote: Compressing objects: 47% (84140/179021)
remote: Compressing objects: 48% (85931/179021)
remote: Compressing objects: 49% (87721/179021)
remote: Compressing objects: 50% (89511/179021)
remote: Compressing objects: 51% (91301/179021)
remote: Compressing objects: 52% (93091/179021)
remote: Compressing objects: 53% (94882/179021)
remote: Compressing objects: 54% (96672/179021)
remote: Compressing objects: 55% (98462/179021)
remote: Compressing objects: 56% (100252/179021)
remote: Compressing objects: 57% (102042/179021)
remote: Compressing objects: 58% (103833/179021)
remote: Compressing objects: 59% (105623/179021)
remote: Compressing objects: 60% (107413/179021)
remote: Compressing objects: 61% (109203/179021)
remote: Compressing objects: 62% (110994/179021)
remote: Compressing objects: 63% (112784/179021)
remote: Compressing objects: 64% (114574/179021)
remote: Compressing objects: 65% (116364/179021)
remote: Compressing objects: 66% (118154/179021)
remote: Compressing objects: 67% (119945/179021)
remote: Compressing objects: 68% (121735/179021)
remote: Compressing objects: 69% (123525/179021)
remote: Compressing objects: 70% (125315/179021)
remote: Compressing objects: 71% (127105/179021)
remote: Compressing objects: 72% (128896/179021)
remote: Compressing objects: 73% (130686/179021)
remote: Compressing objects: 74% (132476/179021)
remote: Compressing objects: 75% (134266/179021)
remote: Compressing objects: 76% (136056/179021)
remote: Compressing objects: 77% (137847/179021)
remote: Compressing objects: 78% (139637/179021)
remote: Compressing objects: 79% (141427/179021)
remote: Compressing objects: 80% (143217/179021)
remote: Compressing objects: 81% (145008/179021)
remote: Compressing objects: 82% (146798/179021)
remote: Compressing objects: 83% (148588/179021)
remote: Compressing objects: 84% (150378/179021)
remote: Compressing objects: 85% (152168/179021)
remote: Compressing objects: 86% (153959/179021)
remote: Compressing objects: 87% (155749/179021)
remote: Compressing objects: 88% (157539/179021)
remote: Compressing objects: 89% (159329/179021)
remote: Compressing objects: 90% (161119/179021)
remote: Compressing objects: 91% (162910/179021)
remote: Compressing objects: 92% (164700/179021)
remote: Compressing objects: 93% (166490/179021)
remote: Compressing objects: 94% (168280/179021)
remote: Compressing objects: 94% (169821/179021)
remote: Compressing objects: 95% (170070/179021)
remote: Compressing objects: 96% (171861/179021)
remote: Compressing objects: 97% (173651/179021)
remote: Compressing objects: 98% (175441/179021)
remote: Compressing objects: 99% (177231/179021)
remote: Compressing objects: 100% (179021/179021)
remote: Compressing objects: 100% (179021/179021), done.
Receiving objects: 0% (1/917474)
Receiving objects: 1% (9175/917474), 880.00 KiB | 1.71 MiB/s
Receiving objects: 1% (17963/917474), 880.00 KiB | 1.71 MiB/s
Receiving objects: 2% (18350/917474), 3.98 MiB | 3.96 MiB/s
Receiving objects: 3% (27525/917474), 3.98 MiB | 3.96 MiB/s
Receiving objects: 4% (36699/917474), 8.67 MiB | 5.75 MiB/s
Receiving objects: 5% (45874/917474), 8.67 MiB | 5.75 MiB/s
Receiving objects: 5% (47616/917474), 8.67 MiB | 5.75 MiB/s
Receiving objects: 6% (55049/917474), 11.76 MiB | 5.84 MiB/s
Receiving objects: 7% (64224/917474), 11.76 MiB | 5.84 MiB/s
Receiving objects: 8% (73398/917474), 11.76 MiB | 5.84 MiB/s
Receiving objects: 9% (82573/917474), 11.76 MiB | 5.84 MiB/s
Receiving objects: 10% (91748/917474), 14.57 MiB | 5.79 MiB/s
Receiving objects: 11% (100923/917474), 14.57 MiB | 5.79 MiB/s
Receiving objects: 12% (110097/917474), 14.57 MiB | 5.79 MiB/s
Receiving objects: 13% (119272/917474), 14.57 MiB | 5.79 MiB/s
Receiving objects: 14% (128447/917474), 14.57 MiB | 5.79 MiB/s
Receiving objects: 15% (137622/917474), 14.57 MiB | 5.79 MiB/s
Receiving objects: 16% (146796/917474), 14.57 MiB | 5.79 MiB/s
Receiving objects: 17% (155971/917474), 14.57 MiB | 5.79 MiB/s
Receiving objects: 18% (165146/917474), 14.57 MiB | 5.79 MiB/s
Receiving objects: 18% (173172/917474), 14.57 MiB | 5.79 MiB/s
Receiving objects: 19% (174321/917474), 14.57 MiB | 5.79 MiB/s
Receiving objects: 20% (183495/917474), 20.42 MiB | 6.77 MiB/s
Receiving objects: 21% (192670/917474), 20.42 MiB | 6.77 MiB/s
Receiving objects: 22% (201845/917474), 20.42 MiB | 6.77 MiB/s
Receiving objects: 23% (211020/917474), 20.42 MiB | 6.77 MiB/s
Receiving objects: 24% (220194/917474), 20.42 MiB | 6.77 MiB/s
Receiving objects: 25% (229369/917474), 20.42 MiB | 6.77 MiB/s
Receiving objects: 26% (238544/917474), 23.80 MiB | 6.77 MiB/s
Receiving objects: 27% (247718/917474), 23.80 MiB | 6.77 MiB/s
Receiving objects: 27% (256142/917474), 23.80 MiB | 6.77 MiB/s
Receiving objects: 28% (256893/917474), 23.80 MiB | 6.77 MiB/s
Receiving objects: 29% (266068/917474), 25.54 MiB | 6.35 MiB/s
Receiving objects: 30% (275243/917474), 25.54 MiB | 6.35 MiB/s
Receiving objects: 31% (284417/917474), 25.54 MiB | 6.35 MiB/s
Receiving objects: 32% (293592/917474), 25.54 MiB | 6.35 MiB/s
Receiving objects: 33% (302767/917474), 25.54 MiB | 6.35 MiB/s
Receiving objects: 34% (311942/917474), 28.73 MiB | 6.34 MiB/s
Receiving objects: 35% (321116/917474), 28.73 MiB | 6.34 MiB/s
Receiving objects: 36% (330291/917474), 28.73 MiB | 6.34 MiB/s
Receiving objects: 37% (339466/917474), 28.73 MiB | 6.34 MiB/s
Receiving objects: 38% (348641/917474), 28.73 MiB | 6.34 MiB/s
Receiving objects: 38% (351452/917474), 28.73 MiB | 6.34 MiB/s
Receiving objects: 39% (357815/917474), 31.95 MiB | 6.86 MiB/s
Receiving objects: 40% (366990/917474), 31.95 MiB | 6.86 MiB/s
Receiving objects: 41% (376165/917474), 31.95 MiB | 6.86 MiB/s
Receiving objects: 41% (384377/917474), 37.57 MiB | 6.38 MiB/s
Receiving objects: 42% (385340/917474), 37.57 MiB | 6.38 MiB/s
Receiving objects: 42% (386813/917474), 39.64 MiB | 6.06 MiB/s
Receiving objects: 42% (387694/917474), 46.22 MiB | 4.77 MiB/s
Receiving objects: 42% (389150/917474), 49.88 MiB | 4.46 MiB/s
Receiving objects: 42% (392270/917474), 52.22 MiB | 4.24 MiB/s
Receiving objects: 43% (394514/917474), 52.22 MiB | 4.24 MiB/s
Receiving objects: 44% (403689/917474), 54.60 MiB | 4.00 MiB/s
Receiving objects: 44% (409787/917474), 56.70 MiB | 3.97 MiB/s
Receiving objects: 45% (412864/917474), 56.70 MiB | 3.97 MiB/s
Receiving objects: 46% (422039/917474), 59.07 MiB | 4.06 MiB/s
Receiving objects: 46% (430518/917474), 61.49 MiB | 3.98 MiB/s
Receiving objects: 47% (431213/917474), 61.49 MiB | 3.98 MiB/s
Receiving objects: 48% (440388/917474), 63.06 MiB | 3.98 MiB/s
Receiving objects: 49% (449563/917474), 65.45 MiB | 4.08 MiB/s
Receiving objects: 49% (449740/917474), 65.45 MiB | 4.08 MiB/s
Receiving objects: 49% (455882/917474), 69.64 MiB | 4.20 MiB/s
Receiving objects: 50% (458737/917474), 69.64 MiB | 4.20 MiB/s
Receiving objects: 50% (460393/917474), 74.57 MiB | 4.23 MiB/s
Receiving objects: 51% (467912/917474), 77.13 MiB | 4.31 MiB/s
Receiving objects: 51% (468479/917474), 79.10 MiB | 4.19 MiB/s
fatal: The remote end hung up unexpectedly
fatal: early EOF
fatal: index-pack failed
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.launchCommandIn(CliGitAPIImpl.java:1924)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.launchCommandWithCredentials(CliGitAPIImpl.java:1643)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.access$300(CliGitAPIImpl.java:71)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl$1.execute(CliGitAPIImpl.java:352)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl$2.execute(CliGitAPIImpl.java:559)
at org.jenkinsci.plugins.gitclient.RemoteGitImpl$CommandInvocationHandler$1.call(RemoteGitImpl.java:153)
at org.jenkinsci.plugins.gitclient.RemoteGitImpl$CommandInvocationHandler$1.call(RemoteGitImpl.java:146)
at hudson.remoting.UserRequest.perform(UserRequest.java:181)
at hudson.remoting.UserRequest.perform(UserRequest.java:52)
at hudson.remoting.Request$2.run(Request.java:336)
at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:68)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:748)
at ......remote call to lucene(Native Method)
at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1554)
at hudson.remoting.UserResponse.retrieve(UserRequest.java:281)
at hudson.remoting.Channel.call(Channel.java:839)
at org.jenkinsci.plugins.gitclient.RemoteGitImpl$CommandInvocationHandler.execute(RemoteGitImpl.java:146)
at sun.reflect.GeneratedMethodAccessor883.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.jenkinsci.plugins.gitclient.RemoteGitImpl$CommandInvocationHandler.invoke(RemoteGitImpl.java:132)
at com.sun.proxy.$Proxy141.execute(Unknown Source)
at hudson.plugins.git.GitSCM.retrieveChanges(GitSCM.java:1075)
at hudson.plugins.git.GitSCM.checkout(GitSCM.java:1115)
at org.jenkinsci.plugins.multiplescms.MultiSCM.checkout(MultiSCM.java:143)
at hudson.scm.SCM.checkout(SCM.java:495)
at hudson.model.AbstractProject.checkout(AbstractProject.java:1212)
at hudson.model.AbstractBuild$AbstractBuildExecution.defaultCheckout(AbstractBuild.java:566)
at jenkins.scm.SCMCheckoutStrategy.checkout(SCMCheckoutStrategy.java:86)
at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:491)
at hudson.model.Run.execute(Run.java:1737)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:419)
ERROR: Error cloning remote repo 'origin'
Retrying after 10 seconds
> git rev-parse --is-inside-work-tree # timeout=10
Fetching changes from the remote Git repository
> git config remote.origin.url git://git.apache.org/lucene-solr.git # timeout=10
Fetching upstream changes from git://git.apache.org/lucene-solr.git
> git --version # timeout=10
> git fetch --tags --progress git://git.apache.org/lucene-solr.git +refs/heads/*:refs/remotes/origin/*
> git rev-parse refs/remotes/origin/branch_7_1^{commit} # timeout=10
> git rev-parse refs/remotes/origin/origin/branch_7_1^{commit} # timeout=10
Checking out Revision 04393fccbf336a0cbe92d2596fc6bc06d1028630 (refs/remotes/origin/branch_7_1)
Commit message: "remove unreleased/unsupported java9 note"
> git config core.sparsecheckout # timeout=10
> git checkout -f 04393fccbf336a0cbe92d2596fc6bc06d1028630
> git rev-list aed571cdfba081e5eb6ec1854ef816ece6385c84 # timeout=10
Cleaning workspace
> git rev-parse --verify HEAD # timeout=10
Resetting working tree
> git reset --hard # timeout=10
> git clean -fdx # timeout=10
Checking out a fresh workspace because /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/test-data doesn't exist
Cleaning local Directory test-data
Checking out http://svn.apache.org/repos/asf/lucene/test-data at revision '2017-10-14T21:09:40.552 +0000'
A README.txt
A enwiki.random.lines.txt
At revision 1812206
No emails were triggered.
[checkout] $ /home/jenkins/tools/ant/apache-ant-1.8.4/bin/ant -file build.xml -Dtests.multiplier=2 -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/test-data/enwiki.random.lines.txt jenkins-nightly
Buildfile: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/build.xml
jenkins-nightly:
-print-java-info:
[java-info] java version "1.8.0_144"
[java-info] Java(TM) SE Runtime Environment (1.8.0_144-b01, Oracle Corporation)
[java-info] Java HotSpot(TM) 64-Bit Server VM (25.144-b01, Oracle Corporation)
[java-info] Test args: []
clean:
clean:
clean:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: Apache Ivy 2.3.0 - 20130110142753 :: http://ant.apache.org/ivy/ ::
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/top-level-ivy-settings.xml
resolve-groovy:
[ivy:cachepath] :: resolving dependencies :: org.codehaus.groovy#groovy-all-caller;working
[ivy:cachepath] confs: [default]
[ivy:cachepath] found org.codehaus.groovy#groovy-all;2.4.12 in public
[ivy:cachepath] :: resolution report :: resolve 1118ms :: artifacts dl 19ms
---------------------------------------------------------------------
| | modules || artifacts |
| conf | number| search|dwnlded|evicted|| number|dwnlded|
---------------------------------------------------------------------
| default | 1 | 0 | 0 | 0 || 1 | 0 |
---------------------------------------------------------------------
-test-with-heapdumps-enabled:
[echo] Java HotSpot(TM) 64-Bit Server VM: Enabling heap dumps on OutOfMemoryError to dir '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/heapdumps'.
[mkdir] Created dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/heapdumps
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/top-level-ivy-settings.xml
resolve-groovy:
test:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/top-level-ivy-settings.xml
-clover.load:
resolve-groovy:
-init-totals:
test-core:
-clover.disable:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/top-level-ivy-settings.xml
-clover.load:
-clover.classpath:
-clover.setup:
clover:
-check-git-state:
-git-cleanroot:
-copy-git-state:
[copy] Copying 1 file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build
git-autoclean:
resolve:
init:
compile-core:
[mkdir] Created dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/classes/java
[javac] Compiling 824 source files to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/classes/java
[javac] Note: Some input files use or override a deprecated API.
[javac] Note: Recompile with -Xlint:deprecation for details.
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/classes/java/org/apache/lucene/util/graph/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/classes/java/org/apache/lucene/document/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/classes/java/org/apache/lucene/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/classes/java/org/apache/lucene/util/bkd/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/classes/java/org/apache/lucene/codecs/compressing/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/classes/java/org/apache/lucene/codecs/lucene60/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/classes/java/org/apache/lucene/codecs/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/classes/java/org/apache/lucene/util/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/classes/java/org/apache/lucene/index/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/classes/java/org/apache/lucene/util/fst/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/classes/java/org/apache/lucene/analysis/tokenattributes/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/classes/java/org/apache/lucene/search/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/classes/java/org/apache/lucene/codecs/lucene62/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/classes/java/org/apache/lucene/util/packed/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/classes/java/org/apache/lucene/util/mutable/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/classes/java/org/apache/lucene/codecs/lucene70/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/classes/java/org/apache/lucene/codecs/perfield/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/classes/java/org/apache/lucene/analysis/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/classes/java/org/apache/lucene/analysis/standard/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/classes/java/org/apache/lucene/store/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/classes/java/org/apache/lucene/codecs/blocktree/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/classes/java/org/apache/lucene/search/spans/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/classes/java/org/apache/lucene/search/similarities/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/classes/java/org/apache/lucene/util/automaton/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/classes/java/org/apache/lucene/codecs/lucene50/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/classes/java/org/apache/lucene/geo/package-info.class
[copy] Copying 3 files to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/classes/java
compile-test-framework:
-check-git-state:
-git-cleanroot:
-copy-git-state:
git-autoclean:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/top-level-ivy-settings.xml
resolve:
init:
compile-lucene-core:
-check-git-state:
-git-cleanroot:
-copy-git-state:
git-autoclean:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/top-level-ivy-settings.xml
resolve:
init:
-clover.disable:
-clover.load:
-clover.classpath:
-clover.setup:
clover:
compile-core:
compile-codecs:
-check-git-state:
-git-cleanroot:
-copy-git-state:
git-autoclean:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/top-level-ivy-settings.xml
resolve:
common.init:
compile-lucene-core:
init:
-clover.disable:
-clover.load:
-clover.classpath:
-clover.setup:
clover:
compile-core:
[mkdir] Created dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/codecs/classes/java
[javac] Compiling 67 source files to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/codecs/classes/java
[javac] Note: Some input files use or override a deprecated API.
[javac] Note: Recompile with -Xlint:deprecation for details.
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/codecs/classes/java/org/apache/lucene/codecs/bloom/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/codecs/classes/java/org/apache/lucene/codecs/memory/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/codecs/classes/java/org/apache/lucene/codecs/simpletext/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/codecs/classes/java/org/apache/lucene/codecs/blockterms/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/codecs/classes/java/org/apache/lucene/codecs/blocktreeords/package-info.class
[copy] Copying 3 files to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/codecs/classes/java
-clover.disable:
-clover.load:
-clover.classpath:
-clover.setup:
clover:
common.compile-core:
[mkdir] Created dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/test-framework/classes/java
[javac] Compiling 188 source files to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/test-framework/classes/java
[javac] Note: Some input files use or override a deprecated API.
[javac] Note: Recompile with -Xlint:deprecation for details.
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/test-framework/classes/java/org/apache/lucene/codecs/asserting/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/test-framework/classes/java/org/apache/lucene/codecs/ramonly/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/test-framework/classes/java/org/apache/lucene/mockfile/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/test-framework/classes/java/org/apache/lucene/codecs/cheapbastard/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/test-framework/classes/java/org/apache/lucene/codecs/mockrandom/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/test-framework/classes/java/org/apache/lucene/codecs/cranky/package-info.class
[javac] Creating empty /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/test-framework/classes/java/org/apache/lucene/codecs/compressing/dummy/package-info.class
[copy] Copying 4 files to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/test-framework/classes/java
compile-core:
compile-test:
[mkdir] Created dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/classes/test
[javac] Compiling 462 source files to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/classes/test
[javac] Note: Some input files use or override a deprecated API.
[javac] Note: Recompile with -Xlint:deprecation for details.
[copy] Copying 3 files to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/classes/test
install-junit4-taskdef:
validate:
resolve-groovy:
-init-totals:
-test:
[mkdir] Created dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/test
[mkdir] Created dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/build/core/test/temp
[mkdir] Created dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/.caches/test-stats/core
[junit4] <JUnit4> says hi! Master seed: 7562A2CCB9DE1578
[junit4] Executing 455 suites with 3 JVMs.
[junit4]
[junit4] Started J2 PID(11269@localhost).
[junit4] Started J1 PID(11256@localhost).
[junit4] Started J0 PID(11270@localhost).
[junit4] Suite: org.apache.lucene.search.TestSearchWithThreads
[junit4] Completed [1/455] on J2 in 59.04s, 1 test
[junit4]
[junit4] Suite: org.apache.lucene.index.TestSortedSetDocValues
[junit4] Completed [2/455] on J2 in 0.26s, 1 test
[junit4]
[junit4] Suite: org.apache.lucene.index.Test2BPositions
[junit4] IGNOR/A 0.07s J2 | Test2BPositions.test
[junit4] > Assumption #1: 'monster' test group is disabled (@Monster(value=uses lots of space and takes a few minutes))
[junit4] Completed [3/455] on J2 in 0.10s, 1 test, 1 skipped
[junit4]
[junit4] Suite: org.apache.lucene.search.TestFilterWeight
[junit4] Completed [4/455] on J2 in 0.43s, 1 test
[junit4]
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T21:20:58, stalled for 71.6s at: TestIndexingSequenceNumbers.testStressUpdateSameID
[junit4] Suite: org.apache.lucene.index.TestMultiDocValues
[junit4] Completed [5/455] on J1 in 86.57s, 7 tests
[junit4]
[junit4] Suite: org.apache.lucene.index.TestMaxPosition
[junit4] Completed [6/455] on J1 in 6.12s, 2 tests
[junit4]
[junit4] Suite: org.apache.lucene.index.TestIndexWriterMergePolicy
[junit4] Completed [7/455] on J2 in 48.03s, 7 tests
[junit4]
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T21:21:58, stalled for 132s at: TestIndexingSequenceNumbers.testStressUpdateSameID
[junit4] Suite: org.apache.lucene.store.TestNativeFSLockFactory
[junit4] Completed [8/455] on J1 in 62.20s, 11 tests
[junit4]
[junit4] Suite: org.apache.lucene.store.TestByteArrayDataInput
[junit4] Completed [9/455] on J1 in 0.27s, 1 test
[junit4]
[junit4] Suite: org.apache.lucene.search.TestPhrasePrefixQuery
[junit4] Completed [10/455] on J1 in 0.91s, 1 test
[junit4]
[junit4] Suite: org.apache.lucene.search.TestElevationComparator
[junit4] Completed [11/455] on J1 in 1.29s, 1 test
[junit4]
[junit4] Suite: org.apache.lucene.index.TestFilterLeafReader
[junit4] Completed [12/455] on J1 in 0.75s, 3 tests
[junit4]
[junit4] Suite: org.apache.lucene.search.spans.TestSpanFirstQuery
[junit4] Completed [13/455] on J1 in 0.83s, 1 test
[junit4]
[junit4] Suite: org.apache.lucene.index.TestDocInverterPerFieldErrorInfo
[junit4] Completed [14/455] on J1 in 1.48s, 2 tests
[junit4]
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T21:22:35, stalled for 68.2s at: TestLucene70NormsFormat.testFullLongRange
[junit4] Suite: org.apache.lucene.index.TestAtomicUpdate
[junit4] Completed [15/455] on J1 in 20.12s, 1 test
[junit4]
[junit4] Suite: org.apache.lucene.util.TestRoaringDocIdSet
[junit4] Completed [16/455] on J1 in 3.06s, 5 tests
[junit4]
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T21:22:58, stalled for 192s at: TestIndexingSequenceNumbers.testStressUpdateSameID
[junit4] HEARTBEAT J1 PID(11256@localhost): 2017-10-14T21:23:48, stalled for 61.6s at: TestLongBitSet.testSmall
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T21:24:00, stalled for 71.5s at: TestLucene70NormsFormat.testOutliers2
[junit4] Suite: org.apache.lucene.util.TestLongBitSet
[junit4] Completed [17/455] on J1 in 123.51s, 11 tests
[junit4]
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T21:24:47, stalled for 64.8s at: TestIndexingSequenceNumbers.testStressConcurrentDocValuesUpdatesCommit
[junit4] Suite: org.apache.lucene.index.TestFieldInvertState
[junit4] Completed [18/455] on J1 in 1.67s, 2 tests
[junit4]
[junit4] Suite: org.apache.lucene.search.TestPhraseQuery
[junit4] Completed [19/455] on J1 in 19.75s, 21 tests
[junit4]
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T21:26:36, stalled for 60.9s at: TestIndexingSequenceNumbers.testStressConcurrentAddAndDeleteAndCommit
[junit4] HEARTBEAT J1 PID(11256@localhost): 2017-10-14T21:27:12, stalled for 68.0s at: TestLucene50StoredFieldsFormat.testBigDocuments
[junit4] Suite: org.apache.lucene.index.TestIndexingSequenceNumbers
[junit4] Completed [20/455] on J0 in 471.69s, 8 tests
[junit4]
[junit4] Suite: org.apache.lucene.codecs.lucene50.TestLucene50StoredFieldsFormat
[junit4] Completed [21/455] on J1 in 152.44s, 17 tests
[junit4]
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T21:28:01, stalled for 63.6s at: TestLucene70NormsFormat.testFewSparseValues
[junit4] Suite: org.apache.lucene.index.TestIndexWriterWithThreads
[junit4] IGNOR/A 0.23s J0 | TestIndexWriterWithThreads.testOpenTwoIndexWritersOnDifferentThreads
[junit4] > Assumption #1: aborting test: timeout obtaining lock
[junit4] Completed [22/455] on J0 in 28.92s, 12 tests, 1 skipped
[junit4]
[junit4] Suite: org.apache.lucene.search.spans.TestSpanNearQuery
[junit4] Completed [23/455] on J0 in 0.69s, 4 tests
[junit4]
[junit4] Suite: org.apache.lucene.search.similarities.TestBooleanSimilarity
[junit4] Completed [24/455] on J0 in 0.85s, 3 tests
[junit4]
[junit4] HEARTBEAT J1 PID(11256@localhost): 2017-10-14T21:28:50, stalled for 69.2s at: TestDocValuesQueries.testDuelPointRangeMultivaluedSortedNumericRangeQuery
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T21:29:13, stalled for 67.6s at: TestLucene70NormsFormat.testNCommonBig
[junit4] Suite: org.apache.lucene.search.TestLongValuesSource
[junit4] Completed [25/455] on J0 in 69.60s, 4 tests
[junit4]
[junit4] Suite: org.apache.lucene.search.TestConstantScoreQuery
[junit4] Completed [26/455] on J0 in 3.07s, 5 tests
[junit4]
[junit4] Suite: org.apache.lucene.index.TestForceMergeForever
[junit4] Completed [27/455] on J0 in 8.08s, 1 test
[junit4]
[junit4] Suite: org.apache.lucene.search.TestTotalHitCountCollector
[junit4] Completed [28/455] on J0 in 0.37s, 1 test
[junit4]
[junit4] Suite: org.apache.lucene.index.TestDocsAndPositions
[junit4] Completed [29/455] on J0 in 8.93s, 6 tests
[junit4]
[junit4] Suite: org.apache.lucene.index.TestCodecHoldsOpenFiles
[junit4] Completed [30/455] on J0 in 1.40s, 1 test
[junit4]
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T21:30:13, stalled for 128s at: TestLucene70NormsFormat.testNCommonBig
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T21:30:39, stalled for 62.8s at: TestPagedBytes.testDataInputOutput
[junit4] Suite: org.apache.lucene.util.TestPagedBytes
[junit4] Completed [31/455] on J0 in 78.39s, 4 tests, 1 skipped
[junit4]
[junit4] Suite: org.apache.lucene.util.TestFixedLengthBytesRefArray
[junit4] Completed [32/455] on J0 in 2.23s, 2 tests
[junit4]
[junit4] Suite: org.apache.lucene.index.TestFlex
[junit4] Completed [33/455] on J0 in 1.57s, 2 tests
[junit4]
[junit4] Suite: org.apache.lucene.search.TestMatchAllDocsQuery
[junit4] Completed [34/455] on J0 in 0.39s, 2 tests
[junit4]
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T21:31:13, stalled for 188s at: TestLucene70NormsFormat.testNCommonBig
[junit4] Suite: org.apache.lucene.codecs.lucene70.TestIndexedDISI
[junit4] Completed [35/455] on J0 in 46.05s, 10 tests
[junit4]
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T21:32:14, stalled for 248s at: TestLucene70NormsFormat.testNCommonBig
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T21:32:52, stalled for 66.7s at: TestBooleanScorer.testSparseClauseOptimization
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T21:33:14, stalled for 309s at: TestLucene70NormsFormat.testNCommonBig
[junit4] Suite: org.apache.lucene.search.TestDocValuesQueries
[junit4] Completed [36/455] on J1 in 351.99s, 10 tests
[junit4]
[junit4] Suite: org.apache.lucene.index.TestDocumentsWriterDeleteQueue
[junit4] Completed [37/455] on J1 in 0.59s, 5 tests
[junit4]
[junit4] Suite: org.apache.lucene.store.TestRAMDirectory
[junit4] IGNOR/A 0.04s J1 | TestRAMDirectory.testPendingDeletions
[junit4] > Assumption #1: we can only install VirusCheckingFS on an FSDirectory
[junit4] IGNOR/A 0.04s J1 | TestRAMDirectory.testFsyncDoesntCreateNewFiles
[junit4] > Assumption #1: test only works for FSDirectory subclasses
[junit4] Completed [38/455] on J1 in 8.40s, 46 tests, 2 skipped
[junit4]
[junit4] Suite: org.apache.lucene.search.TestMatchNoDocsQuery
[junit4] Completed [39/455] on J1 in 0.04s, 3 tests
[junit4]
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T21:33:52, stalled for 127s at: TestBooleanScorer.testSparseClauseOptimization
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T21:34:14, stalled for 369s at: TestLucene70NormsFormat.testNCommonBig
[junit4] Suite: org.apache.lucene.index.TestConcurrentMergeScheduler
[junit4] Completed [40/455] on J1 in 41.74s, 16 tests
[junit4]
[junit4] Suite: org.apache.lucene.search.TestBooleanScorer
[junit4] Completed [41/455] on J0 in 167.26s, 5 tests
[junit4]
[junit4] Suite: org.apache.lucene.index.TestDocsWithFieldSet
[junit4] Completed [42/455] on J0 in 0.44s, 3 tests
[junit4]
[junit4] Suite: org.apache.lucene.search.spans.TestSpanTermQuery
[junit4] Completed [43/455] on J0 in 0.18s, 2 tests
[junit4]
[junit4] Suite: org.apache.lucene.search.TestComplexExplanations
[junit4] Completed [44/455] on J1 in 14.09s, 21 tests
[junit4]
[junit4] Suite: org.apache.lucene.index.TestNoMergeScheduler
[junit4] Completed [45/455] on J1 in 0.12s, 3 tests
[junit4]
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T21:35:15, stalled for 429s at: TestLucene70NormsFormat.testNCommonBig
[junit4] Suite: org.apache.lucene.index.TestByteSlices
[junit4] Completed [46/455] on J1 in 52.52s, 1 test
[junit4]
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T21:35:40, stalled for 66.0s at: TestFloatRangeFieldQueries.testRandomMedium
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T21:36:16, stalled for 491s at: TestLucene70NormsFormat.testNCommonBig
[junit4] HEARTBEAT J1 PID(11256@localhost): 2017-10-14T21:36:31, stalled for 60.0s at: TestBlockPostingsFormat3.test
[junit4] Suite: org.apache.lucene.codecs.lucene50.TestBlockPostingsFormat3
[junit4] Completed [47/455] on J1 in 103.01s, 1 test
[junit4]
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T21:37:17, stalled for 552s at: TestLucene70NormsFormat.testNCommonBig
[junit4] Suite: org.apache.lucene.util.automaton.TestLevenshteinAutomata
[junit4] Completed [48/455] on J1 in 22.72s, 4 tests
[junit4]
[junit4] Suite: org.apache.lucene.search.TestPositiveScoresOnlyCollector
[junit4] Completed [49/455] on J1 in 0.16s, 1 test
[junit4]
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T21:38:05, stalled for 63.2s at: TestFloatRangeFieldQueries.testRandomBig
[junit4] Suite: org.apache.lucene.index.TestNorms
[junit4] Completed [50/455] on J1 in 40.29s, 2 tests
[junit4]
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T21:38:17, stalled for 612s at: TestLucene70NormsFormat.testNCommonBig
[junit4] Suite: org.apache.lucene.index.TestParallelLeafReader
[junit4] Completed [51/455] on J1 in 1.27s, 9 tests
[junit4]
[junit4] Suite: org.apache.lucene.codecs.TestCodecUtil
[junit4] Completed [52/455] on J1 in 0.18s, 16 tests
[junit4]
[junit4] Suite: org.apache.lucene.analysis.TestGraphTokenizers
[junit4] Completed [53/455] on J1 in 13.94s, 23 tests
[junit4]
[junit4] Suite: org.apache.lucene.index.TestSegmentInfos
[junit4] Completed [54/455] on J1 in 0.13s, 4 tests
[junit4]
[junit4] Suite: org.apache.lucene.index.TestParallelTermEnum
[junit4] Completed [55/455] on J1 in 0.31s, 1 test
[junit4]
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T21:39:06, stalled for 124s at: TestFloatRangeFieldQueries.testRandomBig
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T21:40:06, stalled for 184s at: TestFloatRangeFieldQueries.testRandomBig
[junit4] HEARTBEAT J1 PID(11256@localhost): 2017-10-14T21:40:59, stalled for 61.9s at: TestFSTs.testRandomWords
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T21:41:06, stalled for 244s at: TestFloatRangeFieldQueries.testRandomBig
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T21:42:06, stalled for 304s at: TestFloatRangeFieldQueries.testRandomBig
[junit4] HEARTBEAT J1 PID(11256@localhost): 2017-10-14T21:42:24, stalled for 60.2s at: TestFSTs.testRealTerms
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T21:43:08, stalled for 366s at: TestFloatRangeFieldQueries.testRandomBig
[junit4] HEARTBEAT J1 PID(11256@localhost): 2017-10-14T21:43:48, stalled for 67.6s at: TestFSTs.testBigSet
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T21:44:08, stalled for 426s at: TestFloatRangeFieldQueries.testRandomBig
[junit4] Suite: org.apache.lucene.util.fst.TestFSTs
[junit4] Completed [56/455] on J1 in 361.48s, 20 tests
[junit4]
[junit4] Suite: org.apache.lucene.index.TestSizeBoundedForceMerge
[junit4] Completed [57/455] on J1 in 0.46s, 11 tests
[junit4]
[junit4] Suite: org.apache.lucene.store.TestLockFactory
[junit4] Completed [58/455] on J1 in 0.18s, 2 tests
[junit4]
[junit4] Suite: org.apache.lucene.search.FuzzyTermOnShortTermsTest
[junit4] Completed [59/455] on J1 in 0.58s, 1 test
[junit4]
[junit4] Suite: org.apache.lucene.index.TestCrash
[junit4] Completed [60/455] on J1 in 5.49s, 5 tests
[junit4]
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T21:45:08, stalled for 486s at: TestFloatRangeFieldQueries.testRandomBig
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T21:45:32, stalled for 68.3s at: TestLucene70NormsFormat.testLongRange
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T21:46:08, stalled for 546s at: TestFloatRangeFieldQueries.testRandomBig
[junit4] HEARTBEAT J1 PID(11256@localhost): 2017-10-14T21:46:25, stalled for 71.6s at: TestIndexSorting.testRandom3
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T21:46:57, stalled for 66.3s at: TestLucene70NormsFormat.testSparseAllZeros
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T21:47:09, stalled for 607s at: TestFloatRangeFieldQueries.testRandomBig
[junit4] Suite: org.apache.lucene.search.TestFloatRangeFieldQueries
[junit4] Completed [61/455] on J0 in 764.40s, 5 tests
[junit4]
[junit4] HEARTBEAT J1 PID(11256@localhost): 2017-10-14T21:47:25, stalled for 132s at: TestIndexSorting.testRandom3
[junit4] Suite: org.apache.lucene.index.TestDeletionPolicy
[junit4] Completed [62/455] on J0 in 9.53s, 6 tests
[junit4]
[junit4] Suite: org.apache.lucene.search.TestBooleanQueryVisitSubscorers
[junit4] Completed [63/455] on J0 in 0.71s, 7 tests
[junit4]
[junit4] Suite: org.apache.lucene.index.TestIntBlockPool
[junit4] Completed [64/455] on J0 in 8.23s, 2 tests
[junit4]
[junit4] Suite: org.apache.lucene.index.TestCodecs
[junit4] Completed [65/455] on J0 in 2.93s, 3 tests
[junit4]
[junit4] HEARTBEAT J1 PID(11256@localhost): 2017-10-14T21:48:25, stalled for 192s at: TestIndexSorting.testRandom3
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T21:49:10, stalled for 71.1s at: TestLucene70NormsFormat.testByteRange
[junit4] HEARTBEAT J1 PID(11256@localhost): 2017-10-14T21:49:25, stalled for 252s at: TestIndexSorting.testRandom3
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T21:49:34, stalled for 62.6s at: TestLucene50TermVectorsFormat.testRamBytesUsed
[junit4] HEARTBEAT J1 PID(11256@localhost): 2017-10-14T21:50:26, stalled for 313s at: TestIndexSorting.testRandom3
[junit4] HEARTBEAT J1 PID(11256@localhost): 2017-10-14T21:51:27, stalled for 374s at: TestIndexSorting.testRandom3
[junit4] Suite: org.apache.lucene.codecs.lucene50.TestLucene50TermVectorsFormat
[junit4] Completed [66/455] on J0 in 235.49s, 17 tests
[junit4]
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T21:51:48, stalled for 65.3s at: TestLucene70NormsFormat.testSparseNCommonBig
[junit4] HEARTBEAT J1 PID(11256@localhost): 2017-10-14T21:52:28, stalled for 435s at: TestIndexSorting.testRandom3
[junit4] Suite: org.apache.lucene.util.automaton.TestAutomaton
[junit4] Completed [67/455] on J0 in 59.33s, 56 tests
[junit4]
[junit4] Suite: org.apache.lucene.search.TestMultiPhraseQuery
[junit4] Completed [68/455] on J0 in 1.19s, 16 tests, 1 skipped
[junit4]
[junit4] Suite: org.apache.lucene.index.TestIsCurrent
[junit4] Completed [69/455] on J0 in 0.11s, 2 tests
[junit4]
[junit4] Suite: org.apache.lucene.index.TestNRTReaderCleanup
[junit4] Completed [70/455] on J0 in 0.17s, 1 test
[junit4]
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T21:52:48, stalled for 125s at: TestLucene70NormsFormat.testSparseNCommonBig
[junit4] Suite: org.apache.lucene.codecs.lucene50.TestBlockPostingsFormat2
[junit4] Completed [71/455] on J0 in 28.58s, 4 tests
[junit4]
[junit4] Suite: org.apache.lucene.analysis.TestWordlistLoader
[junit4] Completed [72/455] on J0 in 0.15s, 3 tests
[junit4]
[junit4] Suite: org.apache.lucene.index.TestPersistentSnapshotDeletionPolicy
[junit4] Completed [73/455] on J0 in 3.41s, 13 tests
[junit4]
[junit4] Suite: org.apache.lucene.index.TestNewestSegment
[junit4] Completed [74/455] on J0 in 0.09s, 1 test
[junit4]
[junit4] Suite: org.apache.lucene.util.bkd.TestMutablePointsReaderUtils
[junit4] Completed [75/455] on J0 in 11.66s, 3 tests
[junit4]
[junit4] HEARTBEAT J1 PID(11256@localhost): 2017-10-14T21:53:28, stalled for 495s at: TestIndexSorting.testRandom3
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T21:53:49, stalled for 187s at: TestLucene70NormsFormat.testSparseNCommonBig
[junit4] Suite: org.apache.lucene.index.TestPostingsOffsets
[junit4] Completed [76/455] on J0 in 53.53s, 13 tests
[junit4]
[junit4] Suite: org.apache.lucene.search.spans.TestSpanMultiTermQueryWrapper
[junit4] Completed [77/455] on J0 in 0.86s, 9 tests
[junit4]
[junit4] Suite: org.apache.lucene.index.TestUniqueTermCount
[junit4] Completed [78/455] on J0 in 0.29s, 1 test
[junit4]
[junit4] Suite: org.apache.lucene.index.TestDirectoryReaderReopen
[junit4] Completed [79/455] on J0 in 5.87s, 14 tests
[junit4]
[junit4] HEARTBEAT J1 PID(11256@localhost): 2017-10-14T21:54:28, stalled for 555s at: TestIndexSorting.testRandom3
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T21:54:49, stalled for 247s at: TestLucene70NormsFormat.testSparseNCommonBig
[junit4] Suite: org.apache.lucene.index.TestIndexWriter
[junit4] Completed [80/455] on J0 in 38.64s, 72 tests, 1 skipped
[junit4]
[junit4] Suite: org.apache.lucene.search.TestSimilarityProvider
[junit4] Completed [81/455] on J0 in 0.12s, 1 test
[junit4]
[junit4] Suite: org.apache.lucene.search.TestBooleanMinShouldMatch
[junit4] Completed [82/455] on J0 in 27.85s, 17 tests
[junit4]
[junit4] HEARTBEAT J1 PID(11256@localhost): 2017-10-14T21:55:29, stalled for 616s at: TestIndexSorting.testRandom3
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T21:55:50, stalled for 307s at: TestLucene70NormsFormat.testSparseNCommonBig
[junit4] HEARTBEAT J1 PID(11256@localhost): 2017-10-14T21:56:29, stalled for 676s at: TestIndexSorting.testRandom3
[junit4] Suite: org.apache.lucene.analysis.standard.TestStandardAnalyzer
[junit4] Completed [83/455] on J0 in 83.71s, 36 tests
[junit4]
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T21:56:51, stalled for 368s at: TestLucene70NormsFormat.testSparseNCommonBig
[junit4] Suite: org.apache.lucene.index.TestFlushByRamOrCountsPolicy
[junit4] Completed [84/455] on J0 in 19.50s, 5 tests
[junit4]
[junit4] Suite: org.apache.lucene.search.TestNGramPhraseQuery
[junit4] Completed [85/455] on J0 in 0.11s, 1 test
[junit4]
[junit4] Suite: org.apache.lucene.document.TestDateTools
[junit4] Completed [86/455] on J0 in 0.36s, 5 tests
[junit4]
[junit4] HEARTBEAT J1 PID(11256@localhost): 2017-10-14T21:57:29, stalled for 736s at: TestIndexSorting.testRandom3
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T21:57:51, stalled for 429s at: TestLucene70NormsFormat.testSparseNCommonBig
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T21:58:15, stalled for 60.9s at: TestSleepingLockWrapper.testObtainConcurrently
[junit4] HEARTBEAT J1 PID(11256@localhost): 2017-10-14T21:58:29, stalled for 796s at: TestIndexSorting.testRandom3
[junit4] Suite: org.apache.lucene.store.TestSleepingLockWrapper
[junit4] Completed [87/455] on J0 in 78.91s, 6 tests
[junit4]
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T21:58:51, stalled for 489s at: TestLucene70NormsFormat.testSparseNCommonBig
[junit4] HEARTBEAT J1 PID(11256@localhost): 2017-10-14T21:59:30, stalled for 857s at: TestIndexSorting.testRandom3
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T21:59:39, stalled for 70.0s at: Test4GBStoredFields.test
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T21:59:51, stalled for 549s at: TestLucene70NormsFormat.testSparseNCommonBig
[junit4] HEARTBEAT J1 PID(11256@localhost): 2017-10-14T22:00:30, stalled for 917s at: TestIndexSorting.testRandom3
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T22:00:39, stalled for 130s at: Test4GBStoredFields.test
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T22:00:51, stalled for 609s at: TestLucene70NormsFormat.testSparseNCommonBig
[junit4] HEARTBEAT J1 PID(11256@localhost): 2017-10-14T22:01:31, stalled for 978s at: TestIndexSorting.testRandom3
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T22:01:40, stalled for 190s at: Test4GBStoredFields.test
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T22:01:52, stalled for 669s at: TestLucene70NormsFormat.testSparseNCommonBig
[junit4] HEARTBEAT J1 PID(11256@localhost): 2017-10-14T22:02:31, stalled for 1038s at: TestIndexSorting.testRandom3
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T22:02:41, stalled for 252s at: Test4GBStoredFields.test
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T22:02:53, stalled for 730s at: TestLucene70NormsFormat.testSparseNCommonBig
[junit4] HEARTBEAT J1 PID(11256@localhost): 2017-10-14T22:03:32, stalled for 1099s at: TestIndexSorting.testRandom3
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T22:03:41, stalled for 312s at: Test4GBStoredFields.test
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T22:03:53, stalled for 790s at: TestLucene70NormsFormat.testSparseNCommonBig
[junit4] Suite: org.apache.lucene.codecs.lucene70.TestLucene70NormsFormat
[junit4] IGNOR/A 0.00s J2 | TestLucene70NormsFormat.testMergeStability
[junit4] > Assumption #1: The MockRandom PF randomizes content on the fly, so we can't check it
[junit4] Completed [88/455] on J2 in 2581.99s, 29 tests, 1 skipped
[junit4]
[junit4] HEARTBEAT J1 PID(11256@localhost): 2017-10-14T22:04:32, stalled for 1159s at: TestIndexSorting.testRandom3
[junit4] Suite: org.apache.lucene.index.TestIndexManyDocuments
[junit4] Completed [89/455] on J2 in 4.04s, 1 test
[junit4]
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T22:04:41, stalled for 372s at: Test4GBStoredFields.test
[junit4] Suite: org.apache.lucene.store.TestMmapDirectory
[junit4] Completed [90/455] on J2 in 21.49s, 43 tests, 1 skipped
[junit4]
[junit4] Suite: org.apache.lucene.util.TestBytesRefHash
[junit4] Completed [91/455] on J2 in 15.37s, 8 tests
[junit4]
[junit4] Suite: org.apache.lucene.search.TestShardSearching
[junit4] Completed [92/455] on J2 in 18.80s, 1 test
[junit4]
[junit4] HEARTBEAT J1 PID(11256@localhost): 2017-10-14T22:05:33, stalled for 1220s at: TestIndexSorting.testRandom3
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T22:05:42, stalled for 433s at: Test4GBStoredFields.test
[junit4] Suite: org.apache.lucene.index.TestMixedCodecs
[junit4] Completed [93/455] on J2 in 23.72s, 1 test
[junit4]
[junit4] HEARTBEAT J1 PID(11256@localhost): 2017-10-14T22:06:33, stalled for 1280s at: TestIndexSorting.testRandom3
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T22:06:42, stalled for 493s at: Test4GBStoredFields.test
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T22:07:31, stalled for 71.7s at: TestLucene60PointsFormat.testRandomBinaryBig
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T22:07:43, stalled for 554s at: Test4GBStoredFields.test
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T22:08:31, stalled for 132s at: TestLucene60PointsFormat.testRandomBinaryBig
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T22:08:43, stalled for 614s at: Test4GBStoredFields.test
[junit4] Suite: org.apache.lucene.index.TestIndexSorting
[junit4] Completed [94/455] on J1 in 1484.51s, 50 tests
[junit4]
[junit4] Suite: org.apache.lucene.search.TestSynonymQuery
[junit4] Completed [95/455] on J1 in 0.31s, 4 tests
[junit4]
[junit4] Suite: org.apache.lucene.search.TestBoostQuery
[junit4] Completed [96/455] on J1 in 0.17s, 3 tests
[junit4]
[junit4] Suite: org.apache.lucene.analysis.TestReusableStringReader
[junit4] Completed [97/455] on J1 in 0.14s, 1 test
[junit4]
[junit4] Suite: org.apache.lucene.index.TestFilterDirectoryReader
[junit4] Completed [98/455] on J1 in 0.18s, 1 test
[junit4]
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T22:09:44, stalled for 674s at: Test4GBStoredFields.test
[junit4] Suite: org.apache.lucene.search.TestRegexpRandom2
[junit4] Completed [99/455] on J1 in 41.59s, 1 test
[junit4]
[junit4] Suite: org.apache.lucene.codecs.lucene60.TestLucene60PointsFormat
[junit4] IGNOR/A 0.00s J2 | TestLucene60PointsFormat.testMergeStability
[junit4] > Assumption #1: TODO: mess with the parameters and test gets angry!
[junit4] Completed [100/455] on J2 in 262.33s, 20 tests, 1 skipped
[junit4]
[junit4] Suite: org.apache.lucene.index.TestIndexCommit
[junit4] Completed [101/455] on J2 in 0.17s, 1 test
[junit4]
[junit4] Suite: org.apache.lucene.search.similarities.TestBM25Similarity
[junit4] Completed [102/455] on J2 in 0.75s, 3 tests
[junit4]
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T22:10:44, stalled for 734s at: Test4GBStoredFields.test
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T22:11:44, stalled for 795s at: Test4GBStoredFields.test
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T22:12:44, stalled for 855s at: Test4GBStoredFields.test
[junit4] Suite: org.apache.lucene.search.TestSloppyPhraseQuery2
[junit4] Completed [103/455] on J2 in 156.65s, 9 tests
[junit4]
[junit4] Suite: org.apache.lucene.util.TestRollingBuffer
[junit4] Completed [104/455] on J2 in 1.64s, 1 test
[junit4]
[junit4] Suite: org.apache.lucene.util.bkd.TestDocIdsWriter
[junit4] Completed [105/455] on J2 in 38.48s, 2 tests
[junit4]
[junit4] Suite: org.apache.lucene.index.TestIndexWriterCommit
[junit4] IGNOR/A 0.04s J2 | TestIndexWriterCommit.testCommitOnCloseDiskUsage
[junit4] > Assumption #1: This test cannot run with Memory codec
[junit4] Completed [106/455] on J2 in 8.88s, 14 tests, 1 skipped
[junit4]
[junit4] Suite: org.apache.lucene.analysis.TestStopFilter
[junit4] Completed [107/455] on J2 in 0.22s, 4 tests
[junit4]
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T22:13:44, stalled for 915s at: Test4GBStoredFields.test
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T22:14:45, stalled for 976s at: Test4GBStoredFields.test
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T22:14:45, stalled for 60.3s at: TestEarlyTerminatingSortingCollector.testTerminatedEarly
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T22:15:45, stalled for 1036s at: Test4GBStoredFields.test
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T22:15:45, stalled for 120s at: TestEarlyTerminatingSortingCollector.testTerminatedEarly
[junit4] HEARTBEAT J1 PID(11256@localhost): 2017-10-14T22:15:50, stalled for 65.0s at: TestPerFieldDocValuesFormat.testRandomAdvanceNumeric
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T22:16:45, stalled for 1096s at: Test4GBStoredFields.test
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T22:17:09, stalled for 70.8s at: TestEarlyTerminatingSortingCollector.testEarlyTermination
[junit4] Suite: org.apache.lucene.search.TestEarlyTerminatingSortingCollector
[junit4] Completed [108/455] on J2 in 240.44s, 4 tests
[junit4]
[junit4] Suite: org.apache.lucene.store.TestSingleInstanceLockFactory
[junit4] IGNOR/A 0.00s J2 | TestSingleInstanceLockFactory.testStressLocks
[junit4] > Assumption #1: cannot handle buggy Files.delete
[junit4] Completed [109/455] on J2 in 0.75s, 7 tests, 1 skipped
[junit4]
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T22:17:45, stalled for 1156s at: Test4GBStoredFields.test
[junit4] Suite: org.apache.lucene.search.TestSimpleExplanationsOfNonMatches
[junit4] Completed [110/455] on J2 in 2.18s, 70 tests
[junit4]
[junit4] Suite: org.apache.lucene.store.TestMultiMMap
[junit4] Completed [111/455] on J2 in 48.18s, 54 tests
[junit4]
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T22:18:45, stalled for 1216s at: Test4GBStoredFields.test
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T22:19:45, stalled for 1276s at: Test4GBStoredFields.test
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T22:20:45, stalled for 1336s at: Test4GBStoredFields.test
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T22:21:09, stalled for 60.3s at: TestPointQueries.testRandomBinaryBig
[junit4] Suite: org.apache.lucene.codecs.perfield.TestPerFieldDocValuesFormat
[junit4] Completed [112/455] on J1 in 681.71s, 110 tests
[junit4]
[junit4] Suite: org.apache.lucene.util.TestFilterIterator
[junit4] Completed [113/455] on J1 in 2.37s, 8 tests
[junit4]
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T22:21:45, stalled for 1396s at: Test4GBStoredFields.test
[junit4] HEARTBEAT J1 PID(11256@localhost): 2017-10-14T22:22:39, stalled for 66.9s at: Test2BPostings.test
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T22:22:45, stalled for 1456s at: Test4GBStoredFields.test
[junit4] HEARTBEAT J2 PID(11269@localhost): 2017-10-14T22:23:21, stalled for 66.0s at: TestPointQueries.testRandomLongsBig
[junit4] Suite: org.apache.lucene.search.TestPointQueries
[junit4] Completed [114/455] on J2 in 291.22s, 49 tests
[junit4]
[junit4] Suite: org.apache.lucene.index.TestMultiLevelSkipList
[junit4] Completed [115/455] on J2 in 1.05s, 1 test
[junit4]
[junit4] HEARTBEAT J1 PID(11256@localhost): 2017-10-14T22:23:40, stalled for 127s at: Test2BPostings.test
[junit4] HEARTBEAT J0 PID(11270@localhost): 2017-10-14T22:23:46, stalled for 1517s at: Test4GBStoredFields.test
[junit4] Suite: org.apache.lucene.codecs.perfield.TestPerFieldPostingsFormat2
[junit4] Completed [116/455] on J2 in 53.98s, 6 tests
[junit4]
[junit4] Suite: org.apache.lucene.analysis.TestCharacterUtils
[junit4] Completed [117/455] on J2 in 0.19s, 4 tests
[junit4]
[junit4] Suite: org.apache.lucene.search.TestBoolean2ScorerSupplier
[junit4] Completed [118/455] on J2 in 2.24s, 10 tests
[junit4]
[junit4] Suite: org.apache.lucene.index.TestIndexWriterDeleteByQuery
[junit4] Completed [119/455] on J2 in 0.22s, 1 test
[junit4]
[junit4] Suite: org.apache.lucene.search.TestDocValuesScoring
[junit4] Completed [120/455] on J2 in 0.75s, 1 test
[junit4]
[junit4] Suite: org.apache.lucene.index.TestExceedMaxTermLength
[junit4] Completed [121/455] on J2 in 0.19s, 1 test
[junit4]
[junit4] Suite: org.apache.lucene.index.TestIndexWriterLockRelease
[junit4] Completed [122/455] on J2 in 0.07s, 1 test
[junit4]
[junit4] Suite: org.apache.lucene.search.spans.TestSpanContainQuery
[junit4] Completed [123/455] on J2 in 0.83s, 4 tests
[junit4]
[junit4] Suite: org.apache.lucene.search.T
[...truncated too long message...]
t/lucene/top-level-ivy-settings.xml
common.resolve:
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/top-level-ivy-settings.xml
resolve:
jar-checksums:
[mkdir] Created dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/null1597715435
[copy] Copying 35 files to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/null1597715435
[delete] Deleting directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/null1597715435
resolve-example:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/top-level-ivy-settings.xml
resolve:
resolve-server:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/top-level-ivy-settings.xml
resolve:
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/lucene/top-level-ivy-settings.xml
resolve:
jar-checksums:
[mkdir] Created dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/solr/null616739460
[copy] Copying 218 files to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/solr/null616739460
[delete] Deleting directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.1/checkout/solr/null616739460
check-working-copy:
[ivy:cachepath] :: resolving dependencies :: org.eclipse.jgit#org.eclipse.jgit-caller;working
[ivy:cachepath] confs: [default]
[ivy:cachepath] found org.eclipse.jgit#org.eclipse.jgit;4.6.0.201612231935-r in public
[ivy:cachepath] found com.jcraft#jsch;0.1.53 in public
[ivy:cachepath] found com.googlecode.javaewah#JavaEWAH;1.1.6 in public
[ivy:cachepath] found org.apache.httpcomponents#httpclient;4.3.6 in public
[ivy:cachepath] found org.apache.httpcomponents#httpcore;4.3.3 in public
[ivy:cachepath] found commons-logging#commons-logging;1.1.3 in public
[ivy:cachepath] found commons-codec#commons-codec;1.6 in public
[ivy:cachepath] found org.slf4j#slf4j-api;1.7.2 in public
[ivy:cachepath] :: resolution report :: resolve 90ms :: artifacts dl 6ms
---------------------------------------------------------------------
| | modules || artifacts |
| conf | number| search|dwnlded|evicted|| number|dwnlded|
---------------------------------------------------------------------
| default | 8 | 0 | 0 | 0 || 8 | 0 |
---------------------------------------------------------------------
[wc-checker] Initializing working copy...
[wc-checker] SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
[wc-checker] SLF4J: Defaulting to no-operation (NOP) logger implementation
[wc-checker] SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details.
[wc-checker] Checking working copy status...
-jenkins-base:
BUILD SUCCESSFUL
Total time: 801 minutes 36 seconds
Archiving artifacts
WARN: No artifacts found that match the file pattern "**/*.events,heapdumps/**,**/hs_err_pid*". Configuration error?
WARN: java.lang.InterruptedException: no matches found within 10000
Recording test results
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)