Build: https://builds.apache.org/job/Lucene-Solr-BadApples-Tests-7.x/162/
3 tests failed.
FAILED:
org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest.testInactiveShardCleanup
Error Message:
should be at least one inactive event
Stack Trace:
java.lang.AssertionError: should be at least one inactive event
at
__randomizedtesting.SeedInfo.seed([DD3A5CBFED94B8B7:C0169CCD8CD79FBC]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at
org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest.testInactiveShardCleanup(ScheduledMaintenanceTriggerTest.java:224)
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.TestSimComputePlanAction.testNodeLost
Error Message:
Timed out waiting for replicas of new collection to be active Live Nodes:
[127.0.0.1:10001_solr, 127.0.0.1:10000_solr] Last available state:
DocCollection(testNodeLost//clusterstate.json/5)={ "replicationFactor":"1",
"pullReplicas":"0", "router":{"name":"compositeId"},
"maxShardsPerNode":"1", "autoAddReplicas":"false", "nrtReplicas":"2",
"tlogReplicas":"0", "autoCreated":"true", "shards":{"shard1":{
"replicas":{ "core_node1":{
"core":"testNodeLost_shard1_replica_n1",
"SEARCHER.searcher.maxDoc":0, "SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240, "node_name":"127.0.0.1:10001_solr",
"state":"active", "type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6, "SEARCHER.searcher.numDocs":0},
"core_node2":{ "core":"testNodeLost_shard1_replica_n2",
"SEARCHER.searcher.maxDoc":0, "SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10000_solr", "state":"active",
"type":"NRT", "INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0}}, "range":"80000000-7fffffff",
"state":"active"}}}
Stack Trace:
java.lang.AssertionError: Timed out waiting for replicas of new collection to
be active
Live Nodes: [127.0.0.1:10001_solr, 127.0.0.1:10000_solr]
Last available state: DocCollection(testNodeLost//clusterstate.json/5)={
"replicationFactor":"1",
"pullReplicas":"0",
"router":{"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"false",
"nrtReplicas":"2",
"tlogReplicas":"0",
"autoCreated":"true",
"shards":{"shard1":{
"replicas":{
"core_node1":{
"core":"testNodeLost_shard1_replica_n1",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10001_solr",
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0},
"core_node2":{
"core":"testNodeLost_shard1_replica_n2",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10000_solr",
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0}},
"range":"80000000-7fffffff",
"state":"active"}}}
at
__randomizedtesting.SeedInfo.seed([DD3A5CBFED94B8B7:622F92416E7EDD31]:0)
at
org.apache.solr.cloud.CloudTestUtils.waitForState(CloudTestUtils.java:70)
at
org.apache.solr.cloud.autoscaling.sim.TestSimComputePlanAction.testNodeLost(TestSimComputePlanAction.java:148)
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)
Caused by: java.util.concurrent.TimeoutException: last state:
DocCollection(testNodeLost//clusterstate.json/5)={
"replicationFactor":"1",
"pullReplicas":"0",
"router":{"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"false",
"nrtReplicas":"2",
"tlogReplicas":"0",
"autoCreated":"true",
"shards":{"shard1":{
"replicas":{
"core_node1":{
"core":"testNodeLost_shard1_replica_n1",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10001_solr",
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0},
"core_node2":{
"core":"testNodeLost_shard1_replica_n2",
"SEARCHER.searcher.maxDoc":0,
"SEARCHER.searcher.deletedDocs":0,
"INDEX.sizeInBytes":10240,
"node_name":"127.0.0.1:10000_solr",
"state":"active",
"type":"NRT",
"INDEX.sizeInGB":9.5367431640625E-6,
"SEARCHER.searcher.numDocs":0}},
"range":"80000000-7fffffff",
"state":"active"}}}
at
org.apache.solr.cloud.CloudTestUtils.waitForState(CloudTestUtils.java:112)
at
org.apache.solr.cloud.CloudTestUtils.waitForState(CloudTestUtils.java:64)
... 40 more
FAILED:
org.apache.solr.update.processor.TimeRoutedAliasUpdateProcessorTest.test
Error Message:
Error from server at https://127.0.0.1:44399/solr: KeeperErrorCode = Session
expired for /configs/timeConfigtest
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error
from server at https://127.0.0.1:44399/solr: KeeperErrorCode = Session expired
for /configs/timeConfigtest
at
__randomizedtesting.SeedInfo.seed([DD3A5CBFED94B8B7:556E63654368D54F]:0)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1107)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:884)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:817)
at
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194)
at
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211)
at
org.apache.solr.update.processor.TimeRoutedAliasUpdateProcessorTest.createConfigSet(TimeRoutedAliasUpdateProcessorTest.java:228)
at
org.apache.solr.update.processor.TimeRoutedAliasUpdateProcessorTest.test(TimeRoutedAliasUpdateProcessorTest.java:120)
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 12713 lines...]
[junit4] Suite:
org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest
[junit4] 2> 842634 INFO
(SUITE-ScheduledMaintenanceTriggerTest-seed#[DD3A5CBFED94B8B7]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest_DD3A5CBFED94B8B7-001/init-core-data-001
[junit4] 2> 842668 WARN
(SUITE-ScheduledMaintenanceTriggerTest-seed#[DD3A5CBFED94B8B7]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 842684 INFO
(SUITE-ScheduledMaintenanceTriggerTest-seed#[DD3A5CBFED94B8B7]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 842685 INFO
(SUITE-ScheduledMaintenanceTriggerTest-seed#[DD3A5CBFED94B8B7]-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> 842686 INFO
(SUITE-ScheduledMaintenanceTriggerTest-seed#[DD3A5CBFED94B8B7]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest_DD3A5CBFED94B8B7-001/tempDir-001
[junit4] 2> 842686 INFO
(SUITE-ScheduledMaintenanceTriggerTest-seed#[DD3A5CBFED94B8B7]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 842687 INFO (Thread-653) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 842687 INFO (Thread-653) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 842713 ERROR (Thread-653) [ ] 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> 842793 INFO
(SUITE-ScheduledMaintenanceTriggerTest-seed#[DD3A5CBFED94B8B7]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:43736
[junit4] 2> 842914 INFO (zkConnectionManagerCallback-876-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 843060 INFO (jetty-launcher-873-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> 843095 INFO (jetty-launcher-873-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 843095 INFO (jetty-launcher-873-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 843095 INFO (jetty-launcher-873-thread-1) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 843095 INFO (jetty-launcher-873-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@2e90f86a{/solr,null,AVAILABLE}
[junit4] 2> 843097 INFO (jetty-launcher-873-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@54ebfe60{SSL,[ssl,
http/1.1]}{127.0.0.1:42314}
[junit4] 2> 843097 INFO (jetty-launcher-873-thread-1) [ ]
o.e.j.s.Server Started @843141ms
[junit4] 2> 843097 INFO (jetty-launcher-873-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=42314}
[junit4] 2> 843097 ERROR (jetty-launcher-873-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 843097 INFO (jetty-launcher-873-thread-1) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 843097 INFO (jetty-launcher-873-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.6.0
[junit4] 2> 843097 INFO (jetty-launcher-873-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 843097 INFO (jetty-launcher-873-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 843097 INFO (jetty-launcher-873-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-09-18T00:39:46.624Z
[junit4] 2> 843144 INFO (zkConnectionManagerCallback-878-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 843144 INFO (jetty-launcher-873-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 844930 INFO (jetty-launcher-873-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43736/solr
[junit4] 2> 844952 INFO (zkConnectionManagerCallback-882-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 845017 INFO (zkConnectionManagerCallback-884-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 845815 INFO (jetty-launcher-873-thread-1)
[n:127.0.0.1:42314_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:42314_solr
[junit4] 2> 845816 INFO (jetty-launcher-873-thread-1)
[n:127.0.0.1:42314_solr ] o.a.s.c.Overseer Overseer
(id=73340040966569987-127.0.0.1:42314_solr-n_0000000000) starting
[junit4] 2> 845943 INFO (zkConnectionManagerCallback-891-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 845961 INFO (jetty-launcher-873-thread-1)
[n:127.0.0.1:42314_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:43736/solr ready
[junit4] 2> 846012 INFO
(OverseerStateUpdate-73340040966569987-127.0.0.1:42314_solr-n_0000000000)
[n:127.0.0.1:42314_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:42314_solr
[junit4] 2> 846048 INFO (jetty-launcher-873-thread-1)
[n:127.0.0.1:42314_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:42314_solr
[junit4] 2> 846049 DEBUG
(OverseerAutoScalingTriggerThread-73340040966569987-127.0.0.1:42314_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 846069 DEBUG
(OverseerAutoScalingTriggerThread-73340040966569987-127.0.0.1:42314_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 1
[junit4] 2> 846070 DEBUG
(OverseerAutoScalingTriggerThread-73340040966569987-127.0.0.1:42314_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1,
lastZnodeVersion -1
[junit4] 2> 846070 DEBUG
(OverseerAutoScalingTriggerThread-73340040966569987-127.0.0.1:42314_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 1
[junit4] 2> 846071 INFO
(OverseerStateUpdate-73340040966569987-127.0.0.1:42314_solr-n_0000000000)
[n:127.0.0.1:42314_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 846138 INFO (zkCallback-890-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 846268 INFO (jetty-launcher-873-thread-1)
[n:127.0.0.1:42314_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 846290 DEBUG
(OverseerAutoScalingTriggerThread-73340040966569987-127.0.0.1:42314_solr-n_0000000000)
[ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial
livenodes: [127.0.0.1:42314_solr]
[junit4] 2> 846307 DEBUG
(OverseerAutoScalingTriggerThread-73340040966569987-127.0.0.1:42314_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 846544 DEBUG
(OverseerAutoScalingTriggerThread-73340040966569987-127.0.0.1:42314_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1,
lastZnodeVersion 1
[junit4] 2> 846545 DEBUG (ScheduledTrigger-1425-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 846636 INFO (jetty-launcher-873-thread-1)
[n:127.0.0.1:42314_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42314.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@28b9780b
[junit4] 2> 846778 INFO (jetty-launcher-873-thread-1)
[n:127.0.0.1:42314_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42314.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@28b9780b
[junit4] 2> 846778 INFO (jetty-launcher-873-thread-1)
[n:127.0.0.1:42314_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42314.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@28b9780b
[junit4] 2> 846779 INFO (jetty-launcher-873-thread-1)
[n:127.0.0.1:42314_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest_DD3A5CBFED94B8B7-001/tempDir-001/node1/.
[junit4] 2> 847064 INFO (zkConnectionManagerCallback-894-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 847101 INFO (zkConnectionManagerCallback-899-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 847158 INFO
(SUITE-ScheduledMaintenanceTriggerTest-seed#[DD3A5CBFED94B8B7]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 847247 INFO
(SUITE-ScheduledMaintenanceTriggerTest-seed#[DD3A5CBFED94B8B7]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43736/solr ready
[junit4] 2> 847674 DEBUG (ScheduledTrigger-1425-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 847753 DEBUG
(SUITE-ScheduledMaintenanceTriggerTest-seed#[DD3A5CBFED94B8B7]-worker) [ ]
o.a.s.c.a.s.SimClusterStateProvider --- new Overseer leader:
127.0.0.1:10000_solr
[junit4] 2> 847754 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 847767 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 0
[junit4] 2> 847788 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion -1
[junit4] 2> 847788 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 0
[junit4] 2> 847808 INFO
(SUITE-ScheduledMaintenanceTriggerTest-seed#[DD3A5CBFED94B8B7]-worker) [ ]
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history
in memory.
[junit4] 2> 847836 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial
livenodes: [127.0.0.1:10000_solr]
[junit4] 2> 847852 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 847853 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion 0
[junit4] 2> 847857 DEBUG (ScheduledTrigger-1434-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 848024 DEBUG (ScheduledTrigger-1434-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 848077 DEBUG (ScheduledTrigger-1434-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 848113 DEBUG (ScheduledTrigger-1434-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 848149 DEBUG (ScheduledTrigger-1434-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 848170 DEBUG (ScheduledTrigger-1434-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 848171 INFO
(TEST-ScheduledMaintenanceTriggerTest.testTriggerDefaults-seed#[DD3A5CBFED94B8B7])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testTriggerDefaults
[junit4] 2> 848171 INFO
(TEST-ScheduledMaintenanceTriggerTest.testTriggerDefaults-seed#[DD3A5CBFED94B8B7])
[ ] o.a.s.c.a.ScheduledMaintenanceTriggerTest {
[junit4] 2> "cluster-preferences":[
[junit4] 2> {
[junit4] 2> "minimize":"cores",
[junit4] 2> "precision":1},
[junit4] 2> {"maximize":"freedisk"}],
[junit4] 2> "triggers":{
[junit4] 2> ".auto_add_replicas":{
[junit4] 2> "name":".auto_add_replicas",
[junit4] 2> "event":"nodeLost",
[junit4] 2> "waitFor":120,
[junit4] 2> "enabled":true,
[junit4] 2> "actions":[
[junit4] 2> {
[junit4] 2> "name":"auto_add_replicas_plan",
[junit4] 2> "class":"solr.AutoAddReplicasPlanAction"},
[junit4] 2> {
[junit4] 2> "name":"execute_plan",
[junit4] 2> "class":"solr.ExecutePlanAction"}]},
[junit4] 2> ".scheduled_maintenance":{
[junit4] 2> "name":".scheduled_maintenance",
[junit4] 2> "event":"scheduled",
[junit4] 2> "startTime":"NOW",
[junit4] 2> "every":"+1DAY",
[junit4] 2> "enabled":true,
[junit4] 2> "actions":[
[junit4] 2> {
[junit4] 2> "name":"inactive_shard_plan",
[junit4] 2> "class":"solr.InactiveShardPlanAction"},
[junit4] 2> {
[junit4] 2> "name":"execute_plan",
[junit4] 2> "class":"solr.ExecutePlanAction"}]}},
[junit4] 2> "listeners":{
[junit4] 2> ".auto_add_replicas.system":{
[junit4] 2> "beforeAction":[],
[junit4] 2> "afterAction":[],
[junit4] 2> "stage":[
[junit4] 2> "STARTED",
[junit4] 2> "ABORTED",
[junit4] 2> "SUCCEEDED",
[junit4] 2> "FAILED",
[junit4] 2> "BEFORE_ACTION",
[junit4] 2> "AFTER_ACTION",
[junit4] 2> "IGNORED"],
[junit4] 2> "trigger":".auto_add_replicas",
[junit4] 2>
"class":"org.apache.solr.cloud.autoscaling.SystemLogListener"},
[junit4] 2> ".scheduled_maintenance.system":{
[junit4] 2> "beforeAction":[],
[junit4] 2> "afterAction":[],
[junit4] 2> "stage":[
[junit4] 2> "STARTED",
[junit4] 2> "ABORTED",
[junit4] 2> "SUCCEEDED",
[junit4] 2> "FAILED",
[junit4] 2> "BEFORE_ACTION",
[junit4] 2> "AFTER_ACTION",
[junit4] 2> "IGNORED"],
[junit4] 2> "trigger":".scheduled_maintenance",
[junit4] 2>
"class":"org.apache.solr.cloud.autoscaling.SystemLogListener"}},
[junit4] 2> "properties":{}}
[junit4] 2> 848190 DEBUG (ScheduledTrigger-1434-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 848210 DEBUG (ScheduledTrigger-1434-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 848223 DEBUG (simCloudManagerPool-1433-thread-1) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 848224 DEBUG (simCloudManagerPool-1433-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
1
[junit4] 2> 848224 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 848224 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 848225 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 848225 INFO
(TEST-ScheduledMaintenanceTriggerTest.testTriggerDefaults-seed#[DD3A5CBFED94B8B7])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testTriggerDefaults
[junit4] 2> 848239 DEBUG (ScheduledTrigger-1434-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 848312 DEBUG (ScheduledTrigger-1434-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 848328 INFO
(TEST-ScheduledMaintenanceTriggerTest.testInactiveShardCleanup-seed#[DD3A5CBFED94B8B7])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testInactiveShardCleanup
[junit4] 2> 848345 DEBUG (ScheduledTrigger-1434-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 848367 DEBUG (ScheduledTrigger-1434-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 848401 DEBUG (ScheduledTrigger-1434-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 848422 DEBUG (ScheduledTrigger-1434-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 848423 DEBUG (simCloudManagerPool-1433-thread-3) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- submit leader election for
ScheduledMaintenanceTriggerTest_collection1 / shard1
[junit4] 2> 848434 DEBUG (simCloudManagerPool-1433-thread-4) [ ]
o.a.s.c.a.s.SimClusterStateProvider Running leader election for
ScheduledMaintenanceTriggerTest_collection1 / shard1
[junit4] 2> 848475 DEBUG (ScheduledTrigger-1434-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 848476 DEBUG (simCloudManagerPool-1433-thread-4) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for
ScheduledMaintenanceTriggerTest_collection1 / shard1: core_node1
[junit4] 2> 848495 DEBUG (ScheduledTrigger-1434-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 848531 DEBUG (ScheduledTrigger-1434-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 848552 DEBUG (ScheduledTrigger-1434-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 848572 DEBUG (ScheduledTrigger-1434-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 848605 DEBUG (ScheduledTrigger-1434-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 848662 DEBUG (simCloudManagerPool-1433-thread-5) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- already has leader
ScheduledMaintenanceTriggerTest_collection1 / shard1
[junit4] 2> 848662 DEBUG (simCloudManagerPool-1433-thread-5) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- submit leader election for
ScheduledMaintenanceTriggerTest_collection1 / shard1_1
[junit4] 2> 848682 DEBUG (ScheduledTrigger-1434-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 848702 DEBUG (ScheduledTrigger-1434-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 848722 DEBUG (ScheduledTrigger-1434-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 848751 DEBUG (simCloudManagerPool-1433-thread-5) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- submit leader election for
ScheduledMaintenanceTriggerTest_collection1 / shard1_0
[junit4] 2> 848751 DEBUG (simCloudManagerPool-1433-thread-6) [ ]
o.a.s.c.a.s.SimClusterStateProvider Running leader election for
ScheduledMaintenanceTriggerTest_collection1 / shard1_1
[junit4] 2> 848761 DEBUG (simCloudManagerPool-1433-thread-7) [ ]
o.a.s.c.a.s.SimClusterStateProvider Running leader election for
ScheduledMaintenanceTriggerTest_collection1 / shard1_0
[junit4] 2> 848775 DEBUG (ScheduledTrigger-1425-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 848775 DEBUG (ScheduledTrigger-1434-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 848776 DEBUG (simCloudManagerPool-1433-thread-6) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for
ScheduledMaintenanceTriggerTest_collection1 / shard1_1: core_node5
[junit4] 2> 848776 DEBUG (simCloudManagerPool-1433-thread-7) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for
ScheduledMaintenanceTriggerTest_collection1 / shard1_0: core_node4
[junit4] 2> 848795 DEBUG (ScheduledTrigger-1434-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 848849 DEBUG (ScheduledTrigger-1434-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 848856 DEBUG (simCloudManagerPool-1433-thread-8) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 848856 DEBUG (simCloudManagerPool-1433-thread-8) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
2
[junit4] 2> 848865 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 848865 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 848865 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 848869 DEBUG (ScheduledTrigger-1434-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 848923 DEBUG (simCloudManagerPool-1433-thread-9) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 848923 DEBUG (simCloudManagerPool-1433-thread-9) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
3
[junit4] 2> 848938 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 848938 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 848938 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 848940 DEBUG (ScheduledTrigger-1434-thread-4) [ ]
o.a.s.c.a.ScheduledTrigger ScheduledTrigger .scheduled_maintenance firing
registered processor for scheduled time 2018-09-18T00:40:13.122Z,
now=2018-09-18T00:41:08.720Z
[junit4] 2> 848954 DEBUG (ScheduledTrigger-1434-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 848975 DEBUG (ScheduledTrigger-1434-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
[junit4] 2> "id":"45859a5c6b1355T3e4zglo7r8w59b3h7ugwlymy7",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":19568671415014229,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{"actualEventTime":1537231268720}}
[junit4] 2> 848991 DEBUG (ScheduledTrigger-1434-thread-4) [ ]
o.a.s.c.a.ScheduledTriggers Pausing all triggers: [.auto_add_replicas,
.scheduled_maintenance]
[junit4] 2> 849072 DEBUG (simCloudManagerPool-1433-thread-11) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- submit leader election for .system /
shard1
[junit4] 2> 849088 DEBUG (simCloudManagerPool-1433-thread-12) [ ]
o.a.s.c.a.s.SimClusterStateProvider Running leader election for .system / shard1
[junit4] 2> 849109 DEBUG (simCloudManagerPool-1433-thread-12) [ ]
o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for .system / shard1:
core_node1
[junit4] 2> 849111 INFO (ScheduledTrigger-1434-thread-4) [ ]
o.a.s.c.a.ScheduledMaintenanceTriggerTest =======>
CapturedEvent{timestamp=19568678213395529, stage=STARTED, actionName='null',
event={
[junit4] 2> "id":"45859a5c6b1355T3e4zglo7r8w59b3h7ugwlymy7",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":19568671415014229,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{
[junit4] 2> "actualEventTime":1537231268720,
[junit4] 2> "_enqueue_time_":19568672215612679}}, context={}, config={
[junit4] 2> "trigger":".scheduled_maintenance",
[junit4] 2> "stage":[
[junit4] 2> "STARTED",
[junit4] 2> "ABORTED",
[junit4] 2> "SUCCEEDED",
[junit4] 2> "FAILED"],
[junit4] 2> "beforeAction":"inactive_shard_plan",
[junit4] 2> "afterAction":"inactive_shard_plan",
[junit4] 2>
"class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"},
message='null'}
[junit4] 2> 849111 DEBUG (AutoscalingActionExecutor-1435-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -- processing actions for {
[junit4] 2> "id":"45859a5c6b1355T3e4zglo7r8w59b3h7ugwlymy7",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":19568671415014229,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{
[junit4] 2> "actualEventTime":1537231268720,
[junit4] 2> "_enqueue_time_":19568672215612679}}
[junit4] 2> 849231 INFO (AutoscalingActionExecutor-1435-thread-1) [ ]
o.a.s.c.a.ScheduledMaintenanceTriggerTest =======>
CapturedEvent{timestamp=19568684230739479, stage=BEFORE_ACTION,
actionName='inactive_shard_plan', event={
[junit4] 2> "id":"45859a5c6b1355T3e4zglo7r8w59b3h7ugwlymy7",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":19568671415014229,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{
[junit4] 2> "actualEventTime":1537231268720,
[junit4] 2> "_enqueue_time_":19568672215612679}},
context={properties.BEFORE_ACTION=[inactive_shard_plan],
source=.scheduled_maintenance}, config={
[junit4] 2> "trigger":".scheduled_maintenance",
[junit4] 2> "stage":[
[junit4] 2> "STARTED",
[junit4] 2> "ABORTED",
[junit4] 2> "SUCCEEDED",
[junit4] 2> "FAILED"],
[junit4] 2> "beforeAction":"inactive_shard_plan",
[junit4] 2> "afterAction":"inactive_shard_plan",
[junit4] 2>
"class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"},
message='null'}
[junit4] 2> 849234 DEBUG (AutoscalingActionExecutor-1435-thread-1) [ ]
o.a.s.c.a.InactiveShardPlanAction
ScheduledMaintenanceTriggerTest_collection1/shard1: tstamp=1537231253435583100,
time=1537231283460441650, delta=30
[junit4] 2> 849234 DEBUG (AutoscalingActionExecutor-1435-thread-1) [ ]
o.a.s.c.a.InactiveShardPlanAction -- delete inactive
ScheduledMaintenanceTriggerTest_collection1 / shard1
[junit4] 2> 849288 INFO (AutoscalingActionExecutor-1435-thread-1) [ ]
o.a.s.c.a.ScheduledMaintenanceTriggerTest =======>
CapturedEvent{timestamp=19568685336314929, stage=AFTER_ACTION,
actionName='inactive_shard_plan', event={
[junit4] 2> "id":"45859a5c6b1355T3e4zglo7r8w59b3h7ugwlymy7",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":19568671415014229,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{
[junit4] 2> "actualEventTime":1537231268720,
[junit4] 2> "_enqueue_time_":19568672215612679}},
context={properties.operations=[{class=org.apache.solr.client.solrj.request.CollectionAdminRequest$DeleteShard,
method=GET, params.action=DELETESHARD,
params.collection=ScheduledMaintenanceTriggerTest_collection1,
params.shard=shard1}], properties.BEFORE_ACTION=[inactive_shard_plan],
source=.scheduled_maintenance,
properties.inactive_shard_plan={inactive={ScheduledMaintenanceTriggerTest_collection1=[shard1]},
cleanup={ScheduledMaintenanceTriggerTest_collection1=[shard1]}},
properties.AFTER_ACTION=[inactive_shard_plan]}, config={
[junit4] 2> "trigger":".scheduled_maintenance",
[junit4] 2> "stage":[
[junit4] 2> "STARTED",
[junit4] 2> "ABORTED",
[junit4] 2> "SUCCEEDED",
[junit4] 2> "FAILED"],
[junit4] 2> "beforeAction":"inactive_shard_plan",
[junit4] 2> "afterAction":"inactive_shard_plan",
[junit4] 2>
"class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"},
message='null'}
[junit4] 2> 849288 DEBUG (AutoscalingActionExecutor-1435-thread-1) [ ]
o.a.s.c.a.ExecutePlanAction -- processing event: {
[junit4] 2> "id":"45859a5c6b1355T3e4zglo7r8w59b3h7ugwlymy7",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":19568671415014229,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{
[junit4] 2> "actualEventTime":1537231268720,
[junit4] 2> "_enqueue_time_":19568672215612679}} with context
properties:
{operations=[org.apache.solr.client.solrj.request.CollectionAdminRequest$DeleteShard@6dbe515],
inactive_shard_plan={inactive={ScheduledMaintenanceTriggerTest_collection1=[shard1]},
cleanup={ScheduledMaintenanceTriggerTest_collection1=[shard1]}},
AFTER_ACTION=[inactive_shard_plan], BEFORE_ACTION=[inactive_shard_plan,
execute_plan]}
[junit4] 2> 849288 DEBUG (AutoscalingActionExecutor-1435-thread-1) [ ]
o.a.s.c.a.ExecutePlanAction Executing operation:
action=DELETESHARD&collection=ScheduledMaintenanceTriggerTest_collection1&shard=shard1
[junit4] 2> 849503 INFO (AutoscalingActionExecutor-1435-thread-1) [ ]
o.a.s.c.a.ScheduledMaintenanceTriggerTest =======>
CapturedEvent{timestamp=19568697851310929, stage=SUCCEEDED, actionName='null',
event={
[junit4] 2> "id":"45859a5c6b1355T3e4zglo7r8w59b3h7ugwlymy7",
[junit4] 2> "source":".scheduled_maintenance",
[junit4] 2> "eventTime":19568671415014229,
[junit4] 2> "eventType":"SCHEDULED",
[junit4] 2> "properties":{
[junit4] 2> "actualEventTime":1537231268720,
[junit4] 2> "_enqueue_time_":19568672215612679}}, context={}, config={
[junit4] 2> "trigger":".scheduled_maintenance",
[junit4] 2> "stage":[
[junit4] 2> "STARTED",
[junit4] 2> "ABORTED",
[junit4] 2> "SUCCEEDED",
[junit4] 2> "FAILED"],
[junit4] 2> "beforeAction":"inactive_shard_plan",
[junit4] 2> "afterAction":"inactive_shard_plan",
[junit4] 2>
"class":"org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest$CapturingTriggerListener"},
message='null'}
[junit4] 2> 849504 DEBUG (AutoscalingActionExecutor-1435-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Resuming trigger: .auto_add_replicas after 100ms
[junit4] 2> 849504 DEBUG (AutoscalingActionExecutor-1435-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Resuming trigger: .scheduled_maintenance after 100ms
[junit4] 2> 849504 DEBUG (AutoscalingActionExecutor-1435-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -- processing took 19627 ms for event
id=45859a5c6b1355T3e4zglo7r8w59b3h7ugwlymy7
[junit4] 2> 849505 DEBUG (simCloudManagerPool-1433-thread-17) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 849505 DEBUG (simCloudManagerPool-1433-thread-17) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
4
[junit4] 2> 849505 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 4
[junit4] 2> 849541 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 849541 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVersion 4
[junit4] 2> 849561 DEBUG (simCloudManagerPool-1433-thread-18) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 849561 DEBUG (simCloudManagerPool-1433-thread-18) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
5
[junit4] 2> 849561 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 5
[junit4] 2> 849561 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 849561 DEBUG (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5, lastZnodeVersion 5
[junit4] 2> 849561 INFO
(TEST-ScheduledMaintenanceTriggerTest.testInactiveShardCleanup-seed#[DD3A5CBFED94B8B7])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testInactiveShardCleanup
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=ScheduledMaintenanceTriggerTest
-Dtests.method=testInactiveShardCleanup -Dtests.seed=DD3A5CBFED94B8B7
-Dtests.multiplier=2 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=hr
-Dtests.timezone=Asia/Omsk -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 1.36s J2 |
ScheduledMaintenanceTriggerTest.testInactiveShardCleanup <<<
[junit4] > Throwable #1: java.lang.AssertionError: should be at least one
inactive event
[junit4] > at
__randomizedtesting.SeedInfo.seed([DD3A5CBFED94B8B7:C0169CCD8CD79FBC]:0)
[junit4] > at
org.apache.solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest.testInactiveShardCleanup(ScheduledMaintenanceTriggerTest.java:224)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 849598 WARN (Simulated OverseerAutoScalingTriggerThread) [
] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are
closed, exiting.
[junit4] 2> 849613 DEBUG
(SUITE-ScheduledMaintenanceTriggerTest-seed#[DD3A5CBFED94B8B7]-worker) [ ]
o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 849613 DEBUG
(SUITE-ScheduledMaintenanceTriggerTest-seed#[DD3A5CBFED94B8B7]-worker) [ ]
o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 849613 DEBUG
(SUITE-ScheduledMaintenanceTriggerTest-seed#[DD3A5CBFED94B8B7]-worker) [ ]
o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 849614 DEBUG
(SUITE-ScheduledMaintenanceTriggerTest-seed#[DD3A5CBFED94B8B7]-worker) [ ]
o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool
executor
[junit4] 2> 849634 DEBUG
(SUITE-ScheduledMaintenanceTriggerTest-seed#[DD3A5CBFED94B8B7]-worker) [ ]
o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 849634 DEBUG
(SUITE-ScheduledMaintenanceTriggerTest-seed#[DD3A5CBFED94B8B7]-worker) [ ]
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 849725 INFO (jetty-closer-874-thread-1) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@54ebfe60{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 849726 INFO (jetty-closer-874-thread-1) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1473525951
[junit4] 2> 849727 INFO (jetty-closer-874-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 849727 INFO (jetty-closer-874-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@55b307: rootName =
solr_42314, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@acafd58
[junit4] 2> 849784 DEBUG (ScheduledTrigger-1425-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 849785 INFO (jetty-closer-874-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 849785 INFO (jetty-closer-874-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1087e0d: rootName =
solr_42314, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@5b8f23db
[junit4] 2> 849801 INFO (jetty-closer-874-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 849801 INFO (jetty-closer-874-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@314ff155: rootName =
solr_42314, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@39626a58
[junit4] 2> 849803 INFO (jetty-closer-874-thread-1) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:42314_solr
[junit4] 2> 849821 INFO (jetty-closer-874-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 849821 INFO (zkCallback-883-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 849821 INFO (jetty-closer-874-thread-1) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 849821 INFO (jetty-closer-874-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=73340040966569987-127.0.0.1:42314_solr-n_0000000000) closing
[junit4] 2> 849821 INFO
(OverseerStateUpdate-73340040966569987-127.0.0.1:42314_solr-n_0000000000)
[n:127.0.0.1:42314_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:42314_solr
[junit4] 2> 849822 WARN
(OverseerAutoScalingTriggerThread-73340040966569987-127.0.0.1:42314_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 849822 INFO (zkCallback-890-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 849946 DEBUG (jetty-closer-874-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 849947 DEBUG (jetty-closer-874-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 849947 DEBUG (jetty-closer-874-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 849947 DEBUG (jetty-closer-874-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool
executor
[junit4] 2> 849947 DEBUG (jetty-closer-874-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 849947 DEBUG (jetty-closer-874-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 849999 INFO (jetty-closer-874-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@2e90f86a{/solr,null,UNAVAILABLE}
[junit4] 2> 850015 INFO (jetty-closer-874-thread-1) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 850016 ERROR
(SUITE-ScheduledMaintenanceTriggerTest-seed#[DD3A5CBFED94B8B7]-worker) [ ]
o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper
server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 850017 INFO
(SUITE-ScheduledMaintenanceTriggerTest-seed#[DD3A5CBFED94B8B7]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:43736 43736
[junit4] 2> 850076 INFO (Thread-653) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:43736 43736
[junit4] 2> 850105 WARN (Thread-653) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/aliases.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/live_nodes
[junit4] 2> 3 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ScheduledMaintenanceTriggerTest_DD3A5CBFED94B8B7-001
[junit4] 2> Sep 18, 2018 12:39:53 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Lucene70,
sim=RandomSimilarity(queryNorm=false): {}, locale=hr, timezone=Asia/Omsk
[junit4] 2> NOTE: Linux 4.4.0-112-generic amd64/Oracle Corporation
1.8.0_172 (64-bit)/cpus=4,threads=1,free=270936304,total=535298048
[junit4] 2> NOTE: All tests run in this JVM:
[TestSchemalessBufferedUpdates, NodeAddedTriggerIntegrationTest,
TestCloudPivotFacet, TestConfigSetsAPIExclusivity,
TestDeleteCollectionOnDownNodes, DistributedDebugComponentTest,
TestInPlaceUpdatesStandalone, MetricsHistoryHandlerTest, TestSearcherReuse,
TestReload, DistributedSpellCheckComponentTest, BasicAuthIntegrationTest,
JavabinLoaderTest, HttpPartitionTest, SolrXmlInZkTest, FacetPivotSmallTest,
RollingRestartTest, TestPivotHelperCode, SOLR749Test,
TestLeaderElectionWithEmptyReplica, TestNumericRangeQuery64,
AutoAddReplicasPlanActionTest, TestMacroExpander, TestExactSharedStatsCache,
TestFiltering, TestDynamicFieldResource, ScheduledMaintenanceTriggerTest]
[junit4] Completed [110/833 (1!)] on J2 in 8.11s, 2 tests, 1 failure <<<
FAILURES!
[...truncated 860 lines...]
[junit4] Suite:
org.apache.solr.update.processor.TimeRoutedAliasUpdateProcessorTest
[junit4] 2> 2236032 INFO
(SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[DD3A5CBFED94B8B7]-worker) [
] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_DD3A5CBFED94B8B7-001/init-core-data-001
[junit4] 2> 2236032 WARN
(SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[DD3A5CBFED94B8B7]-worker) [
] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 2236032 INFO
(SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[DD3A5CBFED94B8B7]-worker) [
] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 2236034 INFO
(SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[DD3A5CBFED94B8B7]-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> 2236068 INFO
(SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[DD3A5CBFED94B8B7]-worker) [
] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_DD3A5CBFED94B8B7-001/tempDir-001
[junit4] 2> 2236069 INFO
(SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[DD3A5CBFED94B8B7]-worker) [
] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2236080 INFO (Thread-2370) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2236080 INFO (Thread-2370) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 2236101 ERROR (Thread-2370) [ ] 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> 2236190 INFO
(SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[DD3A5CBFED94B8B7]-worker) [
] o.a.s.c.ZkTestServer start zk server on port:44452
[junit4] 2> 2236211 INFO (zkConnectionManagerCallback-3358-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2236228 INFO (jetty-launcher-3355-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> 2236248 INFO (jetty-launcher-3355-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> 2236253 INFO (jetty-launcher-3355-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> 2236290 INFO (jetty-launcher-3355-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> 2236394 INFO (jetty-launcher-3355-thread-4) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2236394 INFO (jetty-launcher-3355-thread-4) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2236394 INFO (jetty-launcher-3355-thread-4) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2236394 INFO (jetty-launcher-3355-thread-4) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@66cd0d8d{/solr,null,AVAILABLE}
[junit4] 2> 2236395 INFO (jetty-launcher-3355-thread-4) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@68352b84{SSL,[ssl,
http/1.1]}{127.0.0.1:41204}
[junit4] 2> 2236395 INFO (jetty-launcher-3355-thread-4) [ ]
o.e.j.s.Server Started @2236431ms
[junit4] 2> 2236395 INFO (jetty-launcher-3355-thread-4) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=41204}
[junit4] 2> 2236395 ERROR (jetty-launcher-3355-thread-4) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2236395 INFO (jetty-launcher-3355-thread-4) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2236395 INFO (jetty-launcher-3355-thread-4) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.6.0
[junit4] 2> 2236395 INFO (jetty-launcher-3355-thread-4) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2236395 INFO (jetty-launcher-3355-thread-4) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2236395 INFO (jetty-launcher-3355-thread-4) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-09-18T01:02:59.915Z
[junit4] 2> 2236483 INFO (jetty-launcher-3355-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2236483 INFO (jetty-launcher-3355-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2236483 INFO (jetty-launcher-3355-thread-1) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2236491 INFO (jetty-launcher-3355-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@7c8c522b{/solr,null,AVAILABLE}
[junit4] 2> 2236492 INFO (jetty-launcher-3355-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@300c4648{SSL,[ssl,
http/1.1]}{127.0.0.1:41870}
[junit4] 2> 2236492 INFO (jetty-launcher-3355-thread-1) [ ]
o.e.j.s.Server Started @2236528ms
[junit4] 2> 2236492 INFO (jetty-launcher-3355-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=41870}
[junit4] 2> 2236492 ERROR (jetty-launcher-3355-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2236492 INFO (jetty-launcher-3355-thread-1) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2236492 INFO (jetty-launcher-3355-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.6.0
[junit4] 2> 2236492 INFO (jetty-launcher-3355-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2236492 INFO (jetty-launcher-3355-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2236492 INFO (jetty-launcher-3355-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-09-18T01:03:00.012Z
[junit4] 2> 2236649 INFO (jetty-launcher-3355-thread-3) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2236649 INFO (jetty-launcher-3355-thread-3) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2236649 INFO (jetty-launcher-3355-thread-3) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2236650 INFO (jetty-launcher-3355-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@3bfa555d{/solr,null,AVAILABLE}
[junit4] 2> 2236650 INFO (jetty-launcher-3355-thread-3) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@7f305b6d{SSL,[ssl,
http/1.1]}{127.0.0.1:33522}
[junit4] 2> 2236650 INFO (jetty-launcher-3355-thread-3) [ ]
o.e.j.s.Server Started @2236687ms
[junit4] 2> 2236650 INFO (jetty-launcher-3355-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=33522}
[junit4] 2> 2236650 ERROR (jetty-launcher-3355-thread-3) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2236651 INFO (jetty-launcher-3355-thread-3) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2236651 INFO (jetty-launcher-3355-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.6.0
[junit4] 2> 2236651 INFO (jetty-launcher-3355-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2236651 INFO (jetty-launcher-3355-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2236651 INFO (jetty-launcher-3355-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-09-18T01:03:00.171Z
[junit4] 2> 2236706 INFO (jetty-launcher-3355-thread-2) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2236706 INFO (jetty-launcher-3355-thread-2) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2236706 INFO (jetty-launcher-3355-thread-2) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2236706 INFO (jetty-launcher-3355-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@61c834f1{/solr,null,AVAILABLE}
[junit4] 2> 2236707 INFO (jetty-launcher-3355-thread-2) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@1b87515{SSL,[ssl,
http/1.1]}{127.0.0.1:44399}
[junit4] 2> 2236707 INFO (jetty-launcher-3355-thread-2) [ ]
o.e.j.s.Server Started @2236744ms
[junit4] 2> 2236707 INFO (jetty-launcher-3355-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=44399}
[junit4] 2> 2236707 ERROR (jetty-launcher-3355-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2236707 INFO (jetty-launcher-3355-thread-2) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2236707 INFO (jetty-launcher-3355-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.6.0
[junit4] 2> 2236707 INFO (jetty-launcher-3355-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2236707 INFO (jetty-launcher-3355-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2236707 INFO (jetty-launcher-3355-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-09-18T01:03:00.227Z
[junit4] 2> 2236735 INFO (zkConnectionManagerCallback-3362-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2236735 INFO (zkConnectionManagerCallback-3360-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2236736 INFO (zkConnectionManagerCallback-3364-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2236755 INFO (jetty-launcher-3355-thread-4) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2236756 INFO (jetty-launcher-3355-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2236756 INFO (jetty-launcher-3355-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2236787 INFO (zkConnectionManagerCallback-3366-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2236807 INFO (jetty-launcher-3355-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2238238 INFO (jetty-launcher-3355-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44452/solr
[junit4] 2> 2238262 INFO (zkConnectionManagerCallback-3370-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2238328 INFO (zkConnectionManagerCallback-3372-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2238714 INFO (jetty-launcher-3355-thread-2)
[n:127.0.0.1:44399_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:44399_solr
[junit4] 2> 2238715 INFO (jetty-launcher-3355-thread-2)
[n:127.0.0.1:44399_solr ] o.a.s.c.Overseer Overseer
(id=73340132282531846-127.0.0.1:44399_solr-n_0000000000) starting
[junit4] 2> 2238733 INFO (zkConnectionManagerCallback-3379-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2238747 INFO (jetty-launcher-3355-thread-2)
[n:127.0.0.1:44399_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:44452/solr ready
[junit4] 2> 2238748 INFO
(OverseerStateUpdate-73340132282531846-127.0.0.1:44399_solr-n_0000000000)
[n:127.0.0.1:44399_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:44399_solr
[junit4] 2> 2238885 INFO (jetty-launcher-3355-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44452/solr
[junit4] 2> 2238921 INFO (jetty-launcher-3355-thread-2)
[n:127.0.0.1:44399_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:44399_solr
[junit4] 2> 2238938 INFO (jetty-launcher-3355-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44452/solr
[junit4] 2> 2238939 INFO (zkConnectionManagerCallback-3383-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2238990 INFO (zkConnectionManagerCallback-3387-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2239010 INFO (zkCallback-3371-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2239042 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x1048e76347b0009, likely client has closed socket
[junit4] 2> 2239232 INFO (zkCallback-3378-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2239247 INFO (jetty-launcher-3355-thread-4) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44452/solr
[junit4] 2> 2239283 INFO (zkConnectionManagerCallback-3393-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2239283 INFO (zkConnectionManagerCallback-3389-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2239303 INFO (zkConnectionManagerCallback-3395-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2239465 INFO (jetty-launcher-3355-thread-2)
[n:127.0.0.1:44399_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 2239523 INFO (zkConnectionManagerCallback-3397-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2239688 INFO (jetty-launcher-3355-thread-2)
[n:127.0.0.1:44399_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_44399.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5519d7eb
[junit4] 2> 2239760 INFO (jetty-launcher-3355-thread-2)
[n:127.0.0.1:44399_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_44399.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5519d7eb
[junit4] 2> 2239760 INFO (jetty-launcher-3355-thread-2)
[n:127.0.0.1:44399_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_44399.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5519d7eb
[junit4] 2> 2239761 INFO (jetty-launcher-3355-thread-2)
[n:127.0.0.1:44399_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_DD3A5CBFED94B8B7-001/tempDir-001/node2/.
[junit4] 2> 2240030 INFO (jetty-launcher-3355-thread-3)
[n:127.0.0.1:33522_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 2240145 INFO (jetty-launcher-3355-thread-4)
[n:127.0.0.1:41204_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 2240145 INFO (jetty-launcher-3355-thread-1)
[n:127.0.0.1:41870_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 2240165 INFO (jetty-launcher-3355-thread-3)
[n:127.0.0.1:33522_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 2240165 INFO (jetty-launcher-3355-thread-3)
[n:127.0.0.1:33522_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:33522_solr
[junit4] 2> 2240185 INFO (zkCallback-3378-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2240202 INFO (zkCallback-3371-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2240202 INFO (jetty-launcher-3355-thread-1)
[n:127.0.0.1:41870_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 2240202 INFO (jetty-launcher-3355-thread-1)
[n:127.0.0.1:41870_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:41870_solr
[junit4] 2> 2240222 INFO (zkCallback-3371-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2240327 INFO (zkCallback-3378-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2240380 INFO (jetty-launcher-3355-thread-4)
[n:127.0.0.1:41204_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 2240380 INFO (jetty-launcher-3355-thread-4)
[n:127.0.0.1:41204_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:41204_solr
[junit4] 2> 2240405 INFO (zkCallback-3371-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2240425 INFO (zkCallback-3378-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2240515 INFO (zkCallback-3396-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (4)
[junit4] 2> 2240515 INFO (zkCallback-3394-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (4)
[junit4] 2> 2240734 INFO (zkCallback-3388-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (4)
[junit4] 2> 2240734 INFO (zkConnectionManagerCallback-3414-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2240735 INFO (jetty-launcher-3355-thread-1)
[n:127.0.0.1:41870_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 2240787 INFO (jetty-launcher-3355-thread-1)
[n:127.0.0.1:41870_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:44452/solr ready
[junit4] 2> 2240819 INFO (zkConnectionManagerCallback-3410-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2240819 INFO (zkConnectionManagerCallback-3419-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2240844 INFO (jetty-launcher-3355-thread-1)
[n:127.0.0.1:41870_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 2240876 INFO (jetty-launcher-3355-thread-4)
[n:127.0.0.1:41204_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 2240876 INFO (jetty-launcher-3355-thread-3)
[n:127.0.0.1:33522_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 2240945 INFO (jetty-launcher-3355-thread-4)
[n:127.0.0.1:41204_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:44452/solr ready
[junit4] 2> 2240949 INFO (jetty-launcher-3355-thread-3)
[n:127.0.0.1:33522_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:44452/solr ready
[junit4] 2> 2240989 INFO (jetty-launcher-3355-thread-3)
[n:127.0.0.1:33522_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 2240991 INFO (jetty-launcher-3355-thread-1)
[n:127.0.0.1:41870_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41870.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5519d7eb
[junit4] 2> 2241026 INFO (jetty-launcher-3355-thread-4)
[n:127.0.0.1:41204_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 2241139 INFO (jetty-launcher-3355-thread-1)
[n:127.0.0.1:41870_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41870.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5519d7eb
[junit4] 2> 2241140 INFO (jetty-launcher-3355-thread-1)
[n:127.0.0.1:41870_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41870.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5519d7eb
[junit4] 2> 2241160 INFO (jetty-launcher-3355-thread-4)
[n:127.0.0.1:41204_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41204.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5519d7eb
[junit4] 2> 2241210 INFO (jetty-launcher-3355-thread-1)
[n:127.0.0.1:41870_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_DD3A5CBFED94B8B7-001/tempDir-001/node1/.
[junit4] 2> 2241269 INFO (jetty-launcher-3355-thread-4)
[n:127.0.0.1:41204_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41204.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5519d7eb
[junit4] 2> 2241269 INFO (jetty-launcher-3355-thread-4)
[n:127.0.0.1:41204_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41204.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5519d7eb
[junit4] 2> 2241278 INFO (jetty-launcher-3355-thread-3)
[n:127.0.0.1:33522_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33522.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5519d7eb
[junit4] 2> 2241298 INFO (jetty-launcher-3355-thread-3)
[n:127.0.0.1:33522_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33522.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5519d7eb
[junit4] 2> 2241298 INFO (jetty-launcher-3355-thread-3)
[n:127.0.0.1:33522_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33522.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5519d7eb
[junit4] 2> 2241369 INFO (jetty-launcher-3355-thread-3)
[n:127.0.0.1:33522_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_DD3A5CBFED94B8B7-001/tempDir-001/node3/.
[junit4] 2> 2241376 INFO (jetty-launcher-3355-thread-4)
[n:127.0.0.1:41204_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_DD3A5CBFED94B8B7-001/tempDir-001/node4/.
[junit4] 2> 2242363 INFO (zkConnectionManagerCallback-3424-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2242535 INFO
(TEST-TimeRoutedAliasUpdateProcessorTest.test-seed#[DD3A5CBFED94B8B7]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 2242689 INFO (qtp1063252342-11674) [n:127.0.0.1:44399_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with
params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2242744 INFO (qtp1063252342-11674) [n:127.0.0.1:44399_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=54
[junit4] 2> 2242749 INFO
(TEST-TimeRoutedAliasUpdateProcessorTest.test-seed#[DD3A5CBFED94B8B7]) [ ]
o.a.s.u.p.TimeRoutedAliasUpdateProcessorTest SolrClient:
org.apache.solr.client.solrj.impl.CloudSolrClient@372d2343
[junit4] 2> 2242749 INFO
(TEST-TimeRoutedAliasUpdateProcessorTest.test-seed#[DD3A5CBFED94B8B7]) [ ]
o.a.s.u.p.TimeRoutedAliasUpdateProcessorTest ClusterStateProvider
org.apache.solr.client.solrj.impl.HttpClusterStateProvider@624d1e6
[junit4] 2> 2242834 INFO (qtp1063252342-11674) [n:127.0.0.1:44399_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with
params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2242834 INFO (qtp1063252342-11674) [n:127.0.0.1:44399_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2242835 INFO (qtp1063252342-11677) [n:127.0.0.1:44399_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with
params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2242839 DEBUG
(OverseerCollectionConfigSetProcessor-73340132282531846-127.0.0.1:44399_solr-n_0000000000)
[n:127.0.0.1:44399_solr ] o.a.s.c.OverseerTaskProcessor Got 0 tasks from
work-queue : [[]]
[junit4] 2> 2242907 DEBUG
(OverseerCollectionConfigSetProcessor-73340132282531846-127.0.0.1:44399_solr-n_0000000000)
[n:127.0.0.1:44399_solr ] o.a.s.c.OverseerTaskProcessor Cleaning up
work-queue. #Running tasks: 0
[junit4] 2> 2242907 DEBUG
(OverseerCollectionConfigSetProcessor-73340132282531846-127.0.0.1:44399_solr-n_0000000000)
[n:127.0.0.1:44399_solr ] o.a.s.c.OverseerTaskProcessor RunningTasks: []
[junit4] 2> 2242907 DEBUG
(OverseerCollectionConfigSetProcessor-73340132282531846-127.0.0.1:44399_solr-n_0000000000)
[n:127.0.0.1:44399_solr ] o.a.s.c.OverseerTaskProcessor BlockedTasks: []
[junit4] 2> 2242907 DEBUG
(OverseerCollectionConfigSetProcessor-73340132282531846-127.0.0.1:44399_solr-n_0000000000)
[n:127.0.0.1:44399_solr ] o.a.s.c.OverseerTaskProcessor CompletedTasks: []
[junit4] 2> 2242907 DEBUG
(OverseerCollectionConfigSetProcessor-73340132282531846-127.0.0.1:44399_solr-n_0000000000)
[n:127.0.0.1:44399_solr ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: []
[junit4] 2> 2242907 DEBUG
(OverseerCollectionConfigSetProcessor-73340132282531846-127.0.0.1:44399_solr-n_0000000000)
[n:127.0.0.1:44399_solr ] o.a.s.c.OverseerTaskQueue Peeking for top 100
elements. ExcludeSet: []
[junit4] 2> 2242913 INFO (qtp1063252342-11677) [n:127.0.0.1:44399_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=77
[junit4] 2> 2242914 INFO (qtp1063252342-11674) [n:127.0.0.1:44399_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with
params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2242915 INFO (qtp1063252342-11674) [n:127.0.0.1:44399_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2242915 INFO (qtp1063252342-11677) [n:127.0.0.1:44399_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with
params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2242916 INFO (qtp1063252342-11677) [n:127.0.0.1:44399_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=CLUSTERSTATUS&wt=javabin&versio
[...truncated too long message...]
/home/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 =
/home/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 =
/home/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 =
/home/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 =
/home/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 =
/home/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 =
/home/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 =
/home/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 =
/home/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 =
/home/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 =
/home/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 =
/home/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 =
/home/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 =
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
jar-checksums:
[mkdir] Created dir:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/null859973967
[copy] Copying 239 files to
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/null859973967
[delete] Deleting directory
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/null859973967
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 31ms :: artifacts dl 5ms
---------------------------------------------------------------------
| | 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: 223 minutes 39 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 lucene2
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]
