Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-7.x/124/
7 tests failed.
FAILED: org.apache.lucene.search.TestIntRangeFieldQueries.testRandomBig
Error Message:
Java heap space
Stack Trace:
java.lang.OutOfMemoryError: Java heap space
at
__randomizedtesting.SeedInfo.seed([9629583554820A69:117E25BAC5DB76E9]:0)
at java.util.Arrays.copyOf(Arrays.java:3308)
at
org.apache.lucene.codecs.compressing.CompressingStoredFieldsIndexReader.<init>(CompressingStoredFieldsIndexReader.java:106)
at
org.apache.lucene.codecs.compressing.CompressingTermVectorsReader.<init>(CompressingTermVectorsReader.java:125)
at
org.apache.lucene.codecs.compressing.CompressingTermVectorsFormat.vectorsReader(CompressingTermVectorsFormat.java:91)
at
org.apache.lucene.index.SegmentCoreReaders.<init>(SegmentCoreReaders.java:128)
at org.apache.lucene.index.SegmentReader.<init>(SegmentReader.java:78)
at org.apache.lucene.index.CheckIndex.checkIndex(CheckIndex.java:702)
at org.apache.lucene.util.TestUtil.checkIndex(TestUtil.java:300)
at
org.apache.lucene.store.MockDirectoryWrapper.close(MockDirectoryWrapper.java:865)
at org.apache.lucene.util.IOUtils.close(IOUtils.java:89)
at org.apache.lucene.util.IOUtils.close(IOUtils.java:76)
at
org.apache.lucene.search.BaseRangeFieldQueryTestCase.verify(BaseRangeFieldQueryTestCase.java:293)
at
org.apache.lucene.search.BaseRangeFieldQueryTestCase.doTestRandom(BaseRangeFieldQueryTestCase.java:160)
at
org.apache.lucene.search.BaseRangeFieldQueryTestCase.testRandomBig(BaseRangeFieldQueryTestCase.java:75)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
FAILED: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.test
Error Message:
Java heap space
Stack Trace:
java.lang.OutOfMemoryError: Java heap space
FAILED:
junit.framework.TestSuite.org.apache.solr.cloud.autoscaling.sim.TestLargeCluster
Error Message:
1 thread leaked from SUITE scope at
org.apache.solr.cloud.autoscaling.sim.TestLargeCluster: 1) Thread[id=2549,
name=AutoscalingActionExecutor-592-thread-1, state=RUNNABLE,
group=TGRP-TestLargeCluster] at
org.apache.solr.client.solrj.cloud.autoscaling.Policy.setApproxValuesAndSortNodes(Policy.java:345)
at
org.apache.solr.client.solrj.cloud.autoscaling.Suggester.testChangedMatrix(Suggester.java:220)
at
org.apache.solr.client.solrj.cloud.autoscaling.MoveReplicaSuggester.tryEachNode(MoveReplicaSuggester.java:62)
at
org.apache.solr.client.solrj.cloud.autoscaling.MoveReplicaSuggester.init(MoveReplicaSuggester.java:32)
at
org.apache.solr.client.solrj.cloud.autoscaling.Suggester.getSuggestion(Suggester.java:124)
at
org.apache.solr.cloud.autoscaling.ComputePlanAction.process(ComputePlanAction.java:74)
at
org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:296)
at
org.apache.solr.cloud.autoscaling.ScheduledTriggers$$Lambda$375/1694897820.run(Unknown
Source) at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
at java.util.concurrent.FutureTask.run(FutureTask.java:266) at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$$Lambda$114/1664044897.run(Unknown
Source) 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)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE
scope at org.apache.solr.cloud.autoscaling.sim.TestLargeCluster:
1) Thread[id=2549, name=AutoscalingActionExecutor-592-thread-1,
state=RUNNABLE, group=TGRP-TestLargeCluster]
at
org.apache.solr.client.solrj.cloud.autoscaling.Policy.setApproxValuesAndSortNodes(Policy.java:345)
at
org.apache.solr.client.solrj.cloud.autoscaling.Suggester.testChangedMatrix(Suggester.java:220)
at
org.apache.solr.client.solrj.cloud.autoscaling.MoveReplicaSuggester.tryEachNode(MoveReplicaSuggester.java:62)
at
org.apache.solr.client.solrj.cloud.autoscaling.MoveReplicaSuggester.init(MoveReplicaSuggester.java:32)
at
org.apache.solr.client.solrj.cloud.autoscaling.Suggester.getSuggestion(Suggester.java:124)
at
org.apache.solr.cloud.autoscaling.ComputePlanAction.process(ComputePlanAction.java:74)
at
org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:296)
at
org.apache.solr.cloud.autoscaling.ScheduledTriggers$$Lambda$375/1694897820.run(Unknown
Source)
at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$$Lambda$114/1664044897.run(Unknown
Source)
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)
at __randomizedtesting.SeedInfo.seed([6F4E12AC6FF7631]:0)
FAILED:
junit.framework.TestSuite.org.apache.solr.cloud.autoscaling.sim.TestLargeCluster
Error Message:
There are still zombie threads that couldn't be terminated: 1)
Thread[id=2549, name=AutoscalingActionExecutor-592-thread-1, state=RUNNABLE,
group=TGRP-TestLargeCluster] at
org.apache.solr.client.solrj.cloud.autoscaling.Policy.lambda$setApproxValuesAndSortNodes$10(Policy.java:344)
at
org.apache.solr.client.solrj.cloud.autoscaling.Policy$$Lambda$403/1051050449.compare(Unknown
Source) at
java.util.TimSort.countRunAndMakeAscending(TimSort.java:360) at
java.util.TimSort.sort(TimSort.java:234) at
java.util.Arrays.sort(Arrays.java:1512) at
java.util.ArrayList.sort(ArrayList.java:1454) at
java.util.Collections.sort(Collections.java:175) at
org.apache.solr.client.solrj.cloud.autoscaling.Policy.setApproxValuesAndSortNodes(Policy.java:344)
at
org.apache.solr.client.solrj.cloud.autoscaling.Suggester.testChangedMatrix(Suggester.java:220)
at
org.apache.solr.client.solrj.cloud.autoscaling.MoveReplicaSuggester.tryEachNode(MoveReplicaSuggester.java:62)
at
org.apache.solr.client.solrj.cloud.autoscaling.MoveReplicaSuggester.init(MoveReplicaSuggester.java:32)
at
org.apache.solr.client.solrj.cloud.autoscaling.Suggester.getSuggestion(Suggester.java:124)
at
org.apache.solr.cloud.autoscaling.ComputePlanAction.process(ComputePlanAction.java:74)
at
org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:296)
at
org.apache.solr.cloud.autoscaling.ScheduledTriggers$$Lambda$375/1694897820.run(Unknown
Source) at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
at java.util.concurrent.FutureTask.run(FutureTask.java:266) at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$$Lambda$114/1664044897.run(Unknown
Source) 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)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie
threads that couldn't be terminated:
1) Thread[id=2549, name=AutoscalingActionExecutor-592-thread-1,
state=RUNNABLE, group=TGRP-TestLargeCluster]
at
org.apache.solr.client.solrj.cloud.autoscaling.Policy.lambda$setApproxValuesAndSortNodes$10(Policy.java:344)
at
org.apache.solr.client.solrj.cloud.autoscaling.Policy$$Lambda$403/1051050449.compare(Unknown
Source)
at java.util.TimSort.countRunAndMakeAscending(TimSort.java:360)
at java.util.TimSort.sort(TimSort.java:234)
at java.util.Arrays.sort(Arrays.java:1512)
at java.util.ArrayList.sort(ArrayList.java:1454)
at java.util.Collections.sort(Collections.java:175)
at
org.apache.solr.client.solrj.cloud.autoscaling.Policy.setApproxValuesAndSortNodes(Policy.java:344)
at
org.apache.solr.client.solrj.cloud.autoscaling.Suggester.testChangedMatrix(Suggester.java:220)
at
org.apache.solr.client.solrj.cloud.autoscaling.MoveReplicaSuggester.tryEachNode(MoveReplicaSuggester.java:62)
at
org.apache.solr.client.solrj.cloud.autoscaling.MoveReplicaSuggester.init(MoveReplicaSuggester.java:32)
at
org.apache.solr.client.solrj.cloud.autoscaling.Suggester.getSuggestion(Suggester.java:124)
at
org.apache.solr.cloud.autoscaling.ComputePlanAction.process(ComputePlanAction.java:74)
at
org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:296)
at
org.apache.solr.cloud.autoscaling.ScheduledTriggers$$Lambda$375/1694897820.run(Unknown
Source)
at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$$Lambda$114/1664044897.run(Unknown
Source)
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)
at __randomizedtesting.SeedInfo.seed([6F4E12AC6FF7631]:0)
FAILED: org.apache.solr.client.solrj.impl.CloudSolrClientTest.testRouting
Error Message:
Could not load collection from ZK: collection1
Stack Trace:
org.apache.solr.common.SolrException: Could not load collection from ZK:
collection1
at
__randomizedtesting.SeedInfo.seed([4871E8FE57C7E3E9:8AC6D49654871391]:0)
at
org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1108)
at
org.apache.solr.common.cloud.ZkStateReader$LazyCollectionRef.get(ZkStateReader.java:647)
at
org.apache.solr.common.cloud.ClusterState.getCollectionOrNull(ClusterState.java:137)
at
org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:117)
at
org.apache.solr.client.solrj.impl.CloudSolrClientTest.testRouting(CloudSolrClientTest.java:308)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
org.junit.rules.ExpectedException$ExpectedExceptionStatement.evaluate(ExpectedException.java:110)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException:
KeeperErrorCode = Session expired for /collections/collection1/state.json
at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1212)
at
org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:339)
at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
at
org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:339)
at
org.apache.solr.common.cloud.ZkStateReader.fetchCollectionState(ZkStateReader.java:1120)
at
org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1106)
... 44 more
FAILED: org.apache.solr.cloud.autoscaling.sim.TestLargeCluster.testNodeLost
Error Message:
no IGNORED events
Stack Trace:
java.lang.AssertionError: no IGNORED events
at
__randomizedtesting.SeedInfo.seed([6F4E12AC6FF7631:B9E12FD4451513B7]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at
org.apache.solr.cloud.autoscaling.sim.TestLargeCluster.testNodeLost(TestLargeCluster.java:369)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.cloud.SharedFSAutoReplicaFailoverTest.test
Error Message:
Could not load collection from ZK: solrj_collection
Stack Trace:
org.apache.solr.common.SolrException: Could not load collection from ZK:
solrj_collection
at
__randomizedtesting.SeedInfo.seed([6F4E12AC6FF7631:8EA0DEF068031BC9]:0)
at
org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1108)
at
org.apache.solr.common.cloud.ZkStateReader$LazyCollectionRef.get(ZkStateReader.java:647)
at
org.apache.solr.common.cloud.ClusterState.getCollectionOrNull(ClusterState.java:137)
at
org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:117)
at
org.apache.solr.cloud.SharedFSAutoReplicaFailoverTest.assertSliceAndReplicaCount(SharedFSAutoReplicaFailoverTest.java:396)
at
org.apache.solr.cloud.SharedFSAutoReplicaFailoverTest.testBasics(SharedFSAutoReplicaFailoverTest.java:221)
at
org.apache.solr.cloud.SharedFSAutoReplicaFailoverTest.test(SharedFSAutoReplicaFailoverTest.java:144)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:993)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException:
KeeperErrorCode = Session expired for /collections/solrj_collection/state.json
at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1212)
at
org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:339)
at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
at
org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:339)
at
org.apache.solr.common.cloud.ZkStateReader.fetchCollectionState(ZkStateReader.java:1120)
at
org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1106)
... 47 more
Build Log:
[...truncated 2448 lines...]
[junit4] Suite: org.apache.lucene.search.TestIntRangeFieldQueries
[junit4] 2> NOTE: download the large Jenkins line-docs file by running
'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=TestIntRangeFieldQueries -Dtests.method=testRandomBig
-Dtests.seed=9629583554820A69 -Dtests.multiplier=2 -Dtests.nightly=true
-Dtests.slow=true
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/test-data/enwiki.random.lines.txt
-Dtests.locale=sr-Latn-ME -Dtests.timezone=America/Nipigon
-Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 2048s J0 | TestIntRangeFieldQueries.testRandomBig <<<
[junit4] > Throwable #1: java.lang.OutOfMemoryError: Java heap space
[junit4] > at
__randomizedtesting.SeedInfo.seed([9629583554820A69:117E25BAC5DB76E9]:0)
[junit4] > at java.util.Arrays.copyOf(Arrays.java:3308)
[junit4] > at
org.apache.lucene.codecs.compressing.CompressingStoredFieldsIndexReader.<init>(CompressingStoredFieldsIndexReader.java:106)
[junit4] > at
org.apache.lucene.codecs.compressing.CompressingTermVectorsReader.<init>(CompressingTermVectorsReader.java:125)
[junit4] > at
org.apache.lucene.codecs.compressing.CompressingTermVectorsFormat.vectorsReader(CompressingTermVectorsFormat.java:91)
[junit4] > at
org.apache.lucene.index.SegmentCoreReaders.<init>(SegmentCoreReaders.java:128)
[junit4] > at
org.apache.lucene.index.SegmentReader.<init>(SegmentReader.java:78)
[junit4] > at
org.apache.lucene.index.CheckIndex.checkIndex(CheckIndex.java:702)
[junit4] > at
org.apache.lucene.util.TestUtil.checkIndex(TestUtil.java:300)
[junit4] > at
org.apache.lucene.store.MockDirectoryWrapper.close(MockDirectoryWrapper.java:865)
[junit4] > at org.apache.lucene.util.IOUtils.close(IOUtils.java:89)
[junit4] > at org.apache.lucene.util.IOUtils.close(IOUtils.java:76)
[junit4] > at
org.apache.lucene.search.BaseRangeFieldQueryTestCase.verify(BaseRangeFieldQueryTestCase.java:293)
[junit4] > at
org.apache.lucene.search.BaseRangeFieldQueryTestCase.doTestRandom(BaseRangeFieldQueryTestCase.java:160)
[junit4] > at
org.apache.lucene.search.BaseRangeFieldQueryTestCase.testRandomBig(BaseRangeFieldQueryTestCase.java:75)
[junit4] 2> NOTE: leaving temporary files on disk at:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/lucene/build/core/test/J0/temp/lucene.search.TestIntRangeFieldQueries_9629583554820A69-001
[junit4] 2> NOTE: test params are:
codec=FastDecompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST_DECOMPRESSION,
chunkSize=5, maxDocsPerChunk=9, blockSize=1),
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST_DECOMPRESSION,
chunkSize=5, blockSize=1)), sim=RandomSimilarity(queryNorm=false): {},
locale=sr-Latn-ME, timezone=America/Nipigon
[junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation
1.8.0_144 (64-bit)/cpus=4,threads=1,free=408230168,total=452984832
[junit4] 2> NOTE: All tests run in this JVM: [TestNot,
TestPersistentSnapshotDeletionPolicy, TestMultiPhraseQuery, TestReadOnlyIndex,
TestBytesStore, TestIndexableField, TestSimpleSearchEquivalence,
TestSloppyMath, TestTotalHitCountCollector, Test2BBKDPoints,
TestStressIndexing, TestMergePolicyWrapper, TestSimpleExplanations,
TestIndexWriterMaxDocs, TestBinaryDocValuesUpdates, TestMultiTermQueryRewrites,
TestLucene50TermVectorsFormat, TestIndexWriterNRTIsCurrent,
TestMultiThreadTermVectors, TestFieldMaskingSpanQuery, TestIndexReaderClose,
TestCharArrayMap, TestSimpleAttributeImpl, TestBytesRef, TestNumericUtils,
TestBinaryTerms, TestRegexpRandom, TestStandardAnalyzer, TestOmitNorms,
TestCrashCausesCorruptIndex, TestNearSpansOrdered, TestSimpleFSDirectory,
TestNoDeletionPolicy, TestWildcardRandom, TestSloppyPhraseQuery, TestAutomaton,
TestIndexWriterWithThreads, TestPagedBytes, TestCodecHoldsOpenFiles,
TestPerFieldPostingsFormat, TestSpanBoostQuery, TestMatchAllDocsQuery,
TestDocsWithFieldSet, TestIndexWriter, TestRadixSelector, TestBooleanScorer,
TestLucene70SegmentInfoFormat, TestUniqueTermCount, TestBlockPostingsFormat2,
TestDocIDMerger, TestAllFilesCheckIndexHeader, TestCharFilter, TestCodecs,
TestDocsAndPositions, TestBagOfPositions, Test2BPoints, TestLogMergePolicy,
TestStringHelper, TestPerSegmentDeletes, TestBoolean2, TestTermVectorsWriter,
TestDelegatingAnalyzerWrapper, TestMultiTermsEnum, TestSmallFloat,
TestCachingCollector, TestGeoUtils, TestFilterCodecReader,
TestFlushByRamOrCountsPolicy, TestUTF32ToUTF8, TestIndexInput,
TestDocValuesRewriteMethod, TestLongRangeFieldQueries,
TestBooleanMinShouldMatch, TestTermVectors, TestMathUtil,
TestSegmentCacheables, TestPerFieldDocValuesFormat, TestFSTs, TestPackedInts,
TestGraphTokenizers, TestShardSearching, TestIndexWriterExceptions,
TestDirectoryReaderReopen, TestSearchWithThreads,
TestDocumentsWriterStallControl, TestConsistentFieldNumbers, TestLockFactory,
TestSegmentMerger, TestFixedBitSet, TestRegexpRandom2, TestNRTCachingDirectory,
TestFastDecompressionMode, TestFieldCacheRewriteMethod, TestTransactions,
TestCrash, TestPerFieldPostingsFormat2, TestSort, TestFieldsReader,
TestDocValuesIndexing, TestHugeRamFile, TestDeterminizeLexicon, Test2BDocs,
TestCustomNorms, TestPostingsOffsets, TestTransactionRollback, TestFlex,
TestSpanExplanations, TestStressIndexing2, TestRAMDirectory, TestDeterminism,
TestBagOfPostings, TestCharTermAttributeImpl, TestOmitPositions,
TestParallelLeafReader, TestThreadedForceMerge, TestStressNRT,
TestSloppyPhraseQuery2, TestSameTokenSamePosition, TestDocCount,
TestBinaryDocument, TestAutomatonQueryUnicode, TestRecyclingByteBlockAllocator,
TestIsCurrent, TestNoMergeScheduler, TestNamedSPILoader,
TestIndexWriterLockRelease, TestDemo, TestTwoPhaseCommitTool,
TestCloseableThreadLocal, TestIndexWriterOnJRECrash, Test4GBStoredFields,
TestReaderClosed, TestNGramPhraseQuery, TestScoreCachingWrappingScorer,
TestIntsRef, TestIndexCommit, TestTerm, TestIOUtils, Test2BTerms,
TestNewestSegment, TestByteArrayDataInput, Test2BPagedBytes, TestStopFilter,
TestWordlistLoader, TestBytesRefAttImpl, TestPackedTokenAttributeImpl,
TestBlockPostingsFormat, TestLucene50CompoundFormat,
TestLucene50StoredFieldsFormat, TestIndexedDISI, TestGeoEncodingUtils,
TestPolygon2D, TestDemoParallelLeafReader, TestIndexWriterThreadsToSegments,
TestManyFields, TestMixedDocValuesUpdates, TestNRTReaderCleanup,
TestNumericDocValuesUpdates, TestOneMergeWrappingMergePolicy, TestPointValues,
TestSortedSetDocValues, TestSwappedIndexFiles, TestTerms, TestTryDelete,
TestUpgradeIndexMergePolicy, FuzzyTermOnShortTermsTest, TermInSetQueryTest,
TestBlendedTermQuery, TestBoolean2ScorerSupplier, TestBooleanRewrites,
TestBoostQuery, TestConjunctionDISI, TestControlledRealTimeReopenThread,
TestEarlyTermination, TestFilterWeight, TestIndexOrDocValuesQuery,
TestIndexSearcher, TestIntRangeFieldQueries]
[junit4] Completed [457/458 (1!)] on J0 in 2090.73s, 5 tests, 1 error <<<
FAILURES!
[...truncated 1 lines...]
[junit4] JVM J0: stdout was not empty, see:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/lucene/build/core/test/temp/junit4-J0-20180119_080810_7204598280772044064031.sysout
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] java.lang.OutOfMemoryError: Java heap space
[junit4] Dumping heap to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/heapdumps/java_pid10895.hprof
...
[junit4] Heap dump file created [511061391 bytes in 20.813 secs]
[junit4] <<< JVM J0: EOF ----
[...truncated 10732 lines...]
[junit4] Suite: org.apache.solr.cloud.SharedFSAutoReplicaFailoverTest
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_6F4E12AC6FF7631-001/init-core-data-001
[junit4] 2> 0 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[6F4E12AC6FF7631]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 407 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[6F4E12AC6FF7631]-worker) [ ]
o.e.j.u.log Logging initialized @27113ms to org.eclipse.jetty.util.log.Slf4jLog
[junit4] 2> 568 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[6F4E12AC6FF7631]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
[junit4] 2> 600 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[6F4E12AC6FF7631]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 622 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[6F4E12AC6FF7631]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /aw_s/r
[junit4] 2> 12913 WARN
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[6F4E12AC6FF7631]-worker) [ ]
o.a.h.u.NativeCodeLoader Unable to load native-hadoop library for your
platform... using builtin-java classes where applicable
[junit4] 1> Formatting using clusterid: testClusterID
[junit4] 2> 23776 WARN
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[6F4E12AC6FF7631]-worker) [ ]
o.a.h.m.i.MetricsConfig Cannot locate configuration: tried
hadoop-metrics2-namenode.properties,hadoop-metrics2.properties
[junit4] 2> 27052 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[6F4E12AC6FF7631]-worker) [ ]
o.m.log Logging to org.slf4j.impl.Log4jLoggerAdapter(org.mortbay.log) via
org.mortbay.log.Slf4jLog
[junit4] 2> 27135 WARN
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[6F4E12AC6FF7631]-worker) [ ]
o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 32064 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[6F4E12AC6FF7631]-worker) [ ]
o.m.log jetty-6.1.26
[junit4] 2> 33855 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[6F4E12AC6FF7631]-worker) [ ]
o.m.log Extract
jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/hdfs
to ./temp/Jetty_localhost_34136_hdfs____.it8z5v/webapp
[junit4] 2> 42848 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[6F4E12AC6FF7631]-worker) [ ]
o.m.log Started
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:34136
[junit4] 2> 68831 WARN
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[6F4E12AC6FF7631]-worker) [ ]
o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 68932 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[6F4E12AC6FF7631]-worker) [ ]
o.m.log jetty-6.1.26
[junit4] 2> 69645 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[6F4E12AC6FF7631]-worker) [ ]
o.m.log Extract
jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/datanode
to ./temp/Jetty_localhost_33215_datanode____.hbvov7/webapp
[junit4] 2> 71328 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[6F4E12AC6FF7631]-worker) [ ]
o.m.log Started
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:33215
[junit4] 2> 76643 WARN
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[6F4E12AC6FF7631]-worker) [ ]
o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 76645 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[6F4E12AC6FF7631]-worker) [ ]
o.m.log jetty-6.1.26
[junit4] 2> 77020 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[6F4E12AC6FF7631]-worker) [ ]
o.m.log Extract
jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/datanode
to ./temp/Jetty_localhost_36438_datanode____.o2ossj/webapp
[junit4] 2> 78979 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[6F4E12AC6FF7631]-worker) [ ]
o.m.log Started
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:36438
[junit4] 2> 85772 ERROR (DataNode:
[[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_6F4E12AC6FF7631-001/tempDir-001/hdfsBaseDir/data/data3/,
[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_6F4E12AC6FF7631-001/tempDir-001/hdfsBaseDir/data/data4/]]
heartbeating to localhost/127.0.0.1:42530) [ ]
o.a.h.h.s.d.DirectoryScanner
dfs.datanode.directoryscan.throttle.limit.ms.per.sec set to value below 1
ms/sec. Assuming default value of 1000
[junit4] 2> 85978 ERROR (DataNode:
[[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_6F4E12AC6FF7631-001/tempDir-001/hdfsBaseDir/data/data1/,
[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_6F4E12AC6FF7631-001/tempDir-001/hdfsBaseDir/data/data2/]]
heartbeating to localhost/127.0.0.1:42530) [ ]
o.a.h.h.s.d.DirectoryScanner
dfs.datanode.directoryscan.throttle.limit.ms.per.sec set to value below 1
ms/sec. Assuming default value of 1000
[junit4] 2> 88171 INFO (Block report processor) [ ] BlockStateChange
BLOCK* processReport 0x3b22002c24ff87: from storage
DS-d0c45fbc-40e9-4605-b469-97c9e5395df4 node
DatanodeRegistration(127.0.0.1:54556,
datanodeUuid=90b70f1b-36b4-4254-a6bf-8b31ea6802d9, infoPort=36727,
infoSecurePort=0, ipcPort=44484,
storageInfo=lv=-56;cid=testClusterID;nsid=60063143;c=0), blocks: 0,
hasStaleStorage: true, processing time: 14 msecs
[junit4] 2> 88190 INFO (Block report processor) [ ] BlockStateChange
BLOCK* processReport 0x3b22002c24ff87: from storage
DS-e39678f7-a7de-4e42-b1aa-97641e06f00c node
DatanodeRegistration(127.0.0.1:54556,
datanodeUuid=90b70f1b-36b4-4254-a6bf-8b31ea6802d9, infoPort=36727,
infoSecurePort=0, ipcPort=44484,
storageInfo=lv=-56;cid=testClusterID;nsid=60063143;c=0), blocks: 0,
hasStaleStorage: false, processing time: 0 msecs
[junit4] 2> 88279 INFO (Block report processor) [ ] BlockStateChange
BLOCK* processReport 0x3b2200298a80b7: from storage
DS-c7556cfe-e088-4a94-8c4d-5b466828d67a node
DatanodeRegistration(127.0.0.1:60789,
datanodeUuid=bcdfce4b-acbf-48b0-a5b6-c8a6b20a46df, infoPort=33380,
infoSecurePort=0, ipcPort=36378,
storageInfo=lv=-56;cid=testClusterID;nsid=60063143;c=0), blocks: 0,
hasStaleStorage: true, processing time: 1 msecs
[junit4] 2> 88291 INFO (Block report processor) [ ] BlockStateChange
BLOCK* processReport 0x3b2200298a80b7: from storage
DS-f1a5a1fb-8572-4592-b5ea-d5c91eccc6c0 node
DatanodeRegistration(127.0.0.1:60789,
datanodeUuid=bcdfce4b-acbf-48b0-a5b6-c8a6b20a46df, infoPort=33380,
infoSecurePort=0, ipcPort=36378,
storageInfo=lv=-56;cid=testClusterID;nsid=60063143;c=0), blocks: 0,
hasStaleStorage: false, processing time: 0 msecs
[junit4] 2> 94138 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 94218 INFO (Thread-111) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 94218 INFO (Thread-111) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 94695 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.c.ZkTestServer start zk server on port:47805
[junit4] 2> 97980 ERROR (Thread-111) [ ] o.a.z.s.ZooKeeperServer
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action
on ERROR or SHUTDOWN server state changes
[junit4] 2> 98449 INFO (zkConnectionManagerCallback-2-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 99443 INFO (zkConnectionManagerCallback-4-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 99582 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 99744 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1/conf/schema15.xml
to /configs/conf1/schema.xml
[junit4] 2> 99760 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 99793 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 99800 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 99878 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 99931 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 100026 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 100029 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 100032 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 100037 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 100043 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly
asked otherwise
[junit4] 2> 109168 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-22T04:27:37+07:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 110646 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 110646 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 110649 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 110679 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@130c9fa5{/aw_s/r,null,AVAILABLE}
[junit4] 2> 110731 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@71f2832c{HTTP/1.1,[http/1.1]}{127.0.0.1:58656}
[junit4] 2> 110731 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.e.j.s.Server Started @137440ms
[junit4] 2> 110731 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {replicaType=NRT,
hostContext=/aw_s/r, hostPort=58656,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_6F4E12AC6FF7631-001/control-001/cores}
[junit4] 2> 110821 ERROR
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 110830 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.3.0
[junit4] 2> 110830 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 110830 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 110881 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-01-19T20:08:32.947Z
[junit4] 2> 112164 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 112813 INFO (zkConnectionManagerCallback-6-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 112819 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 112821 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_6F4E12AC6FF7631-001/control-001/solr.xml
[junit4] 2> 113394 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay
is ignored
[junit4] 2> 113394 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 114375 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@4b81ee5e, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 115286 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:47805/solr
[junit4] 2> 115371 INFO (zkConnectionManagerCallback-10-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 115555 INFO
(zkConnectionManagerCallback-12-thread-1-processing-n:127.0.0.1:58656_aw_s%2Fr)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.c.ConnectionManager zkClient has
connected
[junit4] 2> 117631 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631])
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 117634 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631])
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.OverseerElectionContext I am going to
be the leader 127.0.0.1:58656_aw_s%2Fr
[junit4] 2> 117645 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631])
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.Overseer Overseer
(id=99378298685423620-127.0.0.1:58656_aw_s%2Fr-n_0000000000) starting
[junit4] 2> 120902 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631])
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:58656_aw_s%2Fr
[junit4] 2> 120957 INFO
(zkCallback-11-thread-1-processing-n:127.0.0.1:58656_aw_s%2Fr)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (0) -> (1)
[junit4] 2> 121311 DEBUG
(OverseerAutoScalingTriggerThread-99378298685423620-127.0.0.1:58656_aw_s%2Fr-n_0000000000)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.OverseerTriggerThread Adding
.autoAddReplicas trigger
[junit4] 2> 121344 DEBUG
(OverseerAutoScalingTriggerThread-99378298685423620-127.0.0.1:58656_aw_s%2Fr-n_0000000000)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 1
[junit4] 2> 123597 DEBUG
(OverseerAutoScalingTriggerThread-99378298685423620-127.0.0.1:58656_aw_s%2Fr-n_0000000000)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:58656_aw_s%2Fr]
[junit4] 2> 123597 DEBUG
(OverseerAutoScalingTriggerThread-99378298685423620-127.0.0.1:58656_aw_s%2Fr-n_0000000000)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion -1
[junit4] 2> 123597 DEBUG
(OverseerAutoScalingTriggerThread-99378298685423620-127.0.0.1:58656_aw_s%2Fr-n_0000000000)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.OverseerTriggerThread Processed
trigger updates upto znodeVersion 1
[junit4] 2> 123831 DEBUG
(OverseerAutoScalingTriggerThread-99378298685423620-127.0.0.1:58656_aw_s%2Fr-n_0000000000)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 123832 DEBUG
(OverseerAutoScalingTriggerThread-99378298685423620-127.0.0.1:58656_aw_s%2Fr-n_0000000000)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 123845 DEBUG (ScheduledTrigger-7-thread-1)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 124039 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631])
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4b81ee5e
[junit4] 2> 124041 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631])
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4b81ee5e
[junit4] 2> 124041 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631])
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4b81ee5e
[junit4] 2> 124267 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631])
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_6F4E12AC6FF7631-001/control-001/cores
[junit4] 2> 124859 DEBUG (ScheduledTrigger-7-thread-1)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 125799 INFO (zkConnectionManagerCallback-18-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 125802 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 125804 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:47805/solr ready
[junit4] 2> 125897 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 126897 DEBUG (ScheduledTrigger-7-thread-1)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 127082 INFO (qtp597733244-193) [n:127.0.0.1:58656_aw_s%2Fr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=1&collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:58656_aw_s%252Fr&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 127292 INFO
(OverseerThreadFactory-9-thread-1-processing-n:127.0.0.1:58656_aw_s%2Fr)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.c.CreateCollectionCmd Create
collection control_collection
[junit4] 2> 127901 DEBUG (ScheduledTrigger-7-thread-1)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 127944 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 127960 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 128282 INFO
(zkCallback-11-thread-1-processing-n:127.0.0.1:58656_aw_s%2Fr)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.c.ZkStateReader A cluster state
change: [WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/control_collection/state.json] for collection
[control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 128927 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 129935 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 130800 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 130949 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 131967 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 132028 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.s.IndexSchema [control_collection_shard1_replica_n1] Schema name=test
[junit4] 2> 132579 WARN (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class
[solr.CurrencyField]. Please consult documentation how to replace it
accordingly.
[junit4] 2> 133053 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 134053 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 135055 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 136011 WARN (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class
[solr.GeoHashField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 136063 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 136128 WARN (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class
[solr.LatLonType]. Please consult documentation how to replace it accordingly.
[junit4] 2> 136648 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 137856 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 138856 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 139782 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1'
using configuration from collection control_collection, trusted=true
[junit4] 2> 139815 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.control_collection.shard1.replica_n1' (registry
'solr.core.control_collection.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4b81ee5e
[junit4] 2> 139893 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.HdfsDirectoryFactory
solr.hdfs.home=hdfs://localhost:42530/solr_hdfs_home
[junit4] 2> 139893 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled
[junit4] 2> 139893 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 139895 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 139914 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore
at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_6F4E12AC6FF7631-001/control-001/cores/control_collection_shard1_replica_n1],
dataDir=[hdfs://localhost:42530/solr_hdfs_home/control_collection/core_node2/data/]
[junit4] 2> 140085 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:42530/solr_hdfs_home/control_collection/core_node2/data/snapshot_metadata
[junit4] 2> 140603 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 140604 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[4194304] will allocate [1] slabs and use ~[4194304] bytes
[junit4] 2> 140631 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.HdfsDirectoryFactory Creating new global HDFS BlockCache
[junit4] 2> 140895 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 141903 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 142905 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 143907 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 144915 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 145686 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 145902 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:42530/solr_hdfs_home/control_collection/core_node2/data
[junit4] 2> 145918 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 146919 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 146971 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:42530/solr_hdfs_home/control_collection/core_node2/data/index
[junit4] 2> 147112 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 147112 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[4194304] will allocate [1] slabs and use ~[4194304] bytes
[junit4] 2> 147160 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 147211 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=42, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=1.240234375,
noCFSRatio=0.5449853061335312]
[junit4] 2> 147931 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 148934 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 149282 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:54556 is added to
blk_1073741825_1001{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-d0c45fbc-40e9-4605-b469-97c9e5395df4:NORMAL:127.0.0.1:54556|RBW],
ReplicaUC[[DISK]DS-f1a5a1fb-8572-4592-b5ea-d5c91eccc6c0:NORMAL:127.0.0.1:60789|RBW]]}
size 0
[junit4] 2> 149304 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60789 is added to
blk_1073741825_1001 size 69
[junit4] 2> 149978 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 150247 WARN (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 150979 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 151983 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 153067 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 153513 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.HdfsUpdateLog
[junit4] 2> 153514 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 153514 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.HdfsUpdateLog Initializing HdfsUpdateLog: tlogDfsReplication=2
[junit4] 2> 153960 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 153960 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 154078 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 154162 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=12, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=70.5302734375,
floorSegmentMB=1.8271484375, forceMergeDeletesPctAllowed=27.28479284063425,
segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.30825794874725976
[junit4] 2> 155034 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@da1f76a[control_collection_shard1_replica_n1] main]
[junit4] 2> 155078 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 155247 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 155357 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 155694 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 155860 INFO
(searcherExecutor-12-thread-1-processing-n:127.0.0.1:58656_aw_s%2Fr
x:control_collection_shard1_replica_n1 s:shard1 c:control_collection)
[n:127.0.0.1:58656_aw_s%2Fr c:control_collection s:shard1
x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore
[control_collection_shard1_replica_n1] Registered new searcher
Searcher@da1f76a[control_collection_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 155870 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1590052842882727936
[junit4] 2> 156081 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 156098 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 156098 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 156098 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:58656/aw_s/r/control_collection_shard1_replica_n1/
[junit4] 2> 156099 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 156099 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SyncStrategy
http://127.0.0.1:58656/aw_s/r/control_collection_shard1_replica_n1/ has no
replicas
[junit4] 2> 156100 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 156246 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:58656/aw_s/r/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 156284 INFO
(zkCallback-11-thread-2-processing-n:127.0.0.1:58656_aw_s%2Fr)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.c.ZkStateReader A cluster state
change: [WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/control_collection/state.json] for collection
[control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 156404 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 156429 INFO (qtp597733244-197) [n:127.0.0.1:58656_aw_s%2Fr
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=28503
[junit4] 2> 156530 INFO
(zkCallback-11-thread-2-processing-n:127.0.0.1:58656_aw_s%2Fr)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.c.ZkStateReader A cluster state
change: [WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/control_collection/state.json] for collection
[control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 157087 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 157475 INFO
(OverseerCollectionConfigSetProcessor-99378298685423620-127.0.0.1:58656_aw_s%2Fr-n_0000000000)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 157492 INFO (qtp597733244-193) [n:127.0.0.1:58656_aw_s%2Fr
] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 157492 INFO (qtp597733244-193) [n:127.0.0.1:58656_aw_s%2Fr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=1&collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:58656_aw_s%252Fr&wt=javabin&version=2}
status=0 QTime=30475
[junit4] 2> 157721 INFO (zkConnectionManagerCallback-22-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 157911 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 157940 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:47805/solr ready
[junit4] 2> 157947 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
loss:false
[junit4] 2> 157960 INFO (qtp597733244-195) [n:127.0.0.1:58656_aw_s%2Fr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=1&collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=2&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 158099 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 158182 INFO
(OverseerThreadFactory-9-thread-2-processing-n:127.0.0.1:58656_aw_s%2Fr)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.c.CreateCollectionCmd Create
collection collection1
[junit4] 2> 158223 WARN
(OverseerThreadFactory-9-thread-2-processing-n:127.0.0.1:58656_aw_s%2Fr)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.c.CreateCollectionCmd It is unusual
to create a collection (collection1) without cores.
[junit4] 2> 158688 INFO (qtp597733244-195) [n:127.0.0.1:58656_aw_s%2Fr
] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 158688 INFO (qtp597733244-195) [n:127.0.0.1:58656_aw_s%2Fr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=1&collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=2&wt=javabin&version=2}
status=0 QTime=728
[junit4] 2> 159103 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 159492 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_6F4E12AC6FF7631-001/shard-1-001
of type NRT
[junit4] 2> 159501 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-22T04:27:37+07:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 159578 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 159578 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 159578 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 159595 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@2ca658fb{/aw_s/r,null,AVAILABLE}
[junit4] 2> 159596 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@3937c86f{HTTP/1.1,[http/1.1]}{127.0.0.1:50074}
[junit4] 2> 159596 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.e.j.s.Server Started @186305ms
[junit4] 2> 159596 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {replicaType=NRT,
solrconfig=solrconfig.xml, hostContext=/aw_s/r, hostPort=50074,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_6F4E12AC6FF7631-001/shard-1-001/cores}
[junit4] 2> 159596 ERROR
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 159596 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.3.0
[junit4] 2> 159597 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 159597 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 159597 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-01-19T20:09:21.713Z
[junit4] 2> 159639 INFO (zkConnectionManagerCallback-24-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 159660 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 159660 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_6F4E12AC6FF7631-001/shard-1-001/solr.xml
[junit4] 2> 159729 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay
is ignored
[junit4] 2> 159730 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 159761 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@4b81ee5e, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 159771 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:47805/solr
[junit4] 2> 160139 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 160175 INFO (zkConnectionManagerCallback-28-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 160477 INFO
(OverseerCollectionConfigSetProcessor-99378298685423620-127.0.0.1:58656_aw_s%2Fr-n_0000000000)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 160569 INFO
(zkConnectionManagerCallback-30-thread-1-processing-n:127.0.0.1:50074_aw_s%2Fr)
[n:127.0.0.1:50074_aw_s%2Fr ] o.a.s.c.c.ConnectionManager zkClient has
connected
[junit4] 2> 160657 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631])
[n:127.0.0.1:50074_aw_s%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (0) -> (1)
[junit4] 2> 160675 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631])
[n:127.0.0.1:50074_aw_s%2Fr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 160691 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631])
[n:127.0.0.1:50074_aw_s%2Fr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:50074_aw_s%2Fr
[junit4] 2> 160701 INFO
(zkCallback-11-thread-2-processing-n:127.0.0.1:58656_aw_s%2Fr)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (1) -> (2)
[junit4] 2> 160955 INFO (zkCallback-21-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 161049 INFO
(zkCallback-29-thread-1-processing-n:127.0.0.1:50074_aw_s%2Fr)
[n:127.0.0.1:50074_aw_s%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (1) -> (2)
[junit4] 2> 161167 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 162543 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 162818 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631])
[n:127.0.0.1:50074_aw_s%2Fr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4b81ee5e
[junit4] 2> 163614 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 164397 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631])
[n:127.0.0.1:50074_aw_s%2Fr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4b81ee5e
[junit4] 2> 164397 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631])
[n:127.0.0.1:50074_aw_s%2Fr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4b81ee5e
[junit4] 2> 164521 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[6F4E12AC6FF7631])
[n:127.0.0.1:50074_aw_s%2Fr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_6F4E12AC6FF7631-001/shard-1-001/cores
[junit4] 2> 164614 DEBUG (ScheduledTrigger-7-thread-2)
[n:127.0.0.1:58656_aw_s%2Fr ] o.a.s.c.a.NodeLostTrigger Running
NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 164745 INFO (qtp591446126-255) [n:127.0.0.1:50074_aw_s%2Fr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with
params
node=127.0.0.1:50074_aw_s%252Fr&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 164762 INFO (OverseerThreadFactory-9-thread-3-processing
[...truncated too long message...]
ica_n2, service url = null, agent id = null] for registry
solr.core.multicollection2.shard2.replica_n2 /
com.codahale.metrics.MetricRegistry@3cc2f70e
[junit4] 2> 635282 INFO (coreCloseExecutor-479-thread-6)
[n:127.0.0.1:58484_solr c:multicollection2 s:shard2 r:core_node4
x:multicollection2_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.multicollection2.shard2.leader,
tag=2093335677
[junit4] 2> 635305 INFO (coreCloseExecutor-479-thread-7)
[n:127.0.0.1:58484_solr c:overwrite s:shard1 r:core_node2
x:overwrite_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.overwrite.shard1.replica_n1, tag=1137784878
[junit4] 2> 635305 INFO (coreCloseExecutor-479-thread-7)
[n:127.0.0.1:58484_solr c:overwrite s:shard1 r:core_node2
x:overwrite_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@2e73032d: rootName =
solr_58484, domain = solr.core.overwrite.shard1.replica_n1, service url = null,
agent id = null] for registry solr.core.overwrite.shard1.replica_n1 /
com.codahale.metrics.MetricRegistry@25767114
[junit4] 2> 635447 INFO (coreCloseExecutor-479-thread-8)
[n:127.0.0.1:58484_solr c:foo s:shard1 r:core_node6 x:foo_shard1_replica_n4]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.foo.shard1.replica_n4, tag=755735937
[junit4] 2> 635448 INFO (coreCloseExecutor-479-thread-8)
[n:127.0.0.1:58484_solr c:foo s:shard1 r:core_node6 x:foo_shard1_replica_n4]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@d4194ec: rootName =
solr_58484, domain = solr.core.foo.shard1.replica_n4, service url = null, agent
id = null] for registry solr.core.foo.shard1.replica_n4 /
com.codahale.metrics.MetricRegistry@7292f5e2
[junit4] 2> 636198 INFO (jetty-closer-40-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=99379113964404744-127.0.0.1:40587_solr-n_0000000000) closing
[junit4] 2> 636198 INFO
(OverseerStateUpdate-99379113964404744-127.0.0.1:40587_solr-n_0000000000)
[n:127.0.0.1:40587_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:40587_solr
[junit4] 2> 636219 WARN
(OverseerAutoScalingTriggerThread-99379113964404744-127.0.0.1:40587_solr-n_0000000000)
[n:127.0.0.1:40587_solr ] o.a.s.c.a.OverseerTriggerThread
OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 636223 INFO (coreCloseExecutor-479-thread-1)
[n:127.0.0.1:58484_solr c:2nd_collection s:shard2 r:core_node4
x:2nd_collection_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.2nd_collection.shard2.leader,
tag=782572263
[junit4] 2> 636223 INFO (coreCloseExecutor-479-thread-9)
[n:127.0.0.1:58484_solr c:stale_state_test_col s:shard1 r:core_node4
x:stale_state_test_col_shard1_replica_n3] o.a.s.m.SolrMetricManager Closing
metric reporters for registry=solr.core.stale_state_test_col.shard1.replica_n3,
tag=1754192103
[junit4] 2> 636223 INFO (coreCloseExecutor-479-thread-9)
[n:127.0.0.1:58484_solr c:stale_state_test_col s:shard1 r:core_node4
x:stale_state_test_col_shard1_replica_n3] o.a.s.m.r.SolrJmxReporter Closing
reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4c3c6fef: rootName
= solr_58484, domain = solr.core.stale_state_test_col.shard1.replica_n3,
service url = null, agent id = null] for registry
solr.core.stale_state_test_col.shard1.replica_n3 /
com.codahale.metrics.MetricRegistry@7e2f687d
[junit4] 2> 636238 INFO (jetty-closer-40-thread-3) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@50bf7a41{/solr,null,UNAVAILABLE}
[junit4] 2> 636250 INFO (coreCloseExecutor-479-thread-3)
[n:127.0.0.1:58484_solr c:localShardsTestColl s:shard2 r:core_node11
x:localShardsTestColl_shard2_replica_n8] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.collection.localShardsTestColl.shard2.leader, tag=541805676
[junit4] 2> 636254 INFO (coreCloseExecutor-479-thread-4)
[n:127.0.0.1:58484_solr c:localShardsTestColl s:shard3 r:core_node17
x:localShardsTestColl_shard3_replica_n14] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.collection.localShardsTestColl.shard3.leader, tag=312063495
[junit4] 2> 636254 INFO (coreCloseExecutor-479-thread-7)
[n:127.0.0.1:58484_solr c:overwrite s:shard1 r:core_node2
x:overwrite_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.overwrite.shard1.leader, tag=1137784878
[junit4] 2> 636254 INFO (coreCloseExecutor-479-thread-9)
[n:127.0.0.1:58484_solr c:stale_state_test_col s:shard1 r:core_node4
x:stale_state_test_col_shard1_replica_n3] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.collection.stale_state_test_col.shard1.leader, tag=1754192103
[junit4] 2> 636286 INFO (jetty-closer-40-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@25f314cb{/solr,null,UNAVAILABLE}
[junit4] 2> 636297 INFO (jetty-closer-40-thread-3) [ ] o.e.j.s.session
Stopped scavenging
[junit4] 2> 636239 INFO
(zkCallback-65-thread-10-processing-n:127.0.0.1:58484_solr)
[n:127.0.0.1:58484_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:58484_solr
[junit4] 2> 636310 INFO (jetty-closer-40-thread-1) [ ] o.e.j.s.session
Stopped scavenging
[junit4] 2> 636386 INFO (coreCloseExecutor-479-thread-8)
[n:127.0.0.1:58484_solr c:foo s:shard1 r:core_node6 x:foo_shard1_replica_n4]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.foo.shard1.leader, tag=755735937
[junit4] 2> 637758 INFO (jetty-closer-40-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@90c9ca3{/solr,null,UNAVAILABLE}
[junit4] 2> 637758 INFO (jetty-closer-40-thread-2) [ ] o.e.j.s.session
Stopped scavenging
[junit4] 2> 637790 ERROR
(SUITE-CloudSolrClientTest-seed#[4871E8FE57C7E3E9]-worker) [ ]
o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper
server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 637791 INFO
(SUITE-CloudSolrClientTest-seed#[4871E8FE57C7E3E9]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:34100 34100
[junit4] 2> 637903 INFO (Thread-104) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:34100 34100
[junit4] 2> 637904 WARN (Thread-104) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 9 /solr/aliases.json
[junit4] 2> 3 /solr/security.json
[junit4] 2> 3 /solr/configs/conf
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 27 /solr/collections/localShardsTestColl/state.json
[junit4] 2> 19 /solr/collections/foo/state.json
[junit4] 2> 17 /solr/collections/collection1/state.json
[junit4] 2> 17 /solr/collections/2nd_collection/state.json
[junit4] 2> 17 /solr/collections/multicollection2/state.json
[junit4] 2> 16
/solr/collections/stale_state_test_col/state.json
[junit4] 2> 15 /solr/collections/multicollection1/state.json
[junit4] 2> 9 /solr/clusterprops.json
[junit4] 2> 9 /solr/clusterstate.json
[junit4] 2> 6 /solr/collections/overwrite/state.json
[junit4] 2> 2
/solr/overseer_elect/election/99379113964404744-127.0.0.1:40587_solr-n_0000000000
[junit4] 2> 2
/solr/collections/foo/leader_elect/shard1/election/99379113964404744-core_node5-n_0000000000
[junit4] 2> 2
/solr/collections/localShardsTestColl/leader_elect/shard3/election/99379113964404744-core_node15-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 9 /solr/live_nodes
[junit4] 2> 9 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.CloudSolrClientTest_4871E8FE57C7E3E9-001
[junit4] 2> Jan 19, 2018 11:38:30 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),
sim=RandomSimilarity(queryNorm=true): {}, locale=ar-LB, timezone=CTT
[junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation
1.8.0_144 (64-bit)/cpus=4,threads=1,free=177692208,total=414711808
[junit4] 2> NOTE: All tests run in this JVM: [PowerEvaluatorTest,
TestCoreAdmin, HyperbolicSineEvaluatorTest, NoOpResponseParserTest,
OperationsTest, LessThanEqualToEvaluatorTest, SolrExampleXMLTest,
HttpSolrClientBuilderTest, LargeVolumeJettyTest, CoalesceEvaluatorTest,
TestSolrJErrorHandling, TangentEvaluatorTest, TestBatchUpdate,
GreaterThanEqualToEvaluatorTest, ArrayEvaluatorTest,
TestCollectionAdminRequest, MultiplyEvaluatorTest, AddEvaluatorTest,
TestValidatingJsonMap, TestJavaBinCodec, ArcCosineEvaluatorTest,
CloudSolrClientBuilderTest, CloudSolrClientTest]
[junit4] Completed [66/146 (1!)] on J2 in 175.11s, 15 tests, 1 error <<<
FAILURES!
[...truncated 7940 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/build.xml:844:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/build.xml:788:
Some of the tests produced a heap dump, but did not fail. Maybe a suppressed
OutOfMemoryError? Dumps created:
* java_pid10895.hprof
* java_pid9281.hprof
Total time: 962 minutes 30 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
