You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2021/11/15 18:03:59 UTC

[JENKINS] Lucene » Lucene-NightlyTests-9.0 - Build # 13 - Unstable!

Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-NightlyTests-9.0/13/

1 tests failed.
FAILED:  org.apache.lucene.index.TestIndexWriterMergePolicy.testStressUpdateSameDocumentWithMergeOnGetReader

Error Message:
java.lang.IllegalStateException: this writer hit an unrecoverable error; cannot commit

Stack Trace:
java.lang.IllegalStateException: this writer hit an unrecoverable error; cannot commit
	at org.apache.lucene.index.IndexWriter.startCommit(IndexWriter.java:5264)
	at org.apache.lucene.index.IndexWriter.prepareCommitInternal(IndexWriter.java:3534)
	at org.apache.lucene.index.IndexWriter.commitInternal(IndexWriter.java:3863)
	at org.apache.lucene.index.IndexWriter.commit(IndexWriter.java:3825)
	at org.apache.lucene.index.RandomIndexWriter.getReader(RandomIndexWriter.java:479)
	at org.apache.lucene.index.RandomIndexWriter.getReader(RandomIndexWriter.java:406)
	at org.apache.lucene.index.TestIndexWriterMergePolicy.stressUpdateSameDocumentWithMergeOnX(TestIndexWriterMergePolicy.java:763)
	at org.apache.lucene.index.TestIndexWriterMergePolicy.testStressUpdateSameDocumentWithMergeOnGetReader(TestIndexWriterMergePolicy.java:707)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:44)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:43)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:45)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:60)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:44)
	at org.junit.rules.RunRules.evaluate(RunRules.java:20)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:43)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:38)
	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 org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:44)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:60)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:47)
	at org.junit.rules.RunRules.evaluate(RunRules.java:20)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
	at java.base/java.lang.Thread.run(Thread.java:834)
	Suppressed: org.apache.lucene.store.AlreadyClosedException: refusing to delete any files: this IndexWriter hit an unrecoverable exception
		at app//org.apache.lucene.index.IndexFileDeleter.ensureOpen(IndexFileDeleter.java:400)
		at app//org.apache.lucene.index.IndexFileDeleter.deleteFiles(IndexFileDeleter.java:734)
		at app//org.apache.lucene.index.IndexFileDeleter.decRef(IndexFileDeleter.java:655)
		at app//org.apache.lucene.index.IndexWriter.prepareCommitInternal(IndexWriter.java:3544)
		... 45 more
	Caused by: java.lang.OutOfMemoryError: Java heap space
		at java.base/java.lang.Integer.toString(Integer.java:440)
		at org.apache.lucene.codecs.simpletext.SimpleTextStoredFieldsWriter.startDocument(SimpleTextStoredFieldsWriter.java:78)
		at org.apache.lucene.codecs.StoredFieldsWriter.merge(StoredFieldsWriter.java:134)
		at org.apache.lucene.index.SegmentMerger.mergeFields(SegmentMerger.java:231)
		at org.apache.lucene.index.SegmentMerger$$Lambda$237/0x00000001003c4040.merge(Unknown Source)
		at org.apache.lucene.index.SegmentMerger.mergeWithLogging(SegmentMerger.java:270)
		at org.apache.lucene.index.SegmentMerger.merge(SegmentMerger.java:109)
		at org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:4964)
		at org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:4500)
		at org.apache.lucene.index.IndexWriter$IndexWriterMergeSource.merge(IndexWriter.java:6252)
		at org.apache.lucene.index.ConcurrentMergeScheduler.doMerge(ConcurrentMergeScheduler.java:636)
		at org.apache.lucene.index.ConcurrentMergeScheduler$MergeThread.run(ConcurrentMergeScheduler.java:697)
Caused by: java.lang.OutOfMemoryError: Java heap space
	at java.base/java.lang.Integer.toString(Integer.java:440)
	at org.apache.lucene.codecs.simpletext.SimpleTextStoredFieldsWriter.startDocument(SimpleTextStoredFieldsWriter.java:78)
	at org.apache.lucene.codecs.StoredFieldsWriter.merge(StoredFieldsWriter.java:134)
	at org.apache.lucene.index.SegmentMerger.mergeFields(SegmentMerger.java:231)
	at org.apache.lucene.index.SegmentMerger$$Lambda$237/0x00000001003c4040.merge(Unknown Source)
	at org.apache.lucene.index.SegmentMerger.mergeWithLogging(SegmentMerger.java:270)
	at org.apache.lucene.index.SegmentMerger.merge(SegmentMerger.java:109)
	at org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:4964)
	at org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:4500)
	at org.apache.lucene.index.IndexWriter$IndexWriterMergeSource.merge(IndexWriter.java:6252)
	at org.apache.lucene.index.ConcurrentMergeScheduler.doMerge(ConcurrentMergeScheduler.java:636)
	at org.apache.lucene.index.ConcurrentMergeScheduler$MergeThread.run(ConcurrentMergeScheduler.java:697)




Build Log:
[...truncated 934 lines...]
ERROR: The following test(s) have failed:
  - org.apache.lucene.index.TestIndexWriterMergePolicy.testStressUpdateSameDocumentWithMergeOnGetReader (:lucene:core)
    Test output: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-NightlyTests-9.0/checkout/lucene/core/property 'outputLocation'/outputs/OUTPUT-org.apache.lucene.index.TestIndexWriterMergePolicy.txt
    Reproduce with: gradlew :lucene:core:test --tests "org.apache.lucene.index.TestIndexWriterMergePolicy.testStressUpdateSameDocumentWithMergeOnGetReader" -Ptests.jvms=4 -Ptests.haltonfailure=false -Ptests.jvmargs=-XX:TieredStopAtLevel=1 -Ptests.seed=C35D88E68E5686A1 -Ptests.multiplier=2 -Ptests.nightly=true -Ptests.badapples=false -Ptests.file.encoding=UTF-8 -Ptests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-NightlyTests-9.0/test-data/enwiki.random.lines.txt


BUILD SUCCESSFUL in 4h 45m 8s
195 actionable tasks: 195 executed
Build step 'Invoke Gradle script' changed build result to SUCCESS
Archiving artifacts
java.lang.InterruptedException: no matches found within 10000
	at hudson.FilePath$ValidateAntFileMask.hasMatch(FilePath.java:3069)
	at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2948)
	at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2929)
	at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3317)
Also:   hudson.remoting.Channel$CallSiteStackTrace: Remote call to lucene-solr-1
		at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1800)
		at hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:356)
		at hudson.remoting.Channel.call(Channel.java:1001)
		at hudson.FilePath.act(FilePath.java:1159)
		at hudson.FilePath.act(FilePath.java:1148)
		at hudson.FilePath.validateAntFileMask(FilePath.java:2927)
		at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:270)
		at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:80)
		at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
		at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:803)
		at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:752)
		at hudson.model.Build$BuildExecution.post2(Build.java:177)
		at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:697)
		at hudson.model.Run.execute(Run.java:1931)
		at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
		at hudson.model.ResourceController.execute(ResourceController.java:97)
		at hudson.model.Executor.run(Executor.java:429)
Caused: hudson.FilePath$TunneledInterruptedException
	at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3319)
	at hudson.remoting.UserRequest.perform(UserRequest.java:211)
	at hudson.remoting.UserRequest.perform(UserRequest.java:54)
	at hudson.remoting.Request$2.run(Request.java:376)
	at hudson.remoting.InterceptingExecutorService.lambda$wrap$0(InterceptingExecutorService.java:78)
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
	at java.lang.Thread.run(Thread.java:748)
Caused: java.lang.InterruptedException: java.lang.InterruptedException: no matches found within 10000
	at hudson.FilePath.act(FilePath.java:1161)
	at hudson.FilePath.act(FilePath.java:1148)
	at hudson.FilePath.validateAntFileMask(FilePath.java:2927)
	at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:270)
	at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:80)
	at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
	at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:803)
	at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:752)
	at hudson.model.Build$BuildExecution.post2(Build.java:177)
	at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:697)
	at hudson.model.Run.execute(Run.java:1931)
	at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
	at hudson.model.ResourceController.execute(ResourceController.java:97)
	at hudson.model.Executor.run(Executor.java:429)
No artifacts found that match the file pattern "**/*.events,heapdumps/**,**/hs_err_pid*". Configuration error?
Recording test results
[Checks API] No suitable checks publisher found.
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)

Re: [JENKINS] Lucene » Lucene-NightlyTests-9.0 - Build # 13 - Unstable!

Posted by Michael McCandless <lu...@mikemccandless.com>.
Is this test just a little too evil (hitting OOME on the right seeds) or do
we possibly really have a new memory leak lurking?

Mike McCandless

http://blog.mikemccandless.com


On Mon, Nov 15, 2021 at 1:04 PM Apache Jenkins Server <
jenkins@builds.apache.org> wrote:

> Build:
> https://ci-builds.apache.org/job/Lucene/job/Lucene-NightlyTests-9.0/13/
>
> 1 tests failed.
> FAILED:
> org.apache.lucene.index.TestIndexWriterMergePolicy.testStressUpdateSameDocumentWithMergeOnGetReader
>
> Error Message:
> java.lang.IllegalStateException: this writer hit an unrecoverable error;
> cannot commit
>
> Stack Trace:
> java.lang.IllegalStateException: this writer hit an unrecoverable error;
> cannot commit
>         at
> org.apache.lucene.index.IndexWriter.startCommit(IndexWriter.java:5264)
>         at
> org.apache.lucene.index.IndexWriter.prepareCommitInternal(IndexWriter.java:3534)
>         at
> org.apache.lucene.index.IndexWriter.commitInternal(IndexWriter.java:3863)
>         at
> org.apache.lucene.index.IndexWriter.commit(IndexWriter.java:3825)
>         at
> org.apache.lucene.index.RandomIndexWriter.getReader(RandomIndexWriter.java:479)
>         at
> org.apache.lucene.index.RandomIndexWriter.getReader(RandomIndexWriter.java:406)
>         at
> org.apache.lucene.index.TestIndexWriterMergePolicy.stressUpdateSameDocumentWithMergeOnX(TestIndexWriterMergePolicy.java:763)
>         at
> org.apache.lucene.index.TestIndexWriterMergePolicy.testStressUpdateSameDocumentWithMergeOnGetReader(TestIndexWriterMergePolicy.java:707)
>         at
> java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native
> Method)
>         at
> java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
>         at
> java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
>         at java.base/java.lang.reflect.Method.invoke(Method.java:566)
>         at
> com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
>         at
> com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942)
>         at
> com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978)
>         at
> com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
>         at
> org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:44)
>         at
> org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:43)
>         at
> org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:45)
>         at
> org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:60)
>         at
> org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:44)
>         at org.junit.rules.RunRules.evaluate(RunRules.java:20)
>         at
> com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
>         at
> com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
>         at
> com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
>         at
> com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
>         at
> com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
>         at
> com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
>         at
> com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
>         at
> com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
>         at
> org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:43)
>         at
> com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
>         at
> org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:38)
>         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
> org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
>         at
> org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:43)
>         at
> org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:44)
>         at
> org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:60)
>         at
> org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:47)
>         at org.junit.rules.RunRules.evaluate(RunRules.java:20)
>         at
> com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
>         at
> com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
>         at
> com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
>         at java.base/java.lang.Thread.run(Thread.java:834)
>         Suppressed: org.apache.lucene.store.AlreadyClosedException:
> refusing to delete any files: this IndexWriter hit an unrecoverable
> exception
>                 at
> app//org.apache.lucene.index.IndexFileDeleter.ensureOpen(IndexFileDeleter.java:400)
>                 at
> app//org.apache.lucene.index.IndexFileDeleter.deleteFiles(IndexFileDeleter.java:734)
>                 at
> app//org.apache.lucene.index.IndexFileDeleter.decRef(IndexFileDeleter.java:655)
>                 at
> app//org.apache.lucene.index.IndexWriter.prepareCommitInternal(IndexWriter.java:3544)
>                 ... 45 more
>         Caused by: java.lang.OutOfMemoryError: Java heap space
>                 at java.base/java.lang.Integer.toString(Integer.java:440)
>                 at
> org.apache.lucene.codecs.simpletext.SimpleTextStoredFieldsWriter.startDocument(SimpleTextStoredFieldsWriter.java:78)
>                 at
> org.apache.lucene.codecs.StoredFieldsWriter.merge(StoredFieldsWriter.java:134)
>                 at
> org.apache.lucene.index.SegmentMerger.mergeFields(SegmentMerger.java:231)
>                 at
> org.apache.lucene.index.SegmentMerger$$Lambda$237/0x00000001003c4040.merge(Unknown
> Source)
>                 at
> org.apache.lucene.index.SegmentMerger.mergeWithLogging(SegmentMerger.java:270)
>                 at
> org.apache.lucene.index.SegmentMerger.merge(SegmentMerger.java:109)
>                 at
> org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:4964)
>                 at
> org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:4500)
>                 at
> org.apache.lucene.index.IndexWriter$IndexWriterMergeSource.merge(IndexWriter.java:6252)
>                 at
> org.apache.lucene.index.ConcurrentMergeScheduler.doMerge(ConcurrentMergeScheduler.java:636)
>                 at
> org.apache.lucene.index.ConcurrentMergeScheduler$MergeThread.run(ConcurrentMergeScheduler.java:697)
> Caused by: java.lang.OutOfMemoryError: Java heap space
>         at java.base/java.lang.Integer.toString(Integer.java:440)
>         at
> org.apache.lucene.codecs.simpletext.SimpleTextStoredFieldsWriter.startDocument(SimpleTextStoredFieldsWriter.java:78)
>         at
> org.apache.lucene.codecs.StoredFieldsWriter.merge(StoredFieldsWriter.java:134)
>         at
> org.apache.lucene.index.SegmentMerger.mergeFields(SegmentMerger.java:231)
>         at
> org.apache.lucene.index.SegmentMerger$$Lambda$237/0x00000001003c4040.merge(Unknown
> Source)
>         at
> org.apache.lucene.index.SegmentMerger.mergeWithLogging(SegmentMerger.java:270)
>         at
> org.apache.lucene.index.SegmentMerger.merge(SegmentMerger.java:109)
>         at
> org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:4964)
>         at org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:4500)
>         at
> org.apache.lucene.index.IndexWriter$IndexWriterMergeSource.merge(IndexWriter.java:6252)
>         at
> org.apache.lucene.index.ConcurrentMergeScheduler.doMerge(ConcurrentMergeScheduler.java:636)
>         at
> org.apache.lucene.index.ConcurrentMergeScheduler$MergeThread.run(ConcurrentMergeScheduler.java:697)
>
>
>
>
> Build Log:
> [...truncated 934 lines...]
> ERROR: The following test(s) have failed:
>   -
> org.apache.lucene.index.TestIndexWriterMergePolicy.testStressUpdateSameDocumentWithMergeOnGetReader
> (:lucene:core)
>     Test output:
> /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-NightlyTests-9.0/checkout/lucene/core/property
> 'outputLocation'/outputs/OUTPUT-org.apache.lucene.index.TestIndexWriterMergePolicy.txt
>     Reproduce with: gradlew :lucene:core:test --tests
> "org.apache.lucene.index.TestIndexWriterMergePolicy.testStressUpdateSameDocumentWithMergeOnGetReader"
> -Ptests.jvms=4 -Ptests.haltonfailure=false
> -Ptests.jvmargs=-XX:TieredStopAtLevel=1 -Ptests.seed=C35D88E68E5686A1
> -Ptests.multiplier=2 -Ptests.nightly=true -Ptests.badapples=false
> -Ptests.file.encoding=UTF-8
> -Ptests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-NightlyTests-9.0/test-data/enwiki.random.lines.txt
>
>
> BUILD SUCCESSFUL in 4h 45m 8s
> 195 actionable tasks: 195 executed
> Build step 'Invoke Gradle script' changed build result to SUCCESS
> Archiving artifacts
> java.lang.InterruptedException: no matches found within 10000
>         at hudson.FilePath$ValidateAntFileMask.hasMatch(FilePath.java:3069)
>         at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2948)
>         at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2929)
>         at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3317)
> Also:   hudson.remoting.Channel$CallSiteStackTrace: Remote call to
> lucene-solr-1
>                 at
> hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1800)
>                 at
> hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:356)
>                 at hudson.remoting.Channel.call(Channel.java:1001)
>                 at hudson.FilePath.act(FilePath.java:1159)
>                 at hudson.FilePath.act(FilePath.java:1148)
>                 at hudson.FilePath.validateAntFileMask(FilePath.java:2927)
>                 at
> hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:270)
>                 at
> hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:80)
>                 at
> hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
>                 at
> hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:803)
>                 at
> hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:752)
>                 at hudson.model.Build$BuildExecution.post2(Build.java:177)
>                 at
> hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:697)
>                 at hudson.model.Run.execute(Run.java:1931)
>                 at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
>                 at
> hudson.model.ResourceController.execute(ResourceController.java:97)
>                 at hudson.model.Executor.run(Executor.java:429)
> Caused: hudson.FilePath$TunneledInterruptedException
>         at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3319)
>         at hudson.remoting.UserRequest.perform(UserRequest.java:211)
>         at hudson.remoting.UserRequest.perform(UserRequest.java:54)
>         at hudson.remoting.Request$2.run(Request.java:376)
>         at
> hudson.remoting.InterceptingExecutorService.lambda$wrap$0(InterceptingExecutorService.java:78)
>         at java.util.concurrent.FutureTask.run(FutureTask.java:266)
>         at
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
>         at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
>         at java.lang.Thread.run(Thread.java:748)
> Caused: java.lang.InterruptedException: java.lang.InterruptedException: no
> matches found within 10000
>         at hudson.FilePath.act(FilePath.java:1161)
>         at hudson.FilePath.act(FilePath.java:1148)
>         at hudson.FilePath.validateAntFileMask(FilePath.java:2927)
>         at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:270)
>         at
> hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:80)
>         at
> hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
>         at
> hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:803)
>         at
> hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:752)
>         at hudson.model.Build$BuildExecution.post2(Build.java:177)
>         at
> hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:697)
>         at hudson.model.Run.execute(Run.java:1931)
>         at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
>         at
> hudson.model.ResourceController.execute(ResourceController.java:97)
>         at hudson.model.Executor.run(Executor.java:429)
> No artifacts found that match the file pattern
> "**/*.events,heapdumps/**,**/hs_err_pid*". Configuration error?
> Recording test results
> [Checks API] No suitable checks publisher found.
> Build step 'Publish JUnit test result report' changed build result to
> UNSTABLE
> Email was triggered for: Unstable (Test Failures)
> Sending email for trigger: Unstable (Test Failures)
>
> ---------------------------------------------------------------------
> To unsubscribe, e-mail: builds-unsubscribe@lucene.apache.org
> For additional commands, e-mail: builds-help@lucene.apache.org

Re: [JENKINS] Lucene » Lucene-NightlyTests-9.0 - Build # 13 - Unstable!

Posted by Michael McCandless <lu...@mikemccandless.com>.
Is this test just a little too evil (hitting OOME on the right seeds) or do
we possibly really have a new memory leak lurking?

Mike McCandless

http://blog.mikemccandless.com


On Mon, Nov 15, 2021 at 1:04 PM Apache Jenkins Server <
jenkins@builds.apache.org> wrote:

> Build:
> https://ci-builds.apache.org/job/Lucene/job/Lucene-NightlyTests-9.0/13/
>
> 1 tests failed.
> FAILED:
> org.apache.lucene.index.TestIndexWriterMergePolicy.testStressUpdateSameDocumentWithMergeOnGetReader
>
> Error Message:
> java.lang.IllegalStateException: this writer hit an unrecoverable error;
> cannot commit
>
> Stack Trace:
> java.lang.IllegalStateException: this writer hit an unrecoverable error;
> cannot commit
>         at
> org.apache.lucene.index.IndexWriter.startCommit(IndexWriter.java:5264)
>         at
> org.apache.lucene.index.IndexWriter.prepareCommitInternal(IndexWriter.java:3534)
>         at
> org.apache.lucene.index.IndexWriter.commitInternal(IndexWriter.java:3863)
>         at
> org.apache.lucene.index.IndexWriter.commit(IndexWriter.java:3825)
>         at
> org.apache.lucene.index.RandomIndexWriter.getReader(RandomIndexWriter.java:479)
>         at
> org.apache.lucene.index.RandomIndexWriter.getReader(RandomIndexWriter.java:406)
>         at
> org.apache.lucene.index.TestIndexWriterMergePolicy.stressUpdateSameDocumentWithMergeOnX(TestIndexWriterMergePolicy.java:763)
>         at
> org.apache.lucene.index.TestIndexWriterMergePolicy.testStressUpdateSameDocumentWithMergeOnGetReader(TestIndexWriterMergePolicy.java:707)
>         at
> java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native
> Method)
>         at
> java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
>         at
> java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
>         at java.base/java.lang.reflect.Method.invoke(Method.java:566)
>         at
> com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
>         at
> com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942)
>         at
> com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978)
>         at
> com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
>         at
> org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:44)
>         at
> org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:43)
>         at
> org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:45)
>         at
> org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:60)
>         at
> org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:44)
>         at org.junit.rules.RunRules.evaluate(RunRules.java:20)
>         at
> com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
>         at
> com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
>         at
> com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
>         at
> com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
>         at
> com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
>         at
> com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
>         at
> com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
>         at
> com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
>         at
> org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:43)
>         at
> com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
>         at
> org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:38)
>         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
> org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
>         at
> org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:43)
>         at
> org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:44)
>         at
> org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:60)
>         at
> org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:47)
>         at org.junit.rules.RunRules.evaluate(RunRules.java:20)
>         at
> com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
>         at
> com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
>         at
> com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
>         at java.base/java.lang.Thread.run(Thread.java:834)
>         Suppressed: org.apache.lucene.store.AlreadyClosedException:
> refusing to delete any files: this IndexWriter hit an unrecoverable
> exception
>                 at
> app//org.apache.lucene.index.IndexFileDeleter.ensureOpen(IndexFileDeleter.java:400)
>                 at
> app//org.apache.lucene.index.IndexFileDeleter.deleteFiles(IndexFileDeleter.java:734)
>                 at
> app//org.apache.lucene.index.IndexFileDeleter.decRef(IndexFileDeleter.java:655)
>                 at
> app//org.apache.lucene.index.IndexWriter.prepareCommitInternal(IndexWriter.java:3544)
>                 ... 45 more
>         Caused by: java.lang.OutOfMemoryError: Java heap space
>                 at java.base/java.lang.Integer.toString(Integer.java:440)
>                 at
> org.apache.lucene.codecs.simpletext.SimpleTextStoredFieldsWriter.startDocument(SimpleTextStoredFieldsWriter.java:78)
>                 at
> org.apache.lucene.codecs.StoredFieldsWriter.merge(StoredFieldsWriter.java:134)
>                 at
> org.apache.lucene.index.SegmentMerger.mergeFields(SegmentMerger.java:231)
>                 at
> org.apache.lucene.index.SegmentMerger$$Lambda$237/0x00000001003c4040.merge(Unknown
> Source)
>                 at
> org.apache.lucene.index.SegmentMerger.mergeWithLogging(SegmentMerger.java:270)
>                 at
> org.apache.lucene.index.SegmentMerger.merge(SegmentMerger.java:109)
>                 at
> org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:4964)
>                 at
> org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:4500)
>                 at
> org.apache.lucene.index.IndexWriter$IndexWriterMergeSource.merge(IndexWriter.java:6252)
>                 at
> org.apache.lucene.index.ConcurrentMergeScheduler.doMerge(ConcurrentMergeScheduler.java:636)
>                 at
> org.apache.lucene.index.ConcurrentMergeScheduler$MergeThread.run(ConcurrentMergeScheduler.java:697)
> Caused by: java.lang.OutOfMemoryError: Java heap space
>         at java.base/java.lang.Integer.toString(Integer.java:440)
>         at
> org.apache.lucene.codecs.simpletext.SimpleTextStoredFieldsWriter.startDocument(SimpleTextStoredFieldsWriter.java:78)
>         at
> org.apache.lucene.codecs.StoredFieldsWriter.merge(StoredFieldsWriter.java:134)
>         at
> org.apache.lucene.index.SegmentMerger.mergeFields(SegmentMerger.java:231)
>         at
> org.apache.lucene.index.SegmentMerger$$Lambda$237/0x00000001003c4040.merge(Unknown
> Source)
>         at
> org.apache.lucene.index.SegmentMerger.mergeWithLogging(SegmentMerger.java:270)
>         at
> org.apache.lucene.index.SegmentMerger.merge(SegmentMerger.java:109)
>         at
> org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:4964)
>         at org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:4500)
>         at
> org.apache.lucene.index.IndexWriter$IndexWriterMergeSource.merge(IndexWriter.java:6252)
>         at
> org.apache.lucene.index.ConcurrentMergeScheduler.doMerge(ConcurrentMergeScheduler.java:636)
>         at
> org.apache.lucene.index.ConcurrentMergeScheduler$MergeThread.run(ConcurrentMergeScheduler.java:697)
>
>
>
>
> Build Log:
> [...truncated 934 lines...]
> ERROR: The following test(s) have failed:
>   -
> org.apache.lucene.index.TestIndexWriterMergePolicy.testStressUpdateSameDocumentWithMergeOnGetReader
> (:lucene:core)
>     Test output:
> /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-NightlyTests-9.0/checkout/lucene/core/property
> 'outputLocation'/outputs/OUTPUT-org.apache.lucene.index.TestIndexWriterMergePolicy.txt
>     Reproduce with: gradlew :lucene:core:test --tests
> "org.apache.lucene.index.TestIndexWriterMergePolicy.testStressUpdateSameDocumentWithMergeOnGetReader"
> -Ptests.jvms=4 -Ptests.haltonfailure=false
> -Ptests.jvmargs=-XX:TieredStopAtLevel=1 -Ptests.seed=C35D88E68E5686A1
> -Ptests.multiplier=2 -Ptests.nightly=true -Ptests.badapples=false
> -Ptests.file.encoding=UTF-8
> -Ptests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-NightlyTests-9.0/test-data/enwiki.random.lines.txt
>
>
> BUILD SUCCESSFUL in 4h 45m 8s
> 195 actionable tasks: 195 executed
> Build step 'Invoke Gradle script' changed build result to SUCCESS
> Archiving artifacts
> java.lang.InterruptedException: no matches found within 10000
>         at hudson.FilePath$ValidateAntFileMask.hasMatch(FilePath.java:3069)
>         at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2948)
>         at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2929)
>         at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3317)
> Also:   hudson.remoting.Channel$CallSiteStackTrace: Remote call to
> lucene-solr-1
>                 at
> hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1800)
>                 at
> hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:356)
>                 at hudson.remoting.Channel.call(Channel.java:1001)
>                 at hudson.FilePath.act(FilePath.java:1159)
>                 at hudson.FilePath.act(FilePath.java:1148)
>                 at hudson.FilePath.validateAntFileMask(FilePath.java:2927)
>                 at
> hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:270)
>                 at
> hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:80)
>                 at
> hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
>                 at
> hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:803)
>                 at
> hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:752)
>                 at hudson.model.Build$BuildExecution.post2(Build.java:177)
>                 at
> hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:697)
>                 at hudson.model.Run.execute(Run.java:1931)
>                 at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
>                 at
> hudson.model.ResourceController.execute(ResourceController.java:97)
>                 at hudson.model.Executor.run(Executor.java:429)
> Caused: hudson.FilePath$TunneledInterruptedException
>         at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3319)
>         at hudson.remoting.UserRequest.perform(UserRequest.java:211)
>         at hudson.remoting.UserRequest.perform(UserRequest.java:54)
>         at hudson.remoting.Request$2.run(Request.java:376)
>         at
> hudson.remoting.InterceptingExecutorService.lambda$wrap$0(InterceptingExecutorService.java:78)
>         at java.util.concurrent.FutureTask.run(FutureTask.java:266)
>         at
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
>         at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
>         at java.lang.Thread.run(Thread.java:748)
> Caused: java.lang.InterruptedException: java.lang.InterruptedException: no
> matches found within 10000
>         at hudson.FilePath.act(FilePath.java:1161)
>         at hudson.FilePath.act(FilePath.java:1148)
>         at hudson.FilePath.validateAntFileMask(FilePath.java:2927)
>         at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:270)
>         at
> hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:80)
>         at
> hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
>         at
> hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:803)
>         at
> hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:752)
>         at hudson.model.Build$BuildExecution.post2(Build.java:177)
>         at
> hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:697)
>         at hudson.model.Run.execute(Run.java:1931)
>         at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
>         at
> hudson.model.ResourceController.execute(ResourceController.java:97)
>         at hudson.model.Executor.run(Executor.java:429)
> No artifacts found that match the file pattern
> "**/*.events,heapdumps/**,**/hs_err_pid*". Configuration error?
> Recording test results
> [Checks API] No suitable checks publisher found.
> Build step 'Publish JUnit test result report' changed build result to
> UNSTABLE
> Email was triggered for: Unstable (Test Failures)
> Sending email for trigger: Unstable (Test Failures)
>
> ---------------------------------------------------------------------
> To unsubscribe, e-mail: builds-unsubscribe@lucene.apache.org
> For additional commands, e-mail: builds-help@lucene.apache.org