Build: https://builds.apache.org/job/Lucene-Solr-BadApples-Tests-7.x/94/
5 tests failed. FAILED: org.apache.solr.cloud.api.collections.TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateWithoutCoresThenDelete Error Message: Could not find collection:testSolrCloudCollectionWithoutCores Stack Trace: java.lang.AssertionError: Could not find collection:testSolrCloudCollectionWithoutCores at __randomizedtesting.SeedInfo.seed([2A17621481772413:99D2ADD58E0FF752]: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.api.collections.TestCollectionsAPIViaSolrCloudCluster.createCollection(TestCollectionsAPIViaSolrCloudCluster.java:93) at org.apache.solr.cloud.api.collections.TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateWithoutCoresThenDelete(TestCollectionsAPIViaSolrCloudCluster.java:185) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) FAILED: org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testMixedBounds Error Message: failed to create testMixedBounds_collection Live Nodes: [127.0.0.1:10003_solr, 127.0.0.1:10002_solr] Last available state: DocCollection(testMixedBounds_collection//clusterstate.json/123)={ "replicationFactor":"1", "pullReplicas":"0", "router":{"name":"compositeId"}, "maxShardsPerNode":"2", "autoAddReplicas":"false", "nrtReplicas":"2", "tlogReplicas":"0", "autoCreated":"true", "shards":{ "shard2":{ "replicas":{ "core_node3":{ "core":"testMixedBounds_collection_shard2_replica_n3", "leader":"true", "SEARCHER.searcher.maxDoc":0, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":10240, "node_name":"127.0.0.1:10003_solr", "state":"active", "type":"NRT", "INDEX.sizeInGB":9.5367431640625E-6, "SEARCHER.searcher.numDocs":0}, "core_node4":{ "core":"testMixedBounds_collection_shard2_replica_n4", "SEARCHER.searcher.maxDoc":0, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":10240, "node_name":"127.0.0.1:10002_solr", "state":"active", "type":"NRT", "INDEX.sizeInGB":9.5367431640625E-6, "SEARCHER.searcher.numDocs":0}}, "range":"0-7fffffff", "state":"active"}, "shard1":{ "stateTimestamp":"1530777397599802500", "replicas":{ "core_node1":{ "core":"testMixedBounds_collection_shard1_replica_n1", "leader":"true", "SEARCHER.searcher.maxDoc":495, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":257740, "node_name":"127.0.0.1:10003_solr", "state":"active", "type":"NRT", "INDEX.sizeInGB":2.4003908038139343E-4, "SEARCHER.searcher.numDocs":495}, "core_node2":{ "core":"testMixedBounds_collection_shard1_replica_n2", "SEARCHER.searcher.maxDoc":495, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":257740, "node_name":"127.0.0.1:10002_solr", "state":"active", "type":"NRT", "INDEX.sizeInGB":2.4003908038139343E-4, "SEARCHER.searcher.numDocs":495}}, "range":"80000000-ffffffff", "state":"inactive"}, "shard1_1":{ "parent":"shard1", "stateTimestamp":"1530777397601713500", "range":"c0000000-ffffffff", "state":"active", "replicas":{ "core_node10":{ "leader":"true", "core":"testMixedBounds_collection_shard1_1_replica1", "SEARCHER.searcher.maxDoc":247, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":133740, "node_name":"127.0.0.1:10002_solr", "base_url":"http://127.0.0.1:10002/solr", "state":"active", "type":"NRT", "INDEX.sizeInGB":1.245550811290741E-4, "SEARCHER.searcher.numDocs":247}, "core_node9":{ "core":"testMixedBounds_collection_shard1_1_replica0", "SEARCHER.searcher.maxDoc":247, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":133740, "node_name":"127.0.0.1:10003_solr", "base_url":"http://127.0.0.1:10003/solr", "state":"active", "type":"NRT", "INDEX.sizeInGB":1.245550811290741E-4, "SEARCHER.searcher.numDocs":247}}}, "shard1_0":{ "parent":"shard1", "stateTimestamp":"1530777397600238350", "range":"80000000-bfffffff", "state":"active", "replicas":{ "core_node7":{ "leader":"true", "core":"testMixedBounds_collection_shard1_0_replica0", "SEARCHER.searcher.maxDoc":248, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":144480, "node_name":"127.0.0.1:10003_solr", "base_url":"http://127.0.0.1:10003/solr", "state":"active", "type":"NRT", "INDEX.sizeInGB":1.3455748558044434E-4, "SEARCHER.searcher.numDocs":248}, "core_node8":{ "core":"testMixedBounds_collection_shard1_0_replica1", "SEARCHER.searcher.maxDoc":248, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":144480, "node_name":"127.0.0.1:10002_solr", "base_url":"http://127.0.0.1:10002/solr", "state":"active", "type":"NRT", "INDEX.sizeInGB":1.3455748558044434E-4, "SEARCHER.searcher.numDocs":248}}}}} Stack Trace: java.lang.AssertionError: failed to create testMixedBounds_collection Live Nodes: [127.0.0.1:10003_solr, 127.0.0.1:10002_solr] Last available state: DocCollection(testMixedBounds_collection//clusterstate.json/123)={ "replicationFactor":"1", "pullReplicas":"0", "router":{"name":"compositeId"}, "maxShardsPerNode":"2", "autoAddReplicas":"false", "nrtReplicas":"2", "tlogReplicas":"0", "autoCreated":"true", "shards":{ "shard2":{ "replicas":{ "core_node3":{ "core":"testMixedBounds_collection_shard2_replica_n3", "leader":"true", "SEARCHER.searcher.maxDoc":0, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":10240, "node_name":"127.0.0.1:10003_solr", "state":"active", "type":"NRT", "INDEX.sizeInGB":9.5367431640625E-6, "SEARCHER.searcher.numDocs":0}, "core_node4":{ "core":"testMixedBounds_collection_shard2_replica_n4", "SEARCHER.searcher.maxDoc":0, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":10240, "node_name":"127.0.0.1:10002_solr", "state":"active", "type":"NRT", "INDEX.sizeInGB":9.5367431640625E-6, "SEARCHER.searcher.numDocs":0}}, "range":"0-7fffffff", "state":"active"}, "shard1":{ "stateTimestamp":"1530777397599802500", "replicas":{ "core_node1":{ "core":"testMixedBounds_collection_shard1_replica_n1", "leader":"true", "SEARCHER.searcher.maxDoc":495, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":257740, "node_name":"127.0.0.1:10003_solr", "state":"active", "type":"NRT", "INDEX.sizeInGB":2.4003908038139343E-4, "SEARCHER.searcher.numDocs":495}, "core_node2":{ "core":"testMixedBounds_collection_shard1_replica_n2", "SEARCHER.searcher.maxDoc":495, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":257740, "node_name":"127.0.0.1:10002_solr", "state":"active", "type":"NRT", "INDEX.sizeInGB":2.4003908038139343E-4, "SEARCHER.searcher.numDocs":495}}, "range":"80000000-ffffffff", "state":"inactive"}, "shard1_1":{ "parent":"shard1", "stateTimestamp":"1530777397601713500", "range":"c0000000-ffffffff", "state":"active", "replicas":{ "core_node10":{ "leader":"true", "core":"testMixedBounds_collection_shard1_1_replica1", "SEARCHER.searcher.maxDoc":247, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":133740, "node_name":"127.0.0.1:10002_solr", "base_url":"http://127.0.0.1:10002/solr", "state":"active", "type":"NRT", "INDEX.sizeInGB":1.245550811290741E-4, "SEARCHER.searcher.numDocs":247}, "core_node9":{ "core":"testMixedBounds_collection_shard1_1_replica0", "SEARCHER.searcher.maxDoc":247, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":133740, "node_name":"127.0.0.1:10003_solr", "base_url":"http://127.0.0.1:10003/solr", "state":"active", "type":"NRT", "INDEX.sizeInGB":1.245550811290741E-4, "SEARCHER.searcher.numDocs":247}}}, "shard1_0":{ "parent":"shard1", "stateTimestamp":"1530777397600238350", "range":"80000000-bfffffff", "state":"active", "replicas":{ "core_node7":{ "leader":"true", "core":"testMixedBounds_collection_shard1_0_replica0", "SEARCHER.searcher.maxDoc":248, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":144480, "node_name":"127.0.0.1:10003_solr", "base_url":"http://127.0.0.1:10003/solr", "state":"active", "type":"NRT", "INDEX.sizeInGB":1.3455748558044434E-4, "SEARCHER.searcher.numDocs":248}, "core_node8":{ "core":"testMixedBounds_collection_shard1_0_replica1", "SEARCHER.searcher.maxDoc":248, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":144480, "node_name":"127.0.0.1:10002_solr", "base_url":"http://127.0.0.1:10002/solr", "state":"active", "type":"NRT", "INDEX.sizeInGB":1.3455748558044434E-4, "SEARCHER.searcher.numDocs":248}}}}} at __randomizedtesting.SeedInfo.seed([2A17621481772413:2094DDB9CCCC2F49]:0) at org.apache.solr.cloud.CloudTestUtils.waitForState(CloudTestUtils.java:69) at org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testMixedBounds(IndexSizeTriggerTest.java:561) 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: java.util.concurrent.TimeoutException: last state: DocCollection(testMixedBounds_collection//clusterstate.json/123)={ "replicationFactor":"1", "pullReplicas":"0", "router":{"name":"compositeId"}, "maxShardsPerNode":"2", "autoAddReplicas":"false", "nrtReplicas":"2", "tlogReplicas":"0", "autoCreated":"true", "shards":{ "shard2":{ "replicas":{ "core_node3":{ "core":"testMixedBounds_collection_shard2_replica_n3", "leader":"true", "SEARCHER.searcher.maxDoc":0, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":10240, "node_name":"127.0.0.1:10003_solr", "state":"active", "type":"NRT", "INDEX.sizeInGB":9.5367431640625E-6, "SEARCHER.searcher.numDocs":0}, "core_node4":{ "core":"testMixedBounds_collection_shard2_replica_n4", "SEARCHER.searcher.maxDoc":0, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":10240, "node_name":"127.0.0.1:10002_solr", "state":"active", "type":"NRT", "INDEX.sizeInGB":9.5367431640625E-6, "SEARCHER.searcher.numDocs":0}}, "range":"0-7fffffff", "state":"active"}, "shard1":{ "stateTimestamp":"1530777397599802500", "replicas":{ "core_node1":{ "core":"testMixedBounds_collection_shard1_replica_n1", "leader":"true", "SEARCHER.searcher.maxDoc":495, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":257740, "node_name":"127.0.0.1:10003_solr", "state":"active", "type":"NRT", "INDEX.sizeInGB":2.4003908038139343E-4, "SEARCHER.searcher.numDocs":495}, "core_node2":{ "core":"testMixedBounds_collection_shard1_replica_n2", "SEARCHER.searcher.maxDoc":495, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":257740, "node_name":"127.0.0.1:10002_solr", "state":"active", "type":"NRT", "INDEX.sizeInGB":2.4003908038139343E-4, "SEARCHER.searcher.numDocs":495}}, "range":"80000000-ffffffff", "state":"inactive"}, "shard1_1":{ "parent":"shard1", "stateTimestamp":"1530777397601713500", "range":"c0000000-ffffffff", "state":"active", "replicas":{ "core_node10":{ "leader":"true", "core":"testMixedBounds_collection_shard1_1_replica1", "SEARCHER.searcher.maxDoc":247, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":133740, "node_name":"127.0.0.1:10002_solr", "base_url":"http://127.0.0.1:10002/solr", "state":"active", "type":"NRT", "INDEX.sizeInGB":1.245550811290741E-4, "SEARCHER.searcher.numDocs":247}, "core_node9":{ "core":"testMixedBounds_collection_shard1_1_replica0", "SEARCHER.searcher.maxDoc":247, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":133740, "node_name":"127.0.0.1:10003_solr", "base_url":"http://127.0.0.1:10003/solr", "state":"active", "type":"NRT", "INDEX.sizeInGB":1.245550811290741E-4, "SEARCHER.searcher.numDocs":247}}}, "shard1_0":{ "parent":"shard1", "stateTimestamp":"1530777397600238350", "range":"80000000-bfffffff", "state":"active", "replicas":{ "core_node7":{ "leader":"true", "core":"testMixedBounds_collection_shard1_0_replica0", "SEARCHER.searcher.maxDoc":248, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":144480, "node_name":"127.0.0.1:10003_solr", "base_url":"http://127.0.0.1:10003/solr", "state":"active", "type":"NRT", "INDEX.sizeInGB":1.3455748558044434E-4, "SEARCHER.searcher.numDocs":248}, "core_node8":{ "core":"testMixedBounds_collection_shard1_0_replica1", "SEARCHER.searcher.maxDoc":248, "SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":144480, "node_name":"127.0.0.1:10002_solr", "base_url":"http://127.0.0.1:10002/solr", "state":"active", "type":"NRT", "INDEX.sizeInGB":1.3455748558044434E-4, "SEARCHER.searcher.numDocs":248}}}}} at org.apache.solr.cloud.CloudTestUtils.waitForState(CloudTestUtils.java:111) at org.apache.solr.cloud.CloudTestUtils.waitForState(CloudTestUtils.java:63) ... 40 more FAILED: org.apache.solr.cloud.autoscaling.sim.TestGenericDistributedQueue.testDistributedQueue 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([2A17621481772413]:0) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.autoscaling.sim.TestGenericDistributedQueue Error Message: Suite timeout exceeded (>= 7200000 msec). Stack Trace: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec). at __randomizedtesting.SeedInfo.seed([2A17621481772413]:0) FAILED: org.apache.solr.cloud.api.collections.ShardSplitTest.testSplitWithChaosMonkey Error Message: We think that split was successful but sub-shard states were not updated even after 2 minutes. Stack Trace: java.lang.AssertionError: We think that split was successful but sub-shard states were not updated even after 2 minutes. at __randomizedtesting.SeedInfo.seed([2A17621481772413:A130B1C5C0718F97]:0) at org.junit.Assert.fail(Assert.java:93) at org.apache.solr.cloud.api.collections.ShardSplitTest.testSplitWithChaosMonkey(ShardSplitTest.java:555) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1008) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:983) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) Build Log: [...truncated 13406 lines...] [junit4] Suite: org.apache.solr.cloud.api.collections.TestCollectionsAPIViaSolrCloudCluster [junit4] 2> 1165728 INFO (SUITE-TestCollectionsAPIViaSolrCloudCluster-seed#[2A17621481772413]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.TestCollectionsAPIViaSolrCloudCluster_2A17621481772413-001/init-core-data-001 [junit4] 2> 1165728 WARN (SUITE-TestCollectionsAPIViaSolrCloudCluster-seed#[2A17621481772413]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1 [junit4] 2> 1165729 INFO (SUITE-TestCollectionsAPIViaSolrCloudCluster-seed#[2A17621481772413]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 1165731 INFO (SUITE-TestCollectionsAPIViaSolrCloudCluster-seed#[2A17621481772413]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN) [junit4] 2> 1165743 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 5 servers in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.TestCollectionsAPIViaSolrCloudCluster_2A17621481772413-001/tempDir-001 [junit4] 2> 1165744 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1165756 INFO (Thread-1190) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1165756 INFO (Thread-1190) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1165855 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.a.s.c.ZkTestServer start zk server on port:55678 [junit4] 2> 1165889 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Exception causing close of session 0x0: ZooKeeperServer not running [junit4] 2> 1165904 ERROR (Thread-1190) [ ] 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> 1167604 INFO (zkConnectionManagerCallback-2138-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1167618 INFO (jetty-launcher-2135-thread-1) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11 [junit4] 2> 1167619 INFO (jetty-launcher-2135-thread-2) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11 [junit4] 2> 1167619 INFO (jetty-launcher-2135-thread-3) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11 [junit4] 2> 1167620 INFO (jetty-launcher-2135-thread-4) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11 [junit4] 2> 1167621 INFO (jetty-launcher-2135-thread-5) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11 [junit4] 2> 1167622 INFO (jetty-launcher-2135-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1167622 INFO (jetty-launcher-2135-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1167622 INFO (jetty-launcher-2135-thread-3) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 1167622 INFO (jetty-launcher-2135-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@39d6950{/solr,null,AVAILABLE} [junit4] 2> 1167623 INFO (jetty-launcher-2135-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1566d5f6{HTTP/1.1,[http/1.1]}{127.0.0.1:38609} [junit4] 2> 1167623 INFO (jetty-launcher-2135-thread-3) [ ] o.e.j.s.Server Started @1167735ms [junit4] 2> 1167623 INFO (jetty-launcher-2135-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38609} [junit4] 2> 1167623 ERROR (jetty-launcher-2135-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1167624 INFO (jetty-launcher-2135-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1167624 INFO (jetty-launcher-2135-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.5.0 [junit4] 2> 1167624 INFO (jetty-launcher-2135-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1167624 INFO (jetty-launcher-2135-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1167624 INFO (jetty-launcher-2135-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-07-04T02:33:36.231Z [junit4] 2> 1167649 INFO (jetty-launcher-2135-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1167649 INFO (jetty-launcher-2135-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1167649 INFO (jetty-launcher-2135-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 1167649 INFO (jetty-launcher-2135-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@228a39a9{/solr,null,AVAILABLE} [junit4] 2> 1167650 INFO (jetty-launcher-2135-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@44c4e6d9{HTTP/1.1,[http/1.1]}{127.0.0.1:36682} [junit4] 2> 1167650 INFO (jetty-launcher-2135-thread-1) [ ] o.e.j.s.Server Started @1167762ms [junit4] 2> 1167650 INFO (jetty-launcher-2135-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=36682} [junit4] 2> 1167650 ERROR (jetty-launcher-2135-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1167650 INFO (jetty-launcher-2135-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1167650 INFO (jetty-launcher-2135-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.5.0 [junit4] 2> 1167650 INFO (jetty-launcher-2135-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1167650 INFO (jetty-launcher-2135-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1167650 INFO (jetty-launcher-2135-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-07-04T02:33:36.257Z [junit4] 2> 1167717 INFO (jetty-launcher-2135-thread-5) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1167717 INFO (jetty-launcher-2135-thread-5) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1167717 INFO (jetty-launcher-2135-thread-5) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 1167740 INFO (jetty-launcher-2135-thread-5) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@512d13d0{/solr,null,AVAILABLE} [junit4] 2> 1167785 INFO (jetty-launcher-2135-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1167785 INFO (jetty-launcher-2135-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1167785 INFO (jetty-launcher-2135-thread-4) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 1167836 INFO (jetty-launcher-2135-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3f3ffe2{/solr,null,AVAILABLE} [junit4] 2> 1167837 INFO (jetty-launcher-2135-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3c844280{HTTP/1.1,[http/1.1]}{127.0.0.1:56999} [junit4] 2> 1167850 INFO (zkConnectionManagerCallback-2140-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1167850 INFO (jetty-launcher-2135-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1167901 INFO (jetty-launcher-2135-thread-4) [ ] o.e.j.s.Server Started @1168013ms [junit4] 2> 1167901 INFO (jetty-launcher-2135-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=56999} [junit4] 2> 1167901 ERROR (jetty-launcher-2135-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1167901 INFO (jetty-launcher-2135-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1167932 INFO (zkConnectionManagerCallback-2142-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1167932 INFO (jetty-launcher-2135-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.5.0 [junit4] 2> 1167932 INFO (jetty-launcher-2135-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1167933 INFO (jetty-launcher-2135-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1167933 INFO (jetty-launcher-2135-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-07-04T02:33:36.540Z [junit4] 2> 1167933 INFO (jetty-launcher-2135-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1167933 INFO (jetty-launcher-2135-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1167933 INFO (jetty-launcher-2135-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 1167901 INFO (jetty-launcher-2135-thread-5) [ ] o.e.j.s.AbstractConnector Started ServerConnector@280321d7{HTTP/1.1,[http/1.1]}{127.0.0.1:56416} [junit4] 2> 1167933 INFO (jetty-launcher-2135-thread-5) [ ] o.e.j.s.Server Started @1168045ms [junit4] 2> 1167933 INFO (jetty-launcher-2135-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1167933 INFO (jetty-launcher-2135-thread-5) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=56416} [junit4] 2> 1167934 ERROR (jetty-launcher-2135-thread-5) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1167934 INFO (jetty-launcher-2135-thread-5) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1167934 INFO (jetty-launcher-2135-thread-5) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.5.0 [junit4] 2> 1167934 INFO (jetty-launcher-2135-thread-5) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1167934 INFO (jetty-launcher-2135-thread-5) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1167934 INFO (jetty-launcher-2135-thread-5) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-07-04T02:33:36.541Z [junit4] 2> 1167948 INFO (jetty-launcher-2135-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@9afa0c7{/solr,null,AVAILABLE} [junit4] 2> 1167948 INFO (jetty-launcher-2135-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5f34ca71{HTTP/1.1,[http/1.1]}{127.0.0.1:59959} [junit4] 2> 1167948 INFO (jetty-launcher-2135-thread-2) [ ] o.e.j.s.Server Started @1168061ms [junit4] 2> 1167949 INFO (jetty-launcher-2135-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=59959} [junit4] 2> 1167949 ERROR (jetty-launcher-2135-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1167949 INFO (jetty-launcher-2135-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1167949 INFO (jetty-launcher-2135-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.5.0 [junit4] 2> 1167949 INFO (jetty-launcher-2135-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1167949 INFO (jetty-launcher-2135-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1167949 INFO (jetty-launcher-2135-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-07-04T02:33:36.556Z [junit4] 2> 1168033 INFO (zkConnectionManagerCallback-2146-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1168033 INFO (jetty-launcher-2135-thread-5) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1168042 INFO (zkConnectionManagerCallback-2148-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1168058 INFO (zkConnectionManagerCallback-2144-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1168058 INFO (jetty-launcher-2135-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1168061 INFO (jetty-launcher-2135-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1168844 INFO (jetty-launcher-2135-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55678/solr [junit4] 2> 1168853 INFO (jetty-launcher-2135-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55678/solr [junit4] 2> 1168893 INFO (zkConnectionManagerCallback-2153-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1168905 INFO (zkConnectionManagerCallback-2156-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1168948 INFO (zkConnectionManagerCallback-2158-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1169040 INFO (jetty-launcher-2135-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55678/solr [junit4] 2> 1169072 INFO (zkConnectionManagerCallback-2166-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1169075 INFO (jetty-launcher-2135-thread-5) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55678/solr [junit4] 2> 1169135 INFO (zkConnectionManagerCallback-2160-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1169150 INFO (jetty-launcher-2135-thread-3) [n:127.0.0.1:38609_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1169151 INFO (jetty-launcher-2135-thread-3) [n:127.0.0.1:38609_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:38609_solr [junit4] 2> 1169152 INFO (jetty-launcher-2135-thread-3) [n:127.0.0.1:38609_solr ] o.a.s.c.Overseer Overseer (id=74084197290016777-127.0.0.1:38609_solr-n_0000000000) starting [junit4] 2> 1169154 INFO (jetty-launcher-2135-thread-4) [n:127.0.0.1:56999_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1169168 INFO (jetty-launcher-2135-thread-4) [n:127.0.0.1:56999_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56999_solr [junit4] 2> 1169188 INFO (zkConnectionManagerCallback-2170-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1169203 INFO (zkConnectionManagerCallback-2172-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1169211 INFO (jetty-launcher-2135-thread-2) [n:127.0.0.1:59959_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1169219 INFO (jetty-launcher-2135-thread-2) [n:127.0.0.1:59959_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1169222 INFO (jetty-launcher-2135-thread-2) [n:127.0.0.1:59959_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1169222 INFO (jetty-launcher-2135-thread-2) [n:127.0.0.1:59959_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59959_solr [junit4] 2> 1169238 INFO (zkCallback-2157-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1169238 INFO (zkCallback-2159-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1169354 INFO (zkConnectionManagerCallback-2179-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1169361 INFO (jetty-launcher-2135-thread-5) [n:127.0.0.1:56416_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1169366 INFO (jetty-launcher-2135-thread-5) [n:127.0.0.1:56416_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1169375 INFO (jetty-launcher-2135-thread-5) [n:127.0.0.1:56416_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1169375 INFO (jetty-launcher-2135-thread-5) [n:127.0.0.1:56416_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56416_solr [junit4] 2> 1169415 INFO (zkCallback-2157-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3) [junit4] 2> 1169440 INFO (zkCallback-2159-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3) [junit4] 2> 1169476 INFO (zkConnectionManagerCallback-2184-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1169477 INFO (jetty-launcher-2135-thread-3) [n:127.0.0.1:38609_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 1169478 INFO (jetty-launcher-2135-thread-3) [n:127.0.0.1:38609_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55678/solr ready [junit4] 2> 1169558 INFO (zkCallback-2171-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3) [junit4] 2> 1169558 INFO (zkCallback-2178-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1169569 INFO (jetty-launcher-2135-thread-3) [n:127.0.0.1:38609_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1169569 INFO (jetty-launcher-2135-thread-3) [n:127.0.0.1:38609_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38609_solr [junit4] 2> 1169589 INFO (jetty-launcher-2135-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55678/solr [junit4] 2> 1169666 INFO (zkConnectionManagerCallback-2201-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1169667 INFO (zkCallback-2157-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1169667 INFO (zkCallback-2178-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1169668 INFO (jetty-launcher-2135-thread-2) [n:127.0.0.1:59959_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 1169670 INFO (jetty-launcher-2135-thread-2) [n:127.0.0.1:59959_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55678/solr ready [junit4] 2> 1169675 INFO (zkCallback-2159-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1169675 INFO (zkCallback-2171-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1169677 INFO (jetty-launcher-2135-thread-3) [n:127.0.0.1:38609_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 1169690 INFO (zkConnectionManagerCallback-2192-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1169695 INFO (zkConnectionManagerCallback-2196-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1169699 INFO (jetty-launcher-2135-thread-5) [n:127.0.0.1:56416_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 1169700 INFO (jetty-launcher-2135-thread-4) [n:127.0.0.1:56999_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 1169702 INFO (jetty-launcher-2135-thread-4) [n:127.0.0.1:56999_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55678/solr ready [junit4] 2> 1169702 INFO (jetty-launcher-2135-thread-5) [n:127.0.0.1:56416_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55678/solr ready [junit4] 2> 1169710 INFO (jetty-launcher-2135-thread-3) [n:127.0.0.1:38609_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38609.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3210f963 [junit4] 2> 1169720 INFO (zkConnectionManagerCallback-2205-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1169721 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x107332f56f10011, likely client has closed socket [junit4] 2> 1169721 INFO (jetty-launcher-2135-thread-3) [n:127.0.0.1:38609_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38609.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3210f963 [junit4] 2> 1169722 INFO (jetty-launcher-2135-thread-3) [n:127.0.0.1:38609_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38609.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3210f963 [junit4] 2> 1169724 INFO (jetty-launcher-2135-thread-3) [n:127.0.0.1:38609_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.TestCollectionsAPIViaSolrCloudCluster_2A17621481772413-001/tempDir-001/node3/. [junit4] 2> 1169726 INFO (zkCallback-2183-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1169726 INFO (jetty-launcher-2135-thread-2) [n:127.0.0.1:59959_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 1169733 INFO (jetty-launcher-2135-thread-5) [n:127.0.0.1:56416_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 1169752 INFO (jetty-launcher-2135-thread-4) [n:127.0.0.1:56999_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 1169973 INFO (jetty-launcher-2135-thread-5) [n:127.0.0.1:56416_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56416.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3210f963 [junit4] 2> 1169986 INFO (zkConnectionManagerCallback-2210-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1169994 INFO (jetty-launcher-2135-thread-1) [n:127.0.0.1:36682_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 1169997 INFO (jetty-launcher-2135-thread-1) [n:127.0.0.1:36682_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1169999 INFO (jetty-launcher-2135-thread-1) [n:127.0.0.1:36682_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1169999 INFO (jetty-launcher-2135-thread-1) [n:127.0.0.1:36682_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36682_solr [junit4] 2> 1170001 INFO (zkCallback-2157-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 1170002 INFO (zkCallback-2178-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 1170002 INFO (zkCallback-2171-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 1170002 INFO (zkCallback-2159-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 1170005 INFO (jetty-launcher-2135-thread-2) [n:127.0.0.1:59959_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59959.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3210f963 [junit4] 2> 1170065 INFO (zkCallback-2200-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 1170065 INFO (zkCallback-2195-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 1170093 INFO (zkCallback-2209-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 1170094 INFO (zkCallback-2183-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 1170104 INFO (zkCallback-2191-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 1170114 INFO (zkConnectionManagerCallback-2217-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1170116 INFO (jetty-launcher-2135-thread-1) [n:127.0.0.1:36682_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5) [junit4] 2> 1170117 INFO (jetty-launcher-2135-thread-1) [n:127.0.0.1:36682_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55678/solr ready [junit4] 2> 1170118 WARN (jetty-launcher-2135-thread-2) [n:127.0.0.1:59959_solr ] o.a.s.m.r.j.JmxMetricsReporter Unable to register gauge [junit4] 2> javax.management.InstanceNotFoundException: solr_59959:dom1=solr,dom2=jvm,name0=memory,name1=total,name=init [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1095) ~[?:1.8.0_172] [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:427) ~[?:1.8.0_172] [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:415) ~[?:1.8.0_172] [junit4] 2> at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:546) ~[?:1.8.0_172] [junit4] 2> at org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter$JmxListener.registerMBean(JmxMetricsReporter.java:530) ~[java/:?] [junit4] 2> at org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter$JmxListener.onGaugeAdded(JmxMetricsReporter.java:565) ~[java/:?] [junit4] 2> at org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter.lambda$start$0(JmxMetricsReporter.java:738) ~[java/:?] [junit4] 2> at java.util.HashMap.forEach(HashMap.java:1289) ~[?:1.8.0_172] [junit4] 2> at org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter.start(JmxMetricsReporter.java:728) ~[java/:?] [junit4] 2> at org.apache.solr.metrics.reporters.SolrJmxReporter.doInit(SolrJmxReporter.java:110) ~[java/:?] [junit4] 2> at org.apache.solr.metrics.SolrMetricReporter.init(SolrMetricReporter.java:70) ~[java/:?] [junit4] 2> at org.apache.solr.metrics.SolrMetricManager.loadReporter(SolrMetricManager.java:930) ~[java/:?] [junit4] 2> at org.apache.solr.metrics.SolrMetricManager.loadReporters(SolrMetricManager.java:866) ~[java/:?] [junit4] 2> at org.apache.solr.core.CoreContainer.load(CoreContainer.java:587) ~[java/:?] [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.createCoreContainer(SolrDispatchFilter.java:252) ~[java/:?] [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.init(SolrDispatchFilter.java:172) ~[java/:?] [junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.initialize(FilterHolder.java:139) ~[jetty-servlet-9.4.11.v20180605.jar:9.4.11.v20180605] [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.initialize(ServletHandler.java:741) ~[jetty-servlet-9.4.11.v20180605.jar:9.4.11.v20180605] [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.updateMappings(ServletHandler.java:1477) ~[jetty-servlet-9.4.11.v20180605.jar:9.4.11.v20180605] [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.setFilterMappings(ServletHandler.java:1542) ~[jetty-servlet-9.4.11.v20180605.jar:9.4.11.v20180605] [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.addFilterMapping(ServletHandler.java:1186) ~[jetty-servlet-9.4.11.v20180605.jar:9.4.11.v20180605] [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.addFilterWithMapping(ServletHandler.java:1023) ~[jetty-servlet-9.4.11.v20180605.jar:9.4.11.v20180605] [junit4] 2> at org.eclipse.jetty.servlet.ServletContextHandler.addFilter(ServletContextHandler.java:473) ~[jetty-servlet-9.4.11.v20180605.jar:9.4.11.v20180605] [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$1.lifeCycleStarted(JettySolrRunner.java:308) ~[java/:?] [junit4] 2> at org.eclipse.jetty.util.component.AbstractLifeCycle.setStarted(AbstractLifeCycle.java:179) ~[jetty-util-9.4.11.v20180605.jar:9.4.11.v20180605] [junit4] 2> at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:69) ~[jetty-util-9.4.11.v20180605.jar:9.4.11.v20180605] [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:396) ~[java/:?] [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:369) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.MiniSolrCloudCluster.startJettySolrRunner(MiniSolrCloudCluster.java:392) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.MiniSolrCloudCluster.lambda$new$0(MiniSolrCloudCluster.java:248) ~[java/:?] [junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_172] [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209) ~[java/:?] [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_172] [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_172] [junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172] [junit4] 2> 1170125 INFO (jetty-launcher-2135-thread-4) [n:127.0.0.1:56999_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56999.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3210f963 [junit4] 2> 1170128 INFO (jetty-launcher-2135-thread-5) [n:127.0.0.1:56416_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56416.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3210f963 [junit4] 2> 1170128 INFO (jetty-launcher-2135-thread-5) [n:127.0.0.1:56416_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56416.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3210f963 [junit4] 2> 1170139 INFO (jetty-launcher-2135-thread-5) [n:127.0.0.1:56416_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.TestCollectionsAPIViaSolrCloudCluster_2A17621481772413-001/tempDir-001/node5/. [junit4] 2> 1170154 INFO (jetty-launcher-2135-thread-1) [n:127.0.0.1:36682_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 1170184 INFO (jetty-launcher-2135-thread-4) [n:127.0.0.1:56999_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56999.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3210f963 [junit4] 2> 1170184 INFO (jetty-launcher-2135-thread-4) [n:127.0.0.1:56999_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56999.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3210f963 [junit4] 2> 1170186 INFO (jetty-launcher-2135-thread-4) [n:127.0.0.1:56999_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.TestCollectionsAPIViaSolrCloudCluster_2A17621481772413-001/tempDir-001/node4/. [junit4] 2> 1170188 INFO (jetty-launcher-2135-thread-1) [n:127.0.0.1:36682_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36682.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3210f963 [junit4] 2> 1170211 INFO (jetty-launcher-2135-thread-2) [n:127.0.0.1:59959_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59959.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3210f963 [junit4] 2> 1170211 INFO (jetty-launcher-2135-thread-2) [n:127.0.0.1:59959_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59959.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3210f963 [junit4] 2> 1170228 INFO (jetty-launcher-2135-thread-2) [n:127.0.0.1:59959_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.TestCollectionsAPIViaSolrCloudCluster_2A17621481772413-001/tempDir-001/node2/. [junit4] 2> 1170246 INFO (jetty-launcher-2135-thread-1) [n:127.0.0.1:36682_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36682.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3210f963 [junit4] 2> 1170247 INFO (jetty-launcher-2135-thread-1) [n:127.0.0.1:36682_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36682.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3210f963 [junit4] 2> 1170248 INFO (jetty-launcher-2135-thread-1) [n:127.0.0.1:36682_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.TestCollectionsAPIViaSolrCloudCluster_2A17621481772413-001/tempDir-001/node1/. [junit4] 2> 1170779 INFO (zkConnectionManagerCallback-2220-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1170958 INFO (zkConnectionManagerCallback-2225-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1170959 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5) [junit4] 2> 1170961 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55678/solr ready [junit4] 2> 1170964 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCollectionCreateSearchDelete [junit4] 2> 1170965 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1566d5f6{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 1170965 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2076651334 [junit4] 2> 1170966 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 1170966 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@392525f: rootName = solr_38609, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@2ed77d63 [junit4] 2> 1170976 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 1170976 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1c9e3eff: rootName = solr_38609, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@baf0553 [junit4] 2> 1170981 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 1170981 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@f81da74: rootName = solr_38609, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@33a1c459 [junit4] 2> 1171001 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:38609_solr [junit4] 2> 1171002 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 1171004 INFO (zkCallback-2157-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4) [junit4] 2> 1171004 INFO (zkCallback-2171-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4) [junit4] 2> 1171004 INFO (zkCallback-2183-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4) [junit4] 2> 1171004 INFO (zkCallback-2178-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4) [junit4] 2> 1171006 INFO (zkCallback-2159-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4) [junit4] 2> 1171033 INFO (zkCallback-2200-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4) [junit4] 2> 1171033 INFO (zkCallback-2209-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4) [junit4] 2> 1171034 INFO (zkCallback-2195-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4) [junit4] 2> 1171034 INFO (zkCallback-2191-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4) [junit4] 2> 1171050 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.a.s.c.Overseer Overseer (id=74084197290016777-127.0.0.1:38609_solr-n_0000000000) closing [junit4] 2> 1171050 INFO (OverseerStateUpdate-74084197290016777-127.0.0.1:38609_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:38609_solr [junit4] 2> 1171050 WARN (OverseerAutoScalingTriggerThread-74084197290016777-127.0.0.1:38609_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 1171050 INFO (zkCallback-2224-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4) [junit4] 2> 1171051 INFO (zkCallback-2216-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4) [junit4] 2> 1171077 INFO (zkCallback-2157-thread-2) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:56999_solr [junit4] 2> 1171078 INFO (zkCallback-2157-thread-2) [ ] o.a.s.c.Overseer Overseer (id=74084197290016776-127.0.0.1:56999_solr-n_0000000001) starting [junit4] 2> 1171131 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@39d6950{/solr,null,UNAVAILABLE} [junit4] 2> 1171132 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 1171134 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11 [junit4] 2> 1171136 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1171136 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1171136 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 1171136 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@57a6b1c8{/solr,null,AVAILABLE} [junit4] 2> 1171137 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3c0a62e9{HTTP/1.1,[http/1.1]}{127.0.0.1:44347} [junit4] 2> 1171137 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.e.j.s.Server Started @1171249ms [junit4] 2> 1171137 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44347} [junit4] 2> 1171137 ERROR (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1171137 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1171137 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.5.0 [junit4] 2> 1171137 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1171137 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1171137 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-07-04T02:33:39.744Z [junit4] 2> 1171168 INFO (zkConnectionManagerCallback-2227-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1171169 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1172193 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55678/solr [junit4] 2> 1172215 INFO (zkConnectionManagerCallback-2231-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1172240 INFO (zkConnectionManagerCallback-2233-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1172247 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [n:127.0.0.1:44347_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 1172250 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [n:127.0.0.1:44347_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1172252 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [n:127.0.0.1:44347_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1172252 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [n:127.0.0.1:44347_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44347_solr [junit4] 2> 1172253 INFO (zkCallback-2200-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 1172255 INFO (zkCallback-2171-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 1172255 INFO (zkCallback-2209-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 1172255 INFO (zkCallback-2178-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 1172255 INFO (zkCallback-2224-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 1172255 INFO (zkCallback-2191-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 1172256 INFO (zkCallback-2195-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 1172256 INFO (zkCallback-2157-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 1172272 INFO (zkCallback-2216-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 1172276 INFO (zkCallback-2232-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 1172355 INFO (zkConnectionManagerCallback-2240-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1172357 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [n:127.0.0.1:44347_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5) [junit4] 2> 1172358 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [n:127.0.0.1:44347_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55678/solr ready [junit4] 2> 1172360 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [n:127.0.0.1:44347_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 1172388 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [n:127.0.0.1:44347_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44347.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3210f963 [junit4] 2> 1172402 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [n:127.0.0.1:44347_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44347.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3210f963 [junit4] 2> 1172402 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [n:127.0.0.1:44347_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44347.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3210f963 [junit4] 2> 1172404 INFO (TEST-TestCollectionsAPIViaSolrCloudCluster.testCollectionCreateSearchDelete-seed#[2A17621481772413]) [n:127.0.0.1:44347_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.TestCollectionsAPIViaSolrCloudCluster_2A17621481772413-001/tempDir-001/node6/. [junit4] 2> 1172479 INFO (qtp1992969703-7560) [n:127.0.0.1:36682_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params async=5729ce82-c673-49be-a582-fd29fa581136&collection.configName=solrCloudCollectionConfig&maxShardsPerNode=1&name=testcollection&nrtReplicas=2&action=CREATE&numShards=2&property.solr.directoryFactory=solr.StandardDirectoryFactory&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1172498 INFO (qtp1992969703-7560) [n:127.0.0.1:36682_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=5729ce82-c673-49be-a582-fd29fa581136&collection.configName=solrCloudCollectionConfig&maxShardsPerNode=1&name=testcollection&nrtReplicas=2&action=CREATE&numShards=2&property.solr.directoryFactory=solr.StandardDirectoryFactory&wt=javabin&version=2} status=0 QTime=18 [junit4] 2> 1172499 INFO (qtp1992969703-7556) [n:127.0.0.1:36682_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=5729ce82-c673-49be-a582-fd29fa581136&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1172500 INFO (qtp1992969703-7556) [n:127.0.0.1:36682_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=5729ce82-c673-49be-a582-fd29fa581136&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1172507 INFO (OverseerThreadFactory-3032-thread-1) [ ] o.a.s.c.a.c.CreateCollectionCmd Create collection testcollection [junit4] 2> 1172625 INFO (OverseerStateUpdate-74084197290016776-127.0.0.1:56999_solr-n_0000000001) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"testcollection", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"testcollection_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:56416/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1172630 INFO (OverseerStateUpdate-74084197290016776-127.0.0.1:56999_solr-n_0000000001) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"testcollection", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"testcollection_shard1_replica_n3", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:36682/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1172650 INFO (OverseerStateUpdate-74084197290016776-127.0.0.1:56999_solr-n_0000000001) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"testcollection", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"testcollection_shard2_replica_n5", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:56999/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1172660 INFO (OverseerStateUpdate-74084197290016776-127.0.0.1:56999_solr-n_0000000001) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"testcollection", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"testcollection_shard2_replica_n7", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:59959/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1172918 INFO (qtp1992969703-7555) [n:127.0.0.1:36682_solr x:testcollection_shard1_replica_n3] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=solrCloudCollectionConfig&newCollection=true&collection=testcollection&version=2&replicaType=NRT&async=5729ce82-c673-49be-a582-fd29fa58113630923518278009818&coreNodeName=core_node4&name=testcollection_shard1_replica_n3&action=CREATE&numShards=2&shard=shard1&property.solr.directoryFactory=solr.StandardDirectoryFactory&wt=javabin} status=0 QTime=21 [junit4] 2> 1172919 INFO (parallelCoreAdminExecutor-3021-thread-1-processing-n:127.0.0.1:36682_solr x:testcollection_shard1_replica_n3 5729ce82-c673-49be-a582-fd29fa58113630923518278009818 CREATE) [n:127.0.0.1:36682_solr x:testcollection_shard1_replica_n3] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=solrCloudCollectionConfig&newCollection=true&collection=testcollection&version=2&replicaType=NRT&async=5729ce82-c673-49be-a582-fd29fa58113630923518278009818&coreNodeName=core_node4&name=testcollection_shard1_replica_n3&action=CREATE&numShards=2&shard=shard1&property.solr.directoryFactory=solr.StandardDirectoryFactory&wt=javabin [junit4] 2> 1172952 INFO (qtp830381828-7571) [n:127.0.0.1:56416_solr x:testcollection_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=solrCloudCollectionConfig&newCollection=true&collection=testcollection&version=2&replicaType=NRT&async=5729ce82-c673-49be-a582-fd29fa58113630923518273605235&coreNodeName=core_node2&name=testcollection_shard1_replica_n1&action=CREATE&numShards=2&shard=shard1&property.solr.directoryFactory=solr.StandardDirectoryFactory&wt=javabin} status=0 QTime=56 [junit4] 2> 1172953 INFO (parallelCoreAdminExecutor-3004-thread-1-processing-n:127.0.0.1:56416_solr x:testcollection_shard1_replica_n1 5729ce82-c673-49be-a582-fd29fa58113630923518273605235 CREATE) [n:127.0.0.1:56416_solr x:testcollection_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=solrCloudCollectionConfig&newCollection=true&collection=testcollection&version=2&replicaType=NRT&async=5729ce82-c673-49be-a582-fd29fa58113630923518273605235&coreNodeName=core_node2&name=testcollection_shard1_replica_n1&action=CREATE&numShards=2&shard=shard1&property.solr.directoryFactory=solr.StandardDirectoryFactory&wt=javabin [junit4] 2> 1172968 INFO (qtp426282786-7586) [n:127.0.0.1:59959_solr x:testcollection_shard2_replica_n7] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=solrCloudCollectionConfig&newCollection=true&collection=testcollection&version=2&replicaType=NRT&async=5729ce82-c673-49be-a582-fd29fa58113630923518302687791&coreNodeName=core_node8&name=testcollection_shard2_replica_n7&action=CREATE&numShards=2&shard=shard2&property.solr.directoryFactory=solr.StandardDirectoryFactory&wt=javabin} status=0 QTime=13 [junit4] 2> 1172988 INFO (parallelCoreAdminExecutor-3003-thread-1-processing-n:127.0.0.1:59959_solr x:testcollection_shard2_replica_n7 5729ce82-c673-49be-a582-fd29fa58113630923518302687791 CREATE) [n:127.0.0.1:59959_solr x:testcollection_shard2_replica_n7] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=solrCloudCollectionConfig&newCollection=true&collection=testcollection&version=2&replicaType=NRT&async=5729ce82-c673-49be-a582-fd29fa58113630923518302687791&coreNodeName=core_node8&name=testcollection_shard2_replica_n7&action=CREATE&numShards=2&shard=shard2&property.solr.directoryFactory=solr.StandardDirectoryFactory&wt=javabin [junit4] 2> 1173011 INFO (qtp783032430-7579) [n:127.0.0.1:56999_solr x:testcollection_shard2_replica_n5] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=solrCloudCollectionConfig&newCollection=true&collection=testcollection&version=2&replicaType=NRT&async=5729ce82-c673-49be-a582-fd29fa58113630923518297982121&coreNodeName=core_node6&name=testcollection_shard2_replica_n5&action=CREATE&numShards=2&shard=shard2&property.solr.directoryFactory=solr.StandardDirectoryFactory&wt=javabin} status=0 QTime=34 [junit4] 2> 1173013 INFO (parallelCoreAdminExecutor-3002-thread-1-processing-n:127.0.0.1:56999_solr x:testcollection_shard2_replica_n5 5729ce82-c673-49be-a582-fd29fa58113630923518297982121 CREATE) [n:127.0.0.1:56999_solr x:testcollection_shard2_replica_n5] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=solrCloudCollectionConfig&newCollection=true&collection=testcollection&version=2&replicaType=NRT&async=5729ce82-c673-49be-a582-fd29fa58113630923518297982121&coreNodeName=core_node6&name=testcollection_shard2_replica_n5&action=CREATE&numShards=2&shard=shard2&property.solr.directoryFactory=solr.StandardDirectoryFactory&wt=javabin [junit4] 2> 1173014 INFO (parallelCoreAdminExecutor-3002-thread-1-processing-n:127.0.0.1:56999_solr x:testcollection_shard2_replica_n5 5729ce82-c673-49be-a582-fd29fa58113630923518297982121 CREATE) [n:127.0.0.1:56999_solr x:testcollection_shard2_replica_n5] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1173017 INFO (qtp783032430-7575) [n:127.0.0.1:56999_solr ] o.a.s.h.a.CoreAdminOperation Checking request status for : 5729ce82-c673-49be-a582-fd29fa58113630923518297982121 [junit4] 2> 1173017 INFO (qtp783032430-7575) [n:127.0.0.1:56999_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=5729ce82-c673-49be-a582-fd29fa58113630923518297982121&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1173502 INFO (qtp783032430-7543) [n:127.0.0.1:56999_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=5729ce82-c673-49be-a582-fd29fa581136&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1173504 INFO (qtp783032430-7543) [n:127.0.0.1:56999_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=5729ce82-c673-49be-a582-fd29fa581136&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=2 [junit4] 2> 1174004 INFO (parallelCoreAdminExecutor-3021-thread-1-processing-n:127.0.0.1:36682_solr x:testcollection_shard1_replica_n3 5729ce82-c673-49be-a582-fd29fa58113630923518278009818 CREATE) [n:127.0.0.1:36682_solr c:testcollection s:shard1 r:core_node4 x:testcollection_shard1_replica_n3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0 [junit4] 2> 1174023 INFO (qtp783032430-7578) [n:127.0.0.1:56999_solr ] o.a.s.h.a.CoreAdminOperation Checking request status for : 5729ce82-c673-49be-a582-fd29fa58113630923518297982121 [junit4] 2> 1174023 INFO (qtp783032430-7578) [n:127.0.0.1:56999_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=5729ce82-c673-49be-a582-fd29fa58113630923518297982121&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1174028 INFO (parallelCoreAdminExecutor-3003-thread-1-processing-n:127.0.0.1:59959_solr x:testcollection_shard2_replica_n7 5729ce82-c673-49be-a582-fd29fa58113630923518302687791 CREATE) [n:127.0.0 [...truncated too long message...] ng settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/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-BadApples-Tests-7.x/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-BadApples-Tests-7.x/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-BadApples-Tests-7.x/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-BadApples-Tests-7.x/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-BadApples-Tests-7.x/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-BadApples-Tests-7.x/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-BadApples-Tests-7.x/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-BadApples-Tests-7.x/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-BadApples-Tests-7.x/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-BadApples-Tests-7.x/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-BadApples-Tests-7.x/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-BadApples-Tests-7.x/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-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml resolve: jar-checksums: [mkdir] Created dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/null1479058244 [copy] Copying 247 files to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/null1479058244 [delete] Deleting directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/null1479058244 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 34ms :: artifacts dl 2ms --------------------------------------------------------------------- | | 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: 271 minutes 13 seconds Archiving artifacts java.lang.InterruptedException: no matches found within 10000 at hudson.FilePath$34.hasMatch(FilePath.java:2678) at hudson.FilePath$34.invoke(FilePath.java:2557) at hudson.FilePath$34.invoke(FilePath.java:2547) at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2918) Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to lucene at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1741) at hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357) at hudson.remoting.Channel.call(Channel.java:955) at hudson.FilePath.act(FilePath.java:1036) at hudson.FilePath.act(FilePath.java:1025) at hudson.FilePath.validateAntFileMask(FilePath.java:2547) at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243) at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744) at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690) at hudson.model.Build$BuildExecution.post2(Build.java:186) at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635) at hudson.model.Run.execute(Run.java:1819) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43) at hudson.model.ResourceController.execute(ResourceController.java:97) at hudson.model.Executor.run(Executor.java:429) Caused: hudson.FilePath$TunneledInterruptedException at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2920) at hudson.remoting.UserRequest.perform(UserRequest.java:212) at hudson.remoting.UserRequest.perform(UserRequest.java:54) at hudson.remoting.Request$2.run(Request.java:369) at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72) 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) Caused: java.lang.InterruptedException: java.lang.InterruptedException: no matches found within 10000 at hudson.FilePath.act(FilePath.java:1038) at hudson.FilePath.act(FilePath.java:1025) at hudson.FilePath.validateAntFileMask(FilePath.java:2547) at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243) at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744) at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690) at hudson.model.Build$BuildExecution.post2(Build.java:186) at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635) at hudson.model.Run.execute(Run.java:1819) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43) at hudson.model.ResourceController.execute(ResourceController.java:97) at hudson.model.Executor.run(Executor.java:429) No artifacts found that match the file pattern "**/*.events,heapdumps/**,**/hs_err_pid*". Configuration error? 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)
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org