Mailing List Archive

Whoa, it does repro! I'll open an issue and try to understand ... maybe
just unlucky random IWC, etc.

Mike McCandless

http://blog.mikemccandless.com


On Tue, Sep 7, 2021 at 8:09 AM Michael McCandless <lucene@mikemccandless.com>
wrote:

> Hmm, too many open files. I wonder if we have a file handle leak? I'll
> see if it repros.
>
> Mike McCandless
>
> http://blog.mikemccandless.com
>
>
> On Mon, Sep 6, 2021 at 3:27 AM Apache Jenkins Server <
> jenkins@builds.apache.org> wrote:
>
>> Build:
>> https://ci-builds.apache.org/job/Lucene/job/Lucene-NightlyTests-main/386/
>>
>> 1 tests failed.
>> FAILED:
>> org.apache.lucene.index.TestIndexWriterMergePolicy.testStressUpdateSameDocumentWithMergeOnGetReader
>>
>> Error Message:
>> org.apache.lucene.store.AlreadyClosedException: refusing to delete any
>> files: this IndexWriter hit an unrecoverable exception
>>
>> Stack Trace:
>> 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.IndexFileDeleter.checkpoint(IndexFileDeleter.java:597)
>> at
>> app//org.apache.lucene.index.IndexWriter.checkpointNoSIS(IndexWriter.java:2742)
>> at
>> app//org.apache.lucene.index.IndexWriter.writeReaderPool(IndexWriter.java:3732)
>> at
>> app//org.apache.lucene.index.IndexWriter.prepareCommitInternal(IndexWriter.java:3445)
>> at
>> app//org.apache.lucene.index.IndexWriter.commitInternal(IndexWriter.java:3863)
>> at
>> app//org.apache.lucene.index.IndexWriter.commit(IndexWriter.java:3825)
>> at
>> app//org.apache.lucene.index.RandomIndexWriter.getReader(RandomIndexWriter.java:479)
>> at
>> app//org.apache.lucene.index.RandomIndexWriter.getReader(RandomIndexWriter.java:406)
>> at
>> app//org.apache.lucene.index.TestIndexWriterMergePolicy.stressUpdateSameDocumentWithMergeOnX(TestIndexWriterMergePolicy.java:763)
>> at
>> app//org.apache.lucene.index.TestIndexWriterMergePolicy.testStressUpdateSameDocumentWithMergeOnGetReader(TestIndexWriterMergePolicy.java:707)
>> at java.base@11.0.11/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native
>> Method)
>> at java.base@11.0.11
>> /jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
>> at java.base@11.0.11
>> /jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
>> at java.base@11.0.11
>> /java.lang.reflect.Method.invoke(Method.java:566)
>> at
>> app//com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
>> at
>> app//com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942)
>> at
>> app//com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978)
>> at
>> app//com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
>> at
>> app//org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:44)
>> at
>> app//org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:43)
>> at
>> app//org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:45)
>> at
>> app//org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:60)
>> at
>> app//org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:44)
>> at app//org.junit.rules.RunRules.evaluate(RunRules.java:20)
>> at
>> app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
>> at
>> app//com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
>> at
>> app//com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
>> at
>> app//com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
>> at
>> app//com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
>> at
>> app//com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
>> at
>> app//com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
>> at
>> app//com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
>> at
>> app//org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:43)
>> at
>> app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
>> at
>> app//org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:38)
>> at
>> app//com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
>> at
>> app//com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
>> at
>> app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
>> at
>> app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
>> at
>> app//org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
>> at
>> app//org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:43)
>> at
>> app//org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:44)
>> at
>> app//org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:60)
>> at
>> app//org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:47)
>> at app//org.junit.rules.RunRules.evaluate(RunRules.java:20)
>> at
>> app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
>> at
>> app//com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
>> at
>> app//com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
>> at java.base@11.0.11/java.lang.Thread.run(Thread.java:834)
>> Caused by: java.nio.file.FileSystemException:
>> /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-NightlyTests-main/checkout/lucene/core/build/tmp/tests-tmp/lucene.index.TestIndexWriterMergePolicy_971E1D93B44F0009-001/index-NIOFSDirectory-001/_2eb_Lucene90FieldsIndexfile_pointers_4sn.tmp:
>> Too many open files
>> at
>> org.apache.lucene.mockfile.HandleLimitFS.onOpen(HandleLimitFS.java:45)
>> at
>> org.apache.lucene.mockfile.HandleTrackingFS.callOpenHook(HandleTrackingFS.java:82)
>> at
>> org.apache.lucene.mockfile.HandleTrackingFS.newOutputStream(HandleTrackingFS.java:163)
>> at
>> org.apache.lucene.mockfile.FilterFileSystemProvider.newOutputStream(FilterFileSystemProvider.java:194)
>> at java.base/java.nio.file.Files.newOutputStream(Files.java:220)
>> at
>> org.apache.lucene.store.FSDirectory$FSIndexOutput.<init>(FSDirectory.java:392)
>> at
>> org.apache.lucene.store.FSDirectory.createTempOutput(FSDirectory.java:234)
>> at
>> org.apache.lucene.store.FileSwitchDirectory.createTempOutput(FileSwitchDirectory.java:198)
>> at
>> org.apache.lucene.store.MockDirectoryWrapper.createTempOutput(MockDirectoryWrapper.java:745)
>> at
>> org.apache.lucene.store.FilterDirectory.createTempOutput(FilterDirectory.java:81)
>> at
>> org.apache.lucene.store.TrackingDirectoryWrapper.createTempOutput(TrackingDirectoryWrapper.java:49)
>> at
>> org.apache.lucene.codecs.lucene90.compressing.FieldsIndexWriter.<init>(FieldsIndexWriter.java:87)
>> at
>> org.apache.lucene.codecs.lucene90.compressing.Lucene90CompressingStoredFieldsWriter.<init>(Lucene90CompressingStoredFieldsWriter.java:148)
>> at
>> org.apache.lucene.codecs.lucene90.compressing.Lucene90CompressingStoredFieldsFormat.fieldsWriter(Lucene90CompressingStoredFieldsFormat.java:140)
>> at
>> org.apache.lucene.codecs.lucene90.Lucene90StoredFieldsFormat.fieldsWriter(Lucene90StoredFieldsFormat.java:154)
>> at
>> org.apache.lucene.index.StoredFieldsConsumer.initStoredFieldsWriter(StoredFieldsConsumer.java:49)
>> at
>> org.apache.lucene.index.StoredFieldsConsumer.startDocument(StoredFieldsConsumer.java:56)
>> at
>> org.apache.lucene.index.IndexingChain.startStoredFields(IndexingChain.java:556)
>> at
>> org.apache.lucene.index.IndexingChain.processDocument(IndexingChain.java:587)
>> at
>> org.apache.lucene.index.DocumentsWriterPerThread.updateDocuments(DocumentsWriterPerThread.java:239)
>> at
>> org.apache.lucene.index.DocumentsWriter.updateDocuments(DocumentsWriter.java:432)
>> at
>> org.apache.lucene.index.IndexWriter.updateDocuments(IndexWriter.java:1530)
>> at
>> org.apache.lucene.index.IndexWriter.updateDocument(IndexWriter.java:1815)
>> at
>> org.apache.lucene.index.IndexWriter.addDocument(IndexWriter.java:1468)
>> at
>> org.apache.lucene.index.RandomIndexWriter.addDocument(RandomIndexWriter.java:208)
>> at
>> org.apache.lucene.index.TestIndexWriterMergePolicy.lambda$stressUpdateSameDocumentWithMergeOnX$4(TestIndexWriterMergePolicy.java:748)
>> ... 1 more
>>
>>
>>
>>
>> Build Log:
>> [...truncated 1153 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-main/checkout/lucene/core/build/test-results/test/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=971E1D93B44F0009
>> -Ptests.multiplier=2 -Ptests.nightly=true -Ptests.badapples=false
>> -Ptests.file.encoding=US-ASCII
>> -Ptests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-NightlyTests-main/test-data/enwiki.random.lines.txt
>>
>>
>> Deprecated Gradle features were used in this build, making it
>> incompatible with Gradle 8.0.
>>
>> You can use '--warning-mode all' to show the individual deprecation
>> warnings and determine if they come from your own scripts or plugins.
>>
>> See
>> https://docs.gradle.org/7.2/userguide/command_line_interface.html#sec:command_line_warnings
>>
>> BUILD SUCCESSFUL in 4h 21m 21s
>> 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 lucene1
>> 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
>
>