ilya terskov created JAMES-4039:
-----------------------------------
Summary: Unstable Mailbox
Key: JAMES-4039
URL: https://issues.apache.org/jira/browse/JAMES-4039
Project: James Server
Issue Type: Bug
Components: James Core, mailbox
Affects Versions: 3.8.1
Environment: debian 11, oracle jdk 21
Reporter: ilya terskov
after i send some mail
smtp-source -A -C100 -r 1 -l 500 -m 5000 -s 20 -d -c -f a...@other.com -M
other.com -t it@kantarera.online 172.16.149.37:25
server going work strange
eM Client cant get mails completly
Mailbird load all
Thunderbird load all
After i try delete some mails in Mailbird - they got deleted BUT
now eM Client see no mail (even trashed)
Thunder see all mails like it not beed trashed
Mailbird see mail as it supposed to be partly in Incoming mail and partly in
trashed
i tried restarting docker - not helping
logs about trashing in Maibird
07:53:12.848 [INFO ] o.a.j.u.AuditTrail - IMAP EXPUNGE succeeded.
07:53:13.012 [INFO ] o.a.j.u.AuditTrail - IMAP EXPUNGE succeeded.
07:53:13.180 [INFO ] o.a.j.u.AuditTrail - IMAP EXPUNGE succeeded.
07:53:13.339 [INFO ] o.a.j.u.AuditTrail - IMAP EXPUNGE succeeded.
07:53:13.515 [INFO ] o.a.j.u.AuditTrail - IMAP EXPUNGE succeeded.
07:53:13.664 [INFO ] o.a.j.u.AuditTrail - IMAP EXPUNGE succeeded.
07:53:13.826 [INFO ] o.a.j.u.AuditTrail - IMAP EXPUNGE succeeded.
07:53:14.056 [INFO ] o.a.j.u.AuditTrail - IMAP EXPUNGE succeeded.
07:53:14.224 [INFO ] o.a.j.u.AuditTrail - IMAP EXPUNGE succeeded.
07:53:14.391 [INFO ] o.a.j.u.AuditTrail - IMAP EXPUNGE succeeded.
07:53:14.568 [INFO ] o.a.j.u.AuditTrail - IMAP EXPUNGE succeeded.
07:53:14.741 [INFO ] o.a.j.u.AuditTrail - IMAP EXPUNGE succeeded.
07:53:14.919 [INFO ] o.a.j.u.AuditTrail - IMAP EXPUNGE succeeded.
07:53:15.084 [INFO ] o.a.j.u.AuditTrail - IMAP EXPUNGE succeeded.
07:53:15.285 [INFO ] o.a.j.u.AuditTrail - IMAP EXPUNGE succeeded.
07:53:15.471 [INFO ] o.a.j.u.AuditTrail - IMAP EXPUNGE succeeded.
logs errors
07:53:12.847 [ERROR] o.a.j.e.d.InVmEventDelivery - Error while processing
listener
org.apache.openjpa.persistence.InvalidStateException: The context has been
closed. The stack trace at which the context was closed is available if
Runtime=TRACE logging is enabled.
at org.apache.openjpa.kernel.BrokerImpl.assertOpen(BrokerImpl.java:4897)
at
org.apache.openjpa.kernel.BrokerImpl.beginOperation(BrokerImpl.java:2056)
at org.apache.openjpa.kernel.BrokerImpl.newQuery(BrokerImpl.java:3926)
at
org.apache.openjpa.kernel.DelegatingBroker.newQuery(DelegatingBroker.java:1426)
at
org.apache.openjpa.persistence.EntityManagerImpl.createNamedQuery(EntityManagerImpl.java:1226)
at
org.apache.openjpa.persistence.EntityManagerImpl.createNamedQuery(EntityManagerImpl.java:108)
at
org.apache.james.mailbox.jpa.mail.JPAMessageMapper.findMessagesInMailboxBetweenUIDs(JPAMessageMapper.java:459)
at
org.apache.james.mailbox.jpa.mail.JPAMessageMapper.findAsList(JPAMessageMapper.java:143)
at
org.apache.james.mailbox.jpa.mail.JPAMessageMapper.lambda$findInMailboxReactive$2(JPAMessageMapper.java:116)
at
com.github.fge.lambdas.supplier.SupplierChainer.lambda$sneakyThrow$195(SupplierChainer.java:74)
at reactor.core.publisher.FluxDefer.subscribe(FluxDefer.java:46)
at
reactor.core.publisher.FluxSubscribeOn$SubscribeOnSubscriber.run(FluxSubscribeOn.java:194)
at reactor.core.scheduler.WorkerTask.call(WorkerTask.java:84)
at reactor.core.scheduler.WorkerTask.call(WorkerTask.java:37)
at java.base/java.util.concurrent.FutureTask.run(Unknown Source)
at
java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(Unknown
Source)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown
Source)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown
Source)
at java.base/java.lang.Thread.run(Unknown Source)
also some log errors
07:52:26.040 [ERROR] o.a.j.i.p.AbstractMailboxProcessor - Unexpected error
during IMAP processing
org.apache.openjpa.persistence.EntityNotFoundException: The instance of type
"class org.apache.james.mailbox.jpa.mail.model.openjpa.JPAMailboxMessage" with
oid
"org.apache.james.mailbox.jpa.mail.model.openjpa.JPAMailboxMessage-org.apache.james.mailbox.jpa.mail.model.openjpa.AbstractJPAMailboxMessage$MailboxIdUidKey@2c37"
no longer exists in the data store. This may mean that you deleted the
instance in a separate transaction, but this context still has a cached version.
at
org.apache.openjpa.kernel.StateManagerImpl.loadFields(StateManagerImpl.java:3279)
at
org.apache.openjpa.kernel.StateManagerImpl.loadField(StateManagerImpl.java:3358)
at
org.apache.openjpa.kernel.StateManagerImpl.beforeAccessField(StateManagerImpl.java:1747)
at
org.apache.openjpa.kernel.StateManagerImpl.accessingField(StateManagerImpl.java:1678)
at
org.apache.james.mailbox.jpa.mail.model.openjpa.JPAMailboxMessage.pcGetheader(JPAMailboxMessage.java)
at
org.apache.james.mailbox.jpa.mail.model.openjpa.JPAMailboxMessage.getHeaderContent(JPAMailboxMessage.java:117)
at
org.apache.james.mailbox.store.ResultUtils.createHeaders(ResultUtils.java:156)
at
org.apache.james.mailbox.store.MessageResultImpl$HeadersImpl.headers(MessageResultImpl.java:376)
at
org.apache.james.imap.processor.fetch.FetchResponseBuilder.getHeaders(FetchResponseBuilder.java:416)
at
org.apache.james.imap.processor.fetch.FetchResponseBuilder.fields(FetchResponseBuilder.java:409)
at
org.apache.james.imap.processor.fetch.FetchResponseBuilder.bodyContent(FetchResponseBuilder.java:307)
at
org.apache.james.imap.processor.fetch.FetchResponseBuilder.bodyFetch(FetchResponseBuilder.java:298)
at
org.apache.james.imap.processor.fetch.FetchResponseBuilder.lambda$build$1(FetchResponseBuilder.java:148)
at
org.apache.james.mailbox.NullableMessageSequenceNumber.fold(NullableMessageSequenceNumber.java:62)
at
org.apache.james.imap.processor.fetch.FetchResponseBuilder.build(FetchResponseBuilder.java:125)
at
org.apache.james.imap.processor.fetch.FetchProcessor.toResponse(FetchProcessor.java:297)
at
org.apache.james.imap.processor.fetch.FetchProcessor.lambda$processMessageRanges$17(FetchProcessor.java:256)
at
reactor.core.publisher.FluxConcatMapNoPrefetch$FluxConcatMapNoPrefetchSubscriber.onNext(FluxConcatMapNoPrefetch.java:183)
at
reactor.core.publisher.FluxFilter$FilterSubscriber.onNext(FluxFilter.java:113)
at
reactor.core.publisher.FluxConcatMapNoPrefetch$FluxConcatMapNoPrefetchSubscriber.innerNext(FluxConcatMapNoPrefetch.java:258)
at
reactor.core.publisher.FluxConcatMap$ConcatMapInner.onNext(FluxConcatMap.java:863)
at
reactor.core.publisher.FluxMapFuseable$MapFuseableSubscriber.onNext(FluxMapFuseable.java:129)
at
reactor.core.publisher.FluxPublishOn$PublishOnSubscriber.runAsync(FluxPublishOn.java:440)
at
reactor.core.publisher.FluxPublishOn$PublishOnSubscriber.run(FluxPublishOn.java:527)
at
reactor.core.scheduler.ImmediateScheduler$ImmediateSchedulerWorker.schedule(ImmediateScheduler.java:84)
at
reactor.core.publisher.FluxPublishOn$PublishOnSubscriber.trySchedule(FluxPublishOn.java:312)
at
reactor.core.publisher.FluxPublishOn$PublishOnSubscriber.onNext(FluxPublishOn.java:237)
at
reactor.core.publisher.FluxSubscribeOn$SubscribeOnSubscriber.onNext(FluxSubscribeOn.java:151)
at
reactor.core.publisher.FluxIterable$IterableSubscription.slowPath(FluxIterable.java:335)
at
reactor.core.publisher.FluxIterable$IterableSubscription.request(FluxIterable.java:294)
at
reactor.core.publisher.FluxSubscribeOn$SubscribeOnSubscriber.requestUpstream(FluxSubscribeOn.java:131)
at
reactor.core.publisher.FluxSubscribeOn$SubscribeOnSubscriber.onSubscribe(FluxSubscribeOn.java:124)
at reactor.core.publisher.FluxIterable.subscribe(FluxIterable.java:201)
at reactor.core.publisher.FluxIterable.subscribe(FluxIterable.java:83)
at reactor.core.publisher.FluxDefer.subscribe(FluxDefer.java:54)
at
reactor.core.publisher.FluxSubscribeOn$SubscribeOnSubscriber.run(FluxSubscribeOn.java:194)
at reactor.core.scheduler.WorkerTask.call(WorkerTask.java:84)
at reactor.core.scheduler.WorkerTask.call(WorkerTask.java:37)
at java.base/java.util.concurrent.FutureTask.run(Unknown Source)
at
java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(Unknown
Source)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown
Source)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown
Source)
at java.base/java.lang.Thread.run(Unknown Source)
and some more errors
07:46:02.619 [ERROR] o.a.j.e.d.InVmEventDelivery - Error while processing
listener
java.nio.channels.ClosedByInterruptException: null
at
java.base/java.nio.channels.spi.AbstractInterruptibleChannel.end(Unknown Source)
at java.base/sun.nio.ch.FileChannelImpl.endBlocking(Unknown Source)
at java.base/sun.nio.ch.FileChannelImpl.readInternal(Unknown Source)
at java.base/sun.nio.ch.FileChannelImpl.read(Unknown Source)
at
org.apache.lucene.store.NIOFSDirectory$NIOFSIndexInput.readInternal(NIOFSDirectory.java:162)
... 51 common frames omitted
Wrapped by: java.io.IOException: null: NIOFSIndexInput(path="/root/_2gs.tii")
at
org.apache.lucene.store.NIOFSDirectory$NIOFSIndexInput.readInternal(NIOFSDirectory.java:180)
at
org.apache.lucene.store.BufferedIndexInput.refill(BufferedIndexInput.java:270)
at
org.apache.lucene.store.BufferedIndexInput.readBytes(BufferedIndexInput.java:136)
at
org.apache.lucene.store.BufferedIndexInput.readBytes(BufferedIndexInput.java:111)
at org.apache.lucene.store.DataOutput.copyBytes(DataOutput.java:132)
at
org.apache.lucene.index.CompoundFileWriter.copyFile(CompoundFileWriter.java:235)
at
org.apache.lucene.index.CompoundFileWriter.close(CompoundFileWriter.java:201)
at
org.apache.lucene.index.DocumentsWriter.flush(DocumentsWriter.java:604)
at org.apache.lucene.index.IndexWriter.doFlush(IndexWriter.java:3587)
at org.apache.lucene.index.IndexWriter.flush(IndexWriter.java:3552)
at org.apache.lucene.index.IndexWriter.getReader(IndexWriter.java:450)
at org.apache.lucene.index.IndexWriter.getReader(IndexWriter.java:399)
at org.apache.lucene.index.IndexReader.open(IndexReader.java:296)
at
org.apache.james.mailbox.lucene.search.LuceneMessageSearchIndex.update(LuceneMessageSearchIndex.java:1259)
at
org.apache.james.mailbox.lucene.search.LuceneMessageSearchIndex.lambda$update$4(LuceneMessageSearchIndex.java:1253)
at
com.github.fge.lambdas.runnable.RunnableChainer.doRun(RunnableChainer.java:18)
at
com.github.fge.lambdas.runnable.ThrowingRunnable.run(ThrowingRunnable.java:16)
... 35 common frames omitted
Wrapped by: com.github.fge.lambdas.ThrownByLambdaException:
java.io.IOException: null: NIOFSIndexInput(path="/root/_2gs.tii")
at
com.github.fge.lambdas.runnable.ThrowingRunnable.run(ThrowingRunnable.java:20)
at reactor.core.publisher.MonoRunnable.call(MonoRunnable.java:73)
at reactor.core.publisher.MonoRunnable.call(MonoRunnable.java:32)
at
reactor.core.publisher.MonoIgnoreThen$ThenIgnoreMain.subscribeNext(MonoIgnoreThen.java:253)
at
reactor.core.publisher.MonoIgnoreThen.subscribe(MonoIgnoreThen.java:51)
at reactor.core.publisher.MonoUsing.subscribe(MonoUsing.java:109)
at reactor.core.publisher.MonoDefer.subscribe(MonoDefer.java:53)
at reactor.core.publisher.Mono.subscribe(Mono.java:4512)
at
reactor.core.publisher.MonoIgnorePublisher.subscribe(MonoIgnorePublisher.java:57)
at reactor.core.publisher.FluxRetryWhen.subscribe(FluxRetryWhen.java:80)
at
reactor.core.publisher.MonoRetryWhen.subscribeOrReturn(MonoRetryWhen.java:46)
at reactor.core.publisher.Mono.subscribe(Mono.java:4496)
at
reactor.core.publisher.FluxFlatMap$FlatMapMain.onNext(FluxFlatMap.java:430)
at
reactor.core.publisher.FluxIterable$IterableSubscription.slowPath(FluxIterable.java:335)
at
reactor.core.publisher.FluxIterable$IterableSubscription.request(FluxIterable.java:294)
at
reactor.core.publisher.FluxFlatMap$FlatMapMain.onSubscribe(FluxFlatMap.java:373)
at reactor.core.publisher.FluxIterable.subscribe(FluxIterable.java:201)
at reactor.core.publisher.FluxIterable.subscribe(FluxIterable.java:83)
at reactor.core.publisher.Mono.subscribe(Mono.java:4512)
at
reactor.core.publisher.FluxFlatMap$FlatMapMain.onNext(FluxFlatMap.java:430)
at
reactor.core.publisher.FluxArray$ArraySubscription.slowPath(FluxArray.java:126)
at
reactor.core.publisher.FluxArray$ArraySubscription.request(FluxArray.java:99)
at
reactor.core.publisher.FluxFlatMap$FlatMapMain.onSubscribe(FluxFlatMap.java:373)
at reactor.core.publisher.FluxMerge.subscribe(FluxMerge.java:73)
at
reactor.core.publisher.InternalMonoOperator.subscribe(InternalMonoOperator.java:76)
at
reactor.core.publisher.MonoIgnoreThen$ThenIgnoreMain.subscribeNext(MonoIgnoreThen.java:264)
at
reactor.core.publisher.MonoIgnoreThen.subscribe(MonoIgnoreThen.java:51)
at
reactor.core.publisher.MonoFlatMap$FlatMapMain.onNext(MonoFlatMap.java:165)
at
reactor.core.publisher.FluxSubscribeOnCallable$CallableSubscribeOnSubscription.run(FluxSubscribeOnCallable.java:252)
at reactor.core.scheduler.SchedulerTask.call(SchedulerTask.java:68)
at reactor.core.scheduler.SchedulerTask.call(SchedulerTask.java:28)
at java.base/java.util.concurrent.FutureTask.run(Unknown Source)
at
java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(Unknown
Source)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown
Source)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown
Source)
at java.base/java.lang.Thread.run(Unknown Source)
sorry if too much logs
--
This message was sent by Atlassian Jira
(v8.20.10#820010)
---------------------------------------------------------------------
To unsubscribe, e-mail: server-dev-unsubscr...@james.apache.org
For additional commands, e-mail: server-dev-h...@james.apache.org
