You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2018/12/18 23:52:40 UTC
[JENKINS] Lucene-Solr-7.x-Solaris (64bit/jdk1.8.0) - Build # 964 -
Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/964/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseSerialGC
2 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.sim.TestSimPolicyCloud.testCreateCollectionSplitShard
Error Message:
Expected exactly one replica of collection on node with port: 10029 expected:<1> but was:<0>
Stack Trace:
java.lang.AssertionError: Expected exactly one replica of collection on node with port: 10029 expected:<1> but was:<0>
at __randomizedtesting.SeedInfo.seed([AD6F5F726E4A1850:78E12C89DE0099EE]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotEquals(Assert.java:834)
at org.junit.Assert.assertEquals(Assert.java:645)
at org.apache.solr.cloud.autoscaling.sim.TestSimPolicyCloud.testCreateCollectionSplitShard(TestSimPolicyCloud.java:171)
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:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at org.junit.rules.ExpectedException$ExpectedExceptionStatement.evaluate(ExpectedException.java:239)
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:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.client.solrj.io.stream.StreamDecoratorTest.testParallelCommitStream
Error Message:
expected:<5> but was:<2>
Stack Trace:
java.lang.AssertionError: expected:<5> but was:<2>
at __randomizedtesting.SeedInfo.seed([9B78FE2AC674CCC0:BB929C2A5A35218C]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotEquals(Assert.java:834)
at org.junit.Assert.assertEquals(Assert.java:645)
at org.junit.Assert.assertEquals(Assert.java:631)
at org.apache.solr.client.solrj.io.stream.StreamDecoratorTest.testParallelCommitStream(StreamDecoratorTest.java:3034)
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:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
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:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
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 12633 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.sim.TestSimPolicyCloud
[junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.sim.TestSimPolicyCloud_AD6F5F726E4A1850-001/init-core-data-001
[junit4] 2> 144979 INFO (SUITE-TestSimPolicyCloud-seed#[AD6F5F726E4A1850]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 144980 INFO (SUITE-TestSimPolicyCloud-seed#[AD6F5F726E4A1850]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 144982 INFO (SUITE-TestSimPolicyCloud-seed#[AD6F5F726E4A1850]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 144989 INFO (TEST-TestSimPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[AD6F5F726E4A1850]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCreateCollectionAddShardUsingPolicy
[junit4] 2> 145018 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 145018 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 0
[junit4] 2> 145018 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider --- new Overseer leader: 127.0.0.1:10000_solr
[junit4] 2> 145019 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=0
[junit4] 2> 145039 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 0
[junit4] 2> 145039 INFO (TEST-TestSimPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[AD6F5F726E4A1850]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 145042 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion -1
[junit4] 2> 145042 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 0
[junit4] 2> 145042 INFO (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:10001_solr, 127.0.0.1:10004_solr, 127.0.0.1:10000_solr, 127.0.0.1:10002_solr, 127.0.0.1:10003_solr]
[junit4] 2> 145042 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 145042 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion 0
[junit4] 2> 145043 DEBUG (ScheduledTrigger-1052-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145064 DEBUG (ScheduledTrigger-1052-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145067 DEBUG (simCloudManagerPool-1051-thread-1) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 145070 DEBUG (simCloudManagerPool-1051-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 145071 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 145071 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 145071 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 145073 DEBUG (simCloudManagerPool-1051-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider -- simCreateCollection policiesTest, currentVersion=1
[junit4] 2> 145074 DEBUG (simCloudManagerPool-1051-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=1
[junit4] 2> 145074 DEBUG (simCloudManagerPool-1051-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 1
[junit4] 2> 145084 DEBUG (ScheduledTrigger-1052-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145088 DEBUG (simCloudManagerPool-1051-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=2
[junit4] 2> 145091 DEBUG (simCloudManagerPool-1051-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 2
[junit4] 2> 145104 DEBUG (ScheduledTrigger-1052-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145106 DEBUG (simCloudManagerPool-1051-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider -- finished createCollection policiesTest, currentVersion=3
[junit4] 2> 145122 DEBUG (simCloudManagerPool-1051-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=3
[junit4] 2> 145123 DEBUG (simCloudManagerPool-1051-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 3
[junit4] 2> 145125 DEBUG (ScheduledTrigger-1052-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145137 DEBUG (simCloudManagerPool-1051-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=4
[junit4] 2> 145137 DEBUG (simCloudManagerPool-1051-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 4
[junit4] 2> 145146 DEBUG (ScheduledTrigger-1052-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145145 DEBUG (simCloudManagerPool-1051-thread-6) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for policiesTest / s1 (currentVersion=5): {"core_node1":{
[junit4] 2> "core":"policiesTest_s1_replica_n1",
[junit4] 2> "shard":"s1",
[junit4] 2> "collection":"policiesTest",
[junit4] 2> "node_name":"127.0.0.1:10004_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 145149 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=5
[junit4] 2> 145149 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 5
[junit4] 2> 145150 DEBUG (simCloudManagerPool-1051-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for policiesTest / s2 (currentVersion=6): {"core_node2":{
[junit4] 2> "core":"policiesTest_s2_replica_n2",
[junit4] 2> "shard":"s2",
[junit4] 2> "collection":"policiesTest",
[junit4] 2> "node_name":"127.0.0.1:10004_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 145153 DEBUG (simCloudManagerPool-1051-thread-8) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=6
[junit4] 2> 145158 DEBUG (simCloudManagerPool-1051-thread-8) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 6
[junit4] 2> 145167 DEBUG (ScheduledTrigger-1052-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145187 DEBUG (ScheduledTrigger-1052-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145207 DEBUG (ScheduledTrigger-1052-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145228 DEBUG (ScheduledTrigger-1052-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145248 DEBUG (ScheduledTrigger-1052-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145266 INFO (simCloudManagerPool-1051-thread-8) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10004_solr for creating new replica of shard s3 for collection policiesTest
[junit4] 2> 145266 INFO (simCloudManagerPool-1051-thread-8) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 145268 DEBUG (ScheduledTrigger-1052-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145277 DEBUG (simCloudManagerPool-1051-thread-8) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=7
[junit4] 2> 145277 DEBUG (simCloudManagerPool-1051-thread-8) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 7
[junit4] 2> 145278 DEBUG (simCloudManagerPool-1051-thread-8) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=8
[junit4] 2> 145278 DEBUG (simCloudManagerPool-1051-thread-8) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 8
[junit4] 2> 145279 DEBUG (simCloudManagerPool-1051-thread-9) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for policiesTest / s3 (currentVersion=9): {"core_node4":{
[junit4] 2> "core":"policiesTest_s3_replica_n3",
[junit4] 2> "shard":"s3",
[junit4] 2> "collection":"policiesTest",
[junit4] 2> "node_name":"127.0.0.1:10004_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "leader":"true",
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6}}
[junit4] 2> 145281 WARN (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Interrupted
[junit4] 2> java.lang.InterruptedException: null
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:2014) ~[?:1.8.0_191]
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2048) ~[?:1.8.0_191]
[junit4] 2> at org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:191) [java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_191]
[junit4] 2> 145283 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 145284 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 145284 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 145284 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 145284 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 145284 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 145285 INFO (TEST-TestSimPolicyCloud.testCreateCollectionAddShardUsingPolicy-seed#[AD6F5F726E4A1850]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCreateCollectionAddShardUsingPolicy
[junit4] 2> 145292 INFO (TEST-TestSimPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[AD6F5F726E4A1850]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCreateCollectionAddShardWithReplicaTypeUsingPolicy
[junit4] 2> 145297 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider --- new Overseer leader: 127.0.0.1:10005_solr
[junit4] 2> 145297 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 145297 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=0
[junit4] 2> 145297 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 0
[junit4] 2> 145298 INFO (TEST-TestSimPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[AD6F5F726E4A1850]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 145298 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 0
[junit4] 2> 145298 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion -1
[junit4] 2> 145298 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 0
[junit4] 2> 145298 INFO (TEST-TestSimPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.TestSimPolicyCloud NRT 127.0.0.1:10006_solr PULL 127.0.0.1:10005_solr , TLOG 127.0.0.1:10009_solr
[junit4] 2> 145299 INFO (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:10006_solr, 127.0.0.1:10005_solr, 127.0.0.1:10009_solr, 127.0.0.1:10008_solr, 127.0.0.1:10007_solr]
[junit4] 2> 145299 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 145299 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion 0
[junit4] 2> 145299 DEBUG (ScheduledTrigger-1058-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145303 DEBUG (simCloudManagerPool-1057-thread-1) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 145304 DEBUG (simCloudManagerPool-1057-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 145306 DEBUG (simCloudManagerPool-1057-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider -- simCreateCollection policiesTest, currentVersion=1
[junit4] 2> 145307 DEBUG (simCloudManagerPool-1057-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=1
[junit4] 2> 145307 DEBUG (simCloudManagerPool-1057-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 1
[junit4] 2> 145307 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 145307 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 145307 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 145317 DEBUG (simCloudManagerPool-1057-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=2
[junit4] 2> 145317 DEBUG (simCloudManagerPool-1057-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 2
[junit4] 2> 145321 DEBUG (ScheduledTrigger-1058-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145325 DEBUG (simCloudManagerPool-1057-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider -- finished createCollection policiesTest, currentVersion=3
[junit4] 2> 145340 DEBUG (simCloudManagerPool-1057-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=3
[junit4] 2> 145341 DEBUG (simCloudManagerPool-1057-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 3
[junit4] 2> 145341 DEBUG (ScheduledTrigger-1058-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145353 DEBUG (simCloudManagerPool-1057-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=4
[junit4] 2> 145353 DEBUG (simCloudManagerPool-1057-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 4
[junit4] 2> 145370 DEBUG (simCloudManagerPool-1057-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=5
[junit4] 2> 145380 DEBUG (simCloudManagerPool-1057-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 5
[junit4] 2> 145380 DEBUG (ScheduledTrigger-1058-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145382 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=6
[junit4] 2> 145383 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 6
[junit4] 2> 145383 INFO (TEST-TestSimPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.CloudTestUtils -- shards without leaders: [s1]
[junit4] 2> 145383 DEBUG (simCloudManagerPool-1057-thread-6) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for policiesTest / s1 (currentVersion=7): {"core_node1":{
[junit4] 2> "core":"policiesTest_s1_replica_n1",
[junit4] 2> "shard":"s1",
[junit4] 2> "collection":"policiesTest",
[junit4] 2> "node_name":"127.0.0.1:10006_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 145385 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=7
[junit4] 2> 145386 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 7
[junit4] 2> 145401 DEBUG (ScheduledTrigger-1058-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145422 DEBUG (ScheduledTrigger-1058-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145442 DEBUG (ScheduledTrigger-1058-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145465 DEBUG (ScheduledTrigger-1058-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145489 DEBUG (ScheduledTrigger-1058-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145497 INFO (simCloudManagerPool-1057-thread-9) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10006_solr for creating new replica of shard s3 for collection policiesTest
[junit4] 2> 145497 INFO (simCloudManagerPool-1057-thread-9) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 145507 DEBUG (simCloudManagerPool-1057-thread-9) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=8
[junit4] 2> 145507 DEBUG (simCloudManagerPool-1057-thread-9) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 8
[junit4] 2> 145507 INFO (simCloudManagerPool-1057-thread-9) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10009_solr for creating new replica of shard s3 for collection policiesTest
[junit4] 2> 145508 INFO (simCloudManagerPool-1057-thread-9) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 145509 DEBUG (ScheduledTrigger-1058-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145518 DEBUG (simCloudManagerPool-1057-thread-9) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=9
[junit4] 2> 145518 DEBUG (simCloudManagerPool-1057-thread-9) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 9
[junit4] 2> 145518 INFO (simCloudManagerPool-1057-thread-9) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10005_solr for creating new replica of shard s3 for collection policiesTest
[junit4] 2> 145518 INFO (simCloudManagerPool-1057-thread-9) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 145529 DEBUG (simCloudManagerPool-1057-thread-9) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=10
[junit4] 2> 145529 DEBUG (simCloudManagerPool-1057-thread-9) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 10
[junit4] 2> 145529 DEBUG (simCloudManagerPool-1057-thread-9) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=11
[junit4] 2> 145529 DEBUG (ScheduledTrigger-1058-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145530 DEBUG (simCloudManagerPool-1057-thread-9) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 11
[junit4] 2> 145532 DEBUG (simCloudManagerPool-1057-thread-12) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for policiesTest / s3 (currentVersion=12): {"core_node7":{
[junit4] 2> "core":"policiesTest_s3_replica_t6",
[junit4] 2> "shard":"s3",
[junit4] 2> "collection":"policiesTest",
[junit4] 2> "node_name":"127.0.0.1:10009_solr",
[junit4] 2> "type":"TLOG",
[junit4] 2> "pullReplicas":"1",
[junit4] 2> "leader":"true",
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "tlogReplicas":"1",
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6}}
[junit4] 2> 145533 INFO (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 145533 INFO (simCloudManagerPool-1057-thread-10) [ ] o.a.s.c.ActionThrottle Throttling leader attempts - waiting for 4944ms
[junit4] 2> 145533 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 145536 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 145536 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 145536 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 145537 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 145537 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 145537 DEBUG (simCloudManagerPool-1057-thread-10) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for policiesTest / s3 (currentVersion=12): {"core_node5":{
[junit4] 2> "core":"policiesTest_s3_replica_n4",
[junit4] 2> "shard":"s3",
[junit4] 2> "collection":"policiesTest",
[junit4] 2> "node_name":"127.0.0.1:10006_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "pullReplicas":"1",
[junit4] 2> "leader":"true",
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "tlogReplicas":"1",
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6}}
[junit4] 2> 145538 INFO (TEST-TestSimPolicyCloud.testCreateCollectionAddShardWithReplicaTypeUsingPolicy-seed#[AD6F5F726E4A1850]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCreateCollectionAddShardWithReplicaTypeUsingPolicy
[junit4] 2> 145541 INFO (TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[AD6F5F726E4A1850]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDataProviderPerReplicaDetails
[junit4] 2> 145551 DEBUG (TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider --- new Overseer leader: 127.0.0.1:10010_solr
[junit4] 2> 145551 DEBUG (TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=0
[junit4] 2> 145551 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 145551 DEBUG (TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 0
[junit4] 2> 145551 INFO (TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[AD6F5F726E4A1850]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 145551 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 0
[junit4] 2> 145552 DEBUG (simCloudManagerPool-1063-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider -- simCreateCollection perReplicaDataColl, currentVersion=1
[junit4] 2> 145552 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion -1
[junit4] 2> 145552 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 0
[junit4] 2> 145552 DEBUG (simCloudManagerPool-1063-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=1
[junit4] 2> 145552 DEBUG (simCloudManagerPool-1063-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 1
[junit4] 2> 145553 INFO (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:10010_solr, 127.0.0.1:10013_solr, 127.0.0.1:10012_solr, 127.0.0.1:10014_solr, 127.0.0.1:10011_solr]
[junit4] 2> 145553 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 145553 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion 0
[junit4] 2> 145554 DEBUG (ScheduledTrigger-1064-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145562 DEBUG (simCloudManagerPool-1063-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=2
[junit4] 2> 145563 DEBUG (simCloudManagerPool-1063-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 2
[junit4] 2> 145575 DEBUG (ScheduledTrigger-1064-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145578 DEBUG (simCloudManagerPool-1063-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=3
[junit4] 2> 145578 DEBUG (simCloudManagerPool-1063-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 3
[junit4] 2> 145578 DEBUG (simCloudManagerPool-1063-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider -- finished createCollection perReplicaDataColl, currentVersion=4
[junit4] 2> 145589 DEBUG (simCloudManagerPool-1063-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=4
[junit4] 2> 145589 DEBUG (simCloudManagerPool-1063-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 4
[junit4] 2> 145598 DEBUG (ScheduledTrigger-1064-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145600 DEBUG (simCloudManagerPool-1063-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=5
[junit4] 2> 145601 DEBUG (simCloudManagerPool-1063-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 5
[junit4] 2> 145612 DEBUG (simCloudManagerPool-1063-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=6
[junit4] 2> 145613 DEBUG (simCloudManagerPool-1063-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 6
[junit4] 2> 145622 DEBUG (ScheduledTrigger-1064-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145624 DEBUG (simCloudManagerPool-1063-thread-6) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=7
[junit4] 2> 145624 DEBUG (simCloudManagerPool-1063-thread-6) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 7
[junit4] 2> 145624 DEBUG (TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=8
[junit4] 2> 145625 DEBUG (TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 8
[junit4] 2> 145625 INFO (TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.CloudTestUtils -- shards without leaders: [shard1]
[junit4] 2> 145625 DEBUG (simCloudManagerPool-1063-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for perReplicaDataColl / shard1 (currentVersion=9): {"core_node1":{
[junit4] 2> "core":"perReplicaDataColl_shard1_replica_n1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"perReplicaDataColl",
[junit4] 2> "node_name":"127.0.0.1:10011_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 145627 DEBUG (TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=9
[junit4] 2> 145628 DEBUG (TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 9
[junit4] 2> 145643 DEBUG (ScheduledTrigger-1064-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145664 DEBUG (ScheduledTrigger-1064-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145689 DEBUG (ScheduledTrigger-1064-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145714 DEBUG (ScheduledTrigger-1064-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145733 INFO (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 145734 DEBUG (TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 145734 DEBUG (TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 145734 DEBUG (TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 145734 DEBUG (TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 145735 DEBUG (TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 145735 DEBUG (TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 145735 INFO (TEST-TestSimPolicyCloud.testDataProviderPerReplicaDetails-seed#[AD6F5F726E4A1850]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDataProviderPerReplicaDetails
[junit4] 2> 145744 INFO (TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[AD6F5F726E4A1850]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCreateCollectionAddReplica
[junit4] 2> 145749 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider --- new Overseer leader: 127.0.0.1:10015_solr
[junit4] 2> 145749 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 145749 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 0
[junit4] 2> 145750 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion -1
[junit4] 2> 145750 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 0
[junit4] 2> 145751 INFO (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:10015_solr]
[junit4] 2> 145751 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 145751 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion 0
[junit4] 2> 145751 DEBUG (ScheduledTrigger-1070-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 145751 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=0
[junit4] 2> 145751 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 0
[junit4] 2> 145751 INFO (TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[AD6F5F726E4A1850]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 145753 DEBUG (simCloudManagerPool-1069-thread-1) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 145753 DEBUG (simCloudManagerPool-1069-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 145755 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 145755 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 145755 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 145755 DEBUG (simCloudManagerPool-1069-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider -- simCreateCollection testCreateCollectionAddReplica, currentVersion=1
[junit4] 2> 145756 DEBUG (simCloudManagerPool-1069-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=1
[junit4] 2> 145756 DEBUG (simCloudManagerPool-1069-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 1
[junit4] 2> 145766 DEBUG (simCloudManagerPool-1069-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=2
[junit4] 2> 145766 DEBUG (simCloudManagerPool-1069-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 2
[junit4] 2> 145767 DEBUG (simCloudManagerPool-1069-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider -- finished createCollection testCreateCollectionAddReplica, currentVersion=3
[junit4] 2> 145771 DEBUG (ScheduledTrigger-1070-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 1
[junit4] 2> 145778 DEBUG (simCloudManagerPool-1069-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=3
[junit4] 2> 145778 DEBUG (simCloudManagerPool-1069-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 3
[junit4] 2> 145778 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=4
[junit4] 2> 145779 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 4
[junit4] 2> 145779 INFO (TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.CloudTestUtils -- shards without leaders: [shard1]
[junit4] 2> 145780 DEBUG (simCloudManagerPool-1069-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testCreateCollectionAddReplica / shard1 (currentVersion=5): {"core_node1":{
[junit4] 2> "core":"testCreateCollectionAddReplica_shard1_replica_n1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCreateCollectionAddReplica",
[junit4] 2> "node_name":"127.0.0.1:10016_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 145782 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=5
[junit4] 2> 145782 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 5
[junit4] 2> 145785 INFO (simCloudManagerPool-1069-thread-5) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10016_solr for creating new replica of shard shard1 for collection testCreateCollectionAddReplica
[junit4] 2> 145785 INFO (simCloudManagerPool-1069-thread-5) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 145792 DEBUG (ScheduledTrigger-1070-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145795 DEBUG (simCloudManagerPool-1069-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=6
[junit4] 2> 145795 DEBUG (simCloudManagerPool-1069-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 6
[junit4] 2> 145797 INFO (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 145797 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 145798 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 145798 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 145798 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 145798 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 145798 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 145799 INFO (TEST-TestSimPolicyCloud.testCreateCollectionAddReplica-seed#[AD6F5F726E4A1850]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCreateCollectionAddReplica
[junit4] 2> 145800 INFO (TEST-TestSimPolicyCloud.testDataProvider-seed#[AD6F5F726E4A1850]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDataProvider
[junit4] 2> 145804 DEBUG (TEST-TestSimPolicyCloud.testDataProvider-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider --- new Overseer leader: 127.0.0.1:10020_solr
[junit4] 2> 145804 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 145804 DEBUG (TEST-TestSimPolicyCloud.testDataProvider-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=0
[junit4] 2> 145804 DEBUG (TEST-TestSimPolicyCloud.testDataProvider-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 0
[junit4] 2> 145804 INFO (TEST-TestSimPolicyCloud.testDataProvider-seed#[AD6F5F726E4A1850]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 145804 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 0
[junit4] 2> 145805 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion -1
[junit4] 2> 145805 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 0
[junit4] 2> 145805 INFO (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:10020_solr]
[junit4] 2> 145805 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 145805 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion 0
[junit4] 2> 145806 DEBUG (ScheduledTrigger-1076-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 145807 DEBUG (simCloudManagerPool-1075-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider -- simCreateCollection policiesTest, currentVersion=1
[junit4] 2> 145807 DEBUG (simCloudManagerPool-1075-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=1
[junit4] 2> 145807 DEBUG (simCloudManagerPool-1075-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 1
[junit4] 2> 145819 DEBUG (simCloudManagerPool-1075-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=2
[junit4] 2> 145819 DEBUG (simCloudManagerPool-1075-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 2
[junit4] 2> 145820 DEBUG (simCloudManagerPool-1075-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider -- finished createCollection policiesTest, currentVersion=3
[junit4] 2> 145826 DEBUG (ScheduledTrigger-1076-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 1
[junit4] 2> 145830 DEBUG (simCloudManagerPool-1075-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=3
[junit4] 2> 145831 DEBUG (simCloudManagerPool-1075-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 3
[junit4] 2> 145835 DEBUG (simCloudManagerPool-1075-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for policiesTest / shard1 (currentVersion=4): {"core_node2":{
[junit4] 2> "core":"policiesTest_shard1_replica_n2",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"policiesTest",
[junit4] 2> "node_name":"127.0.0.1:10024_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 145835 DEBUG (TEST-TestSimPolicyCloud.testDataProvider-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=4
[junit4] 2> 145835 DEBUG (TEST-TestSimPolicyCloud.testDataProvider-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 4
[junit4] 2> 145835 INFO (TEST-TestSimPolicyCloud.testDataProvider-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.CloudTestUtils -- wrong number of active replicas for collection policiesTest in slice shard1, expected=2, found=1
[junit4] 2> 145838 INFO (TEST-TestSimPolicyCloud.testDataProvider-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.CloudTestUtils -- wrong number of active replicas for collection policiesTest in slice shard1, expected=2, found=1
[junit4] 2> 145841 INFO (TEST-TestSimPolicyCloud.testDataProvider-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.CloudTestUtils -- wrong number of active replicas for collection policiesTest in slice shard1, expected=2, found=1
[junit4] 2> 145845 INFO (TEST-TestSimPolicyCloud.testDataProvider-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.CloudTestUtils -- wrong number of active replicas for collection policiesTest in slice shard1, expected=2, found=1
[junit4] 2> 145846 DEBUG (simCloudManagerPool-1075-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=5
[junit4] 2> 145846 DEBUG (simCloudManagerPool-1075-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 5
[junit4] 2> 145849 DEBUG (ScheduledTrigger-1076-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145869 DEBUG (ScheduledTrigger-1076-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145880 DEBUG (TEST-TestSimPolicyCloud.testDataProvider-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 145880 DEBUG (TEST-TestSimPolicyCloud.testDataProvider-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 145880 DEBUG (TEST-TestSimPolicyCloud.testDataProvider-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 145880 DEBUG (TEST-TestSimPolicyCloud.testDataProvider-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 145880 INFO (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 145881 DEBUG (TEST-TestSimPolicyCloud.testDataProvider-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 145881 DEBUG (TEST-TestSimPolicyCloud.testDataProvider-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 145882 INFO (TEST-TestSimPolicyCloud.testDataProvider-seed#[AD6F5F726E4A1850]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDataProvider
[junit4] 2> 145884 INFO (TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[AD6F5F726E4A1850]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCreateCollectionSplitShard
[junit4] 2> 145888 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider --- new Overseer leader: 127.0.0.1:10025_solr
[junit4] 2> 145889 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=0
[junit4] 2> 145889 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 0
[junit4] 2> 145889 INFO (TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[AD6F5F726E4A1850]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 145890 DEBUG (simCloudManagerPool-1081-thread-1) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 145891 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 145891 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 145891 DEBUG (simCloudManagerPool-1081-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider -- simCreateCollection testCreateCollectionSplitShard, currentVersion=1
[junit4] 2> 145891 DEBUG (simCloudManagerPool-1081-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=1
[junit4] 2> 145891 DEBUG (simCloudManagerPool-1081-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 1
[junit4] 2> 145892 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion -1
[junit4] 2> 145892 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 145892 INFO (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:10025_solr, 127.0.0.1:10026_solr, 127.0.0.1:10029_solr, 127.0.0.1:10028_solr, 127.0.0.1:10027_solr]
[junit4] 2> 145892 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 145892 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 145892 DEBUG (ScheduledTrigger-1082-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145902 DEBUG (simCloudManagerPool-1081-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=2
[junit4] 2> 145902 DEBUG (simCloudManagerPool-1081-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 2
[junit4] 2> 145915 DEBUG (ScheduledTrigger-1082-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145915 DEBUG (simCloudManagerPool-1081-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=3
[junit4] 2> 145915 DEBUG (simCloudManagerPool-1081-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 3
[junit4] 2> 145916 DEBUG (simCloudManagerPool-1081-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider -- finished createCollection testCreateCollectionSplitShard, currentVersion=4
[junit4] 2> 145917 DEBUG (simCloudManagerPool-1081-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testCreateCollectionSplitShard / shard1 (currentVersion=4): {"core_node1":{
[junit4] 2> "core":"testCreateCollectionSplitShard_shard1_replica_n1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCreateCollectionSplitShard",
[junit4] 2> "node_name":"127.0.0.1:10027_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 145927 DEBUG (simCloudManagerPool-1081-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=4
[junit4] 2> 145927 DEBUG (simCloudManagerPool-1081-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 4
[junit4] 2> 145928 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=5
[junit4] 2> 145928 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 5
[junit4] 2> 145929 INFO (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 145930 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 145931 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 145931 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 145931 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 145931 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 145931 DEBUG (TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 145932 INFO (TEST-TestSimPolicyCloud.testCreateCollectionSplitShard-seed#[AD6F5F726E4A1850]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCreateCollectionSplitShard
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSimPolicyCloud -Dtests.method=testCreateCollectionSplitShard -Dtests.seed=AD6F5F726E4A1850 -Dtests.slow=true -Dtests.locale=es-MX -Dtests.timezone=Asia/Muscat -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] FAILURE 0.05s J1 | TestSimPolicyCloud.testCreateCollectionSplitShard <<<
[junit4] > Throwable #1: java.lang.AssertionError: Expected exactly one replica of collection on node with port: 10029 expected:<1> but was:<0>
[junit4] > at __randomizedtesting.SeedInfo.seed([AD6F5F726E4A1850:78E12C89DE0099EE]:0)
[junit4] > at org.apache.solr.cloud.autoscaling.sim.TestSimPolicyCloud.testCreateCollectionSplitShard(TestSimPolicyCloud.java:171)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 145937 INFO (TEST-TestSimPolicyCloud.testMetricsTag-seed#[AD6F5F726E4A1850]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMetricsTag
[junit4] 2> 145941 DEBUG (TEST-TestSimPolicyCloud.testMetricsTag-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider --- new Overseer leader: 127.0.0.1:10030_solr
[junit4] 2> 145941 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 145941 DEBUG (TEST-TestSimPolicyCloud.testMetricsTag-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=0
[junit4] 2> 145941 DEBUG (TEST-TestSimPolicyCloud.testMetricsTag-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 0
[junit4] 2> 145941 INFO (TEST-TestSimPolicyCloud.testMetricsTag-seed#[AD6F5F726E4A1850]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 145941 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 0
[junit4] 2> 145942 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion -1
[junit4] 2> 145942 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 0
[junit4] 2> 145942 INFO (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:10031_solr, 127.0.0.1:10034_solr, 127.0.0.1:10033_solr, 127.0.0.1:10030_solr, 127.0.0.1:10032_solr]
[junit4] 2> 145943 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 145943 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion 0
[junit4] 2> 145943 DEBUG (ScheduledTrigger-1088-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145943 ERROR (simCloudManagerPool-1087-thread-1) [ ] o.a.s.h.RequestHandlerBase org.apache.solr.api.ApiBag$ExceptionWithErrObject: Error in command payload, errors: [{set-cluster-policy=[{cores=<10, node=#ANY}, {replica=<2, shard=#EACH, node=#ANY}, {metrics:abc=overseer, replica=0}], errorMessages=[Invalid metrics: param in {
[junit4] 2> "metrics:abc":"overseer",
[junit4] 2> "replica":0} must have at 2 or 3 segments after 'metrics:' separated by ':']}],
[junit4] 2> at org.apache.solr.cloud.autoscaling.AutoScalingHandler.processOps(AutoScalingHandler.java:210)
[junit4] 2> at org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:148)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199)
[junit4] 2> at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.simHandleSolrRequest(SimCloudManager.java:708)
[junit4] 2> at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.lambda$request$15(SimCloudManager.java:651)
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 145943 DEBUG (simCloudManagerPool-1087-thread-1) [ ] o.a.s.c.a.s.SimCloudManager -- exception handling request
[junit4] 2> org.apache.solr.api.ApiBag$ExceptionWithErrObject: Error in command payload
[junit4] 2> at org.apache.solr.cloud.autoscaling.AutoScalingHandler.processOps(AutoScalingHandler.java:210) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:148) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.simHandleSolrRequest(SimCloudManager.java:708) ~[test/:?]
[junit4] 2> at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.lambda$request$15(SimCloudManager.java:651) ~[test/:?]
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_191]
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209) ~[java/:?]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_191]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_191]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_191]
[junit4] 2> 145945 DEBUG (simCloudManagerPool-1087-thread-2) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 145945 DEBUG (simCloudManagerPool-1087-thread-2) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 145946 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 145946 DEBUG (simCloudManagerPool-1087-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider -- simCreateCollection metricsTest, currentVersion=1
[junit4] 2> 145947 DEBUG (simCloudManagerPool-1087-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=1
[junit4] 2> 145947 DEBUG (simCloudManagerPool-1087-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 1
[junit4] 2> 145947 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 145947 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 145957 DEBUG (simCloudManagerPool-1087-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=2
[junit4] 2> 145957 DEBUG (simCloudManagerPool-1087-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 2
[junit4] 2> 145961 DEBUG (simCloudManagerPool-1087-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider -- finished createCollection metricsTest, currentVersion=3
[junit4] 2> 145961 DEBUG (TEST-TestSimPolicyCloud.testMetricsTag-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=3
[junit4] 2> 145961 DEBUG (TEST-TestSimPolicyCloud.testMetricsTag-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 3
[junit4] 2> 145961 INFO (TEST-TestSimPolicyCloud.testMetricsTag-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.CloudTestUtils -- wrong number of active replicas for collection metricsTest in slice shard1, expected=1, found=0
[junit4] 2> 145964 INFO (TEST-TestSimPolicyCloud.testMetricsTag-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.CloudTestUtils -- wrong number of active replicas for collection metricsTest in slice shard1, expected=1, found=0
[junit4] 2> 145964 DEBUG (ScheduledTrigger-1088-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 and last live nodes: 5
[junit4] 2> 145966 INFO (TEST-TestSimPolicyCloud.testMetricsTag-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.CloudTestUtils -- wrong number of active replicas for collection metricsTest in slice shard1, expected=1, found=0
[junit4] 2> 145968 INFO (TEST-TestSimPolicyCloud.testMetricsTag-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.CloudTestUtils -- wrong number of active replicas for collection metricsTest in slice shard1, expected=1, found=0
[junit4] 2> 145970 INFO (TEST-TestSimPolicyCloud.testMetricsTag-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.CloudTestUtils -- wrong number of active replicas for collection metricsTest in slice shard1, expected=1, found=0
[junit4] 2> 145971 DEBUG (simCloudManagerPool-1087-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=4
[junit4] 2> 145971 DEBUG (simCloudManagerPool-1087-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 4
[junit4] 2> 145974 DEBUG (simCloudManagerPool-1087-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for metricsTest / shard1 (currentVersion=5): {"core_node1":{
[junit4] 2> "core":"metricsTest_shard1_replica_n1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"metricsTest",
[junit4] 2> "node_name":"127.0.0.1:10032_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 145975 INFO (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 145979 DEBUG (TEST-TestSimPolicyCloud.testMetricsTag-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 145980 DEBUG (TEST-TestSimPolicyCloud.testMetricsTag-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 145980 DEBUG (TEST-TestSimPolicyCloud.testMetricsTag-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 145980 DEBUG (TEST-TestSimPolicyCloud.testMetricsTag-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 145980 DEBUG (TEST-TestSimPolicyCloud.testMetricsTag-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 145980 DEBUG (TEST-TestSimPolicyCloud.testMetricsTag-seed#[AD6F5F726E4A1850]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 145980 INFO (TEST-TestSimPolicyCloud.testMetricsTag-seed#[AD6F5F726E4A1850]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMetricsTag
[junit4] 2> NOTE: leaving temporary files on disk at: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.sim.TestSimPolicyCloud_AD6F5F726E4A1850-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=471, maxMBSortInHeap=6.33211893918656, sim=RandomSimilarity(queryNorm=false): {}, locale=es-MX, timezone=Asia/Muscat
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_191 (64-bit)/cpus=3,threads=1,free=157521368,total=381816832
[junit4] 2> NOTE: All tests run in this JVM: [RandomizedTaggerTest, DateMathParserTest, TestZkChroot, CloudMLTQParserTest, StatsReloadRaceTest, DistributedTermsComponentTest, SchemaApiFailureTest, SampleTest, TestExactSharedStatsCache, TokenizerChainTest, TestStressLiveNodes, QueryElevationComponentTest, TestSimGenericDistributedQueue, ShardRoutingCustomTest, TestUtils, TestSimPolicyCloud]
[junit4] Completed [34/836 (1!)] on J1 in 1.28s, 7 tests, 1 failure <<< FAILURES!
[...truncated 3513 lines...]
[junit4] Suite: org.apache.solr.client.solrj.io.stream.StreamDecoratorTest
[junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamDecoratorTest_9B78FE2AC674CCC0-001/init-core-data-001
[junit4] 1> 23:21:18.304 [jetty-launcher-14-thread-4] ERROR org.apache.solr.util.StartupLoggingUtils - Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 1> 23:21:18.324 [jetty-launcher-14-thread-2] ERROR org.apache.solr.util.StartupLoggingUtils - Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 1> 23:21:18.334 [jetty-launcher-14-thread-1] ERROR org.apache.solr.util.StartupLoggingUtils - Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 1> 23:21:18.359 [jetty-launcher-14-thread-3] ERROR org.apache.solr.util.StartupLoggingUtils - Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] IGNOR/A 0.01s J0 | StreamDecoratorTest.testParallelReducerStream
[junit4] > Assumption #1: 'badapple' test group is disabled (@BadApple(bugUrl=https://issues.apache.org/jira/browse/SOLR-12028))
[junit4] IGNOR/A 0.00s J0 | StreamDecoratorTest.testParallelFetchStream
[junit4] > Assumption #1: 'badapple' test group is disabled (@BadApple(bugUrl=https://issues.apache.org/jira/browse/SOLR-12028))
[junit4] IGNOR/A 0.00s J0 | StreamDecoratorTest.testParallelHavingStream
[junit4] > Assumption #1: 'badapple' test group is disabled (@BadApple(bugUrl=https://issues.apache.org/jira/browse/SOLR-12028))
[junit4] IGNOR/A 0.00s J0 | StreamDecoratorTest.testParallelExecutorStream
[junit4] > Assumption #1: 'badapple' test group is disabled (@BadApple(bugUrl=https://issues.apache.org/jira/browse/SOLR-12028))
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=StreamDecoratorTest -Dtests.method=testParallelCommitStream -Dtests.seed=9B78FE2AC674CCC0 -Dtests.slow=true -Dtests.locale=ar-OM -Dtests.timezone=Europe/Belfast -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] FAILURE 2.96s J0 | StreamDecoratorTest.testParallelCommitStream <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<5> but was:<2>
[junit4] > at __randomizedtesting.SeedInfo.seed([9B78FE2AC674CCC0:BB929C2A5A35218C]:0)
[junit4] > at org.apache.solr.client.solrj.io.stream.StreamDecoratorTest.testParallelCommitStream(StreamDecoratorTest.java:3034)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] IGNOR/A 0.00s J0 | StreamDecoratorTest.testParallelRollupStream
[junit4] > Assumption #1: 'badapple' test group is disabled (@BadApple(bugUrl=https://issues.apache.org/jira/browse/SOLR-12028))
[junit4] IGNOR/A 0.00s J0 | StreamDecoratorTest.testExecutorStream
[junit4] > Assumption #1: 'badapple' test group is disabled (@BadApple(bugUrl=https://issues.apache.org/jira/browse/SOLR-12028))
[junit4] IGNOR/A 0.00s J0 | StreamDecoratorTest.testParallelPriorityStream
[junit4] > Assumption #1: 'badapple' test group is disabled (@BadApple(bugUrl=https://issues.apache.org/jira/browse/SOLR-12028))
[junit4] IGNOR/A 0.00s J0 | StreamDecoratorTest.testParallelMergeStream
[junit4] > Assumption #1: 'badapple' test group is disabled (@BadApple(bugUrl=https://issues.apache.org/jira/browse/SOLR-12028))
[junit4] IGNOR/A 0.00s J0 | StreamDecoratorTest.testParallelDaemonUpdateStream
[junit4] > Assumption #1: 'badapple' test group is disabled (@BadApple(bugUrl=https://issues.apache.org/jira/browse/SOLR-12028))
[junit4] IGNOR/A 0.00s J0 | StreamDecoratorTest.testParallelUpdateStream
[junit4] > Assumption #1: 'badapple' test group is disabled (@BadApple(bugUrl=https://issues.apache.org/jira/browse/SOLR-12028))
[junit4] 2> NOTE: leaving temporary files on disk at: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamDecoratorTest_9B78FE2AC674CCC0-001
[junit4] 2> Dec 18, 2018 11:22:11 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {name_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), type_s=PostingsFormat(name=Memory), terms_ss=PostingsFormat(name=LuceneVarGapFixedInterval), join2_s=PostingsFormat(name=LuceneVarGapFixedInterval), field_s=PostingsFormat(name=Memory), multiDefault=PostingsFormat(name=LuceneVarGapFixedInterval), subject=PostingsFormat(name=LuceneVarGapFixedInterval), a1_s=PostingsFormat(name=LuceneVarGapFixedInterval), a2_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), checkpoint_ss=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), test_t=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), ident_s=PostingsFormat(name=LuceneVarGapFixedInterval), s_multi=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), a_ss=PostingsFormat(name=Memory), a_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), tv_text=PostingsFormat(name=Memory), extra_s=PostingsFormat(name=LuceneVarGapFixedInterval), ykey_s=PostingsFormat(name=Memory), side_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), text_s=FST50, id=FST50, text=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), bbid_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128)))}, docValues:{name_s=DocValuesFormat(name=Direct), iteration_i=DocValuesFormat(name=Asserting), terms_ss=DocValuesFormat(name=Lucene70), join2_s=DocValuesFormat(name=Lucene70), field_s=DocValuesFormat(name=Memory), multiDefault=DocValuesFormat(name=Lucene70), a1_s=DocValuesFormat(name=Lucene70), intDefault=DocValuesFormat(name=Memory), a_f=DocValuesFormat(name=Lucene70), a2_s=DocValuesFormat(name=Direct), join1_i=DocValuesFormat(name=Asserting), falseNegative_i=DocValuesFormat(name=Memory), a_i=DocValuesFormat(name=Asserting), test_i=DocValuesFormat(name=Lucen
[...truncated too long message...]
Completed [4/5] on J0 in 78.76s, 47 tests
[junit4]
[junit4] Suite: org.apache.solr.client.solrj.io.stream.StreamDecoratorTest
[junit4] OK 0.16s J1 | StreamDecoratorTest.testParallelIntersectStream
[junit4] OK 0.08s J1 | StreamDecoratorTest.testLetStream
[junit4] OK 0.09s J1 | StreamDecoratorTest.testParallelReducerStream
[junit4] OK 0.09s J1 | StreamDecoratorTest.testIntersectStream
[junit4] OK 0.13s J1 | StreamDecoratorTest.testFetchStream
[junit4] OK 0.09s J1 | StreamDecoratorTest.testRankStream
[junit4] OK 0.14s J1 | StreamDecoratorTest.testHashJoinStream
[junit4] OK 0.11s J1 | StreamDecoratorTest.testSelectStream
[junit4] OK 0.07s J1 | StreamDecoratorTest.testGetStreamForEOFTuple
[junit4] OK 12.5s J1 | StreamDecoratorTest.testClassifyStream
[junit4] OK 0.10s J1 | StreamDecoratorTest.testHashJoinStreamWithKnownConflict
[junit4] OK 0.16s J1 | StreamDecoratorTest.testParallelFetchStream
[junit4] OK 2.23s J1 | StreamDecoratorTest.testUpdateStream
[junit4] OK 0.15s J1 | StreamDecoratorTest.testParallelHavingStream
[junit4] OK 0.10s J1 | StreamDecoratorTest.testParallelShuffleStream
[junit4] OK 0.11s J1 | StreamDecoratorTest.testHavingStream
[junit4] OK 0.11s J1 | StreamDecoratorTest.testPriorityStream
[junit4] OK 0.10s J1 | StreamDecoratorTest.testParallelRankStream
[junit4] OK 0.08s J1 | StreamDecoratorTest.testReducerStream
[junit4] OK 0.08s J1 | StreamDecoratorTest.testOuterHashJoinStreamWithKnownConflict
[junit4] OK 0.11s J1 | StreamDecoratorTest.testMergeStream
[junit4] OK 10.1s J1 | StreamDecoratorTest.testParallelExecutorStream
[junit4] OK 0.16s J1 | StreamDecoratorTest.testStream
[junit4] OK 3.44s J1 | StreamDecoratorTest.testParallelTerminatingDaemonUpdateStream
[junit4] OK 0.17s J1 | StreamDecoratorTest.testCartesianProductStream
[junit4] OK 0.09s J1 | StreamDecoratorTest.testSortStream
[junit4] OK 0.14s J1 | StreamDecoratorTest.testTerminatingDaemonStream
[junit4] OK 0.14s J1 | StreamDecoratorTest.testUniqueStream
[junit4] OK 2.44s J1 | StreamDecoratorTest.testParallelCommitStream
[junit4] OK 0.17s J1 | StreamDecoratorTest.testParallelRollupStream
[junit4] OK 0.22s J1 | StreamDecoratorTest.testParallelUniqueStream
[junit4] OK 11.0s J1 | StreamDecoratorTest.testExecutorStream
[junit4] OK 2.13s J1 | StreamDecoratorTest.testCommitStream
[junit4] OK 0.15s J1 | StreamDecoratorTest.testParallelPriorityStream
[junit4] OK 11.2s J1 | StreamDecoratorTest.testDaemonStream
[junit4] OK 0.14s J1 | StreamDecoratorTest.testParallelMergeStream
[junit4] OK 0.12s J1 | StreamDecoratorTest.testOuterHashJoinStream
[junit4] OK 0.09s J1 | StreamDecoratorTest.testParallelComplementStream
[junit4] OK 4.28s J1 | StreamDecoratorTest.testParallelDaemonCommitStream
[junit4] OK 0.10s J1 | StreamDecoratorTest.testLeftOuterJoinStream
[junit4] OK 0.09s J1 | StreamDecoratorTest.testParallelNullStream
[junit4] OK 0.07s J1 | StreamDecoratorTest.testRollupStream
[junit4] OK 4.18s J1 | StreamDecoratorTest.testParallelDaemonUpdateStream
[junit4] OK 0.09s J1 | StreamDecoratorTest.testNullStream
[junit4] OK 0.12s J1 | StreamDecoratorTest.testComplementStream
[junit4] OK 0.11s J1 | StreamDecoratorTest.testInnerJoinStream
[junit4] OK 2.27s J1 | StreamDecoratorTest.testParallelUpdateStream
[junit4] Completed [5/5] on J1 in 75.88s, 47 tests
[junit4]
[junit4] JVM J0: 0.67 .. 175.27 = 174.60s
[junit4] JVM J1: 0.68 .. 251.30 = 250.63s
[junit4] Execution time total: 4 minutes 11 seconds
[junit4] Tests summary: 5 suites, 235 tests
[junit4] Could not remove temporary path: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-solrj/test/J1 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-solrj/test/J1/temp])
[junit4] Could not remove temporary path: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-solrj/test/J0 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-solrj/test/J0/temp])
[echo] 5 slowest tests:
[junit4:tophints] 79.43s | org.apache.solr.client.solrj.io.stream.StreamDecoratorTest
[junit4:tophints] 74.47s | org.apache.solr.client.solrj.impl.CloudSolrClientTest
[junit4:tophints] 50.45s | org.apache.solr.common.cloud.TestCollectionStateWatchers
[junit4:tophints] 41.97s | org.apache.solr.client.solrj.request.SchemaTest
[junit4:tophints] 39.28s | org.apache.solr.client.solrj.io.stream.StreamExpressionTest
-check-totals:
test-nocompile:
BUILD SUCCESSFUL
Total time: 4 minutes 13 seconds
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.client.solrj.io.stream.StreamDecoratorTest
[repro] 0/5 failed: org.apache.solr.cloud.autoscaling.sim.TestSimPolicyCloud
[repro] Exiting with code 0
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath$ValidateAntFileMask.hasMatch(FilePath.java:2847)
at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2726)
at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2707)
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3086)
Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to Solaris VBOX
at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1743)
at hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357)
at hudson.remoting.Channel.call(Channel.java:957)
at hudson.FilePath.act(FilePath.java:1072)
at hudson.FilePath.act(FilePath.java:1061)
at hudson.FilePath.validateAntFileMask(FilePath.java:2705)
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:1835)
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:3088)
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:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
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:1074)
at hudson.FilePath.act(FilePath.java:1061)
at hudson.FilePath.validateAntFileMask(FilePath.java:2705)
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:1835)
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/**,**/*_pid*.log". Configuration error?
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all warnings.
<Git Blamer> GIT_COMMIT=28eaf354fb46ba3d5593551c690f4ae17b89ce23, workspace=/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris
[WARNINGS] Computing warning deltas based on reference build #963
Recording test results
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
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)
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[JENKINS] Lucene-Solr-7.x-Solaris (64bit/jdk1.8.0) - Build # 965 -
Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/965/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.lucene.index.TestIndexWriterWithThreads.testIOExceptionDuringAbortWithThreads
Error Message:
MockDirectoryWrapper: cannot close: there are still 7 open files: {_m.cfs=1, _r.cfs=1, _q.cfs=1, _p.cfs=1, _n.cfs=1, _g.cfs=1, _f.cfs=1}
Stack Trace:
java.lang.RuntimeException: MockDirectoryWrapper: cannot close: there are still 7 open files: {_m.cfs=1, _r.cfs=1, _q.cfs=1, _p.cfs=1, _n.cfs=1, _g.cfs=1, _f.cfs=1}
at __randomizedtesting.SeedInfo.seed([4A5A215BC5BEC4EE:2BF729D419904752]:0)
at org.apache.lucene.store.MockDirectoryWrapper.close(MockDirectoryWrapper.java:838)
at org.apache.lucene.index.TestIndexWriterWithThreads._testMultipleThreadsFailure(TestIndexWriterWithThreads.java:341)
at org.apache.lucene.index.TestIndexWriterWithThreads.testIOExceptionDuringAbortWithThreads(TestIndexWriterWithThreads.java:458)
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:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
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:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Caused by: java.lang.RuntimeException: unclosed IndexInput: _g.cfs
at org.apache.lucene.store.MockDirectoryWrapper.addFileHandle(MockDirectoryWrapper.java:730)
at org.apache.lucene.store.MockDirectoryWrapper.openInput(MockDirectoryWrapper.java:773)
at org.apache.lucene.codecs.lucene50.Lucene50CompoundReader.<init>(Lucene50CompoundReader.java:78)
at org.apache.lucene.codecs.lucene50.Lucene50CompoundFormat.getCompoundReader(Lucene50CompoundFormat.java:70)
at org.apache.lucene.index.SegmentCoreReaders.<init>(SegmentCoreReaders.java:100)
at org.apache.lucene.index.SegmentReader.<init>(SegmentReader.java:83)
at org.apache.lucene.index.ReadersAndUpdates.getReader(ReadersAndUpdates.java:172)
at org.apache.lucene.index.BufferedUpdatesStream$SegmentState.<init>(BufferedUpdatesStream.java:271)
at org.apache.lucene.index.FrozenBufferedUpdates.openSegmentStates(FrozenBufferedUpdates.java:327)
at org.apache.lucene.index.FrozenBufferedUpdates.forceApply(FrozenBufferedUpdates.java:221)
at org.apache.lucene.index.FrozenBufferedUpdates.tryApply(FrozenBufferedUpdates.java:159)
at org.apache.lucene.index.BufferedUpdatesStream.waitApply(BufferedUpdatesStream.java:232)
at org.apache.lucene.index.BufferedUpdatesStream.waitApplyForMerge(BufferedUpdatesStream.java:206)
at org.apache.lucene.index.IndexWriter.mergeInit(IndexWriter.java:4215)
at org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:4071)
at org.apache.lucene.index.ConcurrentMergeScheduler.doMerge(ConcurrentMergeScheduler.java:625)
at org.apache.lucene.index.ConcurrentMergeScheduler$MergeThread.run(ConcurrentMergeScheduler.java:662)
Build Log:
[...truncated 1429 lines...]
[junit4] Suite: org.apache.lucene.index.TestIndexWriterWithThreads
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestIndexWriterWithThreads -Dtests.method=testIOExceptionDuringAbortWithThreads -Dtests.seed=4A5A215BC5BEC4EE -Dtests.slow=true -Dtests.locale=ar-EG -Dtests.timezone=Canada/Yukon -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.09s J0 | TestIndexWriterWithThreads.testIOExceptionDuringAbortWithThreads <<<
[junit4] > Throwable #1: java.lang.RuntimeException: MockDirectoryWrapper: cannot close: there are still 7 open files: {_m.cfs=1, _r.cfs=1, _q.cfs=1, _p.cfs=1, _n.cfs=1, _g.cfs=1, _f.cfs=1}
[junit4] > at __randomizedtesting.SeedInfo.seed([4A5A215BC5BEC4EE:2BF729D419904752]:0)
[junit4] > at org.apache.lucene.store.MockDirectoryWrapper.close(MockDirectoryWrapper.java:838)
[junit4] > at org.apache.lucene.index.TestIndexWriterWithThreads._testMultipleThreadsFailure(TestIndexWriterWithThreads.java:341)
[junit4] > at org.apache.lucene.index.TestIndexWriterWithThreads.testIOExceptionDuringAbortWithThreads(TestIndexWriterWithThreads.java:458)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > Caused by: java.lang.RuntimeException: unclosed IndexInput: _g.cfs
[junit4] > at org.apache.lucene.store.MockDirectoryWrapper.addFileHandle(MockDirectoryWrapper.java:730)
[junit4] > at org.apache.lucene.store.MockDirectoryWrapper.openInput(MockDirectoryWrapper.java:773)
[junit4] > at org.apache.lucene.codecs.lucene50.Lucene50CompoundReader.<init>(Lucene50CompoundReader.java:78)
[junit4] > at org.apache.lucene.codecs.lucene50.Lucene50CompoundFormat.getCompoundReader(Lucene50CompoundFormat.java:70)
[junit4] > at org.apache.lucene.index.SegmentCoreReaders.<init>(SegmentCoreReaders.java:100)
[junit4] > at org.apache.lucene.index.SegmentReader.<init>(SegmentReader.java:83)
[junit4] > at org.apache.lucene.index.ReadersAndUpdates.getReader(ReadersAndUpdates.java:172)
[junit4] > at org.apache.lucene.index.BufferedUpdatesStream$SegmentState.<init>(BufferedUpdatesStream.java:271)
[junit4] > at org.apache.lucene.index.FrozenBufferedUpdates.openSegmentStates(FrozenBufferedUpdates.java:327)
[junit4] > at org.apache.lucene.index.FrozenBufferedUpdates.forceApply(FrozenBufferedUpdates.java:221)
[junit4] > at org.apache.lucene.index.FrozenBufferedUpdates.tryApply(FrozenBufferedUpdates.java:159)
[junit4] > at org.apache.lucene.index.BufferedUpdatesStream.waitApply(BufferedUpdatesStream.java:232)
[junit4] > at org.apache.lucene.index.BufferedUpdatesStream.waitApplyForMerge(BufferedUpdatesStream.java:206)
[junit4] > at org.apache.lucene.index.IndexWriter.mergeInit(IndexWriter.java:4215)
[junit4] > at org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:4071)
[junit4] > at org.apache.lucene.index.ConcurrentMergeScheduler.doMerge(ConcurrentMergeScheduler.java:625)
[junit4] > at org.apache.lucene.index.ConcurrentMergeScheduler$MergeThread.run(ConcurrentMergeScheduler.java:662)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70), sim=RandomSimilarity(queryNorm=true): {field=DFR I(ne)LZ(0.3), titleTokenized=DFR I(F)L1, body=DFR G3(800.0)}, locale=ar-EG, timezone=Canada/Yukon
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_191 (64-bit)/cpus=3,threads=1,free=95734504,total=287309824
[junit4] 2> NOTE: All tests run in this JVM: [TestCharacterUtils, TestRamUsageEstimator, TestIndexableField, TestByteBlockPool, TestReqExclBulkScorer, TestLogMergePolicy, TestTrackingDirectoryWrapper, TestLongsRef, TestExitableDirectoryReader, TestFixedLengthBytesRefArray, TestLSBRadixSorter, TestTimeLimitingCollector, TestSumDocFreq, TestIndexWriterDeleteByQuery, TestSpanExplanations, TestFrozenBufferedUpdates, TestCloseableThreadLocal, TestNamedSPILoader, TestMultiPhraseEnum, TestField, TestStressIndexing2, TestDocsAndPositions, TestSearch, TestCachingCollector, TestPackedTokenAttributeImpl, TestByteBuffersDirectory, TestFieldValueQuery, TestDocIdSetBuilder, TestFieldUpdatesBuffer, TestIntroSorter, TestReadOnlyIndex, TestTryDelete, TestCrashCausesCorruptIndex, TestDocIdSetIterator, TestCharsRef, FiniteStringsIteratorTest, TestIndexWriterExceptions2, TestNumericUtils, TestPolygon, TestSimpleSearchEquivalence, TestMultiTermConstantScore, TestTermRangeQuery, TestTopDocsMerge, TestMultiDocValues, TestStringHelper, TestDuelingCodecsAtNight, TestSingleInstanceLockFactory, TestGeoEncodingUtils, TestSpanCollection, TestLucene70NormsFormat, TestGrowableByteArrayDataOutput, TestPerFieldPostingsFormat2, TestVirtualMethod, TestSpanFirstQuery, TestLockFactory, TestCheckIndex, TestCodecs, TestFutureArrays, TestIndexWriterConfig, TestDocCount, TestFilterLeafReader, TestNRTThreads, TestLucene50LiveDocsFormat, TestExternalCodecs, TestOrdinalMap, TestShardSearching, TestQueryBuilder, TestNRTReaderCleanup, TestMaxTermFrequency, TestBoolean2ScorerSupplier, TestDirectoryReaderReopen, TestTopFieldCollectorEarlyTermination, TestWordlistLoader, Test4GBStoredFields, TestManyFields, TestSameTokenSamePosition, TestTerms, TestSoftDeletesDirectoryReaderWrapper, TestCustomTermFreq, TestMultiThreadTermVectors, TestNRTCachingDirectory, TestFloatRange, TestCachingTokenFilter, TestSimilarityBase, TestPostingsOffsets, TestPayloadsOnVectors, TestNearSpansOrdered, TestDaciukMihovAutomatonBuilder, TestAssertions, TestBytesRefArray, TestDemoParallelLeafReader, TestControlledRealTimeReopenThread, TestRegExp, Test2BBKDPoints, TestConjunctions, TestConsistentFieldNumbers, TestDocIdsWriter, TestSpanTermQuery, TestIndexWriterFromReader, TestUnicodeUtil, TestAutomaton, TestIndexFileDeleter, TestMatchNoDocsQuery, TestLatLonDocValuesField, TestDateSort, TestIndexWriterOnVMError, TestIsCurrent, TestSortedSetDocValues, TestPrefixQuery, TestArrayUtil, TestAllFilesHaveChecksumFooter, TestSortedSetSortField, TestSloppyMath, TestNormsFieldExistsQuery, TestSpansEnum, TestIndexWriterMaxDocs, TestOmitNorms, TestAutomatonQuery, TestRegexpRandom, TestSearcherManager, TestCharArraySet, TestCharFilter, TestGraphTokenizers, TestReusableStringReader, TestStopFilter, TestToken, TestBytesRefAttImpl, TestCharTermAttributeImpl, TestCodecUtil, TestFastCompressionMode, TestFastDecompressionMode, TestHighCompressionMode, TestBlockPostingsFormat, TestBlockPostingsFormat2, TestBlockPostingsFormat3, TestForUtil, TestLucene50CompoundFormat, TestLucene50StoredFieldsFormatHighCompression, TestLucene50TermVectorsFormat, TestLucene60FieldInfoFormat, TestLucene60PointsFormat, TestIndexedDISI, TestPerFieldDocValuesFormat, TestPerFieldPostingsFormat, TestDateTools, TestDocument, TestDoubleRange, TestFeatureField, TestLatLonPointDistanceSort, TestLongRange, Test2BBinaryDocValues, Test2BDocs, Test2BNumericDocValues, Test2BPoints, Test2BPositions, Test2BPostings, Test2BPostingsBytes, Test2BSortedDocValuesFixedSorted, Test2BSortedDocValuesOrds, Test2BTerms, TestAllFilesCheckIndexHeader, TestAllFilesDetectTruncation, TestAtomicUpdate, TestBagOfPositions, TestBagOfPostings, TestBinaryDocValuesUpdates, TestBinaryTerms, TestByteSlices, TestDocInverterPerFieldErrorInfo, TestDocValues, TestDocValuesFieldUpdates, TestDocValuesIndexing, TestDocumentWriter, TestDocumentsWriterDeleteQueue, TestDocumentsWriterStallControl, TestDuelingCodecs, TestFieldInvertState, TestFieldReuse, TestFilterCodecReader, TestFilterDirectoryReader, TestFlex, TestFlushByRamOrCountsPolicy, TestForTooMuchCloning, TestForceMergeForever, TestIndexManyDocuments, TestIndexReaderClose, TestIndexSorting, TestIndexTooManyDocs, TestIndexWriter, TestIndexWriterCommit, TestIndexWriterMerging, TestIndexWriterNRTIsCurrent, TestIndexWriterOnDiskFull, TestIndexWriterOnJRECrash, TestIndexWriterReader, TestIndexWriterWithThreads]
[junit4] Completed [328/488 (1!)] on J0 in 5.20s, 14 tests, 1 error <<< FAILURES!
[...truncated 63240 lines...]
[asciidoctor:convert] asciidoctor: ERROR: about-this-guide.adoc: line 1: invalid part, must have at least one section (e.g., chapter, appendix, etc.)
[asciidoctor:convert] asciidoctor: ERROR: solr-glossary.adoc: line 1: invalid part, must have at least one section (e.g., chapter, appendix, etc.)
[java] Processed 2458 links (2009 relative) to 3221 anchors in 247 files
[echo] Validated Links & Anchors via: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-ref-guide/bare-bones-html/
-documentation-lint:
[jtidy] Checking for broken html (such as invalid tags)...
[delete] Deleting directory /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/jtidy_tmp
[echo] Checking for broken links...
[exec]
[exec] Crawl/parse...
[exec]
[exec] Verify...
[echo] Checking for malformed docs...
jar-checksums:
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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
common.resolve:
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
jar-checksums:
[mkdir] Created dir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/null1545486290
[copy] Copying 36 files to /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/null1545486290
[delete] Deleting directory /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/null1545486290
resolve-example:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
resolve-server:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/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 = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
jar-checksums:
[mkdir] Created dir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/null340927417
[copy] Copying 242 files to /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/null340927417
[delete] Deleting directory /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/null340927417
check-working-copy:
[ivy:cachepath] :: resolving dependencies :: org.eclipse.jgit#org.eclipse.jgit-caller;working
[ivy:cachepath] confs: [default]
[ivy:cachepath] found org.eclipse.jgit#org.eclipse.jgit;4.6.0.201612231935-r in public
[ivy:cachepath] found com.jcraft#jsch;0.1.53 in public
[ivy:cachepath] found com.googlecode.javaewah#JavaEWAH;1.1.6 in public
[ivy:cachepath] found org.apache.httpcomponents#httpclient;4.3.6 in public
[ivy:cachepath] found org.apache.httpcomponents#httpcore;4.3.3 in public
[ivy:cachepath] found commons-logging#commons-logging;1.1.3 in public
[ivy:cachepath] found commons-codec#commons-codec;1.6 in public
[ivy:cachepath] found org.slf4j#slf4j-api;1.7.2 in public
[ivy:cachepath] :: resolution report :: resolve 34ms :: artifacts dl 2ms
---------------------------------------------------------------------
| | modules || artifacts |
| conf | number| search|dwnlded|evicted|| number|dwnlded|
---------------------------------------------------------------------
| default | 8 | 0 | 0 | 0 || 8 | 0 |
---------------------------------------------------------------------
[wc-checker] Initializing working copy...
[wc-checker] SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
[wc-checker] SLF4J: Defaulting to no-operation (NOP) logger implementation
[wc-checker] SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details.
[wc-checker] Checking working copy status...
-jenkins-base:
BUILD SUCCESSFUL
Total time: 92 minutes 43 seconds
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[Lucene-Solr-7.x-Solaris] $ /bin/sh -xe /var/tmp/jenkins5265290571413502733.sh
+ mv lucene/build lucene/build.orig
+ mv solr/build solr/build.orig
+ ANT_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
+ export ANT_HOME
+ PATH=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/bin:/export/home/jenkins/tools/java/64bit/jdk1.8.0/bin:/usr/bin:/usr/sbin
+ export PATH
+ python3.4 -u dev-tools/scripts/reproduceJenkinsFailures.py --no-git https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/965/consoleText
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/965/consoleText
[repro] Revision: 28eaf354fb46ba3d5593551c690f4ae17b89ce23
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseParallelGC"
[repro] Repro line: ant test -Dtestcase=TestIndexWriterWithThreads -Dtests.method=testIOExceptionDuringAbortWithThreads -Dtests.seed=4A5A215BC5BEC4EE -Dtests.slow=true -Dtests.locale=ar-EG -Dtests.timezone=Canada/Yukon -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[repro] ant clean
Buildfile: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/build.xml
clean:
clean:
clean:
BUILD SUCCESSFUL
Total time: 0 seconds
[repro] Test suites by module:
[repro] lucene/core
[repro] TestIndexWriterWithThreads
[repro] ant compile-test
Buildfile: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/core/build.xml
-check-git-state:
-git-cleanroot:
-copy-git-state:
[mkdir] Created dir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build
[copy] Copying 1 file to /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build
git-autoclean:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: Apache Ivy 2.4.0 - 20141213170938 :: http://ant.apache.org/ivy/ ::
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
init:
-clover.disable:
-clover.load:
-clover.classpath:
-clover.setup:
clover:
compile-core:
[mkdir] Created dir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java
[javac] Compiling 859 source files to /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java
[javac] /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/core/src/java/org/apache/lucene/index/FrozenBufferedUpdates.java:246: warning: [try] auto-closeable resource finalizer is never referenced in body of corresponding try statement
[javac] try (Closeable finalizer = () -> finishApply(writer, segStates, success.get(), delFiles)) {
[javac] ^
[javac] /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/core/src/java/org/apache/lucene/index/NumericDocValuesFieldUpdates.java:37: warning: [rawtypes] found raw type: AbstractPagedMutable
[javac] private final AbstractPagedMutable values;
[javac] ^
[javac] missing type arguments for generic class AbstractPagedMutable<T>
[javac] where T is a type-variable:
[javac] T extends AbstractPagedMutable<T> declared in class AbstractPagedMutable
[javac] /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/core/src/java/org/apache/lucene/index/NumericDocValuesFieldUpdates.java:41: warning: [rawtypes] found raw type: AbstractPagedMutable
[javac] Iterator(int size, long minValue, AbstractPagedMutable values, PagedMutable docs, long delGen) {
[javac] ^
[javac] missing type arguments for generic class AbstractPagedMutable<T>
[javac] where T is a type-variable:
[javac] T extends AbstractPagedMutable<T> declared in class AbstractPagedMutable
[javac] /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/core/src/java/org/apache/lucene/index/NumericDocValuesFieldUpdates.java:61: warning: [rawtypes] found raw type: AbstractPagedMutable
[javac] private AbstractPagedMutable values;
[javac] ^
[javac] missing type arguments for generic class AbstractPagedMutable<T>
[javac] where T is a type-variable:
[javac] T extends AbstractPagedMutable<T> declared in class AbstractPagedMutable
[javac] Note: Some input files use or override a deprecated API.
[javac] Note: Recompile with -Xlint:deprecation for details.
[javac] 4 warnings
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/util/graph/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/document/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/util/bkd/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/codecs/compressing/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/codecs/lucene60/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/codecs/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/util/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/index/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/util/fst/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/analysis/tokenattributes/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/search/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/codecs/lucene62/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/util/packed/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/util/mutable/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/codecs/lucene70/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/codecs/perfield/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/analysis/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/analysis/standard/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/store/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/codecs/blocktree/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/search/spans/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/search/similarities/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/util/automaton/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/codecs/lucene50/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java/org/apache/lucene/geo/package-info.class
[copy] Copying 3 files to /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/java
compile-test-framework:
-check-git-state:
-git-cleanroot:
-copy-git-state:
git-autoclean:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
init:
compile-lucene-core:
-check-git-state:
-git-cleanroot:
-copy-git-state:
git-autoclean:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
init:
-clover.disable:
-clover.load:
-clover.classpath:
-clover.setup:
clover:
compile-core:
compile-codecs:
-check-git-state:
-git-cleanroot:
-copy-git-state:
git-autoclean:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
common.init:
compile-lucene-core:
init:
-clover.disable:
-clover.load:
-clover.classpath:
-clover.setup:
clover:
compile-core:
[mkdir] Created dir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/codecs/classes/java
[javac] Compiling 67 source files to /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/codecs/classes/java
[javac] Note: Some input files use or override a deprecated API.
[javac] Note: Recompile with -Xlint:deprecation for details.
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/codecs/classes/java/org/apache/lucene/codecs/bloom/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/codecs/classes/java/org/apache/lucene/codecs/memory/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/codecs/classes/java/org/apache/lucene/codecs/simpletext/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/codecs/classes/java/org/apache/lucene/codecs/blockterms/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/codecs/classes/java/org/apache/lucene/codecs/blocktreeords/package-info.class
[copy] Copying 3 files to /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/codecs/classes/java
-clover.disable:
-clover.load:
-clover.classpath:
-clover.setup:
clover:
common.compile-core:
[mkdir] Created dir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/test-framework/classes/java
[javac] Compiling 191 source files to /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/test-framework/classes/java
[javac] /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/test-framework/src/java/org/apache/lucene/store/BaseDirectoryTestCase.java:416: warning: [try] auto-closeable resource output is never referenced in body of corresponding try statement
[javac] try (IndexOutput output = dir.createOutput(fileName, newIOContext(random()))) {
[javac] ^
[javac] /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/test-framework/src/java/org/apache/lucene/store/BaseDirectoryTestCase.java:440: warning: [try] auto-closeable resource input is never referenced in body of corresponding try statement
[javac] try (IndexInput input = dir.openInput(file, newIOContext(random()))) {
[javac] ^
[javac] /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/test-framework/src/java/org/apache/lucene/store/BaseDirectoryTestCase.java:1051: warning: [try] auto-closeable resource out is never referenced in body of corresponding try statement
[javac] try (IndexOutput out = dir.createOutput(name, IOContext.DEFAULT)) {
[javac] ^
[javac] /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/test-framework/src/java/org/apache/lucene/store/BaseDirectoryTestCase.java:1056: warning: [try] auto-closeable resource out is never referenced in body of corresponding try statement
[javac] try (IndexOutput out = dir.createOutput(name, IOContext.DEFAULT)) {
[javac] ^
[javac] Note: Some input files use or override a deprecated API.
[javac] Note: Recompile with -Xlint:deprecation for details.
[javac] 4 warnings
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/test-framework/classes/java/org/apache/lucene/codecs/asserting/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/test-framework/classes/java/org/apache/lucene/codecs/ramonly/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/test-framework/classes/java/org/apache/lucene/mockfile/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/test-framework/classes/java/org/apache/lucene/codecs/cheapbastard/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/test-framework/classes/java/org/apache/lucene/codecs/mockrandom/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/test-framework/classes/java/org/apache/lucene/codecs/cranky/package-info.class
[javac] Creating empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/test-framework/classes/java/org/apache/lucene/codecs/compressing/dummy/package-info.class
[copy] Copying 4 files to /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/test-framework/classes/java
compile-core:
compile-test:
[mkdir] Created dir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/test
[javac] Compiling 496 source files to /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/test
[javac] /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/core/src/test/org/apache/lucene/index/TestFieldUpdatesBuffer.java:158: warning: [unchecked] unchecked cast
[javac] return (T) (rarely() ? value.prepareForApply(random().nextInt(100)) : value);
[javac] ^
[javac] required: T
[javac] found: BinaryDocValuesUpdate
[javac] where T is a type-variable:
[javac] T extends DocValuesUpdate declared in method <T>getRandomUpdate(boolean)
[javac] /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/core/src/test/org/apache/lucene/index/TestFieldUpdatesBuffer.java:163: warning: [unchecked] unchecked cast
[javac] return (T) (rarely() ? value.prepareForApply(random().nextInt(100)) : value);
[javac] ^
[javac] required: T
[javac] found: NumericDocValuesUpdate
[javac] where T is a type-variable:
[javac] T extends DocValuesUpdate declared in method <T>getRandomUpdate(boolean)
[javac] /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/core/src/test/org/apache/lucene/index/TestIndexWriter.java:2708: warning: [try] auto-closeable resource closeable is never referenced in body of corresponding try statement
[javac] Closeable closeable = () -> IOUtils.close(toClose)) {
[javac] ^
[javac] /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/core/src/test/org/apache/lucene/index/TestIndexWriter.java:3667: warning: [try] auto-closeable resource w is never referenced in body of corresponding try statement
[javac] try (IndexWriter w = new IndexWriter(dir, newIndexWriterConfig().setIndexCreatedVersionMajor(previousMajor))) {}
[javac] ^
[javac] /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/core/src/test/org/apache/lucene/index/TestIndexWriter.java:3670: warning: [try] auto-closeable resource w is never referenced in body of corresponding try statement
[javac] try (IndexWriter w = new IndexWriter(dir, newIndexWriterConfig().setOpenMode(openMode).setIndexCreatedVersionMajor(newMajor))) {}
[javac] ^
[javac] Note: Some input files use or override a deprecated API.
[javac] Note: Recompile with -Xlint:deprecation for details.
[javac] 5 warnings
[copy] Copying 3 files to /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/classes/test
BUILD SUCCESSFUL
Total time: 17 seconds
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestIndexWriterWithThreads" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseParallelGC" -Dtests.seed=4A5A215BC5BEC4EE -Dtests.slow=true -Dtests.locale=ar-EG -Dtests.timezone=Canada/Yukon -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
Buildfile: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/core/build.xml
-clover.disable:
ivy-configure:
[ivy:configure] :: Apache Ivy 2.4.0 - 20141213170938 :: http://ant.apache.org/ivy/ ::
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
install-junit4-taskdef:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
resolve-groovy:
[ivy:cachepath] :: resolving dependencies :: org.codehaus.groovy#groovy-all-caller;working
[ivy:cachepath] confs: [default]
[ivy:cachepath] found org.codehaus.groovy#groovy-all;2.4.15 in public
[ivy:cachepath] :: resolution report :: resolve 19ms :: artifacts dl 1ms
---------------------------------------------------------------------
| | modules || artifacts |
| conf | number| search|dwnlded|evicted|| number|dwnlded|
---------------------------------------------------------------------
| default | 1 | 0 | 0 | 0 || 1 | 0 |
---------------------------------------------------------------------
-init-totals:
-test:
[mkdir] Created dir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/test
[junit4:pickseed] Seed property 'tests.seed' already defined: 4A5A215BC5BEC4EE
[mkdir] Created dir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/test/temp
[junit4] <JUnit4> says مرحبا! Master seed: 4A5A215BC5BEC4EE
[junit4] Executing 5 suites with 2 JVMs.
[junit4]
[junit4] Started J1 PID(15331@serv1-vm3).
[junit4] Started J0 PID(15330@serv1-vm3).
[junit4] Suite: org.apache.lucene.index.TestIndexWriterWithThreads
[junit4] OK 4.97s J1 | TestIndexWriterWithThreads.testSoftUpdateSingleDocWithThreads
[junit4] OK 1.22s J1 | TestIndexWriterWithThreads.testUpdateSingleDocWithThreads
[junit4] OK 0.13s J1 | TestIndexWriterWithThreads.testIOExceptionDuringWriteSegmentWithThreads
[junit4] OK 0.02s J1 | TestIndexWriterWithThreads.testIOExceptionDuringAbortOnlyOnce
[junit4] OK 1.07s J1 | TestIndexWriterWithThreads.testCloseWithThreads
[junit4] OK 0.03s J1 | TestIndexWriterWithThreads.testIOExceptionDuringAbortWithThreads
[junit4] OK 0.06s J1 | TestIndexWriterWithThreads.testIOExceptionDuringWriteSegmentWithThreadsOnlyOnce
[junit4] OK 0.01s J1 | TestIndexWriterWithThreads.testOpenTwoIndexWritersOnDifferentThreads
[junit4] OK 2.94s J1 | TestIndexWriterWithThreads.testRollbackAndCommitWithThreads
[junit4] OK 0.02s J1 | TestIndexWriterWithThreads.testIOExceptionDuringWriteSegmentOnlyOnce
[junit4] OK 0.17s J1 | TestIndexWriterWithThreads.testImmediateDiskFullWithThreads
[junit4] OK 0.01s J1 | TestIndexWriterWithThreads.testIOExceptionDuringAbort
[junit4] OK 0.02s J1 | TestIndexWriterWithThreads.testIOExceptionDuringAbortWithThreadsOnlyOnce
[junit4] OK 0.01s J1 | TestIndexWriterWithThreads.testIOExceptionDuringWriteSegment
[junit4] Completed [1/5] on J1 in 11.54s, 14 tests
[junit4]
[junit4] Suite: org.apache.lucene.index.TestIndexWriterWithThreads
[junit4] OK 4.80s J0 | TestIndexWriterWithThreads.testSoftUpdateSingleDocWithThreads
[junit4] OK 0.65s J0 | TestIndexWriterWithThreads.testUpdateSingleDocWithThreads
[junit4] OK 0.12s J0 | TestIndexWriterWithThreads.testIOExceptionDuringWriteSegmentWithThreads
[junit4] OK 0.03s J0 | TestIndexWriterWithThreads.testIOExceptionDuringAbortOnlyOnce
[junit4] OK 1.24s J0 | TestIndexWriterWithThreads.testCloseWithThreads
[junit4] OK 0.24s J0 | TestIndexWriterWithThreads.testIOExceptionDuringAbortWithThreads
[junit4] OK 0.15s J0 | TestIndexWriterWithThreads.testIOExceptionDuringWriteSegmentWithThreadsOnlyOnce
[junit4] OK 0.10s J0 | TestIndexWriterWithThreads.testOpenTwoIndexWritersOnDifferentThreads
[junit4] OK 3.60s J0 | TestIndexWriterWithThreads.testRollbackAndCommitWithThreads
[junit4] OK 0.01s J0 | TestIndexWriterWithThreads.testIOExceptionDuringWriteSegmentOnlyOnce
[junit4] OK 0.05s J0 | TestIndexWriterWithThreads.testImmediateDiskFullWithThreads
[junit4] OK 0.01s J0 | TestIndexWriterWithThreads.testIOExceptionDuringAbort
[junit4] OK 0.04s J0 | TestIndexWriterWithThreads.testIOExceptionDuringAbortWithThreadsOnlyOnce
[junit4] OK 0.02s J0 | TestIndexWriterWithThreads.testIOExceptionDuringWriteSegment
[junit4] Completed [2/5] on J0 in 12.00s, 14 tests
[junit4]
[junit4] Suite: org.apache.lucene.index.TestIndexWriterWithThreads
[junit4] OK 1.18s J0 | TestIndexWriterWithThreads.testSoftUpdateSingleDocWithThreads
[junit4] OK 0.35s J0 | TestIndexWriterWithThreads.testUpdateSingleDocWithThreads
[junit4] OK 0.04s J0 | TestIndexWriterWithThreads.testIOExceptionDuringWriteSegmentWithThreads
[junit4] OK 0.01s J0 | TestIndexWriterWithThreads.testIOExceptionDuringAbortOnlyOnce
[junit4] OK 0.87s J0 | TestIndexWriterWithThreads.testCloseWithThreads
[junit4] OK 0.02s J0 | TestIndexWriterWithThreads.testIOExceptionDuringAbortWithThreads
[junit4] OK 0.02s J0 | TestIndexWriterWithThreads.testIOExceptionDuringWriteSegmentWithThreadsOnlyOnce
[junit4] OK 0.01s J0 | TestIndexWriterWithThreads.testOpenTwoIndexWritersOnDifferentThreads
[junit4] OK 1.96s J0 | TestIndexWriterWithThreads.testRollbackAndCommitWithThreads
[junit4] OK 0.01s J0 | TestIndexWriterWithThreads.testIOExceptionDuringWriteSegmentOnlyOnce
[junit4] OK 0.03s J0 | TestIndexWriterWithThreads.testImmediateDiskFullWithThreads
[junit4] OK 0.01s J0 | TestIndexWriterWithThreads.testIOExceptionDuringAbort
[junit4] OK 0.07s J0 | TestIndexWriterWithThreads.testIOExceptionDuringAbortWithThreadsOnlyOnce
[junit4] OK 0.01s J0 | TestIndexWriterWithThreads.testIOExceptionDuringWriteSegment
[junit4] Completed [3/5] on J0 in 4.79s, 14 tests
[junit4]
[junit4] Suite: org.apache.lucene.index.TestIndexWriterWithThreads
[junit4] OK 1.63s J1 | TestIndexWriterWithThreads.testSoftUpdateSingleDocWithThreads
[junit4] OK 0.56s J1 | TestIndexWriterWithThreads.testUpdateSingleDocWithThreads
[junit4] OK 0.09s J1 | TestIndexWriterWithThreads.testIOExceptionDuringWriteSegmentWithThreads
[junit4] OK 0.01s J1 | TestIndexWriterWithThreads.testIOExceptionDuringAbortOnlyOnce
[junit4] OK 1.03s J1 | TestIndexWriterWithThreads.testCloseWithThreads
[junit4] OK 0.02s J1 | TestIndexWriterWithThreads.testIOExceptionDuringAbortWithThreads
[junit4] OK 0.02s J1 | TestIndexWriterWithThreads.testIOExceptionDuringWriteSegmentWithThreadsOnlyOnce
[junit4] OK 0.01s J1 | TestIndexWriterWithThreads.testOpenTwoIndexWritersOnDifferentThreads
[junit4] OK 2.15s J1 | TestIndexWriterWithThreads.testRollbackAndCommitWithThreads
[junit4] OK 0.01s J1 | TestIndexWriterWithThreads.testIOExceptionDuringWriteSegmentOnlyOnce
[junit4] OK 0.50s J1 | TestIndexWriterWithThreads.testImmediateDiskFullWithThreads
[junit4] OK 0.01s J1 | TestIndexWriterWithThreads.testIOExceptionDuringAbort
[junit4] OK 0.02s J1 | TestIndexWriterWithThreads.testIOExceptionDuringAbortWithThreadsOnlyOnce
[junit4] OK 0.03s J1 | TestIndexWriterWithThreads.testIOExceptionDuringWriteSegment
[junit4] Completed [4/5] on J1 in 6.32s, 14 tests
[junit4]
[junit4] Suite: org.apache.lucene.index.TestIndexWriterWithThreads
[junit4] OK 1.27s J0 | TestIndexWriterWithThreads.testSoftUpdateSingleDocWithThreads
[junit4] OK 0.29s J0 | TestIndexWriterWithThreads.testUpdateSingleDocWithThreads
[junit4] OK 0.09s J0 | TestIndexWriterWithThreads.testIOExceptionDuringWriteSegmentWithThreads
[junit4] OK 0.01s J0 | TestIndexWriterWithThreads.testIOExceptionDuringAbortOnlyOnce
[junit4] OK 0.80s J0 | TestIndexWriterWithThreads.testCloseWithThreads
[junit4] OK 0.12s J0 | TestIndexWriterWithThreads.testIOExceptionDuringAbortWithThreads
[junit4] OK 0.02s J0 | TestIndexWriterWithThreads.testIOExceptionDuringWriteSegmentWithThreadsOnlyOnce
[junit4] OK 0.01s J0 | TestIndexWriterWithThreads.testOpenTwoIndexWritersOnDifferentThreads
[junit4] OK 0.60s J0 | TestIndexWriterWithThreads.testRollbackAndCommitWithThreads
[junit4] OK 0.01s J0 | TestIndexWriterWithThreads.testIOExceptionDuringWriteSegmentOnlyOnce
[junit4] OK 0.02s J0 | TestIndexWriterWithThreads.testImmediateDiskFullWithThreads
[junit4] OK 0.01s J0 | TestIndexWriterWithThreads.testIOExceptionDuringAbort
[junit4] OK 0.01s J0 | TestIndexWriterWithThreads.testIOExceptionDuringAbortWithThreadsOnlyOnce
[junit4] OK 0.02s J0 | TestIndexWriterWithThreads.testIOExceptionDuringWriteSegment
[junit4] Completed [5/5] on J0 in 3.54s, 14 tests
[junit4]
[junit4] JVM J0: 0.70 .. 22.02 = 21.32s
[junit4] JVM J1: 0.69 .. 19.81 = 19.11s
[junit4] Execution time total: 22 seconds
[junit4] Tests summary: 5 suites, 70 tests
[junit4] Could not remove temporary path: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/test/J0 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/test/J0/temp])
[junit4] Could not remove temporary path: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/test/J1 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/build/core/test/J1/temp])
[echo] 5 slowest tests:
[junit4:tophints] 68.26s | org.apache.lucene.codecs.lucene70.TestLucene70DocValuesFormat
[junit4:tophints] 18.85s | org.apache.lucene.index.TestIndexingSequenceNumbers
[junit4:tophints] 11.51s | org.apache.lucene.codecs.perfield.TestPerFieldDocValuesFormat
[junit4:tophints] 11.13s | org.apache.lucene.util.TestTimSorterWorstCase
[junit4:tophints] 10.85s | org.apache.lucene.codecs.lucene50.TestLucene50CompoundFormat
-check-totals:
test-nocompile:
BUILD SUCCESSFUL
Total time: 25 seconds
[repro] Failures:
[repro] 0/5 failed: org.apache.lucene.index.TestIndexWriterWithThreads
[repro] Exiting with code 0
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
mv: cannot access solr/build
+ true
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath$ValidateAntFileMask.hasMatch(FilePath.java:2847)
at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2726)
at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2707)
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3086)
Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to Solaris VBOX
at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1743)
at hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357)
at hudson.remoting.Channel.call(Channel.java:957)
at hudson.FilePath.act(FilePath.java:1072)
at hudson.FilePath.act(FilePath.java:1061)
at hudson.FilePath.validateAntFileMask(FilePath.java:2705)
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:1835)
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:3088)
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:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
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:1074)
at hudson.FilePath.act(FilePath.java:1061)
at hudson.FilePath.validateAntFileMask(FilePath.java:2705)
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:1835)
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/**,**/*_pid*.log". Configuration error?
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all warnings.
<Git Blamer> GIT_COMMIT=28eaf354fb46ba3d5593551c690f4ae17b89ce23, workspace=/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris
[WARNINGS] Computing warning deltas based on reference build #964
Recording test results
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
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)
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2