Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-7.x/173/
2 tests failed.
FAILED: org.apache.lucene.index.TestDuelingCodecsAtNight.testBigEquals
Error Message:
this IndexWriter is closed
Stack Trace:
org.apache.lucene.store.AlreadyClosedException: this IndexWriter is closed
at org.apache.lucene.index.IndexWriter.ensureOpen(IndexWriter.java:897)
at org.apache.lucene.index.IndexWriter.ensureOpen(IndexWriter.java:911)
at
org.apache.lucene.index.IndexWriter.updateDocuments(IndexWriter.java:1528)
at
org.apache.lucene.index.IndexWriter.addDocuments(IndexWriter.java:1508)
at
org.apache.lucene.index.RandomIndexWriter.addDocument(RandomIndexWriter.java:141)
at
org.apache.lucene.index.TestDuelingCodecs.createRandomIndex(TestDuelingCodecs.java:139)
at
org.apache.lucene.index.TestDuelingCodecsAtNight.testBigEquals(TestDuelingCodecsAtNight.java:34)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Caused by: java.lang.OutOfMemoryError: Java heap space
at org.apache.lucene.util.fst.FST.<init>(FST.java:342)
at org.apache.lucene.util.fst.FST.<init>(FST.java:274)
at
org.apache.lucene.codecs.memory.MemoryPostingsFormat$TermsReader.<init>(MemoryPostingsFormat.java:884)
at
org.apache.lucene.codecs.memory.MemoryPostingsFormat.fieldsProducer(MemoryPostingsFormat.java:967)
at
org.apache.lucene.codecs.perfield.PerFieldPostingsFormat$FieldsReader.<init>(PerFieldPostingsFormat.java:292)
at
org.apache.lucene.codecs.perfield.PerFieldPostingsFormat.fieldsProducer(PerFieldPostingsFormat.java:372)
at
org.apache.lucene.index.SegmentCoreReaders.<init>(SegmentCoreReaders.java:112)
at org.apache.lucene.index.SegmentReader.<init>(SegmentReader.java:78)
at
org.apache.lucene.index.ReadersAndUpdates.getReader(ReadersAndUpdates.java:208)
at
org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:4588)
at org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:4083)
at
org.apache.lucene.index.ConcurrentMergeScheduler.doMerge(ConcurrentMergeScheduler.java:624)
at
org.apache.lucene.index.ConcurrentMergeScheduler$MergeThread.run(ConcurrentMergeScheduler.java:661)
FAILED:
org.apache.solr.handler.TestReplicationHandler.doTestReplicateAfterCoreReload
Error Message:
expected:<[{indexVersion=1520822454763,generation=2,filelist=[_1.cfe, _1.cfs,
_1.si, _2.cfe, _2.cfs, _2.si, _3.cfe, _3.cfs, _3.si, _4.cfe, _4.cfs, _4.si,
_5.cfe, _5.cfs, _5.si, _6.cfe, _6.cfs, _6.si, segments_2]}]> but
was:<[{indexVersion=1520822454763,generation=2,filelist=[_1.cfe, _1.cfs, _1.si,
_2.cfe, _2.cfs, _2.si, _3.cfe, _3.cfs, _3.si, _4.cfe, _4.cfs, _4.si, _5.cfe,
_5.cfs, _5.si, _6.cfe, _6.cfs, _6.si, segments_2]},
{indexVersion=1520822454763,generation=3,filelist=[_2.cfe, _2.cfs, _2.si,
_3.cfe, _3.cfs, _3.si, _5.cfe, _5.cfs, _5.si, _7.cfe, _7.cfs, _7.si,
segments_3]}]>
Stack Trace:
java.lang.AssertionError:
expected:<[{indexVersion=1520822454763,generation=2,filelist=[_1.cfe, _1.cfs,
_1.si, _2.cfe, _2.cfs, _2.si, _3.cfe, _3.cfs, _3.si, _4.cfe, _4.cfs, _4.si,
_5.cfe, _5.cfs, _5.si, _6.cfe, _6.cfs, _6.si, segments_2]}]> but
was:<[{indexVersion=1520822454763,generation=2,filelist=[_1.cfe, _1.cfs, _1.si,
_2.cfe, _2.cfs, _2.si, _3.cfe, _3.cfs, _3.si, _4.cfe, _4.cfs, _4.si, _5.cfe,
_5.cfs, _5.si, _6.cfe, _6.cfs, _6.si, segments_2]},
{indexVersion=1520822454763,generation=3,filelist=[_2.cfe, _2.cfs, _2.si,
_3.cfe, _3.cfs, _3.si, _5.cfe, _5.cfs, _5.si, _7.cfe, _7.cfs, _7.si,
segments_3]}]>
at
__randomizedtesting.SeedInfo.seed([B84E20F08BA676B5:9D993BC0FBEE78B6]:0)
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:147)
at
org.apache.solr.handler.TestReplicationHandler.doTestReplicateAfterCoreReload(TestReplicationHandler.java:1280)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 1858 lines...]
[junit4] Suite: org.apache.lucene.index.TestDuelingCodecsAtNight
[junit4] 2> مار 11, 2018 5:18:33 م
com.carrotsearch.randomizedtesting.RandomizedRunner$QueueUncaughtExceptionsHandler
uncaughtException
[junit4] 2> WARNING: Uncaught exception in thread: Thread[Lucene Merge
Thread #33,5,TGRP-TestDuelingCodecsAtNight]
[junit4] 2> org.apache.lucene.index.MergePolicy$MergeException:
org.apache.lucene.store.AlreadyClosedException: refusing to delete any files:
this IndexWriter hit an unrecoverable exception
[junit4] 2> at
__randomizedtesting.SeedInfo.seed([5D847244DCE419B1]:0)
[junit4] 2> at
org.apache.lucene.index.ConcurrentMergeScheduler.handleMergeException(ConcurrentMergeScheduler.java:703)
[junit4] 2> at
org.apache.lucene.index.ConcurrentMergeScheduler$MergeThread.run(ConcurrentMergeScheduler.java:683)
[junit4] 2> Caused by: org.apache.lucene.store.AlreadyClosedException:
refusing to delete any files: this IndexWriter hit an unrecoverable exception
[junit4] 2> at
org.apache.lucene.index.IndexFileDeleter.ensureOpen(IndexFileDeleter.java:345)
[junit4] 2> at
org.apache.lucene.index.IndexFileDeleter.deleteFiles(IndexFileDeleter.java:697)
[junit4] 2> at
org.apache.lucene.index.IndexFileDeleter.deleteNewFiles(IndexFileDeleter.java:692)
[junit4] 2> at
org.apache.lucene.index.IndexWriter.deleteNewFiles(IndexWriter.java:5030)
[junit4] 2> at
org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:4529)
[junit4] 2> at
org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:4083)
[junit4] 2> at
org.apache.lucene.index.ConcurrentMergeScheduler.doMerge(ConcurrentMergeScheduler.java:624)
[junit4] 2> at
org.apache.lucene.index.ConcurrentMergeScheduler$MergeThread.run(ConcurrentMergeScheduler.java:661)
[junit4] 2> Caused by: java.lang.OutOfMemoryError: Java heap space
[junit4] 2> at org.apache.lucene.util.fst.FST.<init>(FST.java:342)
[junit4] 2> at org.apache.lucene.util.fst.FST.<init>(FST.java:274)
[junit4] 2> at
org.apache.lucene.codecs.memory.MemoryPostingsFormat$TermsReader.<init>(MemoryPostingsFormat.java:884)
[junit4] 2> at
org.apache.lucene.codecs.memory.MemoryPostingsFormat.fieldsProducer(MemoryPostingsFormat.java:967)
[junit4] 2> at
org.apache.lucene.codecs.perfield.PerFieldPostingsFormat$FieldsReader.<init>(PerFieldPostingsFormat.java:292)
[junit4] 2> at
org.apache.lucene.codecs.perfield.PerFieldPostingsFormat.fieldsProducer(PerFieldPostingsFormat.java:372)
[junit4] 2> at
org.apache.lucene.index.SegmentCoreReaders.<init>(SegmentCoreReaders.java:112)
[junit4] 2> at
org.apache.lucene.index.SegmentReader.<init>(SegmentReader.java:78)
[junit4] 2> at
org.apache.lucene.index.ReadersAndUpdates.getReader(ReadersAndUpdates.java:208)
[junit4] 2> at
org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:4588)
[junit4] 2> ... 3 more
[junit4] 2>
[junit4] 2> مار 11, 2018 5:18:34 م
com.carrotsearch.randomizedtesting.RandomizedRunner$QueueUncaughtExceptionsHandler
uncaughtException
[junit4] 2> WARNING: Uncaught exception in thread: Thread[Lucene Merge
Thread #24,5,TGRP-TestDuelingCodecsAtNight]
[junit4] 2> org.apache.lucene.index.MergePolicy$MergeException:
java.lang.OutOfMemoryError: Java heap space
[junit4] 2> at
__randomizedtesting.SeedInfo.seed([5D847244DCE419B1]:0)
[junit4] 2> at
org.apache.lucene.index.ConcurrentMergeScheduler.handleMergeException(ConcurrentMergeScheduler.java:703)
[junit4] 2> at
org.apache.lucene.index.ConcurrentMergeScheduler$MergeThread.run(ConcurrentMergeScheduler.java:683)
[junit4] 2> Caused by: java.lang.OutOfMemoryError: Java heap space
[junit4] 2> at org.apache.lucene.util.fst.FST.<init>(FST.java:342)
[junit4] 2> at org.apache.lucene.util.fst.FST.<init>(FST.java:274)
[junit4] 2> at
org.apache.lucene.codecs.memory.MemoryPostingsFormat$TermsReader.<init>(MemoryPostingsFormat.java:884)
[junit4] 2> at
org.apache.lucene.codecs.memory.MemoryPostingsFormat.fieldsProducer(MemoryPostingsFormat.java:967)
[junit4] 2> at
org.apache.lucene.codecs.perfield.PerFieldPostingsFormat$FieldsReader.<init>(PerFieldPostingsFormat.java:292)
[junit4] 2> at
org.apache.lucene.codecs.perfield.PerFieldPostingsFormat.fieldsProducer(PerFieldPostingsFormat.java:372)
[junit4] 2> at
org.apache.lucene.index.SegmentCoreReaders.<init>(SegmentCoreReaders.java:112)
[junit4] 2> at
org.apache.lucene.index.SegmentReader.<init>(SegmentReader.java:78)
[junit4] 2> at
org.apache.lucene.index.ReadersAndUpdates.getReader(ReadersAndUpdates.java:208)
[junit4] 2> at
org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:4588)
[junit4] 2> at
org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:4083)
[junit4] 2> at
org.apache.lucene.index.ConcurrentMergeScheduler.doMerge(ConcurrentMergeScheduler.java:624)
[junit4] 2> at
org.apache.lucene.index.ConcurrentMergeScheduler$MergeThread.run(ConcurrentMergeScheduler.java:661)
[junit4] 2>
[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=TestDuelingCodecsAtNight -Dtests.method=testBigEquals
-Dtests.seed=5D847244DCE419B1 -Dtests.multiplier=2 -Dtests.nightly=true
-Dtests.slow=true
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/test-data/enwiki.random.lines.txt
-Dtests.locale=ar-YE -Dtests.timezone=America/Los_Angeles -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] ERROR 728s J1 | TestDuelingCodecsAtNight.testBigEquals <<<
[junit4] > Throwable #1: org.apache.lucene.store.AlreadyClosedException:
this IndexWriter is closed
[junit4] > at
org.apache.lucene.index.IndexWriter.ensureOpen(IndexWriter.java:897)
[junit4] > at
org.apache.lucene.index.IndexWriter.ensureOpen(IndexWriter.java:911)
[junit4] > at
org.apache.lucene.index.IndexWriter.updateDocuments(IndexWriter.java:1528)
[junit4] > at
org.apache.lucene.index.IndexWriter.addDocuments(IndexWriter.java:1508)
[junit4] > at
org.apache.lucene.index.RandomIndexWriter.addDocument(RandomIndexWriter.java:141)
[junit4] > at
org.apache.lucene.index.TestDuelingCodecs.createRandomIndex(TestDuelingCodecs.java:139)
[junit4] > at
org.apache.lucene.index.TestDuelingCodecsAtNight.testBigEquals(TestDuelingCodecsAtNight.java:34)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > Caused by: java.lang.OutOfMemoryError: Java heap space
[junit4] > at org.apache.lucene.util.fst.FST.<init>(FST.java:342)
[junit4] > at org.apache.lucene.util.fst.FST.<init>(FST.java:274)
[junit4] > at
org.apache.lucene.codecs.memory.MemoryPostingsFormat$TermsReader.<init>(MemoryPostingsFormat.java:884)
[junit4] > at
org.apache.lucene.codecs.memory.MemoryPostingsFormat.fieldsProducer(MemoryPostingsFormat.java:967)
[junit4] > at
org.apache.lucene.codecs.perfield.PerFieldPostingsFormat$FieldsReader.<init>(PerFieldPostingsFormat.java:292)
[junit4] > at
org.apache.lucene.codecs.perfield.PerFieldPostingsFormat.fieldsProducer(PerFieldPostingsFormat.java:372)
[junit4] > at
org.apache.lucene.index.SegmentCoreReaders.<init>(SegmentCoreReaders.java:112)
[junit4] > at
org.apache.lucene.index.SegmentReader.<init>(SegmentReader.java:78)
[junit4] > at
org.apache.lucene.index.ReadersAndUpdates.getReader(ReadersAndUpdates.java:208)
[junit4] > at
org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:4588)
[junit4] > at
org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:4083)
[junit4] > at
org.apache.lucene.index.ConcurrentMergeScheduler.doMerge(ConcurrentMergeScheduler.java:624)
[junit4] > at
org.apache.lucene.index.ConcurrentMergeScheduler$MergeThread.run(ConcurrentMergeScheduler.java:661)Throwable
#2: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an
uncaught exception in thread: Thread[id=17256, name=Lucene Merge Thread #33,
state=RUNNABLE, group=TGRP-TestDuelingCodecsAtNight]
[junit4] > Caused by: org.apache.lucene.index.MergePolicy$MergeException:
org.apache.lucene.store.AlreadyClosedException: refusing to delete any files:
this IndexWriter hit an unrecoverable exception
[junit4] > at
__randomizedtesting.SeedInfo.seed([5D847244DCE419B1]:0)
[junit4] > at
org.apache.lucene.index.ConcurrentMergeScheduler.handleMergeException(ConcurrentMergeScheduler.java:703)
[junit4] > at
org.apache.lucene.index.ConcurrentMergeScheduler$MergeThread.run(ConcurrentMergeScheduler.java:683)
[junit4] > Caused by: org.apache.lucene.store.AlreadyClosedException:
refusing to delete any files: this IndexWriter hit an unrecoverable exception
[junit4] > at
org.apache.lucene.index.IndexFileDeleter.ensureOpen(IndexFileDeleter.java:345)
[junit4] > at
org.apache.lucene.index.IndexFileDeleter.deleteFiles(IndexFileDeleter.java:697)
[junit4] > at
org.apache.lucene.index.IndexFileDeleter.deleteNewFiles(IndexFileDeleter.java:692)
[junit4] > at
org.apache.lucene.index.IndexWriter.deleteNewFiles(IndexWriter.java:5030)
[junit4] > at
org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:4529)
[junit4] > at
org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:4083)
[junit4] > at
org.apache.lucene.index.ConcurrentMergeScheduler.doMerge(ConcurrentMergeScheduler.java:624)
[junit4] > at
org.apache.lucene.index.ConcurrentMergeScheduler$MergeThread.run(ConcurrentMergeScheduler.java:661)
[junit4] > Caused by: java.lang.OutOfMemoryError: Java heap space
[junit4] > at org.apache.lucene.util.fst.FST.<init>(FST.java:342)
[junit4] > at org.apache.lucene.util.fst.FST.<init>(FST.java:274)
[junit4] > at
org.apache.lucene.codecs.memory.MemoryPostingsFormat$TermsReader.<init>(MemoryPostingsFormat.java:884)
[junit4] > at
org.apache.lucene.codecs.memory.MemoryPostingsFormat.fieldsProducer(MemoryPostingsFormat.java:967)
[junit4] > at
org.apache.lucene.codecs.perfield.PerFieldPostingsFormat$FieldsReader.<init>(PerFieldPostingsFormat.java:292)
[junit4] > at
org.apache.lucene.codecs.perfield.PerFieldPostingsFormat.fieldsProducer(PerFieldPostingsFormat.java:372)
[junit4] > at
org.apache.lucene.index.SegmentCoreReaders.<init>(SegmentCoreReaders.java:112)
[junit4] > at
org.apache.lucene.index.SegmentReader.<init>(SegmentReader.java:78)
[junit4] > at
org.apache.lucene.index.ReadersAndUpdates.getReader(ReadersAndUpdates.java:208)
[junit4] > at
org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:4588)
[junit4] > ... 3 moreThrowable #3:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=17247, name=Lucene Merge Thread #24,
state=RUNNABLE, group=TGRP-TestDuelingCodecsAtNight]
[junit4] > Caused by: org.apache.lucene.index.MergePolicy$MergeException:
java.lang.OutOfMemoryError: Java heap space
[junit4] > at
__randomizedtesting.SeedInfo.seed([5D847244DCE419B1]:0)
[junit4] > at
org.apache.lucene.index.ConcurrentMergeScheduler.handleMergeException(ConcurrentMergeScheduler.java:703)
[junit4] > at
org.apache.lucene.index.ConcurrentMergeScheduler$MergeThread.run(ConcurrentMergeScheduler.java:683)
[junit4] > Caused by: java.lang.OutOfMemoryError: Java heap space
[junit4] > at org.apache.lucene.util.fst.FST.<init>(FST.java:342)
[junit4] > at org.apache.lucene.util.fst.FST.<init>(FST.java:274)
[junit4] > at
org.apache.lucene.codecs.memory.MemoryPostingsFormat$TermsReader.<init>(MemoryPostingsFormat.java:884)
[junit4] > at
org.apache.lucene.codecs.memory.MemoryPostingsFormat.fieldsProducer(MemoryPostingsFormat.java:967)
[junit4] > at
org.apache.lucene.codecs.perfield.PerFieldPostingsFormat$FieldsReader.<init>(PerFieldPostingsFormat.java:292)
[junit4] > at
org.apache.lucene.codecs.perfield.PerFieldPostingsFormat.fieldsProducer(PerFieldPostingsFormat.java:372)
[junit4] > at
org.apache.lucene.index.SegmentCoreReaders.<init>(SegmentCoreReaders.java:112)
[junit4] > at
org.apache.lucene.index.SegmentReader.<init>(SegmentReader.java:78)
[junit4] > at
org.apache.lucene.index.ReadersAndUpdates.getReader(ReadersAndUpdates.java:208)
[junit4] > at
org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:4588)
[junit4] > at
org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:4083)
[junit4] > at
org.apache.lucene.index.ConcurrentMergeScheduler.doMerge(ConcurrentMergeScheduler.java:624)
[junit4] > at
org.apache.lucene.index.ConcurrentMergeScheduler$MergeThread.run(ConcurrentMergeScheduler.java:661)
[junit4] 2> NOTE: leaving temporary files on disk at:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/lucene/build/core/test/J1/temp/lucene.index.TestDuelingCodecsAtNight_5D847244DCE419B1-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70),
sim=RandomSimilarity(queryNorm=false): {titleTokenized=IB SPL-L3(800.0),
body=DFR G3(800.0)}, locale=ar-YE, timezone=America/Los_Angeles
[junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation
1.8.0_144 (64-bit)/cpus=4,threads=1,free=301758048,total=454557696
[junit4] 2> NOTE: All tests run in this JVM: [TestLevenshteinAutomata,
TestIndexWriterUnicode, TestPhrasePrefixQuery, TestMergeSchedulerExternal,
TestPerFieldDocValuesFormat, TestReusableStringReader, TestCustomNorms,
TestIndexReaderClose, TestSpanExplanations, TestSearcherManager,
TestAutomatonQueryUnicode, TestBoostQuery,
TestTopFieldCollectorEarlyTermination, Test2BPagedBytes, TestTermContext,
TestExitableDirectoryReader, TestReqExclBulkScorer, TestToken,
TestBytesRefAttImpl, TestDocsWithFieldSet, TestTransactions, TestCharArraySet,
TestComplexExplanations, TestBytesRefArray, TestIndexFileDeleter,
TestBufferedChecksum, TestSpanFirstQuery, TestDeterminism, TestOmitPositions,
TestLucene60FieldInfoFormat, TestRecyclingIntBlockAllocator, TestBooleanQuery,
TestRollback, Test2BPositions, TestParallelTermEnum,
TestOneMergeWrappingMergePolicy, TestMultiTermsEnum,
TestRecyclingByteBlockAllocator, TestTragicIndexWriterDeadlock, TestWildcard,
TestIndexWriterDelete, TestDoc, TestFilterIterator, TestFutureArrays,
TestLucene70DocValuesFormat, TestVirtualMethod, Test2BPostings,
TestParallelReaderEmptyIndex, TestDocumentsWriterStallControl,
TestTransactionRollback, Test2BPoints, TestStringHelper, TestIntRange,
TestBlockPostingsFormat3, TestMultiDocValues, TestSortedSetDocValues,
TestSpanContainQuery, TestSingleInstanceLockFactory, TestSortRandom,
Test2BDocs, TestStressNRT, TestSizeBoundedForceMerge,
TestMultiTermConstantScore, TestFilterLeafReader, TestVersion,
TestTermVectorsReader, TestNormsFieldExistsQuery, TestCustomSearcherSort,
TestTermsEnum2, TestLRUQueryCache, TestMinimize, TestPositionIncrement,
TestMathUtil, TestIndexOrDocValuesQuery, TestCodecUtil,
TestPrefixInBooleanQuery, TestCrash, TestMergePolicyWrapper,
TestConsistentFieldNumbers, TestWeakIdentityMap, TestDirectoryReader,
TestLockFactory, TestPhraseQuery, TestCollectionUtil, TestOmitNorms,
TestFixedBitSet, TestRegexpRandom2, TestFastDecompressionMode,
TestParallelCompositeReader, TestIndexWriterNRTIsCurrent, TestPagedBytes,
TestDocValuesIndexing, TestHugeRamFile, TestBooleanMinShouldMatch,
TestDeterminizeLexicon, TestPostingsOffsets, TestMixedCodecs, TestRAMDirectory,
TestMultiPhraseQuery, TestBagOfPostings, TestElevationComparator,
TestSubScorerFreqs, TestIndexWriterConfig, TestMultiTermQueryRewrites,
TestDateSort, TestSimilarity, TestSpanExplanationsOfNonMatches,
TestRollingBuffer, TestSimilarityProvider, TestPriorityQueue,
TestSameTokenSamePosition, TestDocCount, TestTotalHitCountCollector,
TestBooleanScorer, TestLucene60PointsFormat, TestPolygon2D,
TestAllFilesCheckIndexHeader, TestAllFilesDetectTruncation, TestCustomTermFreq,
TestDocIDMerger, TestDocInverterPerFieldErrorInfo, TestDocValues,
TestDuelingCodecsAtNight]
[junit4] Completed [466/466 (1!)] on J1 in 753.01s, 3 tests, 1 error <<<
FAILURES!
[...truncated 1 lines...]
[junit4] JVM J1: stdout was not empty, see:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/lucene/build/core/test/temp/junit4-J1-20180311_231643_892825417785303092047.sysout
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] java.lang.OutOfMemoryError: Java heap space
[junit4] Dumping heap to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/heapdumps/java_pid28612.hprof
...
[junit4] Heap dump file created [276039245 bytes in 0.352 secs]
[junit4] <<< JVM J1: EOF ----
[...truncated 11539 lines...]
[junit4] JVM J0: stdout was not empty, see:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/temp/junit4-J0-20180312_021120_4274052398804739330652.sysout
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] java.lang.OutOfMemoryError: GC overhead limit exceeded
[junit4] Dumping heap to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/heapdumps/java_pid25869.hprof
...
[junit4] Heap dump file created [453924353 bytes in 1.386 secs]
[junit4] <<< JVM J0: EOF ----
[junit4] JVM J0: stderr was not empty, see:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/temp/junit4-J0-20180312_021120_4274397120260331958997.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] WARN: Unhandled exception in event serialization. ->
java.lang.OutOfMemoryError: GC overhead limit exceeded
[junit4] at java.util.Arrays.copyOf(Arrays.java:3332)
[junit4] at
java.lang.AbstractStringBuilder.ensureCapacityInternal(AbstractStringBuilder.java:124)
[junit4] at
java.lang.AbstractStringBuilder.append(AbstractStringBuilder.java:649)
[junit4] at java.lang.StringBuilder.append(StringBuilder.java:202)
[junit4] at
com.carrotsearch.ant.tasks.junit4.events.AbstractEvent.toAscii(AbstractEvent.java:108)
[junit4] at
com.carrotsearch.ant.tasks.junit4.events.AbstractEvent.writeBinaryProperty(AbstractEvent.java:36)
[junit4] at
com.carrotsearch.ant.tasks.junit4.events.AppendStdErrEvent.serialize(AppendStdErrEvent.java:30)
[junit4] at
com.carrotsearch.ant.tasks.junit4.events.Serializer$2.run(Serializer.java:119)
[junit4] at
com.carrotsearch.ant.tasks.junit4.events.Serializer$2.run(Serializer.java:114)
[junit4] at java.security.AccessController.doPrivileged(Native Method)
[junit4] at
com.carrotsearch.ant.tasks.junit4.events.Serializer.flushQueue(Serializer.java:114)
[junit4] at
com.carrotsearch.ant.tasks.junit4.events.Serializer.serialize(Serializer.java:99)
[junit4] at
com.carrotsearch.ant.tasks.junit4.slave.SlaveMain$3$2.write(SlaveMain.java:472)
[junit4] at
java.io.BufferedOutputStream.flushBuffer(BufferedOutputStream.java:82)
[junit4] at
java.io.BufferedOutputStream.flush(BufferedOutputStream.java:140)
[junit4] at java.io.PrintStream.flush(PrintStream.java:338)
[junit4] at java.io.FilterOutputStream.flush(FilterOutputStream.java:140)
[junit4] at java.io.PrintStream.write(PrintStream.java:482)
[junit4] at sun.nio.cs.StreamEncoder.writeBytes(StreamEncoder.java:221)
[junit4] at
sun.nio.cs.StreamEncoder.implFlushBuffer(StreamEncoder.java:291)
[junit4] at sun.nio.cs.StreamEncoder.implFlush(StreamEncoder.java:295)
[junit4] at sun.nio.cs.StreamEncoder.flush(StreamEncoder.java:141)
[junit4] at java.io.OutputStreamWriter.flush(OutputStreamWriter.java:229)
[junit4] at
org.apache.log4j.helpers.QuietWriter.flush(QuietWriter.java:59)
[junit4] at
org.apache.log4j.WriterAppender.subAppend(WriterAppender.java:324)
[junit4] at
org.apache.log4j.WriterAppender.append(WriterAppender.java:162)
[junit4] at org.apache.log4j.AppenderSkeleton.doAppend
[junit4] (AppenderSkeleton.java:251)
[junit4] at
org.apache.log4j.helpers.AppenderAttachableImpl.appendLoopOnAppenders(AppenderAttachableImpl.java:66)
[junit4] at org.apache.log4j.Category.callAppenders(Category.java:206)
[junit4] at org.apache.log4j.Category.forcedLog(Category.java:391)
[junit4] at org.apache.log4j.Category.log(Category.java:856)
[junit4] at
org.slf4j.impl.Log4jLoggerAdapter.error(Log4jLoggerAdapter.java:498)
[junit4] <<< JVM J0: EOF ----
[...truncated 174 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B84E20F08BA676B5-001/init-core-data-001
[junit4] 2> 1670276 WARN
(SUITE-TestReplicationHandler-seed#[B84E20F08BA676B5]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=27 numCloses=27
[junit4] 2> 1670276 INFO
(SUITE-TestReplicationHandler-seed#[B84E20F08BA676B5]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1670278 INFO
(SUITE-TestReplicationHandler-seed#[B84E20F08BA676B5]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
[junit4] 2> 1670278 INFO
(SUITE-TestReplicationHandler-seed#[B84E20F08BA676B5]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1670280 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testEmptyCommits
[junit4] 2> 1670280 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B84E20F08BA676B5-001/solr-instance-001/collection1
[junit4] 2> 1670283 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-22T01:27:37+04:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1670284 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1670284 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1670284 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 1670285 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@6ceaaba0{/solr,null,AVAILABLE}
[junit4] 2> 1670287 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@1259100d{HTTP/1.1,[http/1.1]}{127.0.0.1:54276}
[junit4] 2> 1670287 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.e.j.s.Server Started @1673471ms
[junit4] 2> 1670287 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B84E20F08BA676B5-001/solr-instance-001/collection1/data,
hostContext=/solr, hostPort=54276}
[junit4] 2> 1670287 ERROR
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1670288 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.3.0
[junit4] 2> 1670288 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on
port null
[junit4] 2> 1670288 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1670288 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-03-12T02:39:13.953Z
[junit4] 2> 1670288 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B84E20F08BA676B5-001/solr-instance-001/solr.xml
[junit4] 2> 1670292 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay
is ignored
[junit4] 2> 1670292 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1670294 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@5d7ba0d, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 1670337 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5d7ba0d
[junit4] 2> 1670351 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5d7ba0d
[junit4] 2> 1670351 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5d7ba0d
[junit4] 2> 1670354 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B84E20F08BA676B5-001/solr-instance-001/.
[junit4] 2> 1670354 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1670354 INFO (coreLoadExecutor-5276-thread-1) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 1670366 INFO (coreLoadExecutor-5276-thread-1) [
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 1670375 INFO (coreLoadExecutor-5276-thread-1) [
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1670377 INFO (coreLoadExecutor-5276-thread-1) [
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1670377 INFO (coreLoadExecutor-5276-thread-1) [
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using
configuration from instancedir
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B84E20F08BA676B5-001/solr-instance-001/./collection1,
trusted=true
[junit4] 2> 1670378 INFO (coreLoadExecutor-5276-thread-1) [
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5d7ba0d
[junit4] 2> 1670378 INFO (coreLoadExecutor-5276-thread-1) [
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1670378 INFO (coreLoadExecutor-5276-thread-1) [
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B84E20F08BA676B5-001/solr-instance-001/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B84E20F08BA676B5-001/solr-instance-001/./collection1/data/]
[junit4] 2> 1670380 INFO (coreLoadExecutor-5276-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy:
minMergeSize=0, mergeFactor=10, maxMergeSize=1825132783,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 1670434 INFO (coreLoadExecutor-5276-thread-1) [
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1670434 INFO (coreLoadExecutor-5276-thread-1) [
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1670435 INFO (coreLoadExecutor-5276-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.MockRandomMergePolicy:
org.apache.lucene.index.MockRandomMergePolicy@644ffc04
[junit4] 2> 1670435 INFO (coreLoadExecutor-5276-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@1253f80e[collection1] main]
[junit4] 2> 1670436 INFO (coreLoadExecutor-5276-thread-1) [
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to
use dir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B84E20F08BA676B5-001/solr-instance-001/collection1/conf
[junit4] 2> 1670436 INFO (coreLoadExecutor-5276-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following
config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 1670436 INFO (coreLoadExecutor-5276-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1670439 INFO
(searcherExecutor-5277-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@1253f80e[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1670439 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B84E20F08BA676B5-001/solr-instance-002/collection1
[junit4] 2> 1670442 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-22T01:27:37+04:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1670443 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1670443 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1670443 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 1670443 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@44c5d0ae{/solr,null,AVAILABLE}
[junit4] 2> 1670443 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@5555fdc7{HTTP/1.1,[http/1.1]}{127.0.0.1:48726}
[junit4] 2> 1670444 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.e.j.s.Server Started @1673627ms
[junit4] 2> 1670444 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B84E20F08BA676B5-001/solr-instance-002/collection1/data,
hostContext=/solr, hostPort=48726}
[junit4] 2> 1670444 ERROR
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1670444 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.3.0
[junit4] 2> 1670444 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on
port null
[junit4] 2> 1670444 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1670444 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-03-12T02:39:14.109Z
[junit4] 2> 1670445 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B84E20F08BA676B5-001/solr-instance-002/solr.xml
[junit4] 2> 1670449 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay
is ignored
[junit4] 2> 1670449 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1670450 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@5d7ba0d, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 1670510 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5d7ba0d
[junit4] 2> 1670526 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5d7ba0d
[junit4] 2> 1670526 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5d7ba0d
[junit4] 2> 1670529 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B84E20F08BA676B5-001/solr-instance-002/.
[junit4] 2> 1670530 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1670530 INFO (coreLoadExecutor-5286-thread-1) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 1670542 INFO (coreLoadExecutor-5286-thread-1) [
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 1670550 INFO (coreLoadExecutor-5286-thread-1) [
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1670552 INFO (coreLoadExecutor-5286-thread-1) [
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1670553 INFO (coreLoadExecutor-5286-thread-1) [
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using
configuration from instancedir
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B84E20F08BA676B5-001/solr-instance-002/./collection1,
trusted=true
[junit4] 2> 1670553 INFO (coreLoadExecutor-5286-thread-1) [
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5d7ba0d
[junit4] 2> 1670553 INFO (coreLoadExecutor-5286-thread-1) [
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1670553 INFO (coreLoadExecutor-5286-thread-1) [
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B84E20F08BA676B5-001/solr-instance-002/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B84E20F08BA676B5-001/solr-instance-002/./collection1/data/]
[junit4] 2> 1670558 INFO (coreLoadExecutor-5286-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy:
minMergeSize=0, mergeFactor=10, maxMergeSize=1825132783,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 1670615 INFO (coreLoadExecutor-5286-thread-1) [
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1670615 INFO (coreLoadExecutor-5286-thread-1) [
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1670617 INFO (coreLoadExecutor-5286-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.MockRandomMergePolicy:
org.apache.lucene.index.MockRandomMergePolicy@a92d1f1
[junit4] 2> 1670617 INFO (coreLoadExecutor-5286-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@5c943e08[collection1] main]
[junit4] 2> 1670617 INFO (coreLoadExecutor-5286-thread-1) [
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to
use dir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B84E20F08BA676B5-001/solr-instance-002/collection1/conf
[junit4] 2> 1670619 INFO (coreLoadExecutor-5286-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of
1000ms
[junit4] 2> 1670619 INFO (coreLoadExecutor-5286-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1670621 INFO
(searcherExecutor-5287-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@5c943e08[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1670623 INFO (qtp1059274041-26385) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/select
params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 1670624 INFO (qtp1059274041-26387) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=javabin&version=2}{add=[1]} 0 0
[junit4] 2> 1670625 INFO (qtp1059274041-26389) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1670625 INFO (qtp1059274041-26389) [ x:collection1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@6ad75b9e commitCommandVersion:0
[junit4] 2> 1670627 INFO (qtp1059274041-26389) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@255cfec9[collection1] main]
[junit4] 2> 1670627 INFO (qtp1059274041-26389) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1670628 INFO
(searcherExecutor-5277-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@255cfec9[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):c1)))}
[junit4] 2> 1670629 INFO (qtp1059274041-26389) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={commit=true&wt=javabin&version=2}{commit=} 0 3
[junit4] 2> 1670631 WARN (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication
suffix
[junit4] 2> 1670633 INFO (qtp1059274041-26389) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0
QTime=0
[junit4] 2> 1670633 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Master's generation: 2
[junit4] 2> 1670633 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Master's version: 1520822354290
[junit4] 2> 1670633 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 1670633 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Slave's version: 0
[junit4] 2> 1670633 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 1670635 INFO (qtp1059274041-26387) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=2&qt=/replication&tlogFiles=false&wt=javabin&version=2&command=filelist}
status=0 QTime=2
[junit4] 2> 1670636 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Number of files in latest index in master: 4
[junit4] 2> 1670636 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=29, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=3.4052734375,
floorSegmentMB=1.0576171875, forceMergeDeletesPctAllowed=24.870534420489452,
segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1670637 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1670637 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to
MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@4d163aa8
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@549525f9;
maxCacheMB=0.173828125 maxMergeSizeMB=0.9443359375))
[junit4] 2> 1670637 INFO (qtp1059274041-26389) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=2&qt=/replication&file=_0.cfe&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1670639 INFO (qtp1059274041-26387) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=2&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1670640 INFO (qtp1059274041-26389) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=2&qt=/replication&file=_0.cfs&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1670641 INFO (qtp1059274041-26387) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=2&qt=/replication&file=segments_2&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1670641 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Total time taken for download
(fullCopy=false,bytesDownloaded=1723) : 0 secs (null bytes/sec) to
MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@4d163aa8
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@549525f9;
maxCacheMB=0.173828125 maxMergeSizeMB=0.9443359375))
[junit4] 2> 1670642 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=14, maxMergeAtOnceExplicit=16, maxMergedSegmentMB=45.66015625,
floorSegmentMB=1.126953125, forceMergeDeletesPctAllowed=16.389224012897333,
segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1670643 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1670643 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@4ea8fe95[collection1] main]
[junit4] 2> 1670645 INFO
(searcherExecutor-5287-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@4ea8fe95[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):c1)))}
[junit4] 2> 1670645 INFO (qtp1446767886-26402) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={wait=true&masterUrl=http://127.0.0.1:54276/solr/collection1/replication&command=fetchindex}
status=0 QTime=14
[junit4] 2> 1670647 INFO (qtp1446767886-26406) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/select
params={q=name:empty1&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 2> 1670647 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
[junit4] 2> 1670648 INFO (qtp1059274041-26390) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details}
status=0 QTime=0
[junit4] 2> 1670648 INFO (qtp1059274041-26387) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details}
status=0 QTime=0
[junit4] 2> 1670650 INFO (qtp1059274041-26389) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={slave=false&qt=/replication&wt=javabin&version=2&command=details}
status=0 QTime=0
[junit4] 2> 1670650 INFO (qtp1446767886-26408) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details}
status=0 QTime=0
[junit4] 2> 1670650 INFO (qtp1059274041-26390) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion}
status=0 QTime=0
[junit4] 2> 1670651 INFO (qtp1446767886-26406) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion}
status=0 QTime=0
[junit4] 2> 1670651 INFO (qtp1059274041-26389) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1670651 INFO (qtp1059274041-26389) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1670651 INFO (qtp1059274041-26389) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1670651 INFO (qtp1059274041-26389) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={commit=true&wt=javabin&version=2}{commit=} 0 0
[junit4] 2> 1670652 WARN (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication
suffix
[junit4] 2> 1670653 INFO (qtp1059274041-26387) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0
QTime=0
[junit4] 2> 1670653 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Master's generation: 2
[junit4] 2> 1670653 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Master's version: 1520822354290
[junit4] 2> 1670653 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Slave's generation: 2
[junit4] 2> 1670653 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Slave's version: 1520822354290
[junit4] 2> 1670653 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Slave in sync with master.
[junit4] 2> 1670653 INFO (qtp1446767886-26403) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={wait=true&masterUrl=http://127.0.0.1:54276/solr/collection1/replication&command=fetchindex}
status=0 QTime=1
[junit4] 2> 1670655 INFO (qtp1059274041-26390) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=javabin&version=2}{add=[2]} 0 0
[junit4] 2> 1670655 INFO (qtp1059274041-26387) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1670655 INFO (qtp1059274041-26387) [ x:collection1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@6ad75b9e commitCommandVersion:0
[junit4] 2> 1670707 INFO (qtp1059274041-26387) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@43b3720b[collection1] main]
[junit4] 2> 1670707 INFO (qtp1059274041-26387) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1670710 INFO
(searcherExecutor-5277-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@43b3720b[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):c1)
Uninverting(_1(7.3.0):c1)))}
[junit4] 2> 1670710 INFO (qtp1059274041-26387) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={commit=true&wt=javabin&version=2}{commit=} 0 55
[junit4] 2> 1670712 WARN (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication
suffix
[junit4] 2> 1670713 INFO (qtp1059274041-26389) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0
QTime=0
[junit4] 2> 1670713 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Master's generation: 3
[junit4] 2> 1670713 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Master's version: 1520822354320
[junit4] 2> 1670713 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Slave's generation: 2
[junit4] 2> 1670713 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Slave's version: 1520822354290
[junit4] 2> 1670713 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 1670714 INFO (qtp1059274041-26387) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&tlogFiles=false&wt=javabin&version=2&command=filelist}
status=0 QTime=1
[junit4] 2> 1670714 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Number of files in latest index in master: 7
[junit4] 2> 1670715 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=29, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=3.4052734375,
floorSegmentMB=1.0576171875, forceMergeDeletesPctAllowed=24.870534420489452,
segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1670715 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1670716 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to
MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@29c75ac5
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2d8fe704;
maxCacheMB=0.173828125 maxMergeSizeMB=0.9443359375))
[junit4] 2> 1670716 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _0.cfe because it already exists
[junit4] 2> 1670716 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
[junit4] 2> 1670716 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _0.cfs because it already exists
[junit4] 2> 1670716 INFO (qtp1059274041-26389) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&file=_1.cfs&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1670717 INFO (qtp1059274041-26387) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&file=_1.cfe&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1670718 INFO (qtp1059274041-26389) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&file=_1.si&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1670719 INFO (qtp1059274041-26387) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&file=segments_3&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1670720 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Total time taken for download
(fullCopy=false,bytesDownloaded=1788) : 0 secs (null bytes/sec) to
MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@29c75ac5
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2d8fe704;
maxCacheMB=0.173828125 maxMergeSizeMB=0.9443359375))
[junit4] 2> 1670721 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=14, maxMergeAtOnceExplicit=16, maxMergedSegmentMB=45.66015625,
floorSegmentMB=1.126953125, forceMergeDeletesPctAllowed=16.389224012897333,
segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1670721 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1670722 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@5471420e[collection1] main]
[junit4] 2> 1670726 INFO
(searcherExecutor-5287-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@5471420e[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):c1)
Uninverting(_1(7.3.0):c1)))}
[junit4] 2> 1670726 INFO (qtp1446767886-26406) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={wait=true&masterUrl=http://127.0.0.1:54276/solr/collection1/replication&command=fetchindex}
status=0 QTime=15
[junit4] 2> 1670728 INFO (qtp1446767886-26408) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/select
params={q=name:empty2&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 2> 1670728 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
[junit4] 2> 1670729 INFO (qtp1059274041-26390) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details}
status=0 QTime=0
[junit4] 2> 1670729 INFO (qtp1059274041-26387) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details}
status=0 QTime=0
[junit4] 2> 1670731 INFO (qtp1059274041-26389) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={slave=false&qt=/replication&wt=javabin&version=2&command=details}
status=0 QTime=0
[junit4] 2> 1670731 INFO (qtp1446767886-26406) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details}
status=0 QTime=1
[junit4] 2> 1670732 INFO (qtp1059274041-26390) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion}
status=0 QTime=0
[junit4] 2> 1670732 INFO (qtp1446767886-26408) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion}
status=0 QTime=0
[junit4] 2> 1670733 INFO (qtp1059274041-26389) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=javabin&version=2}{add=[3]} 0 0
[junit4] 2> 1670734 INFO (qtp1059274041-26390) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1670734 INFO (qtp1059274041-26390) [ x:collection1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@6ad75b9e commitCommandVersion:0
[junit4] 2> 1670789 INFO (qtp1059274041-26390) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@5d6e95b3[collection1] realtime]
[junit4] 2> 1670789 INFO (qtp1059274041-26390) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1670790 INFO (qtp1059274041-26390) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={openSearcher=false&commit=true&wt=javabin&version=2}{commit=} 0 56
[junit4] 2> 1670792 WARN (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication
suffix
[junit4] 2> 1670793 INFO (qtp1059274041-26387) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0
QTime=0
[junit4] 2> 1670793 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Master's generation: 4
[junit4] 2> 1670793 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Master's version: 1520822354399
[junit4] 2> 1670793 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Slave's generation: 3
[junit4] 2> 1670793 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Slave's version: 1520822354320
[junit4] 2> 1670793 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 1670794 INFO (qtp1059274041-26390) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=4&qt=/replication&tlogFiles=false&wt=javabin&version=2&command=filelist}
status=0 QTime=0
[junit4] 2> 1670794 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Number of files in latest index in master: 10
[junit4] 2> 1670795 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=29, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=3.4052734375,
floorSegmentMB=1.0576171875, forceMergeDeletesPctAllowed=24.870534420489452,
segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1670796 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1670796 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 1670796 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files
to be delete-able
[junit4] 2> 1671796 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 1671796 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files
to be delete-able
[junit4] 2> 1672797 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 1672797 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files
to be delete-able
[junit4] 2> 1673798 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 1673798 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files
to be delete-able
[junit4] 2> 1674799 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 1674799 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files
to be delete-able
[junit4] 2> 1675799 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 1675799 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files
to be delete-able
[junit4] 2> 1676800 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 1676800 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files
to be delete-able
[junit4] 2> 1677801 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 1677801 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files
to be delete-able
[junit4] 2> 1678802 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 1678802 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files
to be delete-able
[junit4] 2> 1679802 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 1679803 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files
to be delete-able
[junit4] 2> 1680803 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 1680803 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files
to be delete-able
[junit4] 2> 1681804 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher IndexFetcher slept for 11000ms for unused lucene index
files to be delete-able
[junit4] 2> 1681804 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to
MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@51068bb5
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@52885a17;
maxCacheMB=0.173828125 maxMergeSizeMB=0.9443359375))
[junit4] 2> 1681804 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _0.cfe because it already exists
[junit4] 2> 1681804 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
[junit4] 2> 1681805 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _0.cfs because it already exists
[junit4] 2> 1681805 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _1.cfs because it already exists
[junit4] 2> 1681805 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _1.cfe because it already exists
[junit4] 2> 1681805 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _1.si because it already exists
[junit4] 2> 1681807 INFO (qtp1059274041-26387) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=4&qt=/replication&file=_2.si&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1681809 INFO (qtp1059274041-26390) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=4&qt=/replication&file=_2.cfe&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1681813 INFO (qtp1059274041-26387) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=4&qt=/replication&file=_2.cfs&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1681814 INFO (qtp1059274041-26390) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=4&qt=/replication&file=segments_4&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1681815 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Total time taken for download
(fullCopy=false,bytesDownloaded=1853) : 11 secs (168 bytes/sec) to
MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@51068bb5
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@52885a17;
maxCacheMB=0.173828125 maxMergeSizeMB=0.9443359375))
[junit4] 2> 1681816 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=14, maxMergeAtOnceExplicit=16, maxMergedSegmentMB=45.66015625,
floorSegmentMB=1.126953125, forceMergeDeletesPctAllowed=16.389224012897333,
segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1681868 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1681869 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@52f192b2[collection1] main]
[junit4] 2> 1681871 INFO
(searcherExecutor-5287-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@52f192b2[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):c1)
Uninverting(_1(7.3.0):c1) Uninverting(_2(7.3.0):c1)))}
[junit4] 2> 1681871 INFO (qtp1446767886-26403) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={wait=true&masterUrl=http://127.0.0.1:54276/solr/collection1/replication&command=fetchindex}
status=0 QTime=11079
[junit4] 2> 1681874 INFO (qtp1059274041-26389) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/select
params={q=name:empty3&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 1681874 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.h.TestReplicationHandler Waited for 0ms and found 0 docs
[junit4] 2> 1681876 INFO (qtp1446767886-26406) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/select
params={q=name:empty3&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 2> 1681876 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[B84E20F08BA676B5]) [ ]
o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
[junit4] 2> 1681877 INFO (qtp1059274041-26390) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=javabin&version=2}{add=[4]} 0 0
[junit4] 2> 1681878 INFO (qtp1059274041-26389) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1681878 INFO (qtp1059274041-26389) [ x:collection1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@6ad75b9e commitCommandVersion:0
[junit4] 2> 1681879 INFO (qtp1059274041-26389) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@549b2c28[collection1] main]
[junit4] 2> 1681880 INFO (qtp1059274041-26389) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1681881 INFO
(searcherExecutor-5277-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@549b2c28[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(7.3.0):c1)
Uninverting(_1(7.3.0):c1) Uninverting(_2(7.3.0):c1)
Uninverting(_4(7.3.0):c1)))}
[junit4] 2> 1681881 INFO (qtp1059274041-26389) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={commit=true&wt=javabin&version=2}{commit=} 0 4
[junit4] 2> 1681883 WARN (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication
suffix
[junit4] 2> 1681884 INFO (qtp1059274041-26387) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0
QTime=0
[junit4] 2> 1681885 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Master's generation: 5
[junit4] 2> 1681885 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Master's version: 1520822365543
[junit4] 2> 1681885 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Slave's generation: 4
[junit4] 2> 1681885 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Slave's version: 1520822354399
[junit4] 2> 1681885 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 1681886 INFO (qtp1059274041-26389) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=5&qt=/replication&tlogFiles=false&wt=javabin&version=2&command=filelist}
status=0 QTime=0
[junit4] 2> 1681886 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Number of files in latest index in master: 13
[junit4] 2> 1681887 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=29, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=3.4052734375,
floorSegmentMB=1.0576171875, forceMergeDeletesPctAllowed=24.870534420489452,
segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1681888 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1681888 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to
MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@30a4d281
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6e87b424;
maxCacheMB=0.173828125 maxMergeSizeMB=0.9443359375))
[junit4] 2> 1681889 INFO (qtp1059274041-26387) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=5&qt=/replication&file=_3.si&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1681890 INFO (qtp1059274041-26389) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=5&qt=/replication&file=_3.cfs&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1681891 INFO (qtp1059274041-26387) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=5&qt=/replication&file=_3.cfe&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1681891 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _1.cfs because it already exists
[junit4] 2> 1681892 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _1.cfe because it already exists
[junit4] 2> 1681892 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _1.si because it already exists
[junit4] 2> 1681942 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _2.si because it already exists
[junit4] 2> 1681942 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _2.cfe because it already exists
[junit4] 2> 1681942 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _2.cfs because it already exists
[junit4] 2> 1681943 INFO (qtp1059274041-26389) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=5&qt=/replication&file=_4.cfe&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1681945 INFO (qtp1059274041-26387) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=5&qt=/replication&file=_4.cfs&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1681946 INFO (qtp1059274041-26389) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=5&qt=/replication&file=_4.si&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1681947 INFO (qtp1059274041-26387) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=5&qt=/replication&file=segments_5&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1681948 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Total time taken for download
(fullCopy=false,bytesDownloaded=3493) : 0 secs (null bytes/sec) to
MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@30a4d281
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6e87b424;
maxCacheMB=0.173828125 maxMergeSizeMB=0.9443359375))
[junit4] 2> 1681950 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=14, maxMergeAtOnceExplicit=16, maxMergedSegmentMB=45.66015625,
floorSegmentMB=1.126953125, forceMergeDeletesPctAllowed=16.389224012897333,
segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1681951 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1681951 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@16f0b9d4[collection1] main]
[junit4] 2> 1681953 INFO
(searcherExecutor-5287-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@16f0b9d4[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(7.3.0):c1)
Uninverting(_1(7.3.0):c1) Uninverting(_2(7.3.0):c1)
Uninverting(_4(7.3.0):c1)))}
[junit4] 2> 1681954 INFO (qtp1446767886-26408) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={wait=true&masterUrl=http://127.0.0.1:54276/solr/collection1/replication&command=fetchindex}
status=0 QTime=71
[junit4] 2> 1681955 INFO (qtp1059274041-26390) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/select
params={q=name:(empty1+empty2+empty3+empty4)&sort=id+desc&wt=javabin&version=
[...truncated too long message...]
lr-NightlyTests-7.x/checkout/solr/core/test-lib/apacheds-interceptors-authn-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/apacheds-interceptors-authz-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/apacheds-interceptors-changelog-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/apacheds-interceptors-collective-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/apacheds-interceptors-event-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/apacheds-interceptors-exception-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/apacheds-interceptors-journal-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/apacheds-interceptors-normalization-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/apacheds-interceptors-operational-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/apacheds-interceptors-referral-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/apacheds-interceptors-schema-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/apacheds-interceptors-subtree-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/apacheds-interceptors-trigger-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/apacheds-jdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/apacheds-jdbm1-2.0.0-M2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/apacheds-kerberos-codec-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/apacheds-ldif-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/apacheds-mavibot-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/apacheds-protocol-kerberos-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/apacheds-protocol-ldap-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/apacheds-protocol-shared-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/apacheds-xdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/api-all-1.0.0-M20.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/bcprov-jdk15on-1.54.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/byte-buddy-1.6.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/commons-collections-3.2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/commons-math3-3.6.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/ehcache-core-2.4.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/hadoop-common-2.7.4-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/hadoop-hdfs-2.7.4-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/hadoop-minikdc-2.7.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/htrace-core-3.2.0-incubating.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/jersey-core-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/jersey-server-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/jetty-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/jetty-sslengine-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/jetty-util-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/mina-core-2.0.0-M5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/mockito-core-2.6.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/netty-all-4.0.36.Final.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/core/test-lib/objenesis-2.5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/lucene/build/analysis/icu/lucene-analyzers-icu-7.3.0-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/contrib/solr-analysis-extras/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/lucene/build/queryparser/classes/test:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/lucene/build/backward-codecs/classes/test:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/contrib/analysis-extras/lib/icu4j-59.1.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-launcher.jar:/x1/jenkins/.ant/lib/ivy-2.4.0.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-junit.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-log4j.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-junit4.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-jai.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-javamail.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-bsf.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-commons-net.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-antlr.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-jsch.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-oro.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-commons-logging.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-netrexx.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-testutil.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-jdepend.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-bcel.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-xalan2.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-resolver.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-jmf.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-regexp.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-swing.jar:/usr/local/asfpackages/java/jdk1.8.0_144/lib/tools.jar:/x1/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.5.3.jar
com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/temp/junit4-J0-20180312_021120_4275785069403093215372.events
@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/temp/junit4-J0-20180312_021120_4273622185319890078553.suites
-stdin
[junit4] ERROR: JVM J0 ended with an exception: Quit event not received from
the forked process? This may indicate JVM crash or runner bugs.
[junit4] at
com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1540)
[junit4] at
com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:126)
[junit4] at
com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:982)
[junit4] at
com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:979)
[junit4] at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] at java.lang.Thread.run(Thread.java:748)
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/build.xml:636:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/build.xml:562:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/build.xml:59:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build.xml:262:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/common-build.xml:557:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/lucene/common-build.xml:1561:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/lucene/common-build.xml:1085:
At least one slave process threw an exception, first: Quit event not received
from the forked process? This may indicate JVM crash or runner bugs.
Total time: 274 minutes 20 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[Fast Archiver] No artifacts from Lucene-Solr-NightlyTests-7.x #172 to compare,
so performing full copy of 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]
