Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.x/282/
5 tests failed.
FAILED: org.apache.lucene.index.TestIndexSorting.testRandom3
Error Message:
Java heap space
Stack Trace:
java.lang.OutOfMemoryError: Java heap space
at
__randomizedtesting.SeedInfo.seed([977CE2E4FCF62D27:35A4AC3E98040421]:0)
at org.apache.lucene.util.packed.Packed64.<init>(Packed64.java:73)
at
org.apache.lucene.util.packed.PackedInts.getMutable(PackedInts.java:972)
at
org.apache.lucene.util.packed.PackedInts.getMutable(PackedInts.java:939)
at
org.apache.lucene.util.packed.GrowableWriter.ensureCapacity(GrowableWriter.java:80)
at
org.apache.lucene.util.packed.GrowableWriter.set(GrowableWriter.java:88)
at
org.apache.lucene.util.packed.AbstractPagedMutable.set(AbstractPagedMutable.java:98)
at org.apache.lucene.util.fst.NodeHash.addNew(NodeHash.java:152)
at org.apache.lucene.util.fst.NodeHash.rehash(NodeHash.java:169)
at org.apache.lucene.util.fst.NodeHash.add(NodeHash.java:133)
at org.apache.lucene.util.fst.Builder.compileNode(Builder.java:214)
at org.apache.lucene.util.fst.Builder.freezeTail(Builder.java:310)
at org.apache.lucene.util.fst.Builder.add(Builder.java:414)
at
org.apache.lucene.codecs.memory.MemoryDocValuesConsumer.writeFST(MemoryDocValuesConsumer.java:367)
at
org.apache.lucene.codecs.memory.MemoryDocValuesConsumer.addSortedField(MemoryDocValuesConsumer.java:404)
at
org.apache.lucene.codecs.DocValuesConsumer.mergeSortedField(DocValuesConsumer.java:653)
at
org.apache.lucene.codecs.DocValuesConsumer.merge(DocValuesConsumer.java:204)
at
org.apache.lucene.codecs.perfield.PerFieldDocValuesFormat$FieldsWriter.merge(PerFieldDocValuesFormat.java:153)
at
org.apache.lucene.index.SegmentMerger.mergeDocValues(SegmentMerger.java:167)
at org.apache.lucene.index.SegmentMerger.merge(SegmentMerger.java:111)
at
org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:4363)
at org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:3940)
at
org.apache.lucene.index.SerialMergeScheduler.merge(SerialMergeScheduler.java:40)
at org.apache.lucene.index.IndexWriter.maybeMerge(IndexWriter.java:2091)
at
org.apache.lucene.index.IndexWriter.doAfterSegmentFlushed(IndexWriter.java:5004)
at
org.apache.lucene.index.DocumentsWriter$MergePendingEvent.process(DocumentsWriter.java:731)
at
org.apache.lucene.index.IndexWriter.processEvents(IndexWriter.java:5042)
at
org.apache.lucene.index.IndexWriter.processEvents(IndexWriter.java:5033)
at
org.apache.lucene.index.IndexWriter.updateDocument(IndexWriter.java:1582)
at
org.apache.lucene.index.IndexWriter.addDocument(IndexWriter.java:1324)
at
org.apache.lucene.index.TestIndexSorting.testRandom3(TestIndexSorting.java:2229)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
FAILED:
org.apache.solr.cloud.CdcrReplicationDistributedZkTest.testBatchAddsWithDelete
Error Message:
Timeout while trying to assert number of documents @ target_collection
Stack Trace:
java.lang.AssertionError: Timeout while trying to assert number of documents @
target_collection
at
__randomizedtesting.SeedInfo.seed([8D622B05D79CDF:77971B8FADCE9DF3]:0)
at
org.apache.solr.cloud.BaseCdcrDistributedZkTest.assertNumDocs(BaseCdcrDistributedZkTest.java:271)
at
org.apache.solr.cloud.CdcrReplicationDistributedZkTest.testBatchAddsWithDelete(CdcrReplicationDistributedZkTest.java:532)
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:1713)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
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:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
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:745)
Caused by: java.lang.AssertionError: expected:<59> but was:<33>
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at
org.apache.solr.cloud.BaseCdcrDistributedZkTest.assertNumDocs(BaseCdcrDistributedZkTest.java:262)
... 42 more
FAILED:
org.apache.solr.cloud.CdcrReplicationDistributedZkTest.testBatchBoundaries
Error Message:
Timeout while trying to assert number of documents @ target_collection
Stack Trace:
java.lang.AssertionError: Timeout while trying to assert number of documents @
target_collection
at
__randomizedtesting.SeedInfo.seed([8D622B05D79CDF:22A2BB7E7C735D38]:0)
at
org.apache.solr.cloud.BaseCdcrDistributedZkTest.assertNumDocs(BaseCdcrDistributedZkTest.java:271)
at
org.apache.solr.cloud.CdcrReplicationDistributedZkTest.testBatchBoundaries(CdcrReplicationDistributedZkTest.java:563)
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:1713)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
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:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
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:745)
Caused by: java.lang.AssertionError: expected:<128> but was:<71>
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at
org.apache.solr.cloud.BaseCdcrDistributedZkTest.assertNumDocs(BaseCdcrDistributedZkTest.java:262)
... 42 more
FAILED:
org.apache.solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR
Error Message:
There are still nodes recoverying - waited for 330 seconds
Stack Trace:
java.lang.AssertionError: There are still nodes recoverying - waited for 330
seconds
at
__randomizedtesting.SeedInfo.seed([8D622B05D79CDF:DE070C563E75FE2C]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:187)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:144)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:139)
at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:856)
at
org.apache.solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR(LeaderInitiatedRecoveryOnShardRestartTest.java:158)
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:1713)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
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:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
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:745)
FAILED: org.apache.solr.cloud.hdfs.HdfsWriteToMultipleCollectionsTest.test
Error Message:
Stack Trace:
java.lang.AssertionError
at
__randomizedtesting.SeedInfo.seed([8D622B05D79CDF:88D95DF1AB2BF127]:0)
at org.junit.Assert.fail(Assert.java:92)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertTrue(Assert.java:54)
at
org.apache.solr.cloud.hdfs.HdfsWriteToMultipleCollectionsTest.test(HdfsWriteToMultipleCollectionsTest.java:137)
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:1713)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
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:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
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:745)
Build Log:
[...truncated 379 lines...]
[junit4] Suite: org.apache.lucene.index.TestIndexSorting
[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=TestIndexSorting
-Dtests.method=testRandom3 -Dtests.seed=977CE2E4FCF62D27 -Dtests.multiplier=2
-Dtests.nightly=true -Dtests.slow=true
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/test-data/enwiki.random.lines.txt
-Dtests.locale=es-CR -Dtests.timezone=America/Argentina/San_Luis
-Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 171s J2 | TestIndexSorting.testRandom3 <<<
[junit4] > Throwable #1: java.lang.OutOfMemoryError: Java heap space
[junit4] > at
__randomizedtesting.SeedInfo.seed([977CE2E4FCF62D27:35A4AC3E98040421]:0)
[junit4] > at
org.apache.lucene.util.packed.Packed64.<init>(Packed64.java:73)
[junit4] > at
org.apache.lucene.util.packed.PackedInts.getMutable(PackedInts.java:972)
[junit4] > at
org.apache.lucene.util.packed.PackedInts.getMutable(PackedInts.java:939)
[junit4] > at
org.apache.lucene.util.packed.GrowableWriter.ensureCapacity(GrowableWriter.java:80)
[junit4] > at
org.apache.lucene.util.packed.GrowableWriter.set(GrowableWriter.java:88)
[junit4] > at
org.apache.lucene.util.packed.AbstractPagedMutable.set(AbstractPagedMutable.java:98)
[junit4] > at
org.apache.lucene.util.fst.NodeHash.addNew(NodeHash.java:152)
[junit4] > at
org.apache.lucene.util.fst.NodeHash.rehash(NodeHash.java:169)
[junit4] > at
org.apache.lucene.util.fst.NodeHash.add(NodeHash.java:133)
[junit4] > at
org.apache.lucene.util.fst.Builder.compileNode(Builder.java:214)
[junit4] > at
org.apache.lucene.util.fst.Builder.freezeTail(Builder.java:310)
[junit4] > at
org.apache.lucene.util.fst.Builder.add(Builder.java:414)
[junit4] > at
org.apache.lucene.codecs.memory.MemoryDocValuesConsumer.writeFST(MemoryDocValuesConsumer.java:367)
[junit4] > at
org.apache.lucene.codecs.memory.MemoryDocValuesConsumer.addSortedField(MemoryDocValuesConsumer.java:404)
[junit4] > at
org.apache.lucene.codecs.DocValuesConsumer.mergeSortedField(DocValuesConsumer.java:653)
[junit4] > at
org.apache.lucene.codecs.DocValuesConsumer.merge(DocValuesConsumer.java:204)
[junit4] > at
org.apache.lucene.codecs.perfield.PerFieldDocValuesFormat$FieldsWriter.merge(PerFieldDocValuesFormat.java:153)
[junit4] > at
org.apache.lucene.index.SegmentMerger.mergeDocValues(SegmentMerger.java:167)
[junit4] > at
org.apache.lucene.index.SegmentMerger.merge(SegmentMerger.java:111)
[junit4] > at
org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:4363)
[junit4] > at
org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:3940)
[junit4] > at
org.apache.lucene.index.SerialMergeScheduler.merge(SerialMergeScheduler.java:40)
[junit4] > at
org.apache.lucene.index.IndexWriter.maybeMerge(IndexWriter.java:2091)
[junit4] > at
org.apache.lucene.index.IndexWriter.doAfterSegmentFlushed(IndexWriter.java:5004)
[junit4] > at
org.apache.lucene.index.DocumentsWriter$MergePendingEvent.process(DocumentsWriter.java:731)
[junit4] > at
org.apache.lucene.index.IndexWriter.processEvents(IndexWriter.java:5042)
[junit4] > at
org.apache.lucene.index.IndexWriter.processEvents(IndexWriter.java:5033)
[junit4] > at
org.apache.lucene.index.IndexWriter.updateDocument(IndexWriter.java:1582)
[junit4] > at
org.apache.lucene.index.IndexWriter.addDocument(IndexWriter.java:1324)
[junit4] > at
org.apache.lucene.index.TestIndexSorting.testRandom3(TestIndexSorting.java:2229)
[junit4] 2> NOTE: leaving temporary files on disk at:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/lucene/build/core/test/J2/temp/lucene.index.TestIndexSorting_977CE2E4FCF62D27-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62):
{docs=BlockTreeOrds(blocksize=128), norms=BlockTreeOrds(blocksize=128),
positions=PostingsFormat(name=Asserting), id=BlockTreeOrds(blocksize=128),
term_vectors=BlockTreeOrds(blocksize=128)},
docValues:{multi_valued_long=DocValuesFormat(name=Lucene54),
double=DocValuesFormat(name=Memory), foo=DocValuesFormat(name=Lucene54),
numeric=DocValuesFormat(name=Memory), positions=DocValuesFormat(name=Lucene54),
float=DocValuesFormat(name=Direct),
multi_valued_numeric=DocValuesFormat(name=Memory),
int=DocValuesFormat(name=Memory), long=DocValuesFormat(name=Direct),
points=DocValuesFormat(name=Lucene54), sorted=DocValuesFormat(name=Memory),
bar=DocValuesFormat(name=Memory),
multi_valued_double=DocValuesFormat(name=Memory),
docs=DocValuesFormat(name=Memory),
multi_valued_string=DocValuesFormat(name=Memory),
norms=DocValuesFormat(name=Memory), bytes=DocValuesFormat(name=Memory),
binary=DocValuesFormat(name=Memory), id=DocValuesFormat(name=Memory),
multi_valued_int=DocValuesFormat(name=Lucene54),
multi_valued_bytes=DocValuesFormat(name=Memory),
multi_valued_float=DocValuesFormat(name=Lucene54),
term_vectors=DocValuesFormat(name=Memory)}, maxPointsInLeafNode=267,
maxMBSortInHeap=5.490297365896996,
sim=RandomSimilarity(queryNorm=false,coord=yes): {positions=DFR GL2,
id=BM25(k1=1.2,b=0.75), term_vectors=DFR I(ne)BZ(0.3)}, locale=es-CR,
timezone=America/Argentina/San_Luis
[junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation
1.8.0_121 (64-bit)/cpus=4,threads=1,free=230667512,total=332398592
[junit4] 2> NOTE: All tests run in this JVM: [TestTopDocsMerge,
TestBM25Similarity, TestLevenshteinAutomata, TestRamUsageEstimator,
TestSearchWithThreads, TestNumericRangeQuery32, TestMultiPhraseQuery,
TestMatchNoDocsQuery, TestStopFilter, TestEarlyTerminatingSortingCollector,
TestNumericDocValuesUpdates, TestParallelTermEnum, TestPrefixRandom,
TestSortedSetSelector, TestSegmentReader, TestInfoStream, TestIndexSorting]
[junit4] Completed [50/452 (1!)] on J2 in 231.74s, 48 tests, 1 error <<<
FAILURES!
[...truncated 1329 lines...]
[junit4] JVM J2: stdout was not empty, see:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/lucene/build/core/test/temp/junit4-J2-20170213_122706_3652506717803470200019.sysout
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] java.lang.OutOfMemoryError: Java heap space
[junit4] Dumping heap to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/heapdumps/java_pid27858.hprof
...
[junit4] Heap dump file created [439243662 bytes in 4.138 secs]
[junit4] <<< JVM J2: EOF ----
[...truncated 10030 lines...]
[junit4] Suite:
org.apache.solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_8D622B05D79CDF-001/init-core-data-001
[junit4] 2> 963971 INFO
(SUITE-LeaderInitiatedRecoveryOnShardRestartTest-seed#[8D622B05D79CDF]-worker)
[ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-5776)
[junit4] 2> 963971 INFO
(SUITE-LeaderInitiatedRecoveryOnShardRestartTest-seed#[8D622B05D79CDF]-worker)
[ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property:
/
[junit4] 2> 963973 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 963973 INFO (Thread-20312) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 963973 INFO (Thread-20312) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 964073 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.c.ZkTestServer start zk server on port:59925
[junit4] 2> 964085 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 964088 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 964093 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 964095 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 964096 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 964098 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 964099 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 964101 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 964104 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 964105 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 964108 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 964181 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_8D622B05D79CDF-001/control-001/cores/collection1
[junit4] 2> 964182 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 964183 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@21329ac{/,null,AVAILABLE}
[junit4] 2> 964185 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.e.j.s.AbstractConnector Started
ServerConnector@4839e5a5{HTTP/1.1,[http/1.1]}{127.0.0.1:50214}
[junit4] 2> 964185 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.e.j.s.Server Started @967977ms
[junit4] 2> 964185 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_8D622B05D79CDF-001/tempDir-001/control/data,
hostContext=/, hostPort=50214,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_8D622B05D79CDF-001/control-001/cores}
[junit4] 2> 964185 ERROR
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging
may be missing or incomplete.
[junit4] 2> 964185 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™
version 6.5.0
[junit4] 2> 964185 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on
port null
[junit4] 2> 964185 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 964185 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-02-13T15:03:39.353Z
[junit4] 2> 964205 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 964206 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_8D622B05D79CDF-001/control-001/solr.xml
[junit4] 2> 964211 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with
params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 964211 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59925/solr
[junit4] 2> 964241 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[n:127.0.0.1:50214_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 964242 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[n:127.0.0.1:50214_ ] o.a.s.c.OverseerElectionContext I am going to be the
leader 127.0.0.1:50214_
[junit4] 2> 964244 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[n:127.0.0.1:50214_ ] o.a.s.c.Overseer Overseer
(id=97451915289624580-127.0.0.1:50214_-n_0000000000) starting
[junit4] 2> 964260 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[n:127.0.0.1:50214_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:50214_
[junit4] 2> 964265 INFO
(zkCallback-726-thread-1-processing-n:127.0.0.1:50214_) [n:127.0.0.1:50214_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 964329 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[n:127.0.0.1:50214_ ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_8D622B05D79CDF-001/control-001/cores
[junit4] 2> 964329 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[n:127.0.0.1:50214_ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 964338 INFO
(OverseerStateUpdate-97451915289624580-127.0.0.1:50214_-n_0000000000)
[n:127.0.0.1:50214_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard1
[junit4] 2> 965355 WARN
(coreLoadExecutor-1858-thread-1-processing-n:127.0.0.1:50214_)
[n:127.0.0.1:50214_ c:control_collection x:collection1] o.a.s.c.Config
Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory>
instead.
[junit4] 2> 965355 INFO
(coreLoadExecutor-1858-thread-1-processing-n:127.0.0.1:50214_)
[n:127.0.0.1:50214_ c:control_collection x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 6.5.0
[junit4] 2> 965366 INFO
(coreLoadExecutor-1858-thread-1-processing-n:127.0.0.1:50214_)
[n:127.0.0.1:50214_ c:control_collection x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 965456 WARN
(coreLoadExecutor-1858-thread-1-processing-n:127.0.0.1:50214_)
[n:127.0.0.1:50214_ c:control_collection x:collection1] o.a.s.s.IndexSchema
[collection1] default search field in schema is text. WARNING: Deprecated,
please use 'df' on request instead.
[junit4] 2> 965458 INFO
(coreLoadExecutor-1858-thread-1-processing-n:127.0.0.1:50214_)
[n:127.0.0.1:50214_ c:control_collection x:collection1] o.a.s.s.IndexSchema
Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 965477 INFO
(coreLoadExecutor-1858-thread-1-processing-n:127.0.0.1:50214_)
[n:127.0.0.1:50214_ c:control_collection x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection
control_collection
[junit4] 2> 965477 INFO
(coreLoadExecutor-1858-thread-1-processing-n:127.0.0.1:50214_)
[n:127.0.0.1:50214_ c:control_collection x:collection1] o.a.s.c.SolrCore
[[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_8D622B05D79CDF-001/control-001/cores/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_8D622B05D79CDF-001/control-001/cores/collection1/data/]
[junit4] 2> 965478 INFO
(coreLoadExecutor-1858-thread-1-processing-n:127.0.0.1:50214_)
[n:127.0.0.1:50214_ c:control_collection x:collection1]
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@15b780f1
[junit4] 2> 965482 INFO
(coreLoadExecutor-1858-thread-1-processing-n:127.0.0.1:50214_)
[n:127.0.0.1:50214_ c:control_collection x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.MockRandomMergePolicy:
org.apache.lucene.index.MockRandomMergePolicy@7d024cc2
[junit4] 2> 965500 WARN
(coreLoadExecutor-1858-thread-1-processing-n:127.0.0.1:50214_)
[n:127.0.0.1:50214_ c:control_collection x:collection1]
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> 965541 INFO
(coreLoadExecutor-1858-thread-1-processing-n:127.0.0.1:50214_)
[n:127.0.0.1:50214_ c:control_collection x:collection1] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 965541 INFO
(coreLoadExecutor-1858-thread-1-processing-n:127.0.0.1:50214_)
[n:127.0.0.1:50214_ c:control_collection x:collection1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 965542 INFO
(coreLoadExecutor-1858-thread-1-processing-n:127.0.0.1:50214_)
[n:127.0.0.1:50214_ c:control_collection x:collection1] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 965542 INFO
(coreLoadExecutor-1858-thread-1-processing-n:127.0.0.1:50214_)
[n:127.0.0.1:50214_ c:control_collection x:collection1] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 965542 INFO
(coreLoadExecutor-1858-thread-1-processing-n:127.0.0.1:50214_)
[n:127.0.0.1:50214_ c:control_collection x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.MockRandomMergePolicy:
org.apache.lucene.index.MockRandomMergePolicy@4c604a1b
[junit4] 2> 965546 INFO
(coreLoadExecutor-1858-thread-1-processing-n:127.0.0.1:50214_)
[n:127.0.0.1:50214_ c:control_collection x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@d41d29a[collection1] main]
[junit4] 2> 965548 INFO
(coreLoadExecutor-1858-thread-1-processing-n:127.0.0.1:50214_)
[n:127.0.0.1:50214_ c:control_collection x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 965548 INFO
(coreLoadExecutor-1858-thread-1-processing-n:127.0.0.1:50214_)
[n:127.0.0.1:50214_ c:control_collection x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 965548 INFO
(coreLoadExecutor-1858-thread-1-processing-n:127.0.0.1:50214_)
[n:127.0.0.1:50214_ c:control_collection x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 965549 INFO
(searcherExecutor-1859-thread-1-processing-n:127.0.0.1:50214_ x:collection1
c:control_collection) [n:127.0.0.1:50214_ c:control_collection x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@d41d29a[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 965550 INFO
(coreLoadExecutor-1858-thread-1-processing-n:127.0.0.1:50214_)
[n:127.0.0.1:50214_ c:control_collection x:collection1] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1559230646287597568
[junit4] 2> 965562 INFO
(coreZkRegister-1851-thread-1-processing-n:127.0.0.1:50214_ x:collection1
c:control_collection) [n:127.0.0.1:50214_ c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas
found to continue.
[junit4] 2> 965562 INFO
(coreZkRegister-1851-thread-1-processing-n:127.0.0.1:50214_ x:collection1
c:control_collection) [n:127.0.0.1:50214_ c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new
leader - try and sync
[junit4] 2> 965562 INFO
(coreZkRegister-1851-thread-1-processing-n:127.0.0.1:50214_ x:collection1
c:control_collection) [n:127.0.0.1:50214_ c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:50214/collection1/
[junit4] 2> 965562 INFO
(coreZkRegister-1851-thread-1-processing-n:127.0.0.1:50214_ x:collection1
c:control_collection) [n:127.0.0.1:50214_ c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync
replicas to me
[junit4] 2> 965562 INFO
(coreZkRegister-1851-thread-1-processing-n:127.0.0.1:50214_ x:collection1
c:control_collection) [n:127.0.0.1:50214_ c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.SyncStrategy
http://127.0.0.1:50214/collection1/ has no replicas
[junit4] 2> 965572 INFO
(coreZkRegister-1851-thread-1-processing-n:127.0.0.1:50214_ x:collection1
c:control_collection) [n:127.0.0.1:50214_ c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new
leader: http://127.0.0.1:50214/collection1/ shard1
[junit4] 2> 965723 INFO
(coreZkRegister-1851-thread-1-processing-n:127.0.0.1:50214_ x:collection1
c:control_collection) [n:127.0.0.1:50214_ c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery
necessary
[junit4] 2> 965917 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 965919 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at
127.0.0.1:59925/solr ready
[junit4] 2> 965919 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause
connection loss:false
[junit4] 2> 965919 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with
stateFormat=2
[junit4] 2> 966018 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_8D622B05D79CDF-001/shard-1-001/cores/collection1
[junit4] 2> 966020 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_8D622B05D79CDF-001/shard-1-001
[junit4] 2> 966020 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 966029 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@42dea5f{/,null,AVAILABLE}
[junit4] 2> 966029 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.e.j.s.AbstractConnector Started
ServerConnector@77c2954c{HTTP/1.1,[http/1.1]}{127.0.0.1:35745}
[junit4] 2> 966029 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.e.j.s.Server Started @969822ms
[junit4] 2> 966029 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_8D622B05D79CDF-001/tempDir-001/jetty1,
solrconfig=solrconfig.xml, hostContext=/, hostPort=35745,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_8D622B05D79CDF-001/shard-1-001/cores}
[junit4] 2> 966029 ERROR
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging
may be missing or incomplete.
[junit4] 2> 966030 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™
version 6.5.0
[junit4] 2> 966030 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on
port null
[junit4] 2> 966030 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 966030 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-02-13T15:03:41.198Z
[junit4] 2> 966059 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 966059 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_8D622B05D79CDF-001/shard-1-001/solr.xml
[junit4] 2> 966064 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with
params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 966064 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59925/solr
[junit4] 2> 966081 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[n:127.0.0.1:35745_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 966099 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[n:127.0.0.1:35745_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 966100 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[n:127.0.0.1:35745_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:35745_
[junit4] 2> 966101 INFO
(zkCallback-726-thread-3-processing-n:127.0.0.1:50214_) [n:127.0.0.1:50214_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 966102 INFO (zkCallback-730-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 966141 INFO
(zkCallback-736-thread-1-processing-n:127.0.0.1:35745_) [n:127.0.0.1:35745_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 966280 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[n:127.0.0.1:35745_ ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_8D622B05D79CDF-001/shard-1-001/cores
[junit4] 2> 966280 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[n:127.0.0.1:35745_ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 966282 INFO
(OverseerStateUpdate-97451915289624580-127.0.0.1:50214_-n_0000000000)
[n:127.0.0.1:50214_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard1
[junit4] 2> 966388 INFO
(zkCallback-736-thread-2-processing-n:127.0.0.1:35745_) [n:127.0.0.1:35745_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 967341 WARN
(coreLoadExecutor-1869-thread-1-processing-n:127.0.0.1:35745_)
[n:127.0.0.1:35745_ c:collection1 x:collection1] o.a.s.c.Config Beginning
with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 967342 INFO
(coreLoadExecutor-1869-thread-1-processing-n:127.0.0.1:35745_)
[n:127.0.0.1:35745_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 6.5.0
[junit4] 2> 967353 INFO
(coreLoadExecutor-1869-thread-1-processing-n:127.0.0.1:35745_)
[n:127.0.0.1:35745_ c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 967429 WARN
(coreLoadExecutor-1869-thread-1-processing-n:127.0.0.1:35745_)
[n:127.0.0.1:35745_ c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] default search field in schema is text. WARNING: Deprecated,
please use 'df' on request instead.
[junit4] 2> 967431 INFO
(coreLoadExecutor-1869-thread-1-processing-n:127.0.0.1:35745_)
[n:127.0.0.1:35745_ c:collection1 x:collection1] o.a.s.s.IndexSchema Loaded
schema test/1.0 with uniqueid field id
[junit4] 2> 967448 INFO
(coreLoadExecutor-1869-thread-1-processing-n:127.0.0.1:35745_)
[n:127.0.0.1:35745_ c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 967449 INFO
(coreLoadExecutor-1869-thread-1-processing-n:127.0.0.1:35745_)
[n:127.0.0.1:35745_ c:collection1 x:collection1] o.a.s.c.SolrCore
[[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_8D622B05D79CDF-001/shard-1-001/cores/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_8D622B05D79CDF-001/shard-1-001/cores/collection1/data/]
[junit4] 2> 967449 INFO
(coreLoadExecutor-1869-thread-1-processing-n:127.0.0.1:35745_)
[n:127.0.0.1:35745_ c:collection1 x:collection1] o.a.s.c.JmxMonitoredMap JMX
monitoring is enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@15b780f1
[junit4] 2> 967451 INFO
(coreLoadExecutor-1869-thread-1-processing-n:127.0.0.1:35745_)
[n:127.0.0.1:35745_ c:collection1 x:collection1] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy:
org.apache.lucene.index.MockRandomMergePolicy@ab4af8e
[junit4] 2> 967484 WARN
(coreLoadExecutor-1869-thread-1-processing-n:127.0.0.1:35745_)
[n:127.0.0.1:35745_ c:collection1 x:collection1] 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> 967523 INFO
(coreLoadExecutor-1869-thread-1-processing-n:127.0.0.1:35745_)
[n:127.0.0.1:35745_ c:collection1 x:collection1] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 967523 INFO
(coreLoadExecutor-1869-thread-1-processing-n:127.0.0.1:35745_)
[n:127.0.0.1:35745_ c:collection1 x:collection1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 967524 INFO
(coreLoadExecutor-1869-thread-1-processing-n:127.0.0.1:35745_)
[n:127.0.0.1:35745_ c:collection1 x:collection1] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 967524 INFO
(coreLoadExecutor-1869-thread-1-processing-n:127.0.0.1:35745_)
[n:127.0.0.1:35745_ c:collection1 x:collection1] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 967525 INFO
(coreLoadExecutor-1869-thread-1-processing-n:127.0.0.1:35745_)
[n:127.0.0.1:35745_ c:collection1 x:collection1] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy:
org.apache.lucene.index.MockRandomMergePolicy@415b93d3
[junit4] 2> 967528 INFO
(coreLoadExecutor-1869-thread-1-processing-n:127.0.0.1:35745_)
[n:127.0.0.1:35745_ c:collection1 x:collection1] o.a.s.s.SolrIndexSearcher
Opening [Searcher@5a9357a0[collection1] main]
[junit4] 2> 967531 INFO
(coreLoadExecutor-1869-thread-1-processing-n:127.0.0.1:35745_)
[n:127.0.0.1:35745_ c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 967532 INFO
(coreLoadExecutor-1869-thread-1-processing-n:127.0.0.1:35745_)
[n:127.0.0.1:35745_ c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 967532 INFO
(coreLoadExecutor-1869-thread-1-processing-n:127.0.0.1:35745_)
[n:127.0.0.1:35745_ c:collection1 x:collection1] o.a.s.h.ReplicationHandler
Commits will be reserved for 10000
[junit4] 2> 967533 INFO
(searcherExecutor-1870-thread-1-processing-n:127.0.0.1:35745_ x:collection1
c:collection1) [n:127.0.0.1:35745_ c:collection1 x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@5a9357a0[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 967534 INFO
(coreLoadExecutor-1869-thread-1-processing-n:127.0.0.1:35745_)
[n:127.0.0.1:35745_ c:collection1 x:collection1] o.a.s.u.UpdateLog Could not
find max version in index or recent updates, using new clock 1559230648367972352
[junit4] 2> 967541 INFO
(coreZkRegister-1864-thread-1-processing-n:127.0.0.1:35745_ x:collection1
c:collection1) [n:127.0.0.1:35745_ c:collection1 s:shard1 r:core_node1
x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to
continue.
[junit4] 2> 967541 INFO
(coreZkRegister-1864-thread-1-processing-n:127.0.0.1:35745_ x:collection1
c:collection1) [n:127.0.0.1:35745_ c:collection1 s:shard1 r:core_node1
x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try
and sync
[junit4] 2> 967541 INFO
(coreZkRegister-1864-thread-1-processing-n:127.0.0.1:35745_ x:collection1
c:collection1) [n:127.0.0.1:35745_ c:collection1 s:shard1 r:core_node1
x:collection1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:35745/collection1/
[junit4] 2> 967541 INFO
(coreZkRegister-1864-thread-1-processing-n:127.0.0.1:35745_ x:collection1
c:collection1) [n:127.0.0.1:35745_ c:collection1 s:shard1 r:core_node1
x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 967541 INFO
(coreZkRegister-1864-thread-1-processing-n:127.0.0.1:35745_ x:collection1
c:collection1) [n:127.0.0.1:35745_ c:collection1 s:shard1 r:core_node1
x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:35745/collection1/ has no
replicas
[junit4] 2> 967548 INFO
(coreZkRegister-1864-thread-1-processing-n:127.0.0.1:35745_ x:collection1
c:collection1) [n:127.0.0.1:35745_ c:collection1 s:shard1 r:core_node1
x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:35745/collection1/ shard1
[junit4] 2> 967650 INFO
(zkCallback-736-thread-2-processing-n:127.0.0.1:35745_) [n:127.0.0.1:35745_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 967702 INFO
(coreZkRegister-1864-thread-1-processing-n:127.0.0.1:35745_ x:collection1
c:collection1) [n:127.0.0.1:35745_ c:collection1 s:shard1 r:core_node1
x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 967808 INFO
(zkCallback-736-thread-2-processing-n:127.0.0.1:35745_) [n:127.0.0.1:35745_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 967982 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_8D622B05D79CDF-001/shard-2-001/cores/collection1
[junit4] 2> 967982 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_8D622B05D79CDF-001/shard-2-001
[junit4] 2> 967983 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 967984 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@10e1e18d{/,null,AVAILABLE}
[junit4] 2> 967984 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.e.j.s.AbstractConnector Started
ServerConnector@55c40a7e{HTTP/1.1,[http/1.1]}{127.0.0.1:52863}
[junit4] 2> 967984 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.e.j.s.Server Started @971777ms
[junit4] 2> 967984 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_8D622B05D79CDF-001/tempDir-001/jetty2,
solrconfig=solrconfig.xml, hostContext=/, hostPort=52863,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_8D622B05D79CDF-001/shard-2-001/cores}
[junit4] 2> 967984 ERROR
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging
may be missing or incomplete.
[junit4] 2> 967991 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™
version 6.5.0
[junit4] 2> 967991 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on
port null
[junit4] 2> 967991 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 967991 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-02-13T15:03:43.159Z
[junit4] 2> 967996 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 967996 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_8D622B05D79CDF-001/shard-2-001/solr.xml
[junit4] 2> 968001 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with
params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 968001 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59925/solr
[junit4] 2> 968020 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[n:127.0.0.1:52863_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 968026 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[n:127.0.0.1:52863_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 968029 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[n:127.0.0.1:52863_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:52863_
[junit4] 2> 968031 INFO
(zkCallback-726-thread-3-processing-n:127.0.0.1:50214_) [n:127.0.0.1:50214_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 968031 INFO
(zkCallback-736-thread-2-processing-n:127.0.0.1:35745_) [n:127.0.0.1:35745_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 968031 INFO (zkCallback-730-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 968046 INFO
(zkCallback-742-thread-1-processing-n:127.0.0.1:52863_) [n:127.0.0.1:52863_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 968094 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[n:127.0.0.1:52863_ ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_8D622B05D79CDF-001/shard-2-001/cores
[junit4] 2> 968094 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[n:127.0.0.1:52863_ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 968102 INFO
(OverseerStateUpdate-97451915289624580-127.0.0.1:50214_-n_0000000000)
[n:127.0.0.1:50214_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard1
[junit4] 2> 968204 INFO
(zkCallback-736-thread-1-processing-n:127.0.0.1:35745_) [n:127.0.0.1:35745_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 968204 INFO
(zkCallback-742-thread-2-processing-n:127.0.0.1:52863_) [n:127.0.0.1:52863_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 969127 WARN
(coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:52863_)
[n:127.0.0.1:52863_ c:collection1 x:collection1] o.a.s.c.Config Beginning
with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 969127 INFO
(coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:52863_)
[n:127.0.0.1:52863_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 6.5.0
[junit4] 2> 969142 INFO
(coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:52863_)
[n:127.0.0.1:52863_ c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 969225 WARN
(coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:52863_)
[n:127.0.0.1:52863_ c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] default search field in schema is text. WARNING: Deprecated,
please use 'df' on request instead.
[junit4] 2> 969226 INFO
(coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:52863_)
[n:127.0.0.1:52863_ c:collection1 x:collection1] o.a.s.s.IndexSchema Loaded
schema test/1.0 with uniqueid field id
[junit4] 2> 969263 INFO
(coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:52863_)
[n:127.0.0.1:52863_ c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 969263 INFO
(coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:52863_)
[n:127.0.0.1:52863_ c:collection1 x:collection1] o.a.s.c.SolrCore
[[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_8D622B05D79CDF-001/shard-2-001/cores/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_8D622B05D79CDF-001/shard-2-001/cores/collection1/data/]
[junit4] 2> 969264 INFO
(coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:52863_)
[n:127.0.0.1:52863_ c:collection1 x:collection1] o.a.s.c.JmxMonitoredMap JMX
monitoring is enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@15b780f1
[junit4] 2> 969268 INFO
(coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:52863_)
[n:127.0.0.1:52863_ c:collection1 x:collection1] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy:
org.apache.lucene.index.MockRandomMergePolicy@3b67af81
[junit4] 2> 969283 WARN
(coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:52863_)
[n:127.0.0.1:52863_ c:collection1 x:collection1] 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> 969346 INFO
(coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:52863_)
[n:127.0.0.1:52863_ c:collection1 x:collection1] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 969346 INFO
(coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:52863_)
[n:127.0.0.1:52863_ c:collection1 x:collection1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 969347 INFO
(coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:52863_)
[n:127.0.0.1:52863_ c:collection1 x:collection1] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 969347 INFO
(coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:52863_)
[n:127.0.0.1:52863_ c:collection1 x:collection1] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 969354 INFO
(coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:52863_)
[n:127.0.0.1:52863_ c:collection1 x:collection1] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy:
org.apache.lucene.index.MockRandomMergePolicy@75c6b733
[junit4] 2> 969354 INFO
(coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:52863_)
[n:127.0.0.1:52863_ c:collection1 x:collection1] o.a.s.s.SolrIndexSearcher
Opening [Searcher@565956ef[collection1] main]
[junit4] 2> 969355 INFO
(coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:52863_)
[n:127.0.0.1:52863_ c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 969355 INFO
(coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:52863_)
[n:127.0.0.1:52863_ c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 969355 INFO
(coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:52863_)
[n:127.0.0.1:52863_ c:collection1 x:collection1] o.a.s.h.ReplicationHandler
Commits will be reserved for 10000
[junit4] 2> 969356 INFO
(coreLoadExecutor-1880-thread-1-processing-n:127.0.0.1:52863_)
[n:127.0.0.1:52863_ c:collection1 x:collection1] o.a.s.u.UpdateLog Could not
find max version in index or recent updates, using new clock 1559230650278477824
[junit4] 2> 969359 INFO
(searcherExecutor-1881-thread-1-processing-n:127.0.0.1:52863_ x:collection1
c:collection1) [n:127.0.0.1:52863_ c:collection1 x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@565956ef[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 969373 INFO
(coreZkRegister-1875-thread-1-processing-n:127.0.0.1:52863_ x:collection1
c:collection1) [n:127.0.0.1:52863_ c:collection1 s:shard1 r:core_node2
x:collection1] o.a.s.c.ZkController Core needs to recover:collection1
[junit4] 2> 969377 INFO
(updateExecutor-739-thread-1-processing-n:127.0.0.1:52863_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:52863_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.u.DefaultSolrCoreState Running recovery
[junit4] 2> 969392 INFO
(recoveryExecutor-740-thread-1-processing-n:127.0.0.1:52863_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:52863_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy Starting recovery process.
recoveringAfterStartup=true
[junit4] 2> 969396 INFO
(recoveryExecutor-740-thread-1-processing-n:127.0.0.1:52863_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:52863_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy ###### startupVersions=[[]]
[junit4] 2> 969396 INFO
(recoveryExecutor-740-thread-1-processing-n:127.0.0.1:52863_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:52863_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy Begin buffering updates.
core=[collection1]
[junit4] 2> 969396 INFO
(recoveryExecutor-740-thread-1-processing-n:127.0.0.1:52863_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:52863_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.u.UpdateLog Starting to buffer updates.
FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 969396 INFO
(recoveryExecutor-740-thread-1-processing-n:127.0.0.1:52863_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:52863_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy Publishing state of core
[collection1] as recovering, leader is [http://127.0.0.1:35745/collection1/]
and I am [http://127.0.0.1:52863/collection1/]
[junit4] 2> 969419 INFO
(recoveryExecutor-740-thread-1-processing-n:127.0.0.1:52863_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:52863_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy Sending prep recovery
command to [http://127.0.0.1:35745]; [WaitForState:
action=PREPRECOVERY&core=collection1&nodeName=127.0.0.1:52863_&coreNodeName=core_node2&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 969502 INFO
(zkCallback-736-thread-1-processing-n:127.0.0.1:35745_) [n:127.0.0.1:35745_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 969502 INFO
(zkCallback-742-thread-2-processing-n:127.0.0.1:52863_) [n:127.0.0.1:52863_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 969508 INFO (qtp658547280-24084) [n:127.0.0.1:35745_ ]
o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node2, state:
recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true
[junit4] 2> 969509 INFO (qtp658547280-24084) [n:127.0.0.1:35745_ ]
o.a.s.h.a.PrepRecoveryOp Will wait a max of 183 seconds to see collection1
(shard1 of collection1) have state: recovering
[junit4] 2> 969509 INFO (qtp658547280-24084) [n:127.0.0.1:35745_ ]
o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1,
shard=shard1, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader?
true, live=true, checkLive=true, currentState=recovering, localState=active,
nodeName=127.0.0.1:52863_, coreNodeName=core_node2,
onlyIfActiveCheckResult=false, nodeProps:
core_node2:{"core":"collection1","base_url":"http://127.0.0.1:52863","node_name":"127.0.0.1:52863_","state":"recovering"}
[junit4] 2> 969509 INFO (qtp658547280-24084) [n:127.0.0.1:35745_ ]
o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node2, state: recovering,
checkLive: true, onlyIfLeader: true for: 0 seconds.
[junit4] 2> 969509 INFO (qtp658547280-24084) [n:127.0.0.1:35745_ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={nodeName=127.0.0.1:52863_&onlyIfLeaderActive=true&core=collection1&coreNodeName=core_node2&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 969678 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testRestartWithAllInLIR
[junit4] 2> 969678 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish -
collection: collection1 failOnTimeout:true timeout (sec):330
[junit4] 2> 976509 INFO
(recoveryExecutor-740-thread-1-processing-n:127.0.0.1:52863_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:52863_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy Attempting to PeerSync
from [http://127.0.0.1:35745/collection1/] - recoveringAfterStartup=[true]
[junit4] 2> 976510 INFO
(recoveryExecutor-740-thread-1-processing-n:127.0.0.1:52863_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:52863_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.u.PeerSync PeerSync: core=collection1
url=http://127.0.0.1:52863 START replicas=[http://127.0.0.1:35745/collection1/]
nUpdates=100
[junit4] 2> 976514 INFO (qtp658547280-24082) [n:127.0.0.1:35745_
c:collection1 s:shard1 r:core_node1 x:collection1] o.a.s.u.IndexFingerprint
IndexFingerprint millis:0.0 result:{maxVersionSpecified=9223372036854775807,
maxVersionEncountered=0, maxInHash=0, versionsHash=0, numVersions=0, numDocs=0,
maxDoc=0}
[junit4] 2> 976515 INFO (qtp658547280-24082) [n:127.0.0.1:35745_
c:collection1 s:shard1 r:core_node1 x:collection1] o.a.s.c.S.Request
[collection1] webapp= path=/get
params={distrib=false&qt=/get&getFingerprint=9223372036854775807&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 976518 INFO
(recoveryExecutor-740-thread-1-processing-n:127.0.0.1:52863_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:52863_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.u.IndexFingerprint IndexFingerprint
millis:2.0 result:{maxVersionSpecified=9223372036854775807,
maxVersionEncountered=0, maxInHash=0, versionsHash=0, numVersions=0, numDocs=0,
maxDoc=0}
[junit4] 2> 976518 INFO
(recoveryExecutor-740-thread-1-processing-n:127.0.0.1:52863_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:52863_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.u.PeerSync We are already in sync. No need to
do a PeerSync
[junit4] 2> 976518 INFO
(recoveryExecutor-740-thread-1-processing-n:127.0.0.1:52863_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:52863_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 976518 INFO
(recoveryExecutor-740-thread-1-processing-n:127.0.0.1:52863_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:52863_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.u.DirectUpdateHandler2 No uncommitted
changes. Skipping IW.commit.
[junit4] 2> 976519 INFO
(recoveryExecutor-740-thread-1-processing-n:127.0.0.1:52863_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:52863_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 976519 INFO
(recoveryExecutor-740-thread-1-processing-n:127.0.0.1:52863_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:52863_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy PeerSync stage of recovery
was successful.
[junit4] 2> 976519 INFO
(recoveryExecutor-740-thread-1-processing-n:127.0.0.1:52863_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:52863_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy Replaying updates buffered
during PeerSync.
[junit4] 2> 976519 INFO
(recoveryExecutor-740-thread-1-processing-n:127.0.0.1:52863_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:52863_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy No replay needed.
[junit4] 2> 976519 INFO
(recoveryExecutor-740-thread-1-processing-n:127.0.0.1:52863_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:52863_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy Registering as Active
after recovery.
[junit4] 2> 976523 INFO
(zkCallback-736-thread-1-processing-n:127.0.0.1:35745_) [n:127.0.0.1:35745_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 976523 INFO
(zkCallback-742-thread-2-processing-n:127.0.0.1:52863_) [n:127.0.0.1:52863_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 976700 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[8D622B05D79CDF])
[ ] o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection:
collection1
[junit4] 2> 976702 INFO (qtp658547280-24085) [n:127.0.0.1:35745_ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params
name=collection1&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 976711 INFO
(OverseerThreadFactory-1856-thread-1-processing-n:127.0.0.1:50214_)
[n:127.0.0.1:50214_ ] o.a.s.c.OverseerCollectionMessageHandler Executing
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 976727 INFO (qtp658547280-24084) [n:127.0.0.1:35745_ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
[junit4] 2> 976727 INFO (qtp658547280-24084) [n:127.0.0.1:35745_ ]
o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@495ce54f
[junit4] 2> 976733 INFO (qtp267227639-24112) [n:127.0.0.1:52863_ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
[junit4] 2> 976733 INFO (qtp267227639-24112) [n:127.0.0.1:52863_ ]
o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@5b80ac2a
[junit4] 2> 976838 INFO (qtp658547280-24084) [n:127.0.0.1:35745_ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
[junit4] 2> 976845 WARN
(zkCallback-742-thread-2-processing-n:127.0.0.1:52863_) [n:127.0.0.1:52863_
] o.a.s.c.LeaderElector
[junit4] 2> org.apache.solr.common.SolrException: SolrCore not
found:collection1 in []
[junit4] 2> at
org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:292)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:170)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:135)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:56)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:348)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$3.lambda$process$0(SolrZkClient.java:268)
[junit4] 2> at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[junit4] 2> at
java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 976877 INFO (qtp658547280-24084) [n:127.0.0.1:35745_ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={delete
[...truncated too long message...]
ultMBeanServerInterceptor.java:1813)
[junit4] 2> at
com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:430)
[junit4] 2> at
com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit4] 2> at
com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:546)
[junit4] 2> at
org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:81)
[junit4] 2> at
org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stopMBeans(MetricsSourceAdapter.java:226)
[junit4] 2> at
org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stop(MetricsSourceAdapter.java:211)
[junit4] 2> at
org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stopSources(MetricsSystemImpl.java:463)
[junit4] 2> at
org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stop(MetricsSystemImpl.java:213)
[junit4] 2> at
org.apache.hadoop.metrics2.impl.MetricsSystemImpl.shutdown(MetricsSystemImpl.java:594)
[junit4] 2> at
org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdownInstance(DefaultMetricsSystem.java:72)
[junit4] 2> at
org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdown(DefaultMetricsSystem.java:68)
[junit4] 2> at
org.apache.hadoop.hdfs.server.namenode.metrics.NameNodeMetrics.shutdown(NameNodeMetrics.java:171)
[junit4] 2> at
org.apache.hadoop.hdfs.server.namenode.NameNode.stop(NameNode.java:872)
[junit4] 2> at
org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1726)
[junit4] 2> at
org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1705)
[junit4] 2> at
org.apache.solr.cloud.hdfs.HdfsTestUtil.teardownClass(HdfsTestUtil.java:198)
[junit4] 2> at
org.apache.solr.cloud.hdfs.HdfsWriteToMultipleCollectionsTest.teardownClass(HdfsWriteToMultipleCollectionsTest.java:73)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native
Method)
[junit4] 2> at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498)
[junit4] 2> at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
[junit4] 2> at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:870)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
[junit4] 2> at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by: java.lang.NullPointerException
[junit4] 2> at
org.apache.hadoop.hdfs.server.blockmanagement.BlocksMap.size(BlocksMap.java:203)
[junit4] 2> at
org.apache.hadoop.hdfs.server.blockmanagement.BlockManager.getTotalBlocks(BlockManager.java:3370)
[junit4] 2> at
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlocksTotal(FSNamesystem.java:5729)
[junit4] 2> ... 53 more
[junit4] 2> 4665635 INFO
(SUITE-HdfsWriteToMultipleCollectionsTest-seed#[8D622B05D79CDF]-worker) [ ]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsWriteToMultipleCollectionsTest_8D622B05D79CDF-001
[junit4] 2> Feb 13, 2017 4:05:20 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 34 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62):
{rnd_b=PostingsFormat(name=LuceneVarGapDocFreqInterval),
_version_=Lucene50(blocksize=128), a_t=PostingsFormat(name=LuceneFixedGap),
a_i=PostingsFormat(name=LuceneVarGapDocFreqInterval),
id=PostingsFormat(name=LuceneVarGapDocFreqInterval)}, docValues:{},
maxPointsInLeafNode=848, maxMBSortInHeap=7.926673552907783,
sim=RandomSimilarity(queryNorm=true,coord=crazy): {}, locale=he-IL,
timezone=America/Glace_Bay
[junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation
1.8.0_121 (64-bit)/cpus=4,threads=2,free=178134968,total=534249472
[junit4] 2> NOTE: All tests run in this JVM: [V2ApiIntegrationTest,
TestQueryWrapperFilter, TestConfigSets, TestAddFieldRealTimeGet,
TlogReplayBufferedWhileIndexingTest, TestCoreContainer,
BlobRepositoryCloudTest, PeerSyncWithIndexFingerprintCachingTest,
JSONWriterTest, DocValuesTest, TestCloudSchemaless, BlockCacheTest,
TestIntervalFaceting, BlockJoinFacetRandomTest, CreateCollectionCleanupTest,
ConvertedLegacyTest, TestSha256AuthenticationProvider, HttpPartitionTest,
TestRawResponseWriter, TestSolrCloudWithHadoopAuthPlugin,
TestReRankQParserPlugin, AnalysisErrorHandlingTest, SearchHandlerTest,
TestQueryTypes, ClassificationUpdateProcessorIntegrationTest,
TestLuceneMatchVersion, MergeStrategyTest, JvmMetricsTest, MultiTermTest,
TestFieldCacheSort, UpdateRequestProcessorFactoryTest, DeleteShardTest,
OpenCloseCoreStressTest, FileUtilsTest, WordBreakSolrSpellCheckerTest,
UtilsToolTest, TestWordDelimiterFilterFactory, TestSimpleTrackingShardHandler,
SliceStateTest, CopyFieldTest, CoreMergeIndexesAdminHandlerTest,
DirectSolrSpellCheckerTest, TestFieldCacheWithThreads,
TestRequestStatusCollectionAPI, BadCopyFieldTest,
PreAnalyzedUpdateProcessorTest, DistanceUnitsTest, SoftAutoCommitTest,
ZkControllerTest, MigrateRouteKeyTest, ZkSolrClientTest,
TestUniqueKeyFieldResource, IndexSchemaTest, TestHashPartitioner,
HdfsRecoverLeaseTest, TestSearcherReuse, TestFuzzyAnalyzedSuggestions,
DocExpirationUpdateProcessorFactoryTest, ZkCLITest, SolrCmdDistributorTest,
SortByFunctionTest, TestCoreAdminApis, SampleTest,
CdcrReplicationDistributedZkTest, ShufflingReplicaListTransformerTest,
TestPerFieldSimilarityWithDefaultOverride, HdfsUnloadDistributedZkTest,
TestHashQParserPlugin, WrapperMergePolicyFactoryTest,
TestComplexPhraseLeadingWildcard, TestDynamicLoading, HdfsSyncSliceTest,
TestCloudPseudoReturnFields, SmileWriterTest,
DistribDocExpirationUpdateProcessorTest, TestSolrCloudWithKerberosAlt,
TestSolrXml, ConcurrentDeleteAndCreateCollectionTest,
TestNamedUpdateProcessors, SolrIndexSplitterTest,
CollectionTooManyReplicasTest, TestFieldTypeCollectionResource, TestInitParams,
DistributedVersionInfoTest, SharedFSAutoReplicaFailoverUtilsTest,
TestApiFramework, TestTrie, ManagedSchemaRoundRobinCloudTest,
SpellCheckCollatorTest, TestConfigSetsAPIZkFailure, TestSolrIndexConfig,
TestCopyFieldCollectionResource, HighlighterTest, TermVectorComponentTest,
SystemInfoHandlerTest, TestSolr4Spatial2, TestAuthorizationFramework,
RemoteQueryErrorTest, AsyncCallRequestStatusResponseTest,
TestExceedMaxTermLength, TestDistributedGrouping,
OutOfBoxZkACLAndCredentialsProvidersTest, SolrCloudExampleTest,
SpellingQueryConverterTest, TestRTimerTree, TestFastOutputStream,
TestStandardQParsers, TestNumericTerms64, LoggingHandlerTest,
TestCollectionAPI, TestChildDocTransformer, DistributedFacetExistsSmallTest,
MetricUtilsTest, TestClusterStateMutator, SharedFSAutoReplicaFailoverTest,
TestLeaderInitiatedRecoveryThread, TestLocalFSCloudBackupRestore,
TestMiniSolrCloudClusterSSL, TestRandomRequestDistribution,
TestReplicaProperties, TestShortCircuitedRequests,
TestSolrCloudWithDelegationTokens, TestStressLiveNodes,
HdfsBasicDistributedZkTest, HdfsWriteToMultipleCollectionsTest]
[junit4] Completed [543/691 (3!)] on J1 in 57.50s, 1 test, 1 failure <<<
FAILURES!
[...truncated 12164 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/build.xml:783:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/build.xml:727:
Some of the tests produced a heap dump, but did not fail. Maybe a suppressed
OutOfMemoryError? Dumps created:
* java_pid27858.hprof
Total time: 233 minutes 25 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]
