Build: https://builds.apache.org/job/Lucene-Solr-BadApples-Tests-7.x/168/
2 tests failed.
FAILED:
org.apache.solr.cloud.autoscaling.SearchRateTriggerIntegrationTest.testDeleteNode
Error Message:
unexpected DELETENODE status:
{responseHeader={status=0,QTime=7},status={state=notfound,msg=Did not find
[search_rate_trigger3/12c3efca3da829T3rvifa1vaqxcivnj7ko1ocicc/0] in any tasks
queue}}
Stack Trace:
java.lang.AssertionError: unexpected DELETENODE status:
{responseHeader={status=0,QTime=7},status={state=notfound,msg=Did not find
[search_rate_trigger3/12c3efca3da829T3rvifa1vaqxcivnj7ko1ocicc/0] in any tasks
queue}}
at
__randomizedtesting.SeedInfo.seed([D163BF8D5136E1F3:F3F1710F66FC6E8E]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.cloud.autoscaling.SearchRateTriggerIntegrationTest.lambda$testDeleteNode$5(SearchRateTriggerIntegrationTest.java:684)
at java.util.ArrayList.forEach(ArrayList.java:1257)
at
org.apache.solr.cloud.autoscaling.SearchRateTriggerIntegrationTest.testDeleteNode(SearchRateTriggerIntegrationTest.java:676)
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:1742)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985)
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:944)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891)
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.TestSimExecutePlanAction.testExecute
Error Message:
last state: DocCollection(testExecute//clusterstate.json/27)={
"replicationFactor":"1", "pullReplicas":"0",
"router":{"name":"compositeId"}, "maxShardsPerNode":"1",
"autoAddReplicas":"false", "nrtReplicas":"2", "tlogReplicas":"0",
"autoCreated":"true", "shards":{"shard1":{ "replicas":{
"core_node1":{ "core":"testExecute_shard1_replica_n1",
"leader":"true", "SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0, "INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10032_solr", "state":"active",
"type":"NRT", "INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0}, "core_node2":{
"core":"testExecute_shard1_replica_n2", "SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240, "node_name":"127.0.0.1:10031_solr",
"state":"active", "type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6, "SEARCHER.searcher.numDocs":0},
"core_node4":{ "node_name":"127.0.0.1:10032_solr",
"core":"testExecute_shard1_replica_n3", "state":"active",
"INDEX.sizeInBytes":10240, "type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6}}, "range":"80000000-7fffffff",
"state":"active"}}}
Stack Trace:
java.util.concurrent.TimeoutException: last state:
DocCollection(testExecute//clusterstate.json/27)={
"replicationFactor":"1",
"pullReplicas":"0",
"router":{"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"false",
"nrtReplicas":"2",
"tlogReplicas":"0",
"autoCreated":"true",
"shards":{"shard1":{
"replicas":{
"core_node1":{
"core":"testExecute_shard1_replica_n1",
"leader":"true",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10032_solr",
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0},
"core_node2":{
"core":"testExecute_shard1_replica_n2",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10031_solr",
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0},
"core_node4":{
"node_name":"127.0.0.1:10032_solr",
"core":"testExecute_shard1_replica_n3",
"state":"active",
"INDEX.sizeInBytes":10240,
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6}},
"range":"80000000-7fffffff",
"state":"active"}}}
at
__randomizedtesting.SeedInfo.seed([D163BF8D5136E1F3:E0D3AD8043C11E74]:0)
at
org.apache.solr.cloud.CloudTestUtils.waitForState(CloudTestUtils.java:112)
at
org.apache.solr.cloud.autoscaling.sim.TestSimExecutePlanAction.testExecute(TestSimExecutePlanAction.java:154)
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:1742)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985)
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:944)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 13421 lines...]
[junit4] Suite:
org.apache.solr.cloud.autoscaling.sim.TestSimExecutePlanAction
[junit4] 2> 1138817 INFO
(SUITE-TestSimExecutePlanAction-seed#[D163BF8D5136E1F3]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.sim.TestSimExecutePlanAction_D163BF8D5136E1F3-001/init-core-data-001
[junit4] 2> 1138819 INFO
(SUITE-TestSimExecutePlanAction-seed#[D163BF8D5136E1F3]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1138821 INFO
(SUITE-TestSimExecutePlanAction-seed#[D163BF8D5136E1F3]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 1138855 INFO
(SUITE-TestSimExecutePlanAction-seed#[D163BF8D5136E1F3]-worker) [ ]
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history
in memory.
[junit4] 2> 1138920 INFO
(TEST-TestSimExecutePlanAction.testIntegration-seed#[D163BF8D5136E1F3]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testIntegration
[junit4] 2> 1138921 INFO
(TEST-TestSimExecutePlanAction.testIntegration-seed#[D163BF8D5136E1F3]) [ ]
o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and clearing
object cache...
[junit4] 2> 1138922 WARN (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Interrupted
[junit4] 2> java.lang.InterruptedException: null
[junit4] 2> at
java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireInterruptibly(AbstractQueuedSynchronizer.java:1220)
~[?:1.8.0_172]
[junit4] 2> at
java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:335)
~[?:1.8.0_172]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:177)
[java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172]
[junit4] 2> 1139088 DEBUG (simCloudManagerPool-5164-thread-2) [ ]
o.a.s.c.a.c.Assign Identify nodes using default
[junit4] 2> 1139131 DEBUG (simCloudManagerPool-5164-thread-6) [ ]
o.a.s.c.ActionThrottle The last leader attempt started 855ms ago.
[junit4] 2> 1139131 INFO (simCloudManagerPool-5164-thread-6) [ ]
o.a.s.c.ActionThrottle Throttling leader attempts - waiting for 4144ms
[junit4] 2> 1139182 DEBUG (simCloudManagerPool-5164-thread-7) [ ]
o.a.s.c.a.c.Assign Identify nodes using default
[junit4] 2> 1139227 INFO (AutoscalingActionExecutor-5166-thread-1) [ ]
o.a.s.c.a.ComputePlanAction Computed Plan:
action=MOVEREPLICA&collection=testIntegration&targetNode=127.0.0.1:10031_solr&inPlaceMove=true&replica=core_node1
[junit4] 2> 1139227 INFO (AutoscalingActionExecutor-5166-thread-1) [ ]
o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 5336921755
sessionWrapper.createTime 5336921739173863, this.sessionWrapper.createTime
5336921739173863
[junit4] 2> 1139459 INFO
(TEST-TestSimExecutePlanAction.testIntegration-seed#[D163BF8D5136E1F3]) [ ]
o.a.s.c.a.s.TestSimExecutePlanAction -------------_ FINAL STATE --------------
[junit4] 2> 1139459 INFO
(TEST-TestSimExecutePlanAction.testIntegration-seed#[D163BF8D5136E1F3]) [ ]
o.a.s.c.a.s.TestSimExecutePlanAction * Node values: {
[junit4] 2> "127.0.0.1:10031_solr":{
[junit4] 2> "sysprop.java.vendor":"Oracle Corporation",
[junit4] 2> "cores":3,
[junit4] 2> "host":"127.0.0.1",
[junit4] 2> "freedisk":997,
[junit4] 2> "sysprop.java.version":"1.8.0_172",
[junit4] 2> "ip_1":"127",
[junit4] 2> "ip_3":"0",
[junit4] 2> "ip_2":"0",
[junit4] 2> "ip_4":"1",
[junit4] 2> "node":"127.0.0.1:10031_solr",
[junit4] 2> "metrics:solr.jvm:buffers.direct.Count":0,
[junit4] 2> "port":10031,
[junit4] 2> "heapUsage":123450000,
[junit4] 2> "sysLoadAvg":1.0,
[junit4] 2>
"metrics:solr.node:ADMIN./admin/authorization.clientErrors:count":0},
[junit4] 2> "127.0.0.1:10030_solr":{
[junit4] 2> "sysprop.java.vendor":"Oracle Corporation",
[junit4] 2> "cores":1,
[junit4] 2> "host":"127.0.0.1",
[junit4] 2> "freedisk":999,
[junit4] 2> "sysprop.java.version":"1.8.0_172",
[junit4] 2> "ip_1":"127",
[junit4] 2> "ip_3":"0",
[junit4] 2> "ip_2":"0",
[junit4] 2> "ip_4":"1",
[junit4] 2> "node":"127.0.0.1:10030_solr",
[junit4] 2> "metrics:solr.jvm:buffers.direct.Count":0,
[junit4] 2> "port":10030,
[junit4] 2> "heapUsage":123450000,
[junit4] 2> "sysLoadAvg":1.0,
[junit4] 2>
"metrics:solr.node:ADMIN./admin/authorization.clientErrors:count":0}}
[junit4] 2> 1139460 INFO
(TEST-TestSimExecutePlanAction.testIntegration-seed#[D163BF8D5136E1F3]) [ ]
o.a.s.c.a.s.TestSimExecutePlanAction * Live nodes: [127.0.0.1:10031_solr]
[junit4] 2> 1139460 INFO
(TEST-TestSimExecutePlanAction.testIntegration-seed#[D163BF8D5136E1F3]) [ ]
o.a.s.c.a.s.TestSimExecutePlanAction * Collection .system state:
DocCollection(.system//clusterstate.json/17)={
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "autoCreated":"true",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "replicas":{"core_node1":{
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":1,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10740,
[junit4] 2> "node_name":"127.0.0.1:10031_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInGB":1.000240445137024E-5,
[junit4] 2> "SEARCHER.searcher.numDocs":1}},
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active"}}}
[junit4] 2> 1139460 INFO
(TEST-TestSimExecutePlanAction.testIntegration-seed#[D163BF8D5136E1F3]) [ ]
o.a.s.c.a.s.TestSimExecutePlanAction * Collection testIntegration state:
DocCollection(testIntegration//clusterstate.json/17)={
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "autoCreated":"true",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "replicas":{
[junit4] 2> "core_node2":{
[junit4] 2> "core":"testIntegration_shard1_replica_n2",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "node_name":"127.0.0.1:10031_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0},
[junit4] 2> "core_node4":{
[junit4] 2> "node_name":"127.0.0.1:10031_solr",
[junit4] 2> "core":"testIntegration_shard1_replica_n3",
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6}},
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active"}}}
[junit4] 2> 1139461 INFO
(TEST-TestSimExecutePlanAction.testIntegration-seed#[D163BF8D5136E1F3]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testIntegration
[junit4] 2> 1139461 INFO
(TEST-TestSimExecutePlanAction.testIntegration-seed#[D163BF8D5136E1F3]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase #######################################
[junit4] 2> ############ CLUSTER STATE ############
[junit4] 2> #######################################
[junit4] 2> ## Live nodes: 1
[junit4] 2> ## Empty nodes: 0
[junit4] 2> ## Dead nodes: 1
[junit4] 2> ## 127.0.0.1:10030_solr
[junit4] 2> ## Collections: [.system, testIntegration]
[junit4] 2> ## Max replicas per node: 3
[junit4] 2> ## Min replicas per node: 3
[junit4] 2> ## Total replicas: 3
[junit4] 2> ## * .system 1
[junit4] 2> ## - active 1
[junit4] 2> ## * testIntegration 2
[junit4] 2> ## - active 2
[junit4] 2> ######### Solr op counts ##########
[junit4] 2> ## - CREATE 1
[junit4] 2> ## - MOVEREPLICA 1
[junit4] 2> ## - autoscaling 1
[junit4] 2> ## - update 1
[junit4] 2> ######### Autoscaling event counts ###########
[junit4] 2> ## * Trigger: node_lost_trigger
[junit4] 2> ## - STARTED 1
[junit4] 2>
[junit4] 2> 1139465 INFO
(TEST-TestSimExecutePlanAction.testExecute-seed#[D163BF8D5136E1F3]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testExecute
[junit4] 2> 1139466 INFO
(TEST-TestSimExecutePlanAction.testExecute-seed#[D163BF8D5136E1F3]) [ ]
o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and clearing
object cache...
[junit4] 2> 1139466 WARN (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Interrupted
[junit4] 2> java.lang.InterruptedException: null
[junit4] 2> at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:2014)
~[?:1.8.0_172]
[junit4] 2> at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2048)
~[?:1.8.0_172]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:189)
[java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172]
[junit4] 2> 1139470 ERROR (AutoscalingActionExecutor-5166-thread-1) [ ]
o.a.s.c.a.ExecutePlanAction Unexpected Exception while querying status of
requestId=node_lost_trigger/12f5e5e7a35a2fT3rvifa1vaqxcivnj7ko1ocibq/0
[junit4] 2> java.io.IOException: java.lang.InterruptedException
[junit4] 2> at
org.apache.solr.cloud.autoscaling.sim.SimCloudManager.request(SimCloudManager.java:632)
~[test/:?]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ExecutePlanAction.waitForTaskToFinish(ExecutePlanAction.java:132)
~[java/:?]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ExecutePlanAction.process(ExecutePlanAction.java:85)
~[java/:?]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:309)
~[java/:?]
[junit4] 2> at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
~[?:1.8.0_172]
[junit4] 2> at
java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_172]
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
~[java/:?]
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[?:1.8.0_172]
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[?:1.8.0_172]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172]
[junit4] 2> Caused by: java.lang.InterruptedException
[junit4] 2> at
java.util.concurrent.FutureTask.awaitDone(FutureTask.java:404) ~[?:1.8.0_172]
[junit4] 2> at
java.util.concurrent.FutureTask.get(FutureTask.java:191) ~[?:1.8.0_172]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.sim.SimCloudManager.request(SimCloudManager.java:630)
~[test/:?]
[junit4] 2> ... 9 more
[junit4] 2> 1139488 DEBUG (simCloudManagerPool-5164-thread-12) [ ]
o.a.s.c.a.c.Assign Identify nodes using default
[junit4] 2> 1139536 WARN (AutoscalingActionExecutor-5166-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Exception executing actions
[junit4] 2> org.apache.solr.cloud.autoscaling.TriggerActionException:
Error processing action for trigger event: {
[junit4] 2> "id":"12f5e5e7a35a2fT3rvifa1vaqxcivnj7ko1ocibq",
[junit4] 2> "source":"node_lost_trigger",
[junit4] 2> "eventTime":5336917363350063,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[5336917363350063],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":5336918393295513,
[junit4] 2> "nodeNames":["127.0.0.1:10030_solr"]}}
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:312)
~[java/:?]
[junit4] 2> at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
~[?:1.8.0_172]
[junit4] 2> at
java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_172]
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
~[java/:?]
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[?:1.8.0_172]
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[?:1.8.0_172]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172]
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Unexpected
exception while processing event: {
[junit4] 2> "id":"12f5e5e7a35a2fT3rvifa1vaqxcivnj7ko1ocibq",
[junit4] 2> "source":"node_lost_trigger",
[junit4] 2> "eventTime":5336917363350063,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[5336917363350063],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":5336918393295513,
[junit4] 2> "nodeNames":["127.0.0.1:10030_solr"]}}
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ExecutePlanAction.process(ExecutePlanAction.java:120)
~[java/:?]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:309)
~[java/:?]
[junit4] 2> ... 6 more
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Unexpected
exception executing operation:
action=MOVEREPLICA&async=node_lost_trigger/12f5e5e7a35a2fT3rvifa1vaqxcivnj7ko1ocibq/0&waitForFinalState=true&collection=testIntegration&targetNode=127.0.0.1:10031_solr&inPlaceMove=true&replica=core_node1
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ExecutePlanAction.process(ExecutePlanAction.java:110)
~[java/:?]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:309)
~[java/:?]
[junit4] 2> ... 6 more
[junit4] 2> Caused by: java.io.IOException: java.lang.InterruptedException
[junit4] 2> at
org.apache.solr.cloud.autoscaling.sim.SimCloudManager.request(SimCloudManager.java:632)
~[test/:?]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ExecutePlanAction.waitForTaskToFinish(ExecutePlanAction.java:132)
~[java/:?]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ExecutePlanAction.process(ExecutePlanAction.java:85)
~[java/:?]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:309)
~[java/:?]
[junit4] 2> ... 6 more
[junit4] 2> Caused by: java.lang.InterruptedException
[junit4] 2> at
java.util.concurrent.FutureTask.awaitDone(FutureTask.java:404) ~[?:1.8.0_172]
[junit4] 2> at
java.util.concurrent.FutureTask.get(FutureTask.java:191) ~[?:1.8.0_172]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.sim.SimCloudManager.request(SimCloudManager.java:630)
~[test/:?]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ExecutePlanAction.waitForTaskToFinish(ExecutePlanAction.java:132)
~[java/:?]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ExecutePlanAction.process(ExecutePlanAction.java:85)
~[java/:?]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:309)
~[java/:?]
[junit4] 2> ... 6 more
[junit4] 2> 1139659 DEBUG (simCloudManagerPool-5167-thread-1) [ ]
o.a.s.c.a.c.Assign Identify nodes using default
[junit4] 2> 1139720 INFO
(TEST-TestSimExecutePlanAction.testExecute-seed#[D163BF8D5136E1F3]) [ ]
o.a.s.c.a.s.TestSimExecutePlanAction Collection ready after 2459ms
[junit4] 2> 1145969 INFO
(TEST-TestSimExecutePlanAction.testExecute-seed#[D163BF8D5136E1F3]) [ ]
o.a.s.c.a.s.TestSimExecutePlanAction -------------_ FINAL STATE --------------
[junit4] 2> 1145969 INFO
(TEST-TestSimExecutePlanAction.testExecute-seed#[D163BF8D5136E1F3]) [ ]
o.a.s.c.a.s.TestSimExecutePlanAction * Node values: {
[junit4] 2> "127.0.0.1:10031_solr":{
[junit4] 2> "sysprop.java.vendor":"Oracle Corporation",
[junit4] 2> "cores":1,
[junit4] 2> "host":"127.0.0.1",
[junit4] 2> "freedisk":999,
[junit4] 2> "sysprop.java.version":"1.8.0_172",
[junit4] 2> "ip_1":"127",
[junit4] 2> "ip_3":"0",
[junit4] 2> "ip_2":"0",
[junit4] 2> "ip_4":"1",
[junit4] 2> "node":"127.0.0.1:10031_solr",
[junit4] 2> "metrics:solr.jvm:buffers.direct.Count":0,
[junit4] 2> "port":10031,
[junit4] 2> "heapUsage":123450000,
[junit4] 2> "sysLoadAvg":1.0,
[junit4] 2>
"metrics:solr.node:ADMIN./admin/authorization.clientErrors:count":0},
[junit4] 2> "127.0.0.1:10032_solr":{
[junit4] 2> "sysprop.java.vendor":"Oracle Corporation",
[junit4] 2> "cores":2,
[junit4] 2> "host":"127.0.0.1",
[junit4] 2> "freedisk":1022,
[junit4] 2> "sysprop.java.version":"1.8.0_172",
[junit4] 2> "ip_1":"127",
[junit4] 2> "ip_3":"0",
[junit4] 2> "ip_2":"0",
[junit4] 2> "ip_4":"1",
[junit4] 2> "node":"127.0.0.1:10032_solr",
[junit4] 2> "metrics:solr.jvm:buffers.direct.Count":0,
[junit4] 2> "port":10032,
[junit4] 2> "heapUsage":123450000,
[junit4] 2> "sysLoadAvg":1.0,
[junit4] 2>
"metrics:solr.node:ADMIN./admin/authorization.clientErrors:count":0}}
[junit4] 2> 1145969 INFO
(TEST-TestSimExecutePlanAction.testExecute-seed#[D163BF8D5136E1F3]) [ ]
o.a.s.c.a.s.TestSimExecutePlanAction * Live nodes: [127.0.0.1:10031_solr,
127.0.0.1:10032_solr]
[junit4] 2> 1145969 INFO
(TEST-TestSimExecutePlanAction.testExecute-seed#[D163BF8D5136E1F3]) [ ]
o.a.s.c.a.s.TestSimExecutePlanAction * Collection testExecute state:
DocCollection(testExecute//clusterstate.json/27)={
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "autoCreated":"true",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "replicas":{
[junit4] 2> "core_node1":{
[junit4] 2> "core":"testExecute_shard1_replica_n1",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "node_name":"127.0.0.1:10032_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0},
[junit4] 2> "core_node2":{
[junit4] 2> "core":"testExecute_shard1_replica_n2",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "node_name":"127.0.0.1:10031_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0},
[junit4] 2> "core_node4":{
[junit4] 2> "node_name":"127.0.0.1:10032_solr",
[junit4] 2> "core":"testExecute_shard1_replica_n3",
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6}},
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active"}}}
[junit4] 2> 1145969 INFO
(TEST-TestSimExecutePlanAction.testExecute-seed#[D163BF8D5136E1F3]) [ ]
o.a.s.c.a.s.TestSimExecutePlanAction * Collection .system state:
DocCollection(.system//clusterstate.json/27)={
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "autoCreated":"true",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "replicas":{},
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active"}}}
[junit4] 2> 1145970 INFO
(TEST-TestSimExecutePlanAction.testExecute-seed#[D163BF8D5136E1F3]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testExecute
[junit4] 2> 1145970 INFO
(TEST-TestSimExecutePlanAction.testExecute-seed#[D163BF8D5136E1F3]) [ ]
o.a.s.c.a.s.SimSolrCloudTestCase #######################################
[junit4] 2> ############ CLUSTER STATE ############
[junit4] 2> #######################################
[junit4] 2> ## Live nodes: 2
[junit4] 2> ## Empty nodes: 0
[junit4] 2> ## Dead nodes: 0
[junit4] 2> ## Collections: [testExecute, .system]
[junit4] 2> ## Max replicas per node: 2
[junit4] 2> ## Min replicas per node: 1
[junit4] 2> ## Total replicas: 3
[junit4] 2> ## * testExecute 3
[junit4] 2> ## - active 3
[junit4] 2> ######### Solr op counts ##########
[junit4] 2> ## - CREATE 1
[junit4] 2> ## - DELETESTATUS 2
[junit4] 2> ## - MOVEREPLICA 1
[junit4] 2> ## - OVERSEERSTATUS 1
[junit4] 2> ## - REQUESTSTATUS 2
[junit4] 2> ######### Autoscaling event counts ###########
[junit4] 2> ## * Trigger: node_lost_trigger
[junit4] 2> ## - FAILED 1
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=TestSimExecutePlanAction -Dtests.method=testExecute
-Dtests.seed=D163BF8D5136E1F3 -Dtests.multiplier=2 -Dtests.slow=true
-Dtests.badapples=true -Dtests.locale=ro -Dtests.timezone=Australia/Eucla
-Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 6.51s J2 | TestSimExecutePlanAction.testExecute <<<
[junit4] > Throwable #1: java.util.concurrent.TimeoutException: last
state: DocCollection(testExecute//clusterstate.json/27)={
[junit4] > "replicationFactor":"1",
[junit4] > "pullReplicas":"0",
[junit4] > "router":{"name":"compositeId"},
[junit4] > "maxShardsPerNode":"1",
[junit4] > "autoAddReplicas":"false",
[junit4] > "nrtReplicas":"2",
[junit4] > "tlogReplicas":"0",
[junit4] > "autoCreated":"true",
[junit4] > "shards":{"shard1":{
[junit4] > "replicas":{
[junit4] > "core_node1":{
[junit4] > "core":"testExecute_shard1_replica_n1",
[junit4] > "leader":"true",
[junit4] > "SEARCHER.searcher.maxDoc":0,
[junit4] > "SEARCHER.searcher.deletedDocs":0,
[junit4] > "INDEX.sizeInBytes":10240,
[junit4] > "node_name":"127.0.0.1:10032_solr",
[junit4] > "state":"active",
[junit4] > "type":"NRT",
[junit4] > "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] > "SEARCHER.searcher.numDocs":0},
[junit4] > "core_node2":{
[junit4] > "core":"testExecute_shard1_replica_n2",
[junit4] > "SEARCHER.searcher.maxDoc":0,
[junit4] > "SEARCHER.searcher.deletedDocs":0,
[junit4] > "INDEX.sizeInBytes":10240,
[junit4] > "node_name":"127.0.0.1:10031_solr",
[junit4] > "state":"active",
[junit4] > "type":"NRT",
[junit4] > "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] > "SEARCHER.searcher.numDocs":0},
[junit4] > "core_node4":{
[junit4] > "node_name":"127.0.0.1:10032_solr",
[junit4] > "core":"testExecute_shard1_replica_n3",
[junit4] > "state":"active",
[junit4] > "INDEX.sizeInBytes":10240,
[junit4] > "type":"NRT",
[junit4] > "INDEX.sizeInGB":9.5367431640625E-6}},
[junit4] > "range":"80000000-7fffffff",
[junit4] > "state":"active"}}}
[junit4] > at
__randomizedtesting.SeedInfo.seed([D163BF8D5136E1F3:E0D3AD8043C11E74]:0)
[junit4] > at
org.apache.solr.cloud.CloudTestUtils.waitForState(CloudTestUtils.java:112)
[junit4] > at
org.apache.solr.cloud.autoscaling.sim.TestSimExecutePlanAction.testExecute(TestSimExecutePlanAction.java:154)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 1145973 WARN (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are
closed, exiting.
[junit4] 2> NOTE: leaving temporary files on disk at:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.sim.TestSimExecutePlanAction_D163BF8D5136E1F3-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {},
docValues:{}, maxPointsInLeafNode=1082, maxMBSortInHeap=5.386458756763834,
sim=RandomSimilarity(queryNorm=false): {}, locale=ro, timezone=Australia/Eucla
[junit4] 2> NOTE: Linux 4.4.0-130-generic amd64/Oracle Corporation
1.8.0_172 (64-bit)/cpus=4,threads=1,free=129594920,total=517472256
[junit4] 2> NOTE: All tests run in this JVM: [AdminHandlersProxyTest,
TestSimNodeAddedTrigger, TestSolrCloudWithHadoopAuthPlugin, TestRealTimeGet,
TestSimComputePlanAction, TestFaceting, NodeLostTriggerTest,
SystemInfoHandlerTest, TestFieldTypeCollectionResource,
TestDistributedGrouping, SimpleFacetsTest, TestStreamBody,
TestDirectoryFactory, AnalyticsQueryTest, TestSimTriggerIntegration,
TestUseDocValuesAsStored2, MigrateRouteKeyTest, OpenCloseCoreStressTest,
SolrTestCaseJ4Test, TestDFISimilarityFactory, TestCoreBackup,
CSVRequestHandlerTest, ResponseHeaderTest, CleanupOldIndexTest,
SolrGangliaReporterTest, TestRequestForwarding,
LeaderFailoverAfterPartitionTest, TestCloudSearcherWarming,
TestBulkSchemaConcurrent, ScriptEngineTest, TestComplexPhraseQParserPlugin,
TestSerializedLuceneMatchVersion, SchemaApiFailureTest, QueryParsingTest,
TestLegacyTerms, TestConfigSetsAPIExclusivity,
DistributedSpellCheckComponentTest, TestNumericRangeQuery64, TestReload,
ConvertedLegacyTest, SolrIndexSplitterTest, TestMinMaxOnMultiValuedField,
TestSolr4Spatial, PreAnalyzedFieldTest,
DistributedFacetSimpleRefinementLongTailTest, ExplicitHLLTest,
TestRawResponseWriter, JavabinLoaderTest, TermVectorComponentDistributedTest,
TestCloudJSONFacetJoinDomain, AddReplicaTest, TestSubQueryTransformer,
TestHashQParserPlugin, TestCloudNestedDocsSort, TestCoreDiscovery,
TestSolrIndexConfig, TestSQLHandler, SignificantTermsQParserPluginTest,
TestJmxIntegration, TestConfigSetProperties, BasicAuthIntegrationTest,
AnalysisErrorHandlingTest, TestDownShardTolerantSearch, TestCharFilters,
TestReplicationHandlerBackup, ShardRoutingCustomTest,
TestManagedSynonymFilterFactory, Tagger2Test, BasicZkTest,
AsyncCallRequestStatusResponseTest, TestDocumentBuilder, TestJoin,
TestRangeQuery, TestSimpleTrackingShardHandler, HighlighterMaxOffsetTest,
TestNoOpRegenerator, SolrShardReporterTest, URLClassifyProcessorTest,
RequestLoggingTest, TlogReplayBufferedWhileIndexingTest, TestTestInjection,
StatelessScriptUpdateProcessorFactoryTest, BadIndexSchemaTest, TestUpdate,
TestPerFieldSimilarity, DistributedDebugComponentTest,
TestSchemalessBufferedUpdates, TestBlendedInfixSuggestions, DocValuesTest,
StatsComponentTest, FieldMutatingUpdateProcessorTest,
PeerSyncWithLeaderAndIndexFingerprintCachingTest, RollingRestartTest,
MoveReplicaHDFSTest, TestReplicaProperties, TestAuthorizationFramework,
UtilsToolTest, CachingDirectoryFactoryTest, TestNumericTerms64,
SimpleMLTQParserTest, TestSolrCloudSnapshots, TestDeleteCollectionOnDownNodes,
TestCollectionsAPIViaSolrCloudCluster, TestAddFieldRealTimeGet,
AtomicUpdateProcessorFactoryTest, SOLR749Test,
ClassificationUpdateProcessorIntegrationTest, TestBackupRepositoryFactory,
TestImplicitCoreProperties, OpenExchangeRatesOrgProviderTest,
CollectionsAPISolrJTest, TestValueSourceCache, DeleteReplicaTest,
TestConfigsApi, ExecutePlanActionTest, ScheduledMaintenanceTriggerTest,
TestSimExecutePlanAction]
[junit4] Completed [310/833 (1!)] on J2 in 7.20s, 2 tests, 1 error <<<
FAILURES!
[...truncated 83 lines...]
[junit4] Suite:
org.apache.solr.cloud.autoscaling.SearchRateTriggerIntegrationTest
[junit4] 2> 1146026 INFO
(SUITE-SearchRateTriggerIntegrationTest-seed#[D163BF8D5136E1F3]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.SearchRateTriggerIntegrationTest_D163BF8D5136E1F3-001/init-core-data-001
[junit4] 2> 1146028 INFO
(SUITE-SearchRateTriggerIntegrationTest-seed#[D163BF8D5136E1F3]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1146029 INFO
(SUITE-SearchRateTriggerIntegrationTest-seed#[D163BF8D5136E1F3]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 1146029 INFO
(SUITE-SearchRateTriggerIntegrationTest-seed#[D163BF8D5136E1F3]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 5 servers in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.SearchRateTriggerIntegrationTest_D163BF8D5136E1F3-001/tempDir-001
[junit4] 2> 1146029 INFO
(SUITE-SearchRateTriggerIntegrationTest-seed#[D163BF8D5136E1F3]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1146029 INFO (Thread-4163) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1146029 INFO (Thread-4163) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1146061 ERROR (Thread-4163) [ ] 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> 1146129 INFO
(SUITE-SearchRateTriggerIntegrationTest-seed#[D163BF8D5136E1F3]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:34003
[junit4] 2> 1146163 INFO (zkConnectionManagerCallback-5435-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1146195 INFO (jetty-launcher-5432-thread-1) [ ]
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git:
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 1146196 INFO (jetty-launcher-5432-thread-2) [ ]
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git:
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 1146219 INFO (jetty-launcher-5432-thread-3) [ ]
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git:
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 1146235 INFO (jetty-launcher-5432-thread-5) [ ]
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git:
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 1146235 INFO (jetty-launcher-5432-thread-4) [ ]
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git:
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 1146239 INFO (jetty-launcher-5432-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1146239 INFO (jetty-launcher-5432-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1146239 INFO (jetty-launcher-5432-thread-1) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1146240 INFO (jetty-launcher-5432-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@43ea209c{/solr,null,AVAILABLE}
[junit4] 2> 1146241 INFO (jetty-launcher-5432-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@625b0496{HTTP/1.1,[http/1.1]}{127.0.0.1:34422}
[junit4] 2> 1146241 INFO (jetty-launcher-5432-thread-1) [ ]
o.e.j.s.Server Started @1146289ms
[junit4] 2> 1146241 INFO (jetty-launcher-5432-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=34422}
[junit4] 2> 1146241 ERROR (jetty-launcher-5432-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1146241 INFO (jetty-launcher-5432-thread-1) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1146241 INFO (jetty-launcher-5432-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.6.0
[junit4] 2> 1146241 INFO (jetty-launcher-5432-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1146241 INFO (jetty-launcher-5432-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1146241 INFO (jetty-launcher-5432-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-09-26T20:20:11.907Z
[junit4] 2> 1146315 INFO (jetty-launcher-5432-thread-4) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1146315 INFO (jetty-launcher-5432-thread-4) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1146315 INFO (jetty-launcher-5432-thread-4) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1146323 INFO (jetty-launcher-5432-thread-4) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@3c02fb16{/solr,null,AVAILABLE}
[junit4] 2> 1146323 INFO (jetty-launcher-5432-thread-4) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@1ca81373{HTTP/1.1,[http/1.1]}{127.0.0.1:43946}
[junit4] 2> 1146323 INFO (jetty-launcher-5432-thread-4) [ ]
o.e.j.s.Server Started @1146371ms
[junit4] 2> 1146323 INFO (jetty-launcher-5432-thread-4) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=43946}
[junit4] 2> 1146324 ERROR (jetty-launcher-5432-thread-4) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1146324 INFO (jetty-launcher-5432-thread-4) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1146324 INFO (jetty-launcher-5432-thread-4) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.6.0
[junit4] 2> 1146324 INFO (jetty-launcher-5432-thread-4) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1146324 INFO (jetty-launcher-5432-thread-4) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1146324 INFO (jetty-launcher-5432-thread-4) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-09-26T20:20:11.990Z
[junit4] 2> 1146345 INFO (zkConnectionManagerCallback-5439-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1146346 INFO (jetty-launcher-5432-thread-4) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1146387 INFO (jetty-launcher-5432-thread-2) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1146387 INFO (jetty-launcher-5432-thread-2) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1146387 INFO (jetty-launcher-5432-thread-2) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1146387 INFO (jetty-launcher-5432-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@5abeb1a6{/solr,null,AVAILABLE}
[junit4] 2> 1146388 INFO (jetty-launcher-5432-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@52129fe7{HTTP/1.1,[http/1.1]}{127.0.0.1:41300}
[junit4] 2> 1146388 INFO (jetty-launcher-5432-thread-2) [ ]
o.e.j.s.Server Started @1146436ms
[junit4] 2> 1146388 INFO (jetty-launcher-5432-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=41300}
[junit4] 2> 1146388 ERROR (jetty-launcher-5432-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1146388 INFO (jetty-launcher-5432-thread-2) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1146388 INFO (jetty-launcher-5432-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.6.0
[junit4] 2> 1146388 INFO (jetty-launcher-5432-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1146388 INFO (jetty-launcher-5432-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1146388 INFO (jetty-launcher-5432-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-09-26T20:20:12.054Z
[junit4] 2> 1146455 INFO (jetty-launcher-5432-thread-5) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1146455 INFO (jetty-launcher-5432-thread-5) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1146455 INFO (jetty-launcher-5432-thread-5) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1146463 INFO (jetty-launcher-5432-thread-5) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@7fdadfb9{/solr,null,AVAILABLE}
[junit4] 2> 1146463 INFO (jetty-launcher-5432-thread-5) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@58ea9655{HTTP/1.1,[http/1.1]}{127.0.0.1:40902}
[junit4] 2> 1146463 INFO (jetty-launcher-5432-thread-5) [ ]
o.e.j.s.Server Started @1146511ms
[junit4] 2> 1146463 INFO (jetty-launcher-5432-thread-5) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=40902}
[junit4] 2> 1146464 ERROR (jetty-launcher-5432-thread-5) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1146464 INFO (jetty-launcher-5432-thread-5) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1146464 INFO (jetty-launcher-5432-thread-5) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.6.0
[junit4] 2> 1146464 INFO (jetty-launcher-5432-thread-5) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1146464 INFO (jetty-launcher-5432-thread-5) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1146464 INFO (jetty-launcher-5432-thread-5) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-09-26T20:20:12.130Z
[junit4] 2> 1146545 INFO (jetty-launcher-5432-thread-3) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1146545 INFO (jetty-launcher-5432-thread-3) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1146545 INFO (jetty-launcher-5432-thread-3) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1146559 INFO (jetty-launcher-5432-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@637375fd{/solr,null,AVAILABLE}
[junit4] 2> 1146559 INFO (jetty-launcher-5432-thread-3) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@3b906575{HTTP/1.1,[http/1.1]}{127.0.0.1:37136}
[junit4] 2> 1146559 INFO (jetty-launcher-5432-thread-3) [ ]
o.e.j.s.Server Started @1146607ms
[junit4] 2> 1146559 INFO (jetty-launcher-5432-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=37136}
[junit4] 2> 1146560 ERROR (jetty-launcher-5432-thread-3) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1146560 INFO (jetty-launcher-5432-thread-3) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1146560 INFO (jetty-launcher-5432-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.6.0
[junit4] 2> 1146560 INFO (jetty-launcher-5432-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1146560 INFO (jetty-launcher-5432-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1146560 INFO (jetty-launcher-5432-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-09-26T20:20:12.226Z
[junit4] 2> 1146572 INFO (zkConnectionManagerCallback-5437-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1146573 INFO (jetty-launcher-5432-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1146573 INFO (zkConnectionManagerCallback-5445-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1146578 INFO (jetty-launcher-5432-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1146599 INFO (zkConnectionManagerCallback-5443-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1146599 INFO (jetty-launcher-5432-thread-5) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1146639 INFO (zkConnectionManagerCallback-5441-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1146643 INFO (jetty-launcher-5432-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1146907 INFO (jetty-launcher-5432-thread-4) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34003/solr
[junit4] 2> 1146931 INFO (zkConnectionManagerCallback-5449-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1146963 INFO (zkConnectionManagerCallback-5451-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1147075 INFO (jetty-launcher-5432-thread-4)
[n:127.0.0.1:43946_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:43946_solr
[junit4] 2> 1147079 INFO (jetty-launcher-5432-thread-4)
[n:127.0.0.1:43946_solr ] o.a.s.c.Overseer Overseer
(id=72403750695927815-127.0.0.1:43946_solr-n_0000000000) starting
[junit4] 2> 1147144 INFO (jetty-launcher-5432-thread-5) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34003/solr
[junit4] 2> 1147185 INFO (zkConnectionManagerCallback-5462-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1147191 INFO (zkConnectionManagerCallback-5458-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1147193 INFO (jetty-launcher-5432-thread-4)
[n:127.0.0.1:43946_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:34003/solr ready
[junit4] 2> 1147223 INFO
(OverseerStateUpdate-72403750695927815-127.0.0.1:43946_solr-n_0000000000)
[n:127.0.0.1:43946_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:43946_solr
[junit4] 2> 1147226 INFO (jetty-launcher-5432-thread-4)
[n:127.0.0.1:43946_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:43946_solr
[junit4] 2> 1147255 INFO (zkConnectionManagerCallback-5464-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1147255 DEBUG
(OverseerAutoScalingTriggerThread-72403750695927815-127.0.0.1:43946_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 1147257 DEBUG
(OverseerAutoScalingTriggerThread-72403750695927815-127.0.0.1:43946_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 1
[junit4] 2> 1147257 DEBUG
(OverseerAutoScalingTriggerThread-72403750695927815-127.0.0.1:43946_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1,
lastZnodeVersion -1
[junit4] 2> 1147257 DEBUG
(OverseerAutoScalingTriggerThread-72403750695927815-127.0.0.1:43946_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 1
[junit4] 2> 1147259 INFO (zkCallback-5450-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1147260 INFO (jetty-launcher-5432-thread-5)
[n:127.0.0.1:40902_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1147264 INFO (jetty-launcher-5432-thread-5)
[n:127.0.0.1:40902_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 1147264 INFO (jetty-launcher-5432-thread-5)
[n:127.0.0.1:40902_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:40902_solr
[junit4] 2> 1147264 INFO (zkCallback-5457-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1147279 INFO (zkCallback-5457-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1147280 DEBUG
(OverseerAutoScalingTriggerThread-72403750695927815-127.0.0.1:43946_solr-n_0000000000)
[ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial
livenodes: [127.0.0.1:43946_solr, 127.0.0.1:40902_solr]
[junit4] 2> 1147299 INFO (zkCallback-5463-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1147306 INFO (zkCallback-5450-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1147328 INFO (jetty-launcher-5432-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34003/solr
[junit4] 2> 1147341 INFO (jetty-launcher-5432-thread-4)
[n:127.0.0.1:43946_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 1147359 DEBUG (ScheduledTrigger-5187-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 1147368 DEBUG
(OverseerAutoScalingTriggerThread-72403750695927815-127.0.0.1:43946_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 1147374 DEBUG
(OverseerAutoScalingTriggerThread-72403750695927815-127.0.0.1:43946_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1,
lastZnodeVersion 1
[junit4] 2> 1147374 INFO (zkConnectionManagerCallback-5472-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1147389 INFO (jetty-launcher-5432-thread-4)
[n:127.0.0.1:43946_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_43946.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7786d822
[junit4] 2> 1147400 INFO (jetty-launcher-5432-thread-4)
[n:127.0.0.1:43946_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_43946.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7786d822
[junit4] 2> 1147400 INFO (jetty-launcher-5432-thread-4)
[n:127.0.0.1:43946_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_43946.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7786d822
[junit4] 2> 1147402 INFO (jetty-launcher-5432-thread-4)
[n:127.0.0.1:43946_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.SearchRateTriggerIntegrationTest_D163BF8D5136E1F3-001/tempDir-001/node4/.
[junit4] 2> 1147432 INFO (jetty-launcher-5432-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34003/solr
[junit4] 2> 1147432 INFO (zkConnectionManagerCallback-5476-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1147434 INFO (jetty-launcher-5432-thread-5)
[n:127.0.0.1:40902_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 1147435 INFO (jetty-launcher-5432-thread-5)
[n:127.0.0.1:40902_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:34003/solr ready
[junit4] 2> 1147471 INFO (jetty-launcher-5432-thread-5)
[n:127.0.0.1:40902_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 1147493 INFO (jetty-launcher-5432-thread-5)
[n:127.0.0.1:40902_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40902.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7786d822
[junit4] 2> 1147512 INFO (jetty-launcher-5432-thread-5)
[n:127.0.0.1:40902_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40902.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7786d822
[junit4] 2> 1147512 INFO (jetty-launcher-5432-thread-5)
[n:127.0.0.1:40902_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40902.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7786d822
[junit4] 2> 1147513 INFO (jetty-launcher-5432-thread-5)
[n:127.0.0.1:40902_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.SearchRateTriggerIntegrationTest_D163BF8D5136E1F3-001/tempDir-001/node5/.
[junit4] 2> 1147513 INFO (zkConnectionManagerCallback-5480-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1147527 INFO (zkConnectionManagerCallback-5483-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1147532 INFO (jetty-launcher-5432-thread-2)
[n:127.0.0.1:41300_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 1147535 INFO (jetty-launcher-5432-thread-2)
[n:127.0.0.1:41300_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 1147535 INFO (jetty-launcher-5432-thread-2)
[n:127.0.0.1:41300_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:41300_solr
[junit4] 2> 1147536 INFO (zkCallback-5457-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1147536 INFO (zkCallback-5450-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1147563 INFO (zkCallback-5463-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1147571 INFO (zkCallback-5482-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1147591 INFO (zkCallback-5475-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1147627 INFO (zkConnectionManagerCallback-5487-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1147632 INFO (zkConnectionManagerCallback-5492-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1147633 INFO (jetty-launcher-5432-thread-3)
[n:127.0.0.1:37136_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 1147633 INFO (jetty-launcher-5432-thread-2)
[n:127.0.0.1:41300_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 1147634 INFO (jetty-launcher-5432-thread-2)
[n:127.0.0.1:41300_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:34003/solr ready
[junit4] 2> 1147637 INFO (jetty-launcher-5432-thread-3)
[n:127.0.0.1:37136_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 1147637 INFO (jetty-launcher-5432-thread-3)
[n:127.0.0.1:37136_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:37136_solr
[junit4] 2> 1147638 INFO (zkCallback-5457-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1147638 INFO (zkCallback-5450-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1147638 INFO (zkCallback-5463-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1147647 INFO (zkCallback-5482-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1147647 INFO (zkCallback-5475-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1147651 INFO (jetty-launcher-5432-thread-2)
[n:127.0.0.1:41300_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 1147764 INFO (jetty-launcher-5432-thread-2)
[n:127.0.0.1:41300_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41300.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7786d822
[junit4] 2> 1147767 INFO (zkCallback-5491-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1147779 INFO (zkCallback-5486-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1147793 INFO (jetty-launcher-5432-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34003/solr
[junit4] 2> 1147796 INFO (jetty-launcher-5432-thread-2)
[n:127.0.0.1:41300_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41300.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7786d822
[junit4] 2> 1147796 INFO (jetty-launcher-5432-thread-2)
[n:127.0.0.1:41300_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41300.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7786d822
[junit4] 2> 1147797 INFO (jetty-launcher-5432-thread-2)
[n:127.0.0.1:41300_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.SearchRateTriggerIntegrationTest_D163BF8D5136E1F3-001/tempDir-001/node2/.
[junit4] 2> 1147851 INFO (zkConnectionManagerCallback-5500-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1147851 INFO (zkConnectionManagerCallback-5504-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1147867 INFO (jetty-launcher-5432-thread-3)
[n:127.0.0.1:37136_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 1147868 INFO (jetty-launcher-5432-thread-3)
[n:127.0.0.1:37136_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:34003/solr ready
[junit4] 2> 1147876 INFO (jetty-launcher-5432-thread-3)
[n:127.0.0.1:37136_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 1147890 INFO (jetty-launcher-5432-thread-3)
[n:127.0.0.1:37136_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37136.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7786d822
[junit4] 2> 1147891 INFO (zkConnectionManagerCallback-5506-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1147896 INFO (jetty-launcher-5432-thread-1)
[n:127.0.0.1:34422_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 1147899 INFO (jetty-launcher-5432-thread-1)
[n:127.0.0.1:34422_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 1147899 INFO (jetty-launcher-5432-thread-1)
[n:127.0.0.1:34422_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:34422_solr
[junit4] 2> 1147901 INFO (zkCallback-5457-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1147901 INFO (zkCallback-5463-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1147901 INFO (zkCallback-5475-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1147901 INFO (zkCallback-5486-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1147901 INFO (zkCallback-5482-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1147901 INFO (zkCallback-5450-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1147907 INFO (zkCallback-5503-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1147908 INFO (zkCallback-5491-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1147920 INFO (zkCallback-5505-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1147922 INFO (jetty-launcher-5432-thread-3)
[n:127.0.0.1:37136_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37136.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7786d822
[junit4] 2> 1147922 INFO (jetty-launcher-5432-thread-3)
[n:127.0.0.1:37136_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37136.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7786d822
[junit4] 2> 1147923 INFO (jetty-launcher-5432-thread-3)
[n:127.0.0.1:37136_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.SearchRateTriggerIntegrationTest_D163BF8D5136E1F3-001/tempDir-001/node3/.
[junit4] 2> 1148000 INFO (zkConnectionManagerCallback-5514-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1148002 INFO (jetty-launcher-5432-thread-1)
[n:127.0.0.1:34422_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (5)
[junit4] 2> 1148003 INFO (jetty-launcher-5432-thread-1)
[n:127.0.0.1:34422_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:34003/solr ready
[junit4] 2> 1148039 INFO (jetty-launcher-5432-thread-1)
[n:127.0.0.1:34422_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 1148074 INFO (jetty-launcher-5432-thread-1)
[n:127.0.0.1:34422_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_34422.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7786d822
[junit4] 2> 1148128 INFO (jetty-launcher-5432-thread-1)
[n:127.0.0.1:34422_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_34422.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7786d822
[junit4] 2> 1148128 INFO (jetty-launcher-5432-thread-1)
[n:127.0.0.1:34422_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_34422.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7786d822
[junit4] 2> 1148131 INFO (jetty-launcher-5432-thread-1)
[n:127.0.0.1:34422_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.SearchRateTriggerIntegrationTest_D163BF8D5136E1F3-001/tempDir-001/node1/.
[junit4] 2> 1148254 INFO (zkConnectionManagerCallback-5517-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1148307 INFO (zkConnectionManagerCallback-5522-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1148308 INFO
(SUITE-SearchRateTriggerIntegrationTest-seed#[D163BF8D5136E1F3]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
[junit4] 2> 1148309 INFO
(SUITE-SearchRateTriggerIntegrationTest-seed#[D163BF8D5136E1F3]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34003/solr ready
[junit4] 2> 1148344 INFO (qtp1575572367-15556) [n:127.0.0.1:34422_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 1148356 INFO (qtp875274120-15601) [n:127.0.0.1:37136_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 1148359 INFO (qtp1995324172-15591) [n:127.0.0.1:40902_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 1148360 DEBUG (ScheduledTrigger-5187-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 5
[junit4] 2> 1148360 INFO (qtp1991687910-15576) [n:127.0.0.1:41300_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=1
[junit4] 2> 1148376 INFO (qtp1403118025-15567) [n:127.0.0.1:43946_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 1148376 DEBUG (qtp1991687910-15581) [n:127.0.0.1:41300_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1148377 INFO (qtp1991687910-15581) [n:127.0.0.1:41300_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=45
[junit4] 2> 1148379 DEBUG (zkCallback-5457-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
2
[junit4] 2> 1148380 DEBUG
(OverseerAutoScalingTriggerThread-72403750695927815-127.0.0.1:43946_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 2
[junit4] 2> 1148383 DEBUG
(OverseerAutoScalingTriggerThread-72403750695927815-127.0.0.1:43946_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 1148384 DEBUG
(OverseerAutoScalingTriggerThread-72403750695927815-127.0.0.1:43946_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2,
lastZnodeVersion 2
[junit4] 2> 1148507 INFO
(TEST-SearchRateTriggerIntegrationTest.testAboveSearchRate-seed#[D163BF8D5136E1F3])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testAboveSearchRate
[junit4] 2> 1148510 INFO
(TEST-SearchRateTriggerIntegrationTest.testAboveSearchRate-seed#[D163BF8D5136E1F3])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
[junit4] 2> 1148514 DEBUG (zkCallback-5457-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
3
[junit4] 2> 1148514 DEBUG
(OverseerAutoScalingTriggerThread-72403750695927815-127.0.0.1:43946_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 3
[junit4] 2> 1148515 INFO
(TEST-SearchRateTriggerIntegrationTest.testAboveSearchRate-seed#[D163BF8D5136E1F3])
[ ] o.a.s.c.a.SearchRateTriggerIntegrationTest /autoscaling.json reset, new
znode version 3
[junit4] 2> 1148519 DEBUG
(OverseerAutoScalingTriggerThread-72403750695927815-127.0.0.1:43946_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 1148520 DEBUG
(OverseerAutoScalingTriggerThread-72403750695927815-127.0.0.1:43946_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 1148520 DEBUG
(OverseerAutoScalingTriggerThread-72403750695927815-127.0.0.1:43946_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3,
lastZnodeVersion 3
[junit4] 2> 1153529 INFO (qtp1991687910-15577) [n:127.0.0.1:41300_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
collection.configName=conf&name=aboveRate_collection&nrtReplicas=2&action=CREATE&numShards=1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1153541 INFO
(OverseerThreadFactory-5189-thread-1-processing-n:127.0.0.1:43946_solr)
[n:127.0.0.1:43946_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection
aboveRate_collection
[junit4] 2> 1153662 INFO
(OverseerStateUpdate-72403750695927815-127.0.0.1:43946_solr-n_0000000000)
[n:127.0.0.1:43946_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"aboveRate_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"aboveRate_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:40902/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1153664 INFO
(OverseerStateUpdate-72403750695927815-127.0.0.1:43946_solr-n_0000000000)
[n:127.0.0.1:43946_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"aboveRate_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"aboveRate_collection_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:34422/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1153881 INFO (qtp1995324172-15573) [n:127.0.0.1:40902_solr
x:aboveRate_collection_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core
create command
qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=aboveRate_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=aboveRate_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1153883 INFO (qtp1575572367-15551) [n:127.0.0.1:34422_solr
x:aboveRate_collection_shard1_replica_n2] o.a.s.h.a.CoreAdminOperation core
create command
qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=aboveRate_collection_shard1_replica_n2&action=CREATE&numShards=1&collection=aboveRate_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1154915 INFO (qtp1575572367-15551) [n:127.0.0.1:34422_solr
c:aboveRate_collection s:shard1 r:core_node4
x:aboveRate_collection_shard1_replica_n2] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 7.6.0
[junit4] 2> 1155000 INFO (qtp1995324172-15573) [n:127.0.0.1:40902_solr
c:aboveRate_collection s:shard1 r:core_node3
x:aboveRate_collection_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 7.6.0
[junit4] 2> 1155010 INFO (qtp1575572367-15551) [n:127.0.0.1:34422_solr
c:aboveRate_collection s:shard1 r:core_node4
x:aboveRate_collection_shard1_replica_n2] o.a.s.s.IndexSchema
[aboveRate_collection_shard1_replica_n2] Schema name=minimal
[junit4] 2> 1155014 INFO (qtp1575572367-15551) [n:127.0.0.1:34422_solr
c:aboveRate_collection s:shard1 r:core_node4
x:aboveRate_collection_shard1_replica_n2] o.a.s.s.IndexSchema Loaded schema
minimal/1.1 with uniqueid field id
[junit4] 2> 1155014 INFO (qtp1575572367-15551) [n:127.0.0.1:34422_solr
c:aboveRate_collection s:shard1 r:core_node4
x:aboveRate_collection_shard1_replica_n2] o.a.s.c.CoreContainer Creating
SolrCore 'aboveRate_collection_shard1_replica_n2' using configuration from
collection aboveRate_collection, trusted=true
[junit4] 2> 1155023 INFO (qtp1575572367-15551) [n:127.0.0.1:34422_solr
c:aboveRate_collection s:shard1 r:core_node4
x:aboveRate_collection_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr_34422.solr.core.aboveRate_collection.shard1.replica_n2'
(registry 'solr.core.aboveRate_collection.shard1.replica_n2') enabled at
server: com.sun.jmx.mbeanserver.JmxMBeanServer@7786d822
[junit4] 2> 1155023 INFO (qtp1575572367-15551) [n:127.0.0.1:34422_solr
c:aboveRate_collection s:shard1 r:core_node4
x:aboveRate_collection_shard1_replica_n2] o.a.s.c.SolrCore
[[aboveRate_collection_shard1_replica_n2] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.SearchRateTriggerIntegrationTest_D163BF8D5136E1F3-001/tempDir-001/node1/aboveRate_collection_shard1_replica_n2],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.SearchRateTriggerIntegrationTest_D163BF8D5136E1F3-001/tempDir-001/node1/./aboveRate_collection_shard1_replica_n2/data/]
[junit4] 2> 1155030 INFO (qtp1995324172-15573) [n:127.0.0.1:40902_solr
c:aboveRate_collection s:shard1 r:core_node3
x:aboveRate_collection_shard1_replica_n1] o.a.s.s.IndexSchema
[aboveRate_collection_shard1_replica_n1] Schema name=minimal
[junit4] 2> 1155041 INFO (qtp1995324172-15573) [n:127.0.0.1:40902_solr
c:aboveRate_collection s:shard1 r:core_node3
x:aboveRate_collection_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema
minimal/1.1 with uniqueid field id
[junit4] 2> 1155041 INFO (qtp1995324172-15573) [n:127.0.0.1:40902_solr
c:aboveRate_collection s:shard1 r:core_node3
x:aboveRate_collection_shard1_replica_n1] o.a.s.c.CoreContainer Creating
SolrCore 'aboveRate_collection_shard1_replica_n1' using configuration from
collection aboveRate_collection, trusted=true
[junit4] 2> 1155041 INFO (qtp1995324172-15573) [n:127.0.0.1:40902_solr
c:aboveRate_collection s:shard1 r:core_node3
x:aboveRate_collection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr_40902.solr.core.aboveRate_collection.shard1.replica_n1'
(registry 'solr.core.aboveRate_collection.shard1.replica_n1') enabled at
server: com.sun.jmx.mbeanserver.JmxMBeanServer@7786d822
[junit4] 2> 1155042 INFO (qtp1995324172-15573) [n:127.0.0.1:40902_solr
c:aboveRate_collection s:shard1 r:core_node3
x:aboveRate_collection_shard1_replica_n1] o.a.s.c.SolrCore
[[aboveRate_collection_shard1_replica_n1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.SearchRateTriggerIntegrationTest_D163BF8D5136E1F3-001/tempDir-001/node5/aboveRate_collection_shard1_replica_n1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.SearchRateTriggerIntegrationTest_D163BF8D5136E1F3-001/tempDir-001/node5/./aboveRate_collection_shard1_replica_n1/data/]
[junit4] 2> 1155247 INFO (qtp1995324172-15573) [n:127.0.0.1:40902_solr
c:aboveRate_collection s:shard1 r:core_node3
x:aboveRate_collection_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1155247 INFO (qtp1995324172-15573) [n:127.0.0.1:40902_solr
c:aboveRate_collection s:shard1 r:core_node3
x:aboveRate_collection_shard1_replica_n1] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1155249 INFO (qtp1995324172-15573) [n:127.0.0.1:40902_solr
c:aboveRate_collection s:shard1 r:core_node3
x:aboveRate_collection_shard1_replica_n1] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 1155249 INFO (qtp1995324172-15573) [n:127.0.0.1:40902_solr
c:aboveRate_collection s:shard1 r:core_node3
x:aboveRate_collection_shard1_replica_n1] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 1155259 INFO (qtp1995324172-15573) [n:127.0.0.1:40902_solr
c:aboveRate_collection s:shard1 r:core_node3
x:aboveRate_collection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@62fff12a[aboveRate_collection_shard1_replica_n1] main]
[junit4] 2> 1155260 INFO (qtp1995324172-15573) [n:127.0.0.1:40902_solr
c:aboveRate_collection s:shard1 r:core_node3
x:aboveRate_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 1155260 INFO (qtp1995324172-15573) [n:127.0.0.1:40902_solr
c:aboveRate_collection s:shard1 r:core_node3
x:aboveRate_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded
null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1155272 INFO (qtp1995324172-15573) [n:127.0.0.1:40902_solr
c:aboveRate_collection s:shard1 r:core_node3
x:aboveRate_collection_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits
will be reserved for 10000ms.
[junit4] 2> 1155273 INFO (qtp1995324172-15573) [n:127.0.0.1:40902_solr
c:aboveRate_collection s:shard1 r:core_node3
x:aboveRate_collection_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max
version in index or recent updates, using new clock 1612702779639332864
[junit4] 2> 1155275 INFO
(searcherExecutor-5220-thread-1-processing-n:127.0.0.1:40902_solr
x:aboveRate_collection_shard1_replica_n1 c:aboveRate_collection s:shard1
r:core_node3) [n:127.0.0.1:40902_solr c:aboveRate_collection s:shard1
r:core_node3 x:aboveRate_collection_shard1_replica_n1] o.a.s.c.SolrCore
[aboveRate_collection_shard1_replica_n1] Registered new searcher
Searcher@62fff12a[aboveRate_collection_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1155300 INFO (qtp1995324172-15573) [n:127.0.0.1:40902_solr
c:aboveRate_collection s:shard1 r:core_node3
x:aboveRate_collection_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful
update of terms at /collections/aboveRate_collection/terms/shard1 to
Terms{values={core_node3=0}, version=0}
[junit4] 2> 1155320 INFO (qtp1575572367-15551) [n:127.0.0.1:34422_solr
c:aboveRate_collection s:shard1 r:core_node4
x:aboveRate_collection_shard1_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1155321 INFO (qtp1575572367-15551) [n:127.0.0.1:34422_solr
c:aboveRate_collection s:shard1 r:core_node4
x:aboveRate_collection_shard1_replica_n2] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1155321 INFO (qtp1995324172-15573) [n:127.0.0.1:40902_solr
c:aboveRate_collection s:shard1 r:core_nod
[...truncated too long message...]
] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
jar-checksums:
[mkdir] Created dir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/null1328628081
[copy] Copying 239 files to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/null1328628081
[delete] Deleting directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/null1328628081
check-working-copy:
[ivy:cachepath] :: resolving dependencies ::
org.eclipse.jgit#org.eclipse.jgit-caller;working
[ivy:cachepath] confs: [default]
[ivy:cachepath] found
org.eclipse.jgit#org.eclipse.jgit;4.6.0.201612231935-r in public
[ivy:cachepath] found com.jcraft#jsch;0.1.53 in public
[ivy:cachepath] found com.googlecode.javaewah#JavaEWAH;1.1.6 in public
[ivy:cachepath] found org.apache.httpcomponents#httpclient;4.3.6 in
public
[ivy:cachepath] found org.apache.httpcomponents#httpcore;4.3.3 in public
[ivy:cachepath] found commons-logging#commons-logging;1.1.3 in public
[ivy:cachepath] found commons-codec#commons-codec;1.6 in public
[ivy:cachepath] found org.slf4j#slf4j-api;1.7.2 in public
[ivy:cachepath] :: resolution report :: resolve 69ms :: artifacts dl 20ms
---------------------------------------------------------------------
| | modules || artifacts |
| conf | number| search|dwnlded|evicted|| number|dwnlded|
---------------------------------------------------------------------
| default | 8 | 0 | 0 | 0 || 8 | 0 |
---------------------------------------------------------------------
[wc-checker] Initializing working copy...
[wc-checker] SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
[wc-checker] SLF4J: Defaulting to no-operation (NOP) logger implementation
[wc-checker] SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for
further details.
[wc-checker] Checking working copy status...
-jenkins-base:
BUILD SUCCESSFUL
Total time: 108 minutes 51 seconds
Archiving artifacts
java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath$34.hasMatch(FilePath.java:2678)
at hudson.FilePath$34.invoke(FilePath.java:2557)
at hudson.FilePath$34.invoke(FilePath.java:2547)
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2918)
Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to lucene
at
hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1741)
at
hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357)
at hudson.remoting.Channel.call(Channel.java:955)
at hudson.FilePath.act(FilePath.java:1036)
at hudson.FilePath.act(FilePath.java:1025)
at hudson.FilePath.validateAntFileMask(FilePath.java:2547)
at
hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
at
hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
at
hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at
hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
at
hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
at hudson.model.Build$BuildExecution.post2(Build.java:186)
at
hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
at hudson.model.Run.execute(Run.java:1819)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at
hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
Caused: hudson.FilePath$TunneledInterruptedException
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2920)
at hudson.remoting.UserRequest.perform(UserRequest.java:212)
at hudson.remoting.UserRequest.perform(UserRequest.java:54)
at hudson.remoting.Request$2.run(Request.java:369)
at
hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:748)
Caused: java.lang.InterruptedException: java.lang.InterruptedException: no
matches found within 10000
at hudson.FilePath.act(FilePath.java:1038)
at hudson.FilePath.act(FilePath.java:1025)
at hudson.FilePath.validateAntFileMask(FilePath.java:2547)
at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
at
hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at
hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
at
hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
at hudson.model.Build$BuildExecution.post2(Build.java:186)
at
hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
at hudson.model.Run.execute(Run.java:1819)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
No artifacts found that match the file pattern
"**/*.events,heapdumps/**,**/hs_err_pid*". Configuration error?
Recording test results
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
