You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2018/03/12 17:34:47 UTC
[JENKINS] Lucene-Solr-BadApples-Tests-7.x - Build # 12 - Still
Unstable
Build: https://builds.apache.org/job/Lucene-Solr-BadApples-Tests-7.x/12/
8 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.sim.TestComputePlanAction.testNodeAdded
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([2B57E6E4E7FE472E]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.autoscaling.sim.TestComputePlanAction
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([2B57E6E4E7FE472E]:0)
FAILED: org.apache.solr.update.processor.AtomicUpdateProcessorFactoryTest.testMultipleThreads
Error Message:
Exception during query
Stack Trace:
java.lang.RuntimeException: Exception during query
at __randomizedtesting.SeedInfo.seed([2B57E6E4E7FE472E:76591696B3A31AA]:0)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:908)
at org.apache.solr.update.processor.AtomicUpdateProcessorFactoryTest.testMultipleThreads(AtomicUpdateProcessorFactoryTest.java:260)
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.lang.RuntimeException: REQUEST FAILED: xpath=//result[@numFound=1]
xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result>
</response>
request was:q=int_i:18&wt=xml
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:901)
... 40 more
FAILED: org.apache.solr.cloud.autoscaling.AutoAddReplicasIntegrationTest.testSimple
Error Message:
Waiting for collection testSimple1 null Live Nodes: [127.0.0.1:38562_solr, 127.0.0.1:41365_solr] Last available state: DocCollection(testSimple1//collections/testSimple1/state.json/11)={ "pullReplicas":"0", "replicationFactor":"2", "shards":{ "shard1":{ "range":"80000000-ffffffff", "state":"active", "replicas":{ "core_node3":{ "core":"testSimple1_shard1_replica_n1", "base_url":"http://127.0.0.1:37008/solr", "node_name":"127.0.0.1:37008_solr", "state":"down", "type":"NRT"}, "core_node5":{ "core":"testSimple1_shard1_replica_n2", "base_url":"http://127.0.0.1:41365/solr", "node_name":"127.0.0.1:41365_solr", "state":"active", "type":"NRT", "leader":"true"}}}, "shard2":{ "range":"0-7fffffff", "state":"active", "replicas":{ "core_node6":{ "core":"testSimple1_shard2_replica_n4", "base_url":"http://127.0.0.1:37008/solr", "node_name":"127.0.0.1:37008_solr", "state":"down", "type":"NRT"}, "core_node8":{ "core":"testSimple1_shard2_replica_n7", "base_url":"http://127.0.0.1:41365/solr", "node_name":"127.0.0.1:41365_solr", "state":"active", "type":"NRT", "leader":"true"}}}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"2", "autoAddReplicas":"true", "nrtReplicas":"2", "tlogReplicas":"0"}
Stack Trace:
java.lang.AssertionError: Waiting for collection testSimple1
null
Live Nodes: [127.0.0.1:38562_solr, 127.0.0.1:41365_solr]
Last available state: DocCollection(testSimple1//collections/testSimple1/state.json/11)={
"pullReplicas":"0",
"replicationFactor":"2",
"shards":{
"shard1":{
"range":"80000000-ffffffff",
"state":"active",
"replicas":{
"core_node3":{
"core":"testSimple1_shard1_replica_n1",
"base_url":"http://127.0.0.1:37008/solr",
"node_name":"127.0.0.1:37008_solr",
"state":"down",
"type":"NRT"},
"core_node5":{
"core":"testSimple1_shard1_replica_n2",
"base_url":"http://127.0.0.1:41365/solr",
"node_name":"127.0.0.1:41365_solr",
"state":"active",
"type":"NRT",
"leader":"true"}}},
"shard2":{
"range":"0-7fffffff",
"state":"active",
"replicas":{
"core_node6":{
"core":"testSimple1_shard2_replica_n4",
"base_url":"http://127.0.0.1:37008/solr",
"node_name":"127.0.0.1:37008_solr",
"state":"down",
"type":"NRT"},
"core_node8":{
"core":"testSimple1_shard2_replica_n7",
"base_url":"http://127.0.0.1:41365/solr",
"node_name":"127.0.0.1:41365_solr",
"state":"active",
"type":"NRT",
"leader":"true"}}}},
"router":{"name":"compositeId"},
"maxShardsPerNode":"2",
"autoAddReplicas":"true",
"nrtReplicas":"2",
"tlogReplicas":"0"}
at __randomizedtesting.SeedInfo.seed([2B57E6E4E7FE472E:13E4C21AC00D93FF]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.SolrCloudTestCase.waitForState(SolrCloudTestCase.java:269)
at org.apache.solr.cloud.autoscaling.AutoAddReplicasIntegrationTest.testSimple(AutoAddReplicasIntegrationTest.java:94)
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.HdfsAutoAddReplicasIntegrationTest.testSimple
Error Message:
Waiting for collection testSimple1 null Live Nodes: [127.0.0.1:35252_solr, 127.0.0.1:42944_solr] Last available state: DocCollection(testSimple1//collections/testSimple1/state.json/10)={ "pullReplicas":"0", "replicationFactor":"2", "shards":{ "shard1":{ "range":"80000000-ffffffff", "state":"active", "replicas":{ "core_node3":{ "dataDir":"hdfs://localhost:59706/data/testSimple1/core_node3/data/", "base_url":"https://127.0.0.1:38129/solr", "node_name":"127.0.0.1:38129_solr", "type":"NRT", "ulogDir":"hdfs://localhost:59706/data/testSimple1/core_node3/data/tlog", "core":"testSimple1_shard1_replica_n1", "shared_storage":"true", "state":"down"}, "core_node5":{ "dataDir":"hdfs://localhost:59706/data/testSimple1/core_node5/data/", "base_url":"https://127.0.0.1:42944/solr", "node_name":"127.0.0.1:42944_solr", "type":"NRT", "ulogDir":"hdfs://localhost:59706/data/testSimple1/core_node5/data/tlog", "core":"testSimple1_shard1_replica_n2", "shared_storage":"true", "state":"active", "leader":"true"}}}, "shard2":{ "range":"0-7fffffff", "state":"active", "replicas":{ "core_node7":{ "dataDir":"hdfs://localhost:59706/data/testSimple1/core_node7/data/", "base_url":"https://127.0.0.1:38129/solr", "node_name":"127.0.0.1:38129_solr", "type":"NRT", "ulogDir":"hdfs://localhost:59706/data/testSimple1/core_node7/data/tlog", "core":"testSimple1_shard2_replica_n4", "shared_storage":"true", "state":"down"}, "core_node8":{ "dataDir":"hdfs://localhost:59706/data/testSimple1/core_node8/data/", "base_url":"https://127.0.0.1:42944/solr", "node_name":"127.0.0.1:42944_solr", "type":"NRT", "ulogDir":"hdfs://localhost:59706/data/testSimple1/core_node8/data/tlog", "core":"testSimple1_shard2_replica_n6", "shared_storage":"true", "state":"active", "leader":"true"}}}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"2", "autoAddReplicas":"true", "nrtReplicas":"2", "tlogReplicas":"0"}
Stack Trace:
java.lang.AssertionError: Waiting for collection testSimple1
null
Live Nodes: [127.0.0.1:35252_solr, 127.0.0.1:42944_solr]
Last available state: DocCollection(testSimple1//collections/testSimple1/state.json/10)={
"pullReplicas":"0",
"replicationFactor":"2",
"shards":{
"shard1":{
"range":"80000000-ffffffff",
"state":"active",
"replicas":{
"core_node3":{
"dataDir":"hdfs://localhost:59706/data/testSimple1/core_node3/data/",
"base_url":"https://127.0.0.1:38129/solr",
"node_name":"127.0.0.1:38129_solr",
"type":"NRT",
"ulogDir":"hdfs://localhost:59706/data/testSimple1/core_node3/data/tlog",
"core":"testSimple1_shard1_replica_n1",
"shared_storage":"true",
"state":"down"},
"core_node5":{
"dataDir":"hdfs://localhost:59706/data/testSimple1/core_node5/data/",
"base_url":"https://127.0.0.1:42944/solr",
"node_name":"127.0.0.1:42944_solr",
"type":"NRT",
"ulogDir":"hdfs://localhost:59706/data/testSimple1/core_node5/data/tlog",
"core":"testSimple1_shard1_replica_n2",
"shared_storage":"true",
"state":"active",
"leader":"true"}}},
"shard2":{
"range":"0-7fffffff",
"state":"active",
"replicas":{
"core_node7":{
"dataDir":"hdfs://localhost:59706/data/testSimple1/core_node7/data/",
"base_url":"https://127.0.0.1:38129/solr",
"node_name":"127.0.0.1:38129_solr",
"type":"NRT",
"ulogDir":"hdfs://localhost:59706/data/testSimple1/core_node7/data/tlog",
"core":"testSimple1_shard2_replica_n4",
"shared_storage":"true",
"state":"down"},
"core_node8":{
"dataDir":"hdfs://localhost:59706/data/testSimple1/core_node8/data/",
"base_url":"https://127.0.0.1:42944/solr",
"node_name":"127.0.0.1:42944_solr",
"type":"NRT",
"ulogDir":"hdfs://localhost:59706/data/testSimple1/core_node8/data/tlog",
"core":"testSimple1_shard2_replica_n6",
"shared_storage":"true",
"state":"active",
"leader":"true"}}}},
"router":{"name":"compositeId"},
"maxShardsPerNode":"2",
"autoAddReplicas":"true",
"nrtReplicas":"2",
"tlogReplicas":"0"}
at __randomizedtesting.SeedInfo.seed([2B57E6E4E7FE472E:13E4C21AC00D93FF]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.SolrCloudTestCase.waitForState(SolrCloudTestCase.java:269)
at org.apache.solr.cloud.autoscaling.AutoAddReplicasIntegrationTest.testSimple(AutoAddReplicasIntegrationTest.java:94)
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.handler.admin.AutoscalingHistoryHandlerTest.testHistory
Error Message:
replica never fully recovered
Stack Trace:
java.lang.AssertionError: replica never fully recovered
at __randomizedtesting.SeedInfo.seed([2B57E6E4E7FE472E:46AB42195DB6B829]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest.waitForRecovery(AutoscalingHistoryHandlerTest.java:405)
at org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest.testHistory(AutoscalingHistoryHandlerTest.java:341)
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.ltr.TestLTRReRankingPipeline.testDifferentTopN
Error Message:
expected:<1.0> but was:<0.0>
Stack Trace:
java.lang.AssertionError: expected:<1.0> but was:<0.0>
at __randomizedtesting.SeedInfo.seed([B79F0610DD5FA417:463E7440E8E46E85]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:443)
at org.junit.Assert.assertEquals(Assert.java:512)
at org.apache.solr.ltr.TestLTRReRankingPipeline.testDifferentTopN(TestLTRReRankingPipeline.java:255)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at 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 org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.handler.TestReplicationHandler.doTestIndexFetchOnMasterRestart
Error Message:
IOException occured when talking to server at: http://127.0.0.1:55542/solr/collection1
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:55542/solr/collection1
at __randomizedtesting.SeedInfo.seed([2B57E6E4E7FE472E:F3A022004C258572]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:657)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194)
at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:173)
at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:138)
at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:152)
at org.apache.solr.handler.TestReplicationHandler.index(TestReplicationHandler.java:180)
at org.apache.solr.handler.TestReplicationHandler.doTestIndexFetchOnMasterRestart(TestReplicationHandler.java:643)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Caused by: org.apache.http.NoHttpResponseException: 127.0.0.1:55542 failed to respond
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:141)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:165)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185)
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:111)
at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:542)
... 47 more
Build Log:
[...truncated 12289 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_2B57E6E4E7FE472E-001/init-core-data-001
[junit4] 2> 270488 WARN (SUITE-TestReplicationHandler-seed#[2B57E6E4E7FE472E]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=19 numCloses=19
[junit4] 2> 270493 INFO (SUITE-TestReplicationHandler-seed#[2B57E6E4E7FE472E]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 270495 INFO (SUITE-TestReplicationHandler-seed#[2B57E6E4E7FE472E]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
[junit4] 2> 270495 INFO (SUITE-TestReplicationHandler-seed#[2B57E6E4E7FE472E]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 270517 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestReplicateAfterCoreReload
[junit4] 2> 270518 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_2B57E6E4E7FE472E-001/solr-instance-001/collection1
[junit4] 2> 270527 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T00:27:37+03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 270540 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 270540 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 270540 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 270541 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5dfa09b9{/solr,null,AVAILABLE}
[junit4] 2> 270545 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3a59ef8{HTTP/1.1,[http/1.1]}{127.0.0.1:46589}
[junit4] 2> 270545 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.Server Started @273868ms
[junit4] 2> 270545 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_2B57E6E4E7FE472E-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=46589}
[junit4] 2> 270545 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 270545 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.4.0
[junit4] 2> 270545 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 270545 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 270545 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-12T15:04:22.291Z
[junit4] 2> 270546 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_2B57E6E4E7FE472E-001/solr-instance-001/solr.xml
[junit4] 2> 270550 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 270550 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 270552 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@5fbdc677, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 270594 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5fbdc677
[junit4] 2> 270607 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5fbdc677
[junit4] 2> 270607 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5fbdc677
[junit4] 2> 270610 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_2B57E6E4E7FE472E-001/solr-instance-001/.
[junit4] 2> 270610 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 270610 INFO (coreLoadExecutor-718-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 270649 INFO (coreLoadExecutor-718-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0
[junit4] 2> 270658 INFO (coreLoadExecutor-718-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 270670 INFO (coreLoadExecutor-718-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 270670 INFO (coreLoadExecutor-718-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_2B57E6E4E7FE472E-001/solr-instance-001/./collection1, trusted=true
[junit4] 2> 270670 INFO (coreLoadExecutor-718-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5fbdc677
[junit4] 2> 270670 INFO (coreLoadExecutor-718-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 270671 INFO (coreLoadExecutor-718-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_2B57E6E4E7FE472E-001/solr-instance-001/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_2B57E6E4E7FE472E-001/solr-instance-001/./collection1/data/]
[junit4] 2> 270673 INFO (coreLoadExecutor-718-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=44, maxMergedSegmentMB=20.1875, floorSegmentMB=0.3369140625, forceMergeDeletesPctAllowed=12.553520850342473, segmentsPerTier=45.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 270730 INFO (coreLoadExecutor-718-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 270730 INFO (coreLoadExecutor-718-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 270731 INFO (coreLoadExecutor-718-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=30, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6674423176957711]
[junit4] 2> 270731 INFO (coreLoadExecutor-718-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@449d2c46[collection1] main]
[junit4] 2> 270732 INFO (coreLoadExecutor-718-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_2B57E6E4E7FE472E-001/solr-instance-001/collection1/conf
[junit4] 2> 270733 INFO (coreLoadExecutor-718-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 270733 INFO (coreLoadExecutor-718-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 270734 INFO (searcherExecutor-719-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@449d2c46[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 270735 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_2B57E6E4E7FE472E-001/solr-instance-002/collection1
[junit4] 2> 270738 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T00:27:37+03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 270739 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 270739 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 270739 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 270740 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@134cafe{/solr,null,AVAILABLE}
[junit4] 2> 270740 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@438bcdae{HTTP/1.1,[http/1.1]}{127.0.0.1:60629}
[junit4] 2> 270740 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.Server Started @274064ms
[junit4] 2> 270740 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_2B57E6E4E7FE472E-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=60629}
[junit4] 2> 270741 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 270741 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.4.0
[junit4] 2> 270741 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 270741 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 270741 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-12T15:04:22.487Z
[junit4] 2> 270741 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_2B57E6E4E7FE472E-001/solr-instance-002/solr.xml
[junit4] 2> 270749 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 270749 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 270751 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@5fbdc677, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 270802 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5fbdc677
[junit4] 2> 270817 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5fbdc677
[junit4] 2> 270817 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5fbdc677
[junit4] 2> 270820 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_2B57E6E4E7FE472E-001/solr-instance-002/.
[junit4] 2> 270820 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 270821 INFO (coreLoadExecutor-728-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 270838 INFO (coreLoadExecutor-728-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0
[junit4] 2> 270847 INFO (coreLoadExecutor-728-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 270850 INFO (coreLoadExecutor-728-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 270850 INFO (coreLoadExecutor-728-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_2B57E6E4E7FE472E-001/solr-instance-002/./collection1, trusted=true
[junit4] 2> 270850 INFO (coreLoadExecutor-728-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5fbdc677
[junit4] 2> 270850 INFO (coreLoadExecutor-728-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 270850 INFO (coreLoadExecutor-728-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_2B57E6E4E7FE472E-001/solr-instance-002/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_2B57E6E4E7FE472E-001/solr-instance-002/./collection1/data/]
[junit4] 2> 270853 INFO (coreLoadExecutor-728-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=44, maxMergedSegmentMB=20.1875, floorSegmentMB=0.3369140625, forceMergeDeletesPctAllowed=12.553520850342473, segmentsPerTier=45.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 270934 INFO (coreLoadExecutor-728-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 270934 INFO (coreLoadExecutor-728-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 270936 INFO (coreLoadExecutor-728-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=30, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6674423176957711]
[junit4] 2> 270936 INFO (coreLoadExecutor-728-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5bcec623[collection1] main]
[junit4] 2> 270936 INFO (coreLoadExecutor-728-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_2B57E6E4E7FE472E-001/solr-instance-002/collection1/conf
[junit4] 2> 270940 INFO (coreLoadExecutor-728-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 270940 INFO (coreLoadExecutor-728-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 270941 INFO (searcherExecutor-729-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5bcec623[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 270943 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@438bcdae{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 270943 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=928620667
[junit4] 2> 270943 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 270943 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@34b58de8: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@225c3af7
[junit4] 2> 270948 INFO (qtp252647274-1758) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 270949 INFO (indexFetcher-733-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 1
[junit4] 2> 270949 INFO (indexFetcher-733-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 0
[junit4] 2> 270949 INFO (indexFetcher-733-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 270949 INFO (indexFetcher-733-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's version: 0
[junit4] 2> 270949 INFO (indexFetcher-733-thread-1) [ x:collection1] o.a.s.h.IndexFetcher New index in Master. Deleting mine...
[junit4] 2> 270949 INFO (indexFetcher-733-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 270949 INFO (indexFetcher-733-thread-1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1e74bafd commitCommandVersion:0
[junit4] 2> 270951 INFO (indexFetcher-733-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5544323a[collection1] main]
[junit4] 2> 270951 INFO (indexFetcher-733-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 270952 INFO (searcherExecutor-729-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5544323a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 270953 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 270953 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@41e6da67: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@42cf2869
[junit4] 2> 270959 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 270959 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@55b903f7: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@611dc192
[junit4] 2> 270960 INFO (coreCloseExecutor-734-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@285046d7
[junit4] 2> 270960 INFO (coreCloseExecutor-734-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=676349655
[junit4] 2> 270960 INFO (coreCloseExecutor-734-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1a3bfc42: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@6957110a
[junit4] 2> 270972 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@134cafe{/solr,null,UNAVAILABLE}
[junit4] 2> 270972 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 270974 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@3a59ef8{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 270974 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=534963226
[junit4] 2> 270974 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 270974 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@67838b7e: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@21275a13
[junit4] 2> 270976 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 270976 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@411b08ed: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@42cf2869
[junit4] 2> 270977 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 270977 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@dd54db2: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@611dc192
[junit4] 2> 270978 INFO (coreCloseExecutor-736-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@19bef338
[junit4] 2> 270979 INFO (coreCloseExecutor-736-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=431944504
[junit4] 2> 270979 INFO (coreCloseExecutor-736-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6fe19c71: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@39974d15
[junit4] 2> 270983 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5dfa09b9{/solr,null,UNAVAILABLE}
[junit4] 2> 270983 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 270986 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T00:27:37+03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 270989 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 270989 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 270989 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 270989 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@421d6436{/solr,null,AVAILABLE}
[junit4] 2> 270990 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@47172f3b{HTTP/1.1,[http/1.1]}{127.0.0.1:56798}
[junit4] 2> 270990 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.Server Started @274313ms
[junit4] 2> 270990 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_2B57E6E4E7FE472E-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=56798}
[junit4] 2> 270990 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 270993 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.4.0
[junit4] 2> 270993 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 270993 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 270993 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-12T15:04:22.739Z
[junit4] 2> 270993 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_2B57E6E4E7FE472E-001/solr-instance-001/solr.xml
[junit4] 2> 270998 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 270998 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 270999 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@5fbdc677, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 271051 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5fbdc677
[junit4] 2> 271064 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5fbdc677
[junit4] 2> 271064 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5fbdc677
[junit4] 2> 271067 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_2B57E6E4E7FE472E-001/solr-instance-001/.
[junit4] 2> 271067 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 271069 INFO (coreLoadExecutor-743-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 271082 INFO (coreLoadExecutor-743-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0
[junit4] 2> 271092 INFO (coreLoadExecutor-743-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 271094 INFO (coreLoadExecutor-743-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 271094 INFO (coreLoadExecutor-743-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_2B57E6E4E7FE472E-001/solr-instance-001/./collection1, trusted=true
[junit4] 2> 271094 INFO (coreLoadExecutor-743-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5fbdc677
[junit4] 2> 271094 INFO (coreLoadExecutor-743-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 271095 INFO (coreLoadExecutor-743-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_2B57E6E4E7FE472E-001/solr-instance-001/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_2B57E6E4E7FE472E-001/solr-instance-001/./collection1/data/]
[junit4] 2> 271097 INFO (coreLoadExecutor-743-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=44, maxMergedSegmentMB=20.1875, floorSegmentMB=0.3369140625, forceMergeDeletesPctAllowed=12.553520850342473, segmentsPerTier=45.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 271154 INFO (coreLoadExecutor-743-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 271155 INFO (coreLoadExecutor-743-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 271156 INFO (coreLoadExecutor-743-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=30, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6674423176957711]
[junit4] 2> 271156 INFO (coreLoadExecutor-743-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@24e02158[collection1] main]
[junit4] 2> 271157 INFO (coreLoadExecutor-743-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_2B57E6E4E7FE472E-001/solr-instance-001/collection1/conf
[junit4] 2> 271157 INFO (coreLoadExecutor-743-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml
[junit4] 2> 271158 INFO (coreLoadExecutor-743-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 271161 INFO (searcherExecutor-744-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@24e02158[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 271163 INFO (qtp1797026595-1801) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 271163 INFO (qtp1797026595-1801) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 1
[junit4] 2> 271164 INFO (qtp1797026595-1803) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 271164 INFO (qtp1797026595-1803) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@b02b05b commitCommandVersion:0
[junit4] 2> 271165 INFO (qtp1797026595-1803) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@44f85570[collection1] main]
[junit4] 2> 271165 INFO (qtp1797026595-1803) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 271167 INFO (searcherExecutor-744-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@44f85570[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 271167 INFO (qtp1797026595-1803) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 3
[junit4] 2> 271168 INFO (qtp1797026595-1805) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 271168 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 0 docs
[junit4] 2> 271170 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T00:27:37+03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 271177 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 271177 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 271177 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 271178 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7f851661{/solr,null,AVAILABLE}
[junit4] 2> 271178 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5f6d3943{HTTP/1.1,[http/1.1]}{127.0.0.1:41449}
[junit4] 2> 271178 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.e.j.s.Server Started @274502ms
[junit4] 2> 271179 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_2B57E6E4E7FE472E-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=41449}
[junit4] 2> 271179 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 271179 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.4.0
[junit4] 2> 271179 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 271179 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 271179 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-12T15:04:22.925Z
[junit4] 2> 271180 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_2B57E6E4E7FE472E-001/solr-instance-002/solr.xml
[junit4] 2> 271184 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 271184 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 271194 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@5fbdc677, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 271239 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5fbdc677
[junit4] 2> 271253 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5fbdc677
[junit4] 2> 271253 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5fbdc677
[junit4] 2> 271256 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_2B57E6E4E7FE472E-001/solr-instance-002/.
[junit4] 2> 271257 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 271257 INFO (coreLoadExecutor-753-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 271287 INFO (coreLoadExecutor-753-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0
[junit4] 2> 271296 INFO (coreLoadExecutor-753-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 271298 INFO (coreLoadExecutor-753-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 271298 INFO (coreLoadExecutor-753-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_2B57E6E4E7FE472E-001/solr-instance-002/./collection1, trusted=true
[junit4] 2> 271299 INFO (coreLoadExecutor-753-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5fbdc677
[junit4] 2> 271299 INFO (coreLoadExecutor-753-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 271299 INFO (coreLoadExecutor-753-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_2B57E6E4E7FE472E-001/solr-instance-002/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_2B57E6E4E7FE472E-001/solr-instance-002/./collection1/data/]
[junit4] 2> 271302 INFO (coreLoadExecutor-753-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=44, maxMergedSegmentMB=20.1875, floorSegmentMB=0.3369140625, forceMergeDeletesPctAllowed=12.553520850342473, segmentsPerTier=45.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 271359 INFO (coreLoadExecutor-753-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 271359 INFO (coreLoadExecutor-753-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 271361 INFO (coreLoadExecutor-753-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=30, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6674423176957711]
[junit4] 2> 271361 INFO (coreLoadExecutor-753-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@9454b60[collection1] main]
[junit4] 2> 271361 INFO (coreLoadExecutor-753-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_2B57E6E4E7FE472E-001/solr-instance-002/collection1/conf
[junit4] 2> 271363 INFO (coreLoadExecutor-753-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 271363 INFO (coreLoadExecutor-753-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 271365 INFO (searcherExecutor-754-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@9454b60[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 271366 INFO (qtp1797026595-1805) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 271366 INFO (indexFetcher-758-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2
[junit4] 2> 271366 INFO (indexFetcher-758-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1520867062910
[junit4] 2> 271366 INFO (indexFetcher-758-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 271366 INFO (indexFetcher-758-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's version: 0
[junit4] 2> 271366 INFO (indexFetcher-758-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 271369 INFO (qtp1797026595-1806) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&tlogFiles=false&wt=javabin&version=2&command=filelist} status=0 QTime=1
[junit4] 2> 271369 INFO (indexFetcher-758-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 1
[junit4] 2> 271381 INFO (indexFetcher-758-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 271383 INFO (indexFetcher-758-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 271383 INFO (indexFetcher-758-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@11b9bb85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2129631)
[junit4] 2> 271384 INFO (qtp1797026595-1799) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=segments_2&checksum=true&compression=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 271393 INFO (indexFetcher-758-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=117) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@11b9bb85 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2129631)
[junit4] 2> 271405 INFO (indexFetcher-758-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=46, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.41513830151790154]
[junit4] 2> 271405 INFO (qtp986428115-1821) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 271406 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[2B57E6E4E7FE472E]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 0 docs
[junit4] 2> 271407 INFO (qtp1797026595-1803) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getIndexVersion&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 271408 INFO (qtp1797026595-1799) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getCommits&qt=/replication&wt=javabin&version=2&command=commits} status=0 QTime=0
[junit4] 2> 271408 INFO (indexFetcher-758-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 271408 INFO (indexFetcher-758-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@62e93260[collection1] main]
[junit4] 2> 271410 INFO (searcherExecutor-754-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore
[...truncated too long message...]
java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] 2> at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 19) Thread[id=9863, name=simCloudManagerPool-3719-thread-11, state=WAITING, group=TGRP-TestComputePlanAction]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] 2> at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 20) Thread[id=9854, name=simCloudManagerPool-3719-thread-2, state=WAITING, group=TGRP-TestComputePlanAction]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] 2> at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 21) Thread[id=9868, name=simCloudManagerPool-3719-thread-15, state=WAITING, group=TGRP-TestComputePlanAction]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] 2> at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 22) Thread[id=9858, name=simCloudManagerPool-3719-thread-6, state=WAITING, group=TGRP-TestComputePlanAction]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] 2> at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=666, maxMBSortInHeap=6.599817364285484, sim=RandomSimilarity(queryNorm=false): {}, locale=ar-IQ, timezone=Asia/Chita
[junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=4,threads=23,free=469346480,total=534773760
[junit4] 2> NOTE: All tests run in this JVM: [SolrRequestParserTest, TestIndexingPerformance, LargeFieldTest, TestSystemCollAutoCreate, TestAuthenticationFramework, TestMacroExpander, OverseerTaskQueueTest, ClassificationUpdateProcessorFactoryTest, TermVectorComponentDistributedTest, TestGroupingSearch, ExecutePlanActionTest, TestFieldResource, TestConfigOverlay, SpatialHeatmapFacetsTest, TestLegacyTerms, TestHashQParserPlugin, AddSchemaFieldsUpdateProcessorFactoryTest, HdfsRecoveryZkTest, TestDynamicFieldCollectionResource, TestSort, CleanupOldIndexTest, IndexBasedSpellCheckerTest, TestCSVLoader, SolrGangliaReporterTest, TestChildDocTransformer, CollectionStateFormat2Test, TestSolrFieldCacheBean, TestMaxScoreQueryParser, TestSolrConfigHandler, DistributedFacetSimpleRefinementLongTailTest, ShardRoutingTest, TestSolrCoreSnapshots, TestZkAclsWithHadoopAuth, ZkCLITest, TestJsonFacetsWithNestedObjects, TestExtendedDismaxParser, TemplateUpdateProcessorTest, TestComponentsName, AutoAddReplicasPlanActionTest, TestReloadDeadlock, ReplicationFactorTest, ConfigSetsAPITest, TestReplicaProperties, SolrPluginUtilsTest, ChaosMonkeyNothingIsSafeTest, PluginInfoTest, TestTrieFacet, EchoParamsTest, OverseerModifyCollectionTest, CurrencyFieldTypeTest, DistributedQueryComponentOptimizationTest, SolrJmxReporterTest, TestRandomFaceting, TestFieldCacheSort, SolrCLIZkUtilsTest, SchemaApiFailureTest, UpdateParamsTest, TestConfig, PropertiesRequestHandlerTest, SmileWriterTest, TestDistribDocBasedVersion, RequestHandlersTest, TestRTimerTree, TestJoin, ConcurrentDeleteAndCreateCollectionTest, HdfsBasicDistributedZk2Test, DistributedTermsComponentTest, DistributedDebugComponentTest, DeleteStatusTest, DefaultValueUpdateProcessorTest, TolerantUpdateProcessorTest, TestDefaultStatsCache, TestJavabinTupleStreamParser, TestFieldCacheWithThreads, SynonymTokenizerTest, TestCollationField, CursorPagingTest, BlockJoinFacetRandomTest, TestTriggerIntegration, TestFieldSortValues, TestSQLHandlerNonCloud, TestBlendedInfixSuggestions, TestExportWriter, TestDocumentBuilder, UUIDFieldTest, DistribJoinFromCollectionTest, TestCloudInspectUtil, AnalysisErrorHandlingTest, MBeansHandlerTest, MetricTriggerTest, TestBinaryField, CollectionTooManyReplicasTest, TestUseDocValuesAsStored, LeaderVoteWaitTimeoutTest, ConjunctionSolrSpellCheckerTest, SpellingQueryConverterTest, TestRawTransformer, TestWordDelimiterFilterFactory, TestSolrQueryParser, StatsReloadRaceTest, TestLegacyField, CoreMergeIndexesAdminHandlerTest, TestSchemaNameResource, HighlighterConfigTest, TestRebalanceLeaders, TestPivotHelperCode, SearchHandlerTest, TestStressLucene, TestComputePlanAction]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestComputePlanAction -Dtests.seed=2B57E6E4E7FE472E -Dtests.multiplier=2 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=ar-IQ -Dtests.timezone=Asia/Chita -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J0 | TestComputePlanAction (suite) <<<
[junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
[junit4] > at __randomizedtesting.SeedInfo.seed([2B57E6E4E7FE472E]:0)
[junit4] Completed [764/783 (6!)] on J0 in 7283.23s, 2 tests, 2 errors <<< FAILURES!
[...truncated 7504 lines...]
[junit4] Suite: org.apache.solr.ltr.TestLTRReRankingPipeline
[junit4] 2> 16005 INFO (TEST-TestLTRReRankingPipeline.testDifferentTopN-seed#[B79F0610DD5FA417]) [ ] o.a.s.l.TestLTRReRankingPipeline rerank 1 documents
[junit4] 2> 16007 INFO (TEST-TestLTRReRankingPipeline.testDifferentTopN-seed#[B79F0610DD5FA417]) [ ] o.a.s.l.TestLTRReRankingPipeline doc 0 in pos 0
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestLTRReRankingPipeline -Dtests.method=testDifferentTopN -Dtests.seed=B79F0610DD5FA417 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=th -Dtests.timezone=Asia/Singapore -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 0.36s J0 | TestLTRReRankingPipeline.testDifferentTopN <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<1.0> but was:<0.0>
[junit4] > at __randomizedtesting.SeedInfo.seed([B79F0610DD5FA417:463E7440E8E46E85]:0)
[junit4] > at org.apache.solr.ltr.TestLTRReRankingPipeline.testDifferentTopN(TestLTRReRankingPipeline.java:255)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: test params are: codec=HighCompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=29219, maxDocsPerChunk=549, blockSize=10), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=29219, blockSize=10)), sim=RandomSimilarity(queryNorm=true): {field=DFR I(ne)3(800.0)}, locale=th, timezone=Asia/Singapore
[junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=4,threads=1,free=268343064,total=335544320
[junit4] 2> NOTE: All tests run in this JVM: [TestUserTermScorereQDF, TestNeuralNetworkModel, TestLTRWithSort, TestLTRReRankingPipeline]
[junit4] Completed [10/37 (1!)] on J0 in 0.57s, 3 tests, 1 failure <<< FAILURES!
[...truncated 42094 lines...]
[JENKINS] Lucene-Solr-BadApples-Tests-7.x - Build # 13 - Still
Unstable
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://builds.apache.org/job/Lucene-Solr-BadApples-Tests-7.x/13/
7 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.sim.TestLargeCluster.testSearchRate
Error Message:
The trigger did not fire at all
Stack Trace:
java.lang.AssertionError: The trigger did not fire at all
at __randomizedtesting.SeedInfo.seed([472FEA71BD2A3A1:593AE02ED41405EE]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.cloud.autoscaling.sim.TestLargeCluster.testSearchRate(TestLargeCluster.java:550)
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.sim.TestTriggerIntegration.testNodeAddedTriggerRestoreState
Error Message:
Two TriggerAction instances should have been created by now
Stack Trace:
java.lang.AssertionError: Two TriggerAction instances should have been created by now
at __randomizedtesting.SeedInfo.seed([472FEA71BD2A3A1:8C4F77D82112420C]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration.testNodeAddedTriggerRestoreState(TestTriggerIntegration.java:364)
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.sim.TestTriggerIntegration.testSearchRate
Error Message:
The trigger did not fire at all
Stack Trace:
java.lang.AssertionError: The trigger did not fire at all
at __randomizedtesting.SeedInfo.seed([472FEA71BD2A3A1:593AE02ED41405EE]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.cloud.autoscaling.sim.TestTriggerIntegration.testSearchRate(TestTriggerIntegration.java:1185)
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.client.solrj.io.stream.StreamExpressionTest.testDistributions
Error Message:
Stack Trace:
java.lang.AssertionError
at __randomizedtesting.SeedInfo.seed([C2A064C5459A139A:7D5F256F9B60F306]:0)
at org.junit.Assert.fail(Assert.java:92)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertTrue(Assert.java:54)
at org.apache.solr.client.solrj.io.stream.StreamExpressionTest.testDistributions(StreamExpressionTest.java:6639)
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.handler.admin.AutoscalingHistoryHandlerTest.testHistory
Error Message:
expected:<8> but was:<10>
Stack Trace:
java.lang.AssertionError: expected:<8> but was:<10>
at __randomizedtesting.SeedInfo.seed([472FEA71BD2A3A1:698E5A5AA19A5CA6]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest.testHistory(AutoscalingHistoryHandlerTest.java:354)
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.ltr.TestLTRReRankingPipeline.testDifferentTopN
Error Message:
expected:<1.0> but was:<0.0>
Stack Trace:
java.lang.AssertionError: expected:<1.0> but was:<0.0>
at __randomizedtesting.SeedInfo.seed([753A09768479DD29:849B7B26B1C217BB]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:443)
at org.junit.Assert.assertEquals(Assert.java:512)
at org.apache.solr.ltr.TestLTRReRankingPipeline.testDifferentTopN(TestLTRReRankingPipeline.java:255)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at 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 org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.handler.TestReplicationHandler.doTestIndexFetchOnMasterRestart
Error Message:
IOException occured when talking to server at: http://127.0.0.1:40066/solr/collection1
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:40066/solr/collection1
at __randomizedtesting.SeedInfo.seed([472FEA71BD2A3A1:DC853A43B00961FD]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:657)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194)
at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:173)
at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:138)
at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:152)
at org.apache.solr.handler.TestReplicationHandler.index(TestReplicationHandler.java:180)
at org.apache.solr.handler.TestReplicationHandler.doTestIndexFetchOnMasterRestart(TestReplicationHandler.java:643)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Caused by: org.apache.http.NoHttpResponseException: 127.0.0.1:40066 failed to respond
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:141)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:165)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185)
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:111)
at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:542)
... 47 more
Build Log:
[...truncated 1772 lines...]
[junit4] JVM J0: stdout was not empty, see: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/build/core/test/temp/junit4-J0-20180314_051707_2623275096329224685381.sysout
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] codec: CheapBastard, pf: Direct, dvf: Asserting
[junit4] <<< JVM J0: EOF ----
[...truncated 10622 lines...]
[junit4] Suite: org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest
[junit4] 2> Creating dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_472FEA71BD2A3A1-001/init-core-data-001
[junit4] 2> 607378 WARN (SUITE-AutoscalingHistoryHandlerTest-seed#[472FEA71BD2A3A1]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=7 numCloses=7
[junit4] 2> 607379 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[472FEA71BD2A3A1]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 607397 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[472FEA71BD2A3A1]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 607397 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[472FEA71BD2A3A1]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 607398 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[472FEA71BD2A3A1]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_472FEA71BD2A3A1-001/tempDir-001
[junit4] 2> 607398 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[472FEA71BD2A3A1]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 607400 INFO (Thread-915) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 607400 INFO (Thread-915) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 607421 ERROR (Thread-915) [ ] 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> 607500 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[472FEA71BD2A3A1]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:33280
[junit4] 2> 607504 INFO (zkConnectionManagerCallback-646-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 607560 INFO (jetty-launcher-643-thread-1) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T17:27:37-04:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 607560 INFO (jetty-launcher-643-thread-2) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T17:27:37-04:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 607580 INFO (jetty-launcher-643-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 607580 INFO (jetty-launcher-643-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 607580 INFO (jetty-launcher-643-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 607580 INFO (jetty-launcher-643-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 607580 INFO (jetty-launcher-643-thread-2) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 607580 INFO (jetty-launcher-643-thread-1) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 607580 INFO (jetty-launcher-643-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1a13146b{/solr,null,AVAILABLE}
[junit4] 2> 607580 INFO (jetty-launcher-643-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@396520df{/solr,null,AVAILABLE}
[junit4] 2> 607598 INFO (jetty-launcher-643-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@37fe3045{SSL,[ssl, http/1.1]}{127.0.0.1:45941}
[junit4] 2> 607599 INFO (jetty-launcher-643-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7bcb1858{SSL,[ssl, http/1.1]}{127.0.0.1:33935}
[junit4] 2> 607599 INFO (jetty-launcher-643-thread-2) [ ] o.e.j.s.Server Started @619962ms
[junit4] 2> 607599 INFO (jetty-launcher-643-thread-1) [ ] o.e.j.s.Server Started @619962ms
[junit4] 2> 607599 INFO (jetty-launcher-643-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45941}
[junit4] 2> 607599 INFO (jetty-launcher-643-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33935}
[junit4] 2> 607599 ERROR (jetty-launcher-643-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 607599 ERROR (jetty-launcher-643-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 607599 INFO (jetty-launcher-643-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.4.0
[junit4] 2> 607599 INFO (jetty-launcher-643-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.4.0
[junit4] 2> 607599 INFO (jetty-launcher-643-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 607599 INFO (jetty-launcher-643-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 607599 INFO (jetty-launcher-643-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 607599 INFO (jetty-launcher-643-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 607599 INFO (jetty-launcher-643-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-14T06:09:24.239Z
[junit4] 2> 607599 INFO (jetty-launcher-643-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-14T06:09:24.239Z
[junit4] 2> 607624 INFO (zkConnectionManagerCallback-648-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 607624 INFO (zkConnectionManagerCallback-650-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 607628 INFO (jetty-launcher-643-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 607628 INFO (jetty-launcher-643-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 607656 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 0x100c7632ab30002, likely client has closed socket
[junit4] 2> 607659 INFO (jetty-launcher-643-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33280/solr
[junit4] 2> 607660 INFO (jetty-launcher-643-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33280/solr
[junit4] 2> 607674 INFO (zkConnectionManagerCallback-658-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 607703 INFO (zkConnectionManagerCallback-654-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 607735 INFO (zkConnectionManagerCallback-660-thread-1-processing-n:127.0.0.1:33935_solr) [n:127.0.0.1:33935_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 607736 INFO (zkConnectionManagerCallback-662-thread-1-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 608151 INFO (jetty-launcher-643-thread-2) [n:127.0.0.1:45941_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 608151 INFO (jetty-launcher-643-thread-1) [n:127.0.0.1:33935_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 608161 INFO (jetty-launcher-643-thread-2) [n:127.0.0.1:45941_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:45941_solr
[junit4] 2> 608162 INFO (jetty-launcher-643-thread-1) [n:127.0.0.1:33935_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33935_solr
[junit4] 2> 608162 INFO (jetty-launcher-643-thread-2) [n:127.0.0.1:45941_solr ] o.a.s.c.Overseer Overseer (id=72276822769991686-127.0.0.1:45941_solr-n_0000000000) starting
[junit4] 2> 608251 INFO (zkCallback-659-thread-1-processing-n:127.0.0.1:33935_solr) [n:127.0.0.1:33935_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 608254 INFO (zkCallback-661-thread-1-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 608290 INFO (zkConnectionManagerCallback-668-thread-1-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 608291 INFO (jetty-launcher-643-thread-2) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 608292 INFO (jetty-launcher-643-thread-2) [n:127.0.0.1:45941_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33280/solr ready
[junit4] 2> 608293 DEBUG (OverseerStateUpdate-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 608308 INFO (jetty-launcher-643-thread-2) [n:127.0.0.1:45941_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45941_solr
[junit4] 2> 608308 DEBUG (OverseerAutoScalingTriggerThread-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger
[junit4] 2> 608309 INFO (zkCallback-659-thread-1-processing-n:127.0.0.1:33935_solr) [n:127.0.0.1:33935_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 608327 DEBUG (OverseerAutoScalingTriggerThread-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 608328 DEBUG (OverseerAutoScalingTriggerThread-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:33935_solr]
[junit4] 2> 608328 DEBUG (OverseerAutoScalingTriggerThread-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion -1
[junit4] 2> 608328 DEBUG (OverseerAutoScalingTriggerThread-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 608349 INFO (zkCallback-661-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 608359 DEBUG (OverseerAutoScalingTriggerThread-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 608376 INFO (zkCallback-667-thread-1-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 608392 DEBUG (OverseerAutoScalingTriggerThread-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 608392 DEBUG (OverseerStateUpdate-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:45941_solr"} current state version: 0
[junit4] 2> 608392 DEBUG (OverseerStateUpdate-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:45941_solr
[junit4] 2> 608392 DEBUG (ScheduledTrigger-836-thread-1) [n:127.0.0.1:45941_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 608508 INFO (jetty-launcher-643-thread-1) [n:127.0.0.1:33935_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33935.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1a244a91
[junit4] 2> 608559 INFO (jetty-launcher-643-thread-1) [n:127.0.0.1:33935_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33935.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1a244a91
[junit4] 2> 608559 INFO (jetty-launcher-643-thread-1) [n:127.0.0.1:33935_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33935.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1a244a91
[junit4] 2> 608561 INFO (jetty-launcher-643-thread-1) [n:127.0.0.1:33935_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_472FEA71BD2A3A1-001/tempDir-001/node1/.
[junit4] 2> 608591 INFO (zkConnectionManagerCallback-672-thread-1-processing-n:127.0.0.1:33935_solr) [n:127.0.0.1:33935_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 608592 INFO (jetty-launcher-643-thread-1) [n:127.0.0.1:33935_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 608593 INFO (jetty-launcher-643-thread-1) [n:127.0.0.1:33935_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33280/solr ready
[junit4] 2> 608862 INFO (jetty-launcher-643-thread-2) [n:127.0.0.1:45941_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45941.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1a244a91
[junit4] 2> 608893 INFO (jetty-launcher-643-thread-2) [n:127.0.0.1:45941_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45941.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1a244a91
[junit4] 2> 608894 INFO (jetty-launcher-643-thread-2) [n:127.0.0.1:45941_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45941.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1a244a91
[junit4] 2> 608895 INFO (jetty-launcher-643-thread-2) [n:127.0.0.1:45941_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_472FEA71BD2A3A1-001/tempDir-001/node2/.
[junit4] 2> 608976 INFO (zkConnectionManagerCallback-674-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 608991 INFO (zkConnectionManagerCallback-678-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 608992 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[472FEA71BD2A3A1]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 608993 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[472FEA71BD2A3A1]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33280/solr ready
[junit4] 2> 609380 INFO (qtp407445118-3170) [n:127.0.0.1:33935_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&name=.system&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:33935_solr&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 609403 DEBUG (ScheduledTrigger-836-thread-1) [n:127.0.0.1:45941_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 609421 INFO (OverseerThreadFactory-838-thread-1-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection .system
[junit4] 2> 609511 DEBUG (OverseerStateUpdate-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "name":".system",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "numShards":1,
[junit4] 2> "createNodeSet":"127.0.0.1:33935_solr",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 609511 DEBUG (OverseerStateUpdate-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: .system
[junit4] 2> 609511 DEBUG (OverseerStateUpdate-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/.system/state.json
[junit4] 2> 609565 DEBUG (OverseerStateUpdate-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":".system",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:33935/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 609565 INFO (OverseerStateUpdate-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":".system",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:33935/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 609566 DEBUG (OverseerStateUpdate-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 609566 DEBUG (OverseerStateUpdate-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "base_url":"https://127.0.0.1:33935/solr",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 609667 DEBUG (OverseerStateUpdate-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 0
[junit4] 2> 610232 INFO (qtp407445118-3159) [n:127.0.0.1:33935_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n1&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 610233 INFO (qtp407445118-3159) [n:127.0.0.1:33935_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 610265 DEBUG (OverseerStateUpdate-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:33935/solr",
[junit4] 2> "node_name":"127.0.0.1:33935_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":".system",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 610265 DEBUG (OverseerStateUpdate-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:33935/solr",
[junit4] 2> "node_name":"127.0.0.1:33935_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":".system",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"}
[junit4] 2> 610265 DEBUG (OverseerStateUpdate-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{"core":".system_shard1_replica_n1","base_url":"https://127.0.0.1:33935/solr","node_name":"127.0.0.1:33935_solr","state":"down","type":"NRT"}
[junit4] 2> 610265 DEBUG (OverseerStateUpdate-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(.system//collections/.system/state.json/1)={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "base_url":"https://127.0.0.1:33935/solr",
[junit4] 2> "node_name":"127.0.0.1:33935_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 610368 DEBUG (OverseerStateUpdate-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 1
[junit4] 2> 610369 INFO (zkCallback-659-thread-1-processing-n:127.0.0.1:33935_solr) [n:127.0.0.1:33935_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
[junit4] 2> 610369 INFO (zkCallback-659-thread-2-processing-n:127.0.0.1:33935_solr) [n:127.0.0.1:33935_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
[junit4] 2> 610452 DEBUG (ScheduledTrigger-836-thread-2) [n:127.0.0.1:45941_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 611394 WARN (qtp407445118-3159) [n:127.0.0.1:33935_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.Config You should not use LATEST as luceneMatchVersion property: if you use this setting, and then Solr upgrades to a newer release of Lucene, sizable changes may happen. If precise back compatibility is important then you should instead explicitly specify an actual Lucene version.
[junit4] 2> 611396 INFO (qtp407445118-3159) [n:127.0.0.1:33935_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0
[junit4] 2> 611401 INFO (qtp407445118-3159) [n:127.0.0.1:33935_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 611403 INFO (qtp407445118-3159) [n:127.0.0.1:33935_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.IndexSchema [.system_shard1_replica_n1] Schema name=_system collection or core
[junit4] 2> 611428 INFO (qtp407445118-3159) [n:127.0.0.1:33935_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id
[junit4] 2> 611459 DEBUG (ScheduledTrigger-836-thread-1) [n:127.0.0.1:45941_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 611460 INFO (qtp407445118-3159) [n:127.0.0.1:33935_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.ManagedIndexSchema Created and persisted managed schema znode at /configs/.system/managed-schema
[junit4] 2> 611462 INFO (qtp407445118-3159) [n:127.0.0.1:33935_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in ZooKeeper, renamed the non-managed schema /configs/.system/schema.xml to /configs/.system/schema.xml.bak
[junit4] 2> 611462 INFO (qtp407445118-3159) [n:127.0.0.1:33935_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore '.system_shard1_replica_n1' using configuration from collection .system, trusted=true
[junit4] 2> 611475 INFO (qtp407445118-3159) [n:127.0.0.1:33935_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33935.solr.core..system.shard1.replica_n1' (registry 'solr.core..system.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1a244a91
[junit4] 2> 611475 INFO (qtp407445118-3159) [n:127.0.0.1:33935_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 611475 INFO (qtp407445118-3159) [n:127.0.0.1:33935_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.SolrCore [[.system_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_472FEA71BD2A3A1-001/tempDir-001/node1/.system_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_472FEA71BD2A3A1-001/tempDir-001/node1/./.system_shard1_replica_n1/data/]
[junit4] 2> 611735 INFO (qtp407445118-3159) [n:127.0.0.1:33935_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 611736 INFO (qtp407445118-3159) [n:127.0.0.1:33935_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 611737 INFO (qtp407445118-3159) [n:127.0.0.1:33935_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: if 1 uncommited docs
[junit4] 2> 611737 INFO (qtp407445118-3159) [n:127.0.0.1:33935_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 611739 INFO (qtp407445118-3159) [n:127.0.0.1:33935_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5cd5d0f8[.system_shard1_replica_n1] main]
[junit4] 2> 611740 INFO (qtp407445118-3159) [n:127.0.0.1:33935_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/.system
[junit4] 2> 611740 INFO (qtp407445118-3159) [n:127.0.0.1:33935_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/.system
[junit4] 2> 611740 INFO (qtp407445118-3159) [n:127.0.0.1:33935_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/.system/managed-schema
[junit4] 2> 611741 INFO (qtp407445118-3159) [n:127.0.0.1:33935_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 611741 INFO (qtp407445118-3159) [n:127.0.0.1:33935_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 611742 INFO (searcherExecutor-843-thread-1-processing-n:127.0.0.1:33935_solr x:.system_shard1_replica_n1 s:shard1 c:.system r:core_node2) [n:127.0.0.1:33935_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.SolrCore [.system_shard1_replica_n1] Registered new searcher Searcher@5cd5d0f8[.system_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 611743 INFO (qtp407445118-3159) [n:127.0.0.1:33935_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1594892241739972608
[junit4] 2> 611776 INFO (qtp407445118-3159) [n:127.0.0.1:33935_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update terms at /collections/.system/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 611779 INFO (qtp407445118-3159) [n:127.0.0.1:33935_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 611779 INFO (qtp407445118-3159) [n:127.0.0.1:33935_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 611779 INFO (qtp407445118-3159) [n:127.0.0.1:33935_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:33935/solr/.system_shard1_replica_n1/
[junit4] 2> 611779 INFO (qtp407445118-3159) [n:127.0.0.1:33935_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 611779 INFO (qtp407445118-3159) [n:127.0.0.1:33935_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.SyncStrategy https://127.0.0.1:33935/solr/.system_shard1_replica_n1/ has no replicas
[junit4] 2> 611779 INFO (qtp407445118-3159) [n:127.0.0.1:33935_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 611790 INFO (qtp407445118-3159) [n:127.0.0.1:33935_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:33935/solr/.system_shard1_replica_n1/ shard1
[junit4] 2> 611791 DEBUG (OverseerStateUpdate-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":".system",
[junit4] 2> "base_url":"https://127.0.0.1:33935/solr",
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "state":"active"} current state version: 0
[junit4] 2> 611892 DEBUG (OverseerStateUpdate-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 2
[junit4] 2> 611892 INFO (zkCallback-659-thread-2-processing-n:127.0.0.1:33935_solr) [n:127.0.0.1:33935_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
[junit4] 2> 611892 INFO (zkCallback-659-thread-1-processing-n:127.0.0.1:33935_solr) [n:127.0.0.1:33935_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
[junit4] 2> 611942 INFO (qtp407445118-3159) [n:127.0.0.1:33935_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 611943 DEBUG (OverseerStateUpdate-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:33935/solr",
[junit4] 2> "node_name":"127.0.0.1:33935_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":".system",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 611943 DEBUG (OverseerStateUpdate-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:33935/solr",
[junit4] 2> "node_name":"127.0.0.1:33935_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":".system",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"}
[junit4] 2> 611943 DEBUG (OverseerStateUpdate-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{"core":".system_shard1_replica_n1","base_url":"https://127.0.0.1:33935/solr","node_name":"127.0.0.1:33935_solr","state":"active","type":"NRT","leader":"true"}
[junit4] 2> 611943 DEBUG (OverseerStateUpdate-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(.system//collections/.system/state.json/3)={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "base_url":"https://127.0.0.1:33935/solr",
[junit4] 2> "node_name":"127.0.0.1:33935_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true"}}}}}
[junit4] 2> 611944 INFO (qtp407445118-3159) [n:127.0.0.1:33935_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n1&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1711
[junit4] 2> 611947 INFO (qtp407445118-3170) [n:127.0.0.1:33935_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
[junit4] 2> 612044 DEBUG (OverseerStateUpdate-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 3
[junit4] 2> 612045 INFO (zkCallback-659-thread-2-processing-n:127.0.0.1:33935_solr) [n:127.0.0.1:33935_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
[junit4] 2> 612045 INFO (zkCallback-659-thread-1-processing-n:127.0.0.1:33935_solr) [n:127.0.0.1:33935_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
[junit4] 2> 612463 DEBUG (ScheduledTrigger-836-thread-1) [n:127.0.0.1:45941_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 612947 INFO (qtp407445118-3170) [n:127.0.0.1:33935_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&name=.system&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:33935_solr&wt=javabin&version=2} status=0 QTime=3567
[junit4] 2> 612949 INFO (qtp407445118-3165) [n:127.0.0.1:33935_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=3&maxShardsPerNode=3&name=AutoscalingHistoryHandlerTest_collection&nrtReplicas=3&action=CREATE&numShards=1&createNodeSet=127.0.0.1:45941_solr&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 612951 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection AutoscalingHistoryHandlerTest_collection
[junit4] 2> 612951 INFO (OverseerCollectionConfigSetProcessor-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 612952 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/protwords.txt
[junit4] 2> 612953 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/managed-schema
[junit4] 2> 612963 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/solrconfig.xml
[junit4] 2> 612964 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/synonyms.txt
[junit4] 2> 612966 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/stopwords.txt
[junit4] 2> 612967 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/params.json to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/params.json
[junit4] 2> 612968 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 612969 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 612979 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 612980 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 612981 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 612983 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 612984 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 612985 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 612995 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 612996 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 612997 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 613007 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 613008 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 613009 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 613011 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 613037 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 613038 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 613052 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 613053 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 613055 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 613056 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 613058 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 613059 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 613069 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 613070 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 613080 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 613082 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 613083 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 613093 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 613094 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 613096 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 613097 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 613098 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 613099 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 613109 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 613110 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 613112 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 613125 INFO (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_gl.txt
[junit4] 2> 613127 WARN (OverseerThreadFactory-838-thread-2-processing-n:127.0.0.1:45941_solr) [n:127.0.0.1:45941_solr ] o.a.s.c.a.c.CreateCollectionCmd Specified number of replicas of 3 on collection AutoscalingHistoryHandlerTest_collection is higher than the number of Solr instances currently live or live and part of your createNodeSet(1). It's unusual to run two replica of the same slice on the same Solr-instance.
[junit4] 2> 613131 DEBUG (OverseerStateUpdate-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "name":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"3",
[junit4] 2> "numShards":"1",
[junit4] 2> "maxShardsPerNode":"3",
[junit4] 2> "createNodeSet":"127.0.0.1:45941_solr",
[junit4] 2> "nrtReplicas":"3",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 613131 DEBUG (OverseerStateUpdate-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: AutoscalingHistoryHandlerTest_collection
[junit4] 2> 613232 DEBUG (OverseerStateUpdate-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/AutoscalingHistoryHandlerTest_collection/state.json
[junit4] 2> 613365 DEBUG (OverseerStateUpdate-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:45941/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 613365 INFO (OverseerStateUpdate-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:45941/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 613366 DEBUG (OverseerStateUpdate-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 613366 DEBUG (OverseerStateUpdate-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node4":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "base_url":"https://127.0.0.1:45941/solr",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 613366 DEBUG (OverseerStateUpdate-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:45941/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 613366 INFO (OverseerStateUpdate-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:45941/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 613367 DEBUG (OverseerStateUpdate-72276822769991686-127.0.0.1:45941_solr-n_0000000000) [n:127.0.0.1:45941_solr ] o.a.s.c.o.SliceMuta
[...truncated too long message...]
[ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@383baa9b{/solr,null,UNAVAILABLE}
[junit4] 2> 381826 INFO (jetty-closer-7-thread-4) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 381855 INFO (jetty-closer-7-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@31284743{/solr,null,UNAVAILABLE}
[junit4] 2> 381855 INFO (jetty-closer-7-thread-3) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 382918 WARN (zkCallback-38-thread-2-processing-n:127.0.0.1:36125_solr) [n:127.0.0.1:36125_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 382922 INFO (jetty-closer-7-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@a9469ce{/solr,null,UNAVAILABLE}
[junit4] 2> 382922 INFO (jetty-closer-7-thread-1) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 382923 ERROR (SUITE-StreamExpressionTest-seed#[C2A064C5459A139A]-worker) [ ] 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> 382924 INFO (SUITE-StreamExpressionTest-seed#[C2A064C5459A139A]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43583 43583
[junit4] 2> 383238 INFO (Thread-3) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43583 43583
[junit4] 2> 383240 WARN (Thread-3) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 83 /solr/aliases.json
[junit4] 2> 83 /solr/clusterprops.json
[junit4] 2> 10 /solr/configs/conf
[junit4] 2> 7 /solr/collections/parallelDestinationCollection1/terms/shard1
[junit4] 2> 7 /solr/collections/destinationCollection/terms/shard1
[junit4] 2> 6 /solr/collections/parallelDestinationCollection1/terms/shard2
[junit4] 2> 6 /solr/collections/parallelDestinationCollection/terms/shard1
[junit4] 2> 6 /solr/collections/parallelDestinationCollection/terms/shard2
[junit4] 2> 6 /solr/collections/destinationCollection/terms/shard2
[junit4] 2> 4 /solr/collections/workQueue1/terms/shard2
[junit4] 2> 4 /solr/collections/workQueue1/terms/shard1
[junit4] 2> 4 /solr/collections/destination1/terms/shard1
[junit4] 2> 4 /solr/collections/destinationCollection/collectionprops.json
[junit4] 2> 4 /solr/collections/mainCorpus1/terms/shard1
[junit4] 2> 4 /solr/collections/parallelDestinationCollection1/collectionprops.json
[junit4] 2> 4 /solr/collections/parallelDestinationCollection/collectionprops.json
[junit4] 2> 4 /solr/collections/destination/terms/shard2
[junit4] 2> 4 /solr/collections/destination/terms/shard1
[junit4] 2> 4 /solr/collections/mainCorpus/terms/shard2
[junit4] 2> 4 /solr/collections/mainCorpus/terms/shard1
[junit4] 2> 4 /solr/security.json
[junit4] 2> 4 /solr/collections/workQueue/terms/shard2
[junit4] 2> 4 /solr/collections/workQueue/terms/shard1
[junit4] 2> 3 /solr/collections/destination1/terms/shard2
[junit4] 2> 3 /solr/collections/mainCorpus1/terms/shard2
[junit4] 2> 3 /solr/collections/collection1_collection/terms/shard1
[junit4] 2> 3 /solr/collections/collection1_collection/terms/shard2
[junit4] 2> 2 /solr/collections/mainCorpus1/collectionprops.json
[junit4] 2> 2 /solr/collections/workQueue/collectionprops.json
[junit4] 2> 2 /solr/collections/destination/collectionprops.json
[junit4] 2> 2 /solr/collections/collection1_collection/collectionprops.json
[junit4] 2> 2 /solr/collections/destination1/collectionprops.json
[junit4] 2> 2 /solr/collections/workQueue1/collectionprops.json
[junit4] 2> 2 /solr/collections/mainCorpus/collectionprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 83 /solr/clusterstate.json
[junit4] 2> 28 /solr/collections/parallelDestinationCollection1/state.json
[junit4] 2> 24 /solr/collections/destinationCollection/state.json
[junit4] 2> 22 /solr/collections/parallelDestinationCollection/state.json
[junit4] 2> 14 /solr/collections/mainCorpus1/state.json
[junit4] 2> 14 /solr/collections/collection1_collection/state.json
[junit4] 2> 12 /solr/collections/destination/state.json
[junit4] 2> 12 /solr/collections/workQueue/state.json
[junit4] 2> 12 /solr/collections/mainCorpus/state.json
[junit4] 2> 10 /solr/collections/destination1/state.json
[junit4] 2> 10 /solr/collections/workQueue1/state.json
[junit4] 2> 2 /solr/overseer_elect/election/72277108787183625-127.0.0.1:39073_solr-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 83 /solr/live_nodes
[junit4] 2> 83 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_C2A064C5459A139A-001
[junit4] 2> Mar 14, 2018 7:27:34 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {expr_s=FSTOrd50, join2_s=PostingsFormat(name=LuceneFixedGap), multiDefault=PostingsFormat(name=LuceneFixedGap), subject=PostingsFormat(name=LuceneFixedGap), a1_s=BlockTreeOrds(blocksize=128), a2_s=FSTOrd50, test_t=PostingsFormat(name=Asserting), level1_s=FSTOrd50, body_t=PostingsFormat(name=Asserting), ident_s=BlockTreeOrds(blocksize=128), s_multi=FSTOrd50, col_s=BlockTreeOrds(blocksize=128), level2_s=BlockTreeOrds(blocksize=128), a_ss=PostingsFormat(name=LuceneFixedGap), a_s=FSTOrd50, a_t=PostingsFormat(name=LuceneFixedGap), side_s=PostingsFormat(name=Asserting), id=PostingsFormat(name=Asserting), text=FSTOrd50}, docValues:{expr_s=DocValuesFormat(name=Memory), join2_s=DocValuesFormat(name=Lucene70), multiDefault=DocValuesFormat(name=Lucene70), price_f=DocValuesFormat(name=Lucene70), a1_s=DocValuesFormat(name=Lucene70), intDefault=DocValuesFormat(name=Lucene70), a_f=DocValuesFormat(name=Lucene70), a2_s=DocValuesFormat(name=Memory), join1_i=DocValuesFormat(name=Memory), a_i=DocValuesFormat(name=Asserting), test_i=DocValuesFormat(name=Lucene70), join3_i=DocValuesFormat(name=Asserting), field_i=DocValuesFormat(name=Lucene70), level1_s=DocValuesFormat(name=Memory), s_multi=DocValuesFormat(name=Memory), col_s=DocValuesFormat(name=Lucene70), level2_s=DocValuesFormat(name=Lucene70), a_ss=DocValuesFormat(name=Lucene70), a_s=DocValuesFormat(name=Memory), id=DocValuesFormat(name=Asserting), timestamp=DocValuesFormat(name=Lucene70), i_multi=DocValuesFormat(name=Asserting), b_ls=DocValuesFormat(name=Lucene70), test_dt=DocValuesFormat(name=Memory), ident_s=DocValuesFormat(name=Lucene70), order_i=DocValuesFormat(name=Lucene70), _version_=DocValuesFormat(name=Lucene70), side_s=DocValuesFormat(name=Asserting), miles_i=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=1277, maxMBSortInHeap=6.64752513031455, sim=RandomSimilarity(queryNorm=true): {}, locale=fr-CA, timezone=BST
[junit4] 2> NOTE: Linux 4.4.0-112-generic amd64/Oracle Corporation 1.8.0_152 (64-bit)/cpus=4,threads=1,free=201891592,total=386400256
[junit4] 2> NOTE: All tests run in this JVM: [TestPathTrie, AscEvaluatorTest, GreaterThanEvaluatorTest, RawValueEvaluatorTest, TestSpellCheckResponse, CloudSolrClientCacheTest, TermsResponseTest, TestConfigSetAdminRequest, ReverseEvaluatorTest, StreamExpressionParserTest, StreamExpressionTest]
[junit4] Completed [91/146 (1!)] on J0 in 327.67s, 156 tests, 1 failure, 9 skipped <<< FAILURES!
[...truncated 6696 lines...]
[junit4] Suite: org.apache.solr.ltr.TestLTRReRankingPipeline
[junit4] 2> 60835 INFO (TEST-TestLTRReRankingPipeline.testDifferentTopN-seed#[753A09768479DD29]) [ ] o.a.s.l.TestLTRReRankingPipeline rerank 1 documents
[junit4] 2> 60836 INFO (TEST-TestLTRReRankingPipeline.testDifferentTopN-seed#[753A09768479DD29]) [ ] o.a.s.l.TestLTRReRankingPipeline doc 0 in pos 0
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestLTRReRankingPipeline -Dtests.method=testDifferentTopN -Dtests.seed=753A09768479DD29 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=ja -Dtests.timezone=Antarctica/Davis -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 0.31s J2 | TestLTRReRankingPipeline.testDifferentTopN <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<1.0> but was:<0.0>
[junit4] > at __randomizedtesting.SeedInfo.seed([753A09768479DD29:849B7B26B1C217BB]:0)
[junit4] > at org.apache.solr.ltr.TestLTRReRankingPipeline.testDifferentTopN(TestLTRReRankingPipeline.java:255)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {field=PostingsFormat(name=Direct), id=PostingsFormat(name=Asserting), final-score=PostingsFormat(name=Asserting)}, docValues:{final-score=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=219, maxMBSortInHeap=6.221787723681296, sim=RandomSimilarity(queryNorm=false): {field=DFR I(n)2}, locale=ja, timezone=Antarctica/Davis
[junit4] 2> NOTE: Linux 4.4.0-112-generic amd64/Oracle Corporation 1.8.0_152 (64-bit)/cpus=4,threads=1,free=145180592,total=318242816
[junit4] 2> NOTE: All tests run in this JVM: [TestModelManager, TestUserTermScorerQuery, TestOriginalScoreScorer, TestMultipleAdditiveTreesModel, TestLTRQParserPlugin, TestLTRReRankingPipeline]
[junit4] Completed [13/37 (1!)] on J2 in 0.96s, 3 tests, 1 failure <<< FAILURES!
[...truncated 42081 lines...]