thunderbird-android icon indicating copy to clipboard operation
thunderbird-android copied to clipboard

Published 20 hours ago • verified publisher icon thunderbird

Email not being marked as read

Open Bubu opened this issue 3 years ago • 3 comments

Checklist

  • [X] I have used the search function to see if someone else has already submitted the same bug report.
  • [X] I will describe the problem with as much detail as possible.

App version

6.301

Where did you get the app from?

F-Droid

Android version

12

Device model

Pixel 4a

Steps to reproduce

Happens quite randomly but seems somewhat recentish (I've never noticed it before 6.301 but maybe it was also present in a few versions prior)

  1. Open your inbox
  2. Start reading messages
  3. Return to inbox
  4. Notice messages aren't marked as read.

Expected behavior

messages are marked as read

Actual behavior

K-9 mail doesn't mark any messages as read. Also syncing with the server seems to not work anymore until an app restart (or maybe I was impatient and it would be fine again after a minute or two?)

Logs

--------- beginning of main
08-15 15:16:28.588 14308 14325 I MessagingController: Running command 'clearNotifications', seq = 359 (foreground priority)
08-15 15:16:28.589 14308 14325 I MessagingController:  Command 'clearNotifications' completed
08-15 15:16:33.358 14308 14325 I MessagingController: Running command 'moveMessagesInThread', seq = 360 (background priority)
08-15 15:16:33.359 14308 14325 D LocalStore: Query = SELECT subject, sender_list, date, uid, flags, messages.id, to_list, cc_list, bcc_list, reply_to_list, attachment_count, internal_date, messages.message_id, folder_id, preview, threads.id, threads.root, deleted, read, flagged, answered, forwarded, message_part_id, messages.mime_type, preview_type, header FROM messages LEFT JOIN threads ON (threads.message_id = messages.id) LEFT JOIN message_parts ON (message_parts.id = messages.message_part_id) LEFT JOIN folders ON (folders.id = messages.folder_id) WHERE (empty = 0 AND deleted = 0) AND (threads.root = ?) ORDER BY date DESC
08-15 15:16:33.366 14308 14325 I MessagingController: moveOrCopyMessageSynchronous: source folder = 2, 1 messages, destination folder = 9, operation = MOVE
08-15 15:16:33.367 14308 14325 D MoveMessageOperations: Moving message [ID: 2076] to folder [ID: 9]
08-15 15:16:33.385 14308 14325 V LockableDatabase: LockableDatabase: Transaction ended, took 10 ms / com.fsck.k9.storage.messages.MoveMessageOperations.moveMessage(MoveMessageOperations.kt:20)
08-15 15:16:33.400 14308 14325 I MessagingController:  Command 'moveMessagesInThread' completed
08-15 15:16:33.400 14308 14325 I MessagingController: Running command 'processPendingCommands', seq = 361 (background priority)
08-15 15:16:33.401 14308 14325 D MessagingController: Processing pending command 'move_or_copy'
08-15 15:16:33.411 14308 14325 D RealImapConnection: Connecting to imap.bubu1.eu as imap.bubu1.eu/176.9.145.28
08-15 15:16:33.561 14308 14325 V RealImapConnection: conn78397404 <<< #null# [OK, [CAPABILITY, IMAP4rev1, SASL-IR, LOGIN-REFERRALS, ID, ENABLE, IDLE, LITERAL+, AUTH=PLAIN], Dovecot ready.]
08-15 15:16:33.561 14308 14325 D RealImapConnection: Saving [AUTH=PLAIN, LITERAL+, IMAP4REV1, LOGIN-REFERRALS, IDLE, SASL-IR, ID, ENABLE] capabilities for conn78397404
08-15 15:16:33.563 14308 14325 V RealImapConnection: conn78397404>>> 1 AUTHENTICATE PLAIN
08-15 15:16:33.594 14308 14325 V RealImapConnection: conn78397404<<<#+# []
08-15 15:16:33.645 14308 14325 V ImapResponseParser: conn78397404<<<#1# [OK, [CAPABILITY, IMAP4rev1, SASL-IR, LOGIN-REFERRALS, ID, ENABLE, IDLE, SORT, SORT=DISPLAY, THREAD=REFERENCES, THREAD=REFS, THREAD=ORDEREDSUBJECT, MULTIAPPEND, URL-PARTIAL, CATENATE, UNSELECT, CHILDREN, NAMESPACE, UIDPLUS, LIST-EXTENDED, I18NLEVEL=1, CONDSTORE, QRESYNC, ESEARCH, ESORT, SEARCHRES, WITHIN, CONTEXT=SEARCH, LIST-STATUS, BINARY, MOVE, SNIPPET=FUZZY, PREVIEW=FUZZY, PREVIEW, STATUS=SIZE, SAVEDATE, LITERAL+, NOTIFY], Logged in]
08-15 15:16:33.645 14308 14325 D RealImapConnection: Saving [URL-PARTIAL, NAMESPACE, LIST-STATUS, PREVIEW=FUZZY, THREAD=REFERENCES, BINARY, SAVEDATE, SASL-IR, WITHIN, MULTIAPPEND, SEARCHRES, ESORT, CONDSTORE, NOTIFY, STATUS=SIZE, I18NLEVEL=1, CATENATE, ID, SORT=DISPLAY, QRESYNC, UNSELECT, ENABLE, THREAD=ORDEREDSUBJECT, UIDPLUS, CHILDREN, CONTEXT=SEARCH, MOVE, SNIPPET=FUZZY, LITERAL+, IMAP4REV1, LOGIN-REFERRALS, IDLE, ESEARCH, SORT, LIST-EXTENDED, THREAD=REFS, PREVIEW] capabilities for conn78397404
08-15 15:16:33.646 14308 14325 V RealImapConnection: conn78397404>>> 2 STATUS "INBOX" (UIDVALIDITY)
08-15 15:16:33.707 14308 15125 D OpenGLRenderer: endAllActiveAnimators on 0xb400006f83be3ec0 (MenuPopupWindow$MenuDropDownListView) with handle 0xb400006e93b19a60
08-15 15:16:33.780 14308 14325 V ImapResponseParser: conn78397404<<<#null# [STATUS, INBOX, [UIDVALIDITY, 1508270677]]
08-15 15:16:33.781 14308 14325 V ImapResponseParser: conn78397404<<<#2# [OK, Status completed (0.092 + 0.000 + 0.091 secs).]
08-15 15:16:33.782 14308 14325 V RealImapConnection: conn78397404>>> 3 NOOP
08-15 15:16:33.814 14308 14325 V ImapResponseParser: conn78397404<<<#3# [OK, NOOP completed (0.001 + 0.000 secs).]
08-15 15:16:33.815 14308 14325 V RealImapConnection: conn78397404>>> 4 SELECT "INBOX"
08-15 15:16:33.931 14308 14325 V ImapResponseParser: conn78397404<<<#null# [FLAGS, [\Answered, \Flagged, \Deleted, \Seen, \Draft, NonJunk, $label1, Junk, $label2, $label4, $label3, $Forwarded, $label5, $MDNSent, $junk]]
08-15 15:16:33.932 14308 14325 V ImapResponseParser: conn78397404<<<#null# [OK, [PERMANENTFLAGS, [\Answered, \Flagged, \Deleted, \Seen, \Draft, NonJunk, $label1, Junk, $label2, $label4, $label3, $Forwarded, $label5, $MDNSent, $junk, \*]], Flags permitted.]
08-15 15:16:33.933 14308 14325 V ImapResponseParser: conn78397404<<<#null# [92676, EXISTS]
08-15 15:16:33.933 14308 14325 V ImapResponseParser: conn78397404<<<#null# [11, RECENT]
08-15 15:16:33.933 14308 14325 V ImapResponseParser: conn78397404<<<#null# [OK, [UNSEEN, 67070], First unseen.]
08-15 15:16:33.934 14308 14325 V ImapResponseParser: conn78397404<<<#null# [OK, [UIDVALIDITY, 1508270677], UIDs valid]
08-15 15:16:33.934 14308 14325 V ImapResponseParser: conn78397404<<<#null# [OK, [UIDNEXT, 109545], Predicted next UID]
08-15 15:16:33.935 14308 14325 V ImapResponseParser: conn78397404<<<#null# [OK, [HIGHESTMODSEQ, 247695], Highest]
08-15 15:16:33.935 14308 14325 V ImapResponseParser: conn78397404<<<#4# [OK, [READ-WRITE], Select completed (0.085 + 0.000 + 0.084 secs).]
08-15 15:16:33.936 14308 14325 D RealImapFolder: Got untagged EXISTS with value 92676 for ea24c898-0407-43a7-a3f0-9c6cbc54a44c:INBOX/MessagingController/conn78397404
08-15 15:16:33.936 14308 14325 D RealImapFolder: Got UidNext = 109545 for ea24c898-0407-43a7-a3f0-9c6cbc54a44c:INBOX/MessagingController/conn78397404
08-15 15:16:33.938 14308 14325 D CommandMoveOrCopyMessages: moveOrCopyMessages: source folder = INBOX, 1 messages, destination folder = Spam, isCopy = false
08-15 15:16:33.939 14308 14325 D RealImapConnection: Connecting to imap.bubu1.eu as imap.bubu1.eu/176.9.145.28
08-15 15:16:34.091 14308 14325 V RealImapConnection: conn29280409 <<< #null# [OK, [CAPABILITY, IMAP4rev1, SASL-IR, LOGIN-REFERRALS, ID, ENABLE, IDLE, LITERAL+, AUTH=PLAIN], Dovecot ready.]
08-15 15:16:34.092 14308 14325 D RealImapConnection: Saving [AUTH=PLAIN, LITERAL+, IMAP4REV1, LOGIN-REFERRALS, IDLE, SASL-IR, ID, ENABLE] capabilities for conn29280409
08-15 15:16:34.093 14308 14325 V RealImapConnection: conn29280409>>> 1 AUTHENTICATE PLAIN
08-15 15:16:34.129 14308 14325 V RealImapConnection: conn29280409<<<#+# []
08-15 15:16:34.181 14308 14325 V ImapResponseParser: conn29280409<<<#1# [OK, [CAPABILITY, IMAP4rev1, SASL-IR, LOGIN-REFERRALS, ID, ENABLE, IDLE, SORT, SORT=DISPLAY, THREAD=REFERENCES, THREAD=REFS, THREAD=ORDEREDSUBJECT, MULTIAPPEND, URL-PARTIAL, CATENATE, UNSELECT, CHILDREN, NAMESPACE, UIDPLUS, LIST-EXTENDED, I18NLEVEL=1, CONDSTORE, QRESYNC, ESEARCH, ESORT, SEARCHRES, WITHIN, CONTEXT=SEARCH, LIST-STATUS, BINARY, MOVE, SNIPPET=FUZZY, PREVIEW=FUZZY, PREVIEW, STATUS=SIZE, SAVEDATE, LITERAL+, NOTIFY], Logged in]
08-15 15:16:34.182 14308 14325 D RealImapConnection: Saving [URL-PARTIAL, NAMESPACE, LIST-STATUS, PREVIEW=FUZZY, THREAD=REFERENCES, BINARY, SAVEDATE, SASL-IR, WITHIN, MULTIAPPEND, SEARCHRES, ESORT, CONDSTORE, NOTIFY, STATUS=SIZE, I18NLEVEL=1, CATENATE, ID, SORT=DISPLAY, QRESYNC, UNSELECT, ENABLE, THREAD=ORDEREDSUBJECT, UIDPLUS, CHILDREN, CONTEXT=SEARCH, MOVE, SNIPPET=FUZZY, LITERAL+, IMAP4REV1, LOGIN-REFERRALS, IDLE, ESEARCH, SORT, LIST-EXTENDED, THREAD=REFS, PREVIEW] capabilities for conn29280409
08-15 15:16:34.183 14308 14325 V RealImapConnection: conn78397404>>> 5 STATUS "Spam" (RECENT)
08-15 15:16:34.216 14308 14325 V ImapResponseParser: conn78397404<<<#null# [STATUS, Spam, [RECENT, 0]]
08-15 15:16:34.217 14308 14325 V ImapResponseParser: conn78397404<<<#5# [OK, Status completed (0.001 + 0.000 secs).]
08-15 15:16:34.219 14308 14325 V RealImapConnection: conn78397404>>> 6 UID COPY 109543 "Spam"
08-15 15:16:34.613 14308 14325 V ImapResponseParser: conn78397404<<<#6# [OK, [COPYUID, 1508270681, 109543, 9579], Copy completed (0.359 + 0.000 + 0.358 secs).]
08-15 15:16:34.614 14308 14325 V RealImapConnection: conn78397404>>> 7 NOOP
08-15 15:16:34.644 14308 14325 V ImapResponseParser: conn78397404<<<#7# [OK, NOOP completed (0.001 + 0.000 secs).]
08-15 15:16:34.646 14308 14325 V RealImapConnection: conn78397404>>> 8 UID STORE 109543 +FLAGS.SILENT (\Deleted)
08-15 15:16:34.698 14308 14325 V ImapResponseParser: conn78397404<<<#8# [OK, Store completed (0.023 + 0.000 + 0.022 secs).]
08-15 15:16:34.699 14308 14325 I MessagingController: processingPendingMoveOrCopy expunging folder ea24c898-0407-43a7-a3f0-9c6cbc54a44c:INBOX
08-15 15:16:34.700 14308 14325 V RealImapConnection: conn29280409>>> 2 NOOP
08-15 15:16:34.731 14308 14325 V ImapResponseParser: conn29280409<<<#2# [OK, NOOP completed (0.001 + 0.000 secs).]
08-15 15:16:34.732 14308 14325 V RealImapConnection: conn29280409>>> 3 STATUS "INBOX" (UIDVALIDITY)
08-15 15:16:34.768 14308 14325 V ImapResponseParser: conn29280409<<<#null# [STATUS, INBOX, [UIDVALIDITY, 1508270677]]
08-15 15:16:34.768 14308 14325 V ImapResponseParser: conn29280409<<<#3# [OK, Status completed (0.003 + 0.000 + 0.002 secs).]
08-15 15:16:34.769 14308 14325 V RealImapConnection: conn78397404>>> 9 NOOP
08-15 15:16:34.799 14308 14325 V ImapResponseParser: conn78397404<<<#9# [OK, NOOP completed (0.001 + 0.000 secs).]
08-15 15:16:34.800 14308 14325 V RealImapConnection: conn78397404>>> 10 SELECT "INBOX"
08-15 15:16:34.823 14308 14308 D MessageViewFragment: MessageViewFragment displaying message MessageReference(accountUuid=ea24c898-0407-43a7-a3f0-9c6cbc54a44c, folderId=2, uid=109537)
08-15 15:16:34.823 14308 14308 D MessageLoaderHelper: Creating new local message loader
08-15 15:16:34.836 14308 14308 D MessageLoaderHelper: Creating new decode message loader
08-15 15:16:34.968 14308 14308 D Utility : No external images.
08-15 15:16:35.089 14308 14325 V ImapResponseParser: conn78397404<<<#null# [OK, [CLOSED], Previous mailbox closed.]
08-15 15:16:35.089 14308 14325 V ImapResponseParser: conn78397404<<<#null# [FLAGS, [\Answered, \Flagged, \Deleted, \Seen, \Draft, NonJunk, $label1, Junk, $label2, $label4, $label3, $Forwarded, $label5, $MDNSent, $junk]]
08-15 15:16:35.089 14308 14325 V ImapResponseParser: conn78397404<<<#null# [OK, [PERMANENTFLAGS, [\Answered, \Flagged, \Deleted, \Seen, \Draft, NonJunk, $label1, Junk, $label2, $label4, $label3, $Forwarded, $label5, $MDNSent, $junk, \*]], Flags permitted.]
08-15 15:16:35.089 14308 14325 V ImapResponseParser: conn78397404<<<#null# [92676, EXISTS]
08-15 15:16:35.090 14308 14325 V ImapResponseParser: conn78397404<<<#null# [0, RECENT]
08-15 15:16:35.090 14308 14325 V ImapResponseParser: conn78397404<<<#null# [OK, [UNSEEN, 67070], First unseen.]
08-15 15:16:35.090 14308 14325 V ImapResponseParser: conn78397404<<<#null# [OK, [UIDVALIDITY, 1508270677], UIDs valid]
08-15 15:16:35.090 14308 14325 V ImapResponseParser: conn78397404<<<#null# [OK, [UIDNEXT, 109545], Predicted next UID]
08-15 15:16:35.090 14308 14325 V ImapResponseParser: conn78397404<<<#null# [OK, [HIGHESTMODSEQ, 247696], Highest]
08-15 15:16:35.090 14308 14325 V ImapResponseParser: conn78397404<<<#10# [OK, [READ-WRITE], Select completed (0.256 + 0.000 + 0.255 secs).]
08-15 15:16:35.091 14308 14325 D RealImapFolder: Got untagged EXISTS with value 92676 for ea24c898-0407-43a7-a3f0-9c6cbc54a44c:INBOX/MessagingController/conn78397404
08-15 15:16:35.091 14308 14325 D RealImapFolder: Got UidNext = 109545 for ea24c898-0407-43a7-a3f0-9c6cbc54a44c:INBOX/MessagingController/conn78397404
08-15 15:16:35.093 14308 14325 V RealImapConnection: conn78397404>>> 11 NOOP
08-15 15:16:35.122 14308 14325 V ImapResponseParser: conn78397404<<<#11# [OK, NOOP completed (0.001 + 0.000 secs).]
08-15 15:16:35.123 14308 14325 V RealImapConnection: conn78397404>>> 12 UID EXPUNGE 109543
08-15 15:16:45.213 14308 14308 D MessageViewFragment: MessageViewFragment displaying message MessageReference(accountUuid=ea24c898-0407-43a7-a3f0-9c6cbc54a44c, folderId=2, uid=109540)
08-15 15:16:45.213 14308 14308 D MessageLoaderHelper: Creating new local message loader
08-15 15:16:45.225 14308 14308 D MessageLoaderHelper: Creating new decode message loader
08-15 15:16:45.255 14308 14308 D Utility : No external images.
08-15 15:17:31.465 14308 14308 D MessageViewFragment: MessageViewFragment displaying message MessageReference(accountUuid=ea24c898-0407-43a7-a3f0-9c6cbc54a44c, folderId=2, uid=109540)
08-15 15:17:31.465 14308 14308 D MessageLoaderHelper: Creating new local message loader
08-15 15:17:31.477 14308 14308 D MessageLoaderHelper: Creating new decode message loader
08-15 15:17:31.502 14308 14308 D Utility : No external images.
08-15 15:17:35.172 14308 14325 E RealImapFolder: IOException for ea24c898-0407-43a7-a3f0-9c6cbc54a44c:INBOX/MessagingController/conn78397404
08-15 15:17:35.172 14308 14325 E RealImapFolder: java.net.SocketTimeoutException: Read timed out
08-15 15:17:35.172 14308 14325 E RealImapFolder: 	at java.net.SocketInputStream.socketRead0(Native Method)
08-15 15:17:35.172 14308 14325 E RealImapFolder: 	at java.net.SocketInputStream.socketRead(SocketInputStream.java:119)
08-15 15:17:35.172 14308 14325 E RealImapFolder: 	at java.net.SocketInputStream.read(SocketInputStream.java:176)
08-15 15:17:35.172 14308 14325 E RealImapFolder: 	at java.net.SocketInputStream.read(SocketInputStream.java:144)
08-15 15:17:35.172 14308 14325 E RealImapFolder: 	at com.android.org.conscrypt.ConscryptEngineSocket$SSLInputStream.readFromSocket(ConscryptEngineSocket.java:945)
08-15 15:17:35.172 14308 14325 E RealImapFolder: 	at com.android.org.conscrypt.ConscryptEngineSocket$SSLInputStream.processDataFromSocket(ConscryptEngineSocket.java:909)
08-15 15:17:35.172 14308 14325 E RealImapFolder: 	at com.android.org.conscrypt.ConscryptEngineSocket$SSLInputStream.readUntilDataAvailable(ConscryptEngineSocket.java:824)
08-15 15:17:35.172 14308 14325 E RealImapFolder: 	at com.android.org.conscrypt.ConscryptEngineSocket$SSLInputStream.read(ConscryptEngineSocket.java:797)
08-15 15:17:35.172 14308 14325 E RealImapFolder: 	at java.io.BufferedInputStream.fill(BufferedInputStream.java:248)
08-15 15:17:35.172 14308 14325 E RealImapFolder: 	at java.io.BufferedInputStream.read(BufferedInputStream.java:267)
08-15 15:17:35.172 14308 14325 E RealImapFolder: 	at com.fsck.k9.mail.filter.PeekableInputStream.peek(PeekableInputStream.java:36)
08-15 15:17:35.172 14308 14325 E RealImapFolder: 	at com.fsck.k9.mail.store.imap.ImapResponseParser.readResponse(ImapResponseParser.java:36)
08-15 15:17:35.172 14308 14325 E RealImapFolder: 	at com.fsck.k9.mail.store.imap.ImapResponseParser.readResponse(ImapResponseParser.java:28)
08-15 15:17:35.172 14308 14325 E RealImapFolder: 	at com.fsck.k9.mail.store.imap.ImapResponseParser.readStatusResponse(ImapResponseParser.java:86)
08-15 15:17:35.172 14308 14325 E RealImapFolder: 	at com.fsck.k9.mail.store.imap.RealImapConnection.executeSimpleCommand(RealImapConnection.java:762)
08-15 15:17:35.172 14308 14325 E RealImapFolder: 	at com.fsck.k9.mail.store.imap.RealImapConnection.executeSimpleCommand(RealImapConnection.java:748)
08-15 15:17:35.172 14308 14325 E RealImapFolder: 	at com.fsck.k9.mail.store.imap.RealImapConnection.executeCommandWithIdSet(RealImapConnection.java:780)
08-15 15:17:35.172 14308 14325 E RealImapFolder: 	at com.fsck.k9.mail.store.imap.RealImapFolder.expungeUids(RealImapFolder.kt:1083)
08-15 15:17:35.172 14308 14325 E RealImapFolder: 	at com.fsck.k9.backend.imap.CommandExpunge.expungeMessages(CommandExpunge.kt:35)
08-15 15:17:35.172 14308 14325 E RealImapFolder: 	at com.fsck.k9.backend.imap.ImapBackend.expungeMessages(ImapBackend.kt:83)
08-15 15:17:35.172 14308 14325 E RealImapFolder: 	at com.fsck.k9.controller.MessagingController.processPendingMoveOrCopy(MessagingController.java:931)
08-15 15:17:35.172 14308 14325 E RealImapFolder: 	at com.fsck.k9.controller.MessagingController.processPendingMoveOrCopy(MessagingController.java:883)
08-15 15:17:35.172 14308 14325 E RealImapFolder: 	at com.fsck.k9.controller.MessagingControllerCommands$PendingMoveOrCopy.execute(MessagingControllerCommands.java:66)
08-15 15:17:35.172 14308 14325 E RealImapFolder: 	at com.fsck.k9.controller.MessagingController.processPendingCommandsSynchronous(MessagingController.java:742)
08-15 15:17:35.172 14308 14325 E RealImapFolder: 	at com.fsck.k9.controller.MessagingController$3.run(MessagingController.java:712)
08-15 15:17:35.172 14308 14325 E RealImapFolder: 	at com.fsck.k9.controller.MessagingController.runInBackground(MessagingController.java:223)
08-15 15:17:35.172 14308 14325 E RealImapFolder: 	at com.fsck.k9.controller.MessagingController.access$000(MessagingController.java:109)
08-15 15:17:35.172 14308 14325 E RealImapFolder: 	at com.fsck.k9.controller.MessagingController$1.run(MessagingController.java:164)
08-15 15:17:35.172 14308 14325 E RealImapFolder: 	at java.lang.Thread.run(Thread.java:920)
08-15 15:17:35.173 14308 14325 E MessagingController: Could not process command 'com.fsck.k9.controller.MessagingControllerCommands$PendingMoveOrCopy@6973142'
08-15 15:17:35.173 14308 14325 E MessagingController: com.fsck.k9.mail.MessagingException: IO Error
08-15 15:17:35.173 14308 14325 E MessagingController: 	at com.fsck.k9.mail.store.imap.RealImapFolder.ioExceptionHandler(RealImapFolder.kt:1140)
08-15 15:17:35.173 14308 14325 E MessagingController: 	at com.fsck.k9.mail.store.imap.RealImapFolder.expungeUids(RealImapFolder.kt:1088)
08-15 15:17:35.173 14308 14325 E MessagingController: 	at com.fsck.k9.backend.imap.CommandExpunge.expungeMessages(CommandExpunge.kt:35)
08-15 15:17:35.173 14308 14325 E MessagingController: 	at com.fsck.k9.backend.imap.ImapBackend.expungeMessages(ImapBackend.kt:83)
08-15 15:17:35.173 14308 14325 E MessagingController: 	at com.fsck.k9.controller.MessagingController.processPendingMoveOrCopy(MessagingController.java:931)
08-15 15:17:35.173 14308 14325 E MessagingController: 	at com.fsck.k9.controller.MessagingController.processPendingMoveOrCopy(MessagingController.java:883)
08-15 15:17:35.173 14308 14325 E MessagingController: 	at com.fsck.k9.controller.MessagingControllerCommands$PendingMoveOrCopy.execute(MessagingControllerCommands.java:66)
08-15 15:17:35.173 14308 14325 E MessagingController: 	at com.fsck.k9.controller.MessagingController.processPendingCommandsSynchronous(MessagingController.java:742)
08-15 15:17:35.173 14308 14325 E MessagingController: 	at com.fsck.k9.controller.MessagingController$3.run(MessagingController.java:712)
08-15 15:17:35.173 14308 14325 E MessagingController: 	at com.fsck.k9.controller.MessagingController.runInBackground(MessagingController.java:223)
08-15 15:17:35.173 14308 14325 E MessagingController: 	at com.fsck.k9.controller.MessagingController.access$000(MessagingController.java:109)
08-15 15:17:35.173 14308 14325 E MessagingController: 	at com.fsck.k9.controller.MessagingController$1.run(MessagingController.java:164)
08-15 15:17:35.173 14308 14325 E MessagingController: 	at java.lang.Thread.run(Thread.java:920)
08-15 15:17:35.173 14308 14325 E MessagingController: Caused by: java.net.SocketTimeoutException: Read timed out
08-15 15:17:35.173 14308 14325 E MessagingController: 	at java.net.SocketInputStream.socketRead0(Native Method)
08-15 15:17:35.173 14308 14325 E MessagingController: 	at java.net.SocketInputStream.socketRead(SocketInputStream.java:119)
08-15 15:17:35.173 14308 14325 E MessagingController: 	at java.net.SocketInputStream.read(SocketInputStream.java:176)
08-15 15:17:35.173 14308 14325 E MessagingController: 	at java.net.SocketInputStream.read(SocketInputStream.java:144)
08-15 15:17:35.173 14308 14325 E MessagingController: 	at com.android.org.conscrypt.ConscryptEngineSocket$SSLInputStream.readFromSocket(ConscryptEngineSocket.java:945)
08-15 15:17:35.173 14308 14325 E MessagingController: 	at com.android.org.conscrypt.ConscryptEngineSocket$SSLInputStream.processDataFromSocket(ConscryptEngineSocket.java:909)
08-15 15:17:35.173 14308 14325 E MessagingController: 	at com.android.org.conscrypt.ConscryptEngineSocket$SSLInputStream.readUntilDataAvailable(ConscryptEngineSocket.java:824)
08-15 15:17:35.173 14308 14325 E MessagingController: 	at com.android.org.conscrypt.ConscryptEngineSocket$SSLInputStream.read(ConscryptEngineSocket.java:797)
08-15 15:17:35.173 14308 14325 E MessagingController: 	at java.io.BufferedInputStream.fill(BufferedInputStream.java:248)
08-15 15:17:35.173 14308 14325 E MessagingController: 	at java.io.BufferedInputStream.read(BufferedInputStream.java:267)
08-15 15:17:35.173 14308 14325 E MessagingController: 	at com.fsck.k9.mail.filter.PeekableInputStream.peek(PeekableInputStream.java:36)
08-15 15:17:35.173 14308 14325 E MessagingController: 	at com.fsck.k9.mail.store.imap.ImapResponseParser.readResponse(ImapResponseParser.java:36)
08-15 15:17:35.173 14308 14325 E MessagingController: 	at com.fsck.k9.mail.store.imap.ImapResponseParser.readResponse(ImapResponseParser.java:28)
08-15 15:17:35.173 14308 14325 E MessagingController: 	at com.fsck.k9.mail.store.imap.ImapResponseParser.readStatusResponse(ImapResponseParser.java:86)
08-15 15:17:35.173 14308 14325 E MessagingController: 	at com.fsck.k9.mail.store.imap.RealImapConnection.executeSimpleCommand(RealImapConnection.java:762)
08-15 15:17:35.173 14308 14325 E MessagingController: 	at com.fsck.k9.mail.store.imap.RealImapConnection.executeSimpleCommand(RealImapConnection.java:748)
08-15 15:17:35.173 14308 14325 E MessagingController: 	at com.fsck.k9.mail.store.imap.RealImapConnection.executeCommandWithIdSet(RealImapConnection.java:780)
08-15 15:17:35.173 14308 14325 E MessagingController: 	at com.fsck.k9.mail.store.imap.RealImapFolder.expungeUids(RealImapFolder.kt:1083)
08-15 15:17:35.173 14308 14325 E MessagingController: 	... 11 more
08-15 15:17:35.174 14308 14325 E MessagingController: processPendingCommands
08-15 15:17:35.174 14308 14325 E MessagingController: com.fsck.k9.mail.MessagingException: IO Error
08-15 15:17:35.174 14308 14325 E MessagingController: 	at com.fsck.k9.mail.store.imap.RealImapFolder.ioExceptionHandler(RealImapFolder.kt:1140)
08-15 15:17:35.174 14308 14325 E MessagingController: 	at com.fsck.k9.mail.store.imap.RealImapFolder.expungeUids(RealImapFolder.kt:1088)
08-15 15:17:35.174 14308 14325 E MessagingController: 	at com.fsck.k9.backend.imap.CommandExpunge.expungeMessages(CommandExpunge.kt:35)
08-15 15:17:35.174 14308 14325 E MessagingController: 	at com.fsck.k9.backend.imap.ImapBackend.expungeMessages(ImapBackend.kt:83)
08-15 15:17:35.174 14308 14325 E MessagingController: 	at com.fsck.k9.controller.MessagingController.processPendingMoveOrCopy(MessagingController.java:931)
08-15 15:17:35.174 14308 14325 E MessagingController: 	at com.fsck.k9.controller.MessagingController.processPendingMoveOrCopy(MessagingController.java:883)
08-15 15:17:35.174 14308 14325 E MessagingController: 	at com.fsck.k9.controller.MessagingControllerCommands$PendingMoveOrCopy.execute(MessagingControllerCommands.java:66)
08-15 15:17:35.174 14308 14325 E MessagingController: 	at com.fsck.k9.controller.MessagingController.processPendingCommandsSynchronous(MessagingController.java:742)
08-15 15:17:35.174 14308 14325 E MessagingController: 	at com.fsck.k9.controller.MessagingController$3.run(MessagingController.java:712)
08-15 15:17:35.174 14308 14325 E MessagingController: 	at com.fsck.k9.controller.MessagingController.runInBackground(MessagingController.java:223)
08-15 15:17:35.174 14308 14325 E MessagingController: 	at com.fsck.k9.controller.MessagingController.access$000(MessagingController.java:109)
08-15 15:17:35.174 14308 14325 E MessagingController: 	at com.fsck.k9.controller.MessagingController$1.run(MessagingController.java:164)
08-15 15:17:35.174 14308 14325 E MessagingController: 	at java.lang.Thread.run(Thread.java:920)
08-15 15:17:35.174 14308 14325 E MessagingController: Caused by: java.net.SocketTimeoutException: Read timed out
08-15 15:17:35.174 14308 14325 E MessagingController: 	at java.net.SocketInputStream.socketRead0(Native Method)
08-15 15:17:35.174 14308 14325 E MessagingController: 	at java.net.SocketInputStream.socketRead(SocketInputStream.java:119)
08-15 15:17:35.174 14308 14325 E MessagingController: 	at java.net.SocketInputStream.read(SocketInputStream.java:176)
08-15 15:17:35.174 14308 14325 E MessagingController: 	at java.net.SocketInputStream.read(SocketInputStream.java:144)
08-15 15:17:35.174 14308 14325 E MessagingController: 	at com.android.org.conscrypt.ConscryptEngineSocket$SSLInputStream.readFromSocket(ConscryptEngineSocket.java:945)
08-15 15:17:35.174 14308 14325 E MessagingController: 	at com.android.org.conscrypt.ConscryptEngineSocket$SSLInputStream.processDataFromSocket(ConscryptEngineSocket.java:909)
08-15 15:17:35.174 14308 14325 E MessagingController: 	at com.android.org.conscrypt.ConscryptEngineSocket$SSLInputStream.readUntilDataAvailable(ConscryptEngineSocket.java:824)
08-15 15:17:35.174 14308 14325 E MessagingController: 	at com.android.org.conscrypt.ConscryptEngineSocket$SSLInputStream.read(ConscryptEngineSocket.java:797)
08-15 15:17:35.174 14308 14325 E MessagingController: 	at java.io.BufferedInputStream.fill(BufferedInputStream.java:248)
08-15 15:17:35.174 14308 14325 E MessagingController: 	at java.io.BufferedInputStream.read(BufferedInputStream.java:267)
08-15 15:17:35.174 14308 14325 E MessagingController: 	at com.fsck.k9.mail.filter.PeekableInputStream.peek(PeekableInputStream.java:36)
08-15 15:17:35.174 14308 14325 E MessagingController: 	at com.fsck.k9.mail.store.imap.ImapResponseParser.readResponse(ImapResponseParser.java:36)
08-15 15:17:35.174 14308 14325 E MessagingController: 	at com.fsck.k9.mail.store.imap.ImapResponseParser.readResponse(ImapResponseParser.java:28)
08-15 15:17:35.174 14308 14325 E MessagingController: 	at com.fsck.k9.mail.store.imap.ImapResponseParser.readStatusResponse(ImapResponseParser.java:86)
08-15 15:17:35.174 14308 14325 E MessagingController: 	at com.fsck.k9.mail.store.imap.RealImapConnection.executeSimpleCommand(RealImapConnection.java:762)
08-15 15:17:35.174 14308 14325 E MessagingController: 	at com.fsck.k9.mail.store.imap.RealImapConnection.executeSimpleCommand(RealImapConnection.java:748)
08-15 15:17:35.174 14308 14325 E MessagingController: 	at com.fsck.k9.mail.store.imap.RealImapConnection.executeCommandWithIdSet(RealImapConnection.java:780)
08-15 15:17:35.174 14308 14325 E MessagingController: 	at com.fsck.k9.mail.store.imap.RealImapFolder.expungeUids(RealImapFolder.kt:1083)
08-15 15:17:35.174 14308 14325 E MessagingController: 	... 11 more
08-15 15:17:35.174 14308 14325 I MessagingController:  Command 'processPendingCommands' completed
08-15 15:17:35.174 14308 14325 I MessagingController: Running command 'markMessageAsOpened', seq = 362 (foreground priority)
08-15 15:17:35.179 14308 14325 I MessagingController:  Command 'markMessageAsOpened' completed
08-15 15:17:35.179 14308 14325 I MessagingController: Running command 'clearNotifications', seq = 363 (foreground priority)
08-15 15:17:35.179 14308 14325 I MessagingController:  Command 'clearNotifications' completed
08-15 15:17:35.180 14308 14325 I MessagingController: Running command 'markMessageAsOpened', seq = 364 (foreground priority)
08-15 15:17:35.184 14308 14325 I MessagingController:  Command 'markMessageAsOpened' completed
08-15 15:17:35.184 14308 14325 I MessagingController: Running command 'clearNotifications', seq = 365 (foreground priority)
08-15 15:17:35.185 14308 14325 I MessagingController:  Command 'clearNotifications' completed
08-15 15:17:35.185 14308 14325 I MessagingController: Running command 'markMessageAsOpened', seq = 366 (foreground priority)
08-15 15:17:35.186 14308  4465 V LockableDatabase: LockableDatabase: Transaction ended, took 4 ms / com.fsck.k9.mailstore.LocalStore.doBatchSetSelection(LocalStore.java:772)
08-15 15:17:35.187 14308  4466 V LockableDatabase: LockableDatabase: Transaction ended, took 1 ms / com.fsck.k9.mailstore.LocalStore.doBatchSetSelection(LocalStore.java:772)
08-15 15:17:35.187 14308 14325 I MessagingController:  Command 'markMessageAsOpened' completed
08-15 15:17:35.188 14308 14325 I MessagingController: Running command 'clearNotifications', seq = 367 (foreground priority)
08-15 15:17:35.188 14308 14325 I MessagingController:  Command 'clearNotifications' completed
08-15 15:17:35.192 14308  4465 V LockableDatabase: LockableDatabase: Transaction ended, took 1 ms / com.fsck.k9.mailstore.LocalStore.doBatchSetSelection(LocalStore.java:772)
08-15 15:17:35.195 14308  4468 V LockableDatabase: LockableDatabase: Transaction ended, took 2 ms / com.fsck.k9.mailstore.LocalStore.doBatchSetSelection(LocalStore.java:772)
08-15 15:17:35.198 14308  4466 V LockableDatabase: LockableDatabase: Transaction ended, took 1 ms / com.fsck.k9.mailstore.LocalStore.doBatchSetSelection(LocalStore.java:772)
08-15 15:17:35.212 14308  4468 V LockableDatabase: LockableDatabase: Transaction ended, took 0 ms / com.fsck.k9.mailstore.LocalStore.doBatchSetSelection(LocalStore.java:772)
08-15 15:17:35.225 14308 14325 I MessagingController: Running command 'queueSetFlag', seq = 368 (background priority)
08-15 15:17:35.230 14308 14325 I MessagingController:  Command 'queueSetFlag' completed
08-15 15:17:35.231 14308 14325 I MessagingController: Running command 'processPendingCommands', seq = 369 (background priority)
08-15 15:17:35.232 14308 14325 D MessagingController: Processing pending command 'move_or_copy'
08-15 15:17:35.235 14308 14325 V RealImapConnection: conn29280409>>> 4 NOOP
08-15 15:17:35.270 14308 14325 V ImapResponseParser: conn29280409<<<#4# [OK, NOOP completed (0.001 + 0.000 secs).]
08-15 15:17:35.272 14308 14325 V RealImapConnection: conn29280409>>> 5 STATUS "INBOX" (UIDVALIDITY)
08-15 15:17:35.304 14308 14325 V ImapResponseParser: conn29280409<<<#null# [STATUS, INBOX, [UIDVALIDITY, 1508270677]]
08-15 15:17:35.304 14308 14325 V ImapResponseParser: conn29280409<<<#5# [OK, Status completed (0.002 + 0.000 + 0.001 secs).]
08-15 15:17:35.305 14308 14325 V RealImapConnection: conn29280409>>> 6 NOOP
08-15 15:17:35.334 14308 14325 V ImapResponseParser: conn29280409<<<#6# [OK, NOOP completed (0.001 + 0.000 secs).]
08-15 15:17:35.334 14308 14325 V RealImapConnection: conn29280409>>> 7 SELECT "INBOX"
08-15 15:17:35.751 14308 14325 V ImapResponseParser: conn29280409<<<#null# [FLAGS, [\Answered, \Flagged, \Deleted, \Seen, \Draft, NonJunk, $label1, Junk, $label2, $label4, $label3, $Forwarded, $label5, $MDNSent, $junk]]
08-15 15:17:35.751 14308 14325 V ImapResponseParser: conn29280409<<<#null# [OK, [PERMANENTFLAGS, [\Answered, \Flagged, \Deleted, \Seen, \Draft, NonJunk, $label1, Junk, $label2, $label4, $label3, $Forwarded, $label5, $MDNSent, $junk, \*]], Flags permitted.]
08-15 15:17:35.752 14308 14325 V ImapResponseParser: conn29280409<<<#null# [92675, EXISTS]
08-15 15:17:35.752 14308 14325 V ImapResponseParser: conn29280409<<<#null# [0, RECENT]
08-15 15:17:35.752 14308 14325 V ImapResponseParser: conn29280409<<<#null# [OK, [UNSEEN, 67070], First unseen.]
08-15 15:17:35.753 14308 14325 V ImapResponseParser: conn29280409<<<#null# [OK, [UIDVALIDITY, 1508270677], UIDs valid]
08-15 15:17:35.753 14308 14325 V ImapResponseParser: conn29280409<<<#null# [OK, [UIDNEXT, 109545], Predicted next UID]
08-15 15:17:35.753 14308 14325 V ImapResponseParser: conn29280409<<<#null# [OK, [HIGHESTMODSEQ, 247697], Highest]
08-15 15:17:35.754 14308 14325 V ImapResponseParser: conn29280409<<<#7# [OK, [READ-WRITE], Select completed (0.347 + 0.000 + 0.346 secs).]
08-15 15:17:35.757 14308 14325 D RealImapFolder: Got untagged EXISTS with value 92675 for ea24c898-0407-43a7-a3f0-9c6cbc54a44c:INBOX/MessagingController/conn29280409
08-15 15:17:35.757 14308 14325 D RealImapFolder: Got UidNext = 109545 for ea24c898-0407-43a7-a3f0-9c6cbc54a44c:INBOX/MessagingController/conn29280409
08-15 15:17:35.758 14308 14325 D CommandMoveOrCopyMessages: moveOrCopyMessages: source folder = INBOX, 1 messages, destination folder = Spam, isCopy = false
08-15 15:17:35.764 14308 14325 D RealImapConnection: Connecting to imap.bubu1.eu as imap.bubu1.eu/176.9.145.28
08-15 15:17:35.929 14308 14325 V RealImapConnection: conn130057912 <<< #null# [OK, [CAPABILITY, IMAP4rev1, SASL-IR, LOGIN-REFERRALS, ID, ENABLE, IDLE, LITERAL+, AUTH=PLAIN], Dovecot ready.]
08-15 15:17:35.930 14308 14325 D RealImapConnection: Saving [AUTH=PLAIN, LITERAL+, IMAP4REV1, LOGIN-REFERRALS, IDLE, SASL-IR, ID, ENABLE] capabilities for conn130057912
08-15 15:17:35.931 14308 14325 V RealImapConnection: conn130057912>>> 1 AUTHENTICATE PLAIN
08-15 15:17:35.969 14308 14325 V RealImapConnection: conn130057912<<<#+# []
08-15 15:17:36.031 14308 14325 V ImapResponseParser: conn130057912<<<#1# [OK, [CAPABILITY, IMAP4rev1, SASL-IR, LOGIN-REFERRALS, ID, ENABLE, IDLE, SORT, SORT=DISPLAY, THREAD=REFERENCES, THREAD=REFS, THREAD=ORDEREDSUBJECT, MULTIAPPEND, URL-PARTIAL, CATENATE, UNSELECT, CHILDREN, NAMESPACE, UIDPLUS, LIST-EXTENDED, I18NLEVEL=1, CONDSTORE, QRESYNC, ESEARCH, ESORT, SEARCHRES, WITHIN, CONTEXT=SEARCH, LIST-STATUS, BINARY, MOVE, SNIPPET=FUZZY, PREVIEW=FUZZY, PREVIEW, STATUS=SIZE, SAVEDATE, LITERAL+, NOTIFY], Logged in]
08-15 15:17:36.032 14308 14325 D RealImapConnection: Saving [URL-PARTIAL, NAMESPACE, LIST-STATUS, PREVIEW=FUZZY, THREAD=REFERENCES, BINARY, SAVEDATE, SASL-IR, WITHIN, MULTIAPPEND, SEARCHRES, ESORT, CONDSTORE, NOTIFY, STATUS=SIZE, I18NLEVEL=1, CATENATE, ID, SORT=DISPLAY, QRESYNC, UNSELECT, ENABLE, THREAD=ORDEREDSUBJECT, UIDPLUS, CHILDREN, CONTEXT=SEARCH, MOVE, SNIPPET=FUZZY, LITERAL+, IMAP4REV1, LOGIN-REFERRALS, IDLE, ESEARCH, SORT, LIST-EXTENDED, THREAD=REFS, PREVIEW] capabilities for conn130057912
08-15 15:17:36.033 14308 14325 V RealImapConnection: conn29280409>>> 8 STATUS "Spam" (RECENT)
08-15 15:17:36.070 14308 14325 V ImapResponseParser: conn29280409<<<#null# [STATUS, Spam, [RECENT, 1]]
08-15 15:17:36.071 14308 14325 V ImapResponseParser: conn29280409<<<#8# [OK, Status completed (0.003 + 0.000 + 0.002 secs).]
08-15 15:17:36.073 14308 14325 V RealImapConnection: conn29280409>>> 9 UID COPY 109543 "Spam"
08-15 15:17:36.108 14308 14325 V ImapResponseParser: conn29280409<<<#9# [OK, No messages found (0.001 + 0.000 secs).]
08-15 15:17:36.110 14308 14325 V RealImapConnection: conn29280409>>> 10 NOOP
08-15 15:17:36.145 14308 14325 V ImapResponseParser: conn29280409<<<#10# [OK, NOOP completed (0.001 + 0.000 secs).]
08-15 15:17:36.148 14308 14325 V RealImapConnection: conn29280409>>> 11 UID STORE 109543 +FLAGS.SILENT (\Deleted)
08-15 15:17:36.183 14308 14325 V ImapResponseParser: conn29280409<<<#11# [OK, Store completed (0.001 + 0.000 secs).]
08-15 15:17:36.184 14308 14325 I MessagingController: processingPendingMoveOrCopy expunging folder ea24c898-0407-43a7-a3f0-9c6cbc54a44c:INBOX
08-15 15:17:36.186 14308 14325 V RealImapConnection: conn130057912>>> 2 NOOP
08-15 15:17:36.223 14308 14325 V ImapResponseParser: conn130057912<<<#2# [OK, NOOP completed (0.001 + 0.000 secs).]
08-15 15:17:36.226 14308 14325 V RealImapConnection: conn130057912>>> 3 STATUS "INBOX" (UIDVALIDITY)
08-15 15:17:36.262 14308 14325 V ImapResponseParser: conn130057912<<<#null# [STATUS, INBOX, [UIDVALIDITY, 1508270677]]
08-15 15:17:36.262 14308 14325 V ImapResponseParser: conn130057912<<<#3# [OK, Status completed (0.003 + 0.000 + 0.002 secs).]
08-15 15:17:36.264 14308 14325 V RealImapConnection: conn29280409>>> 12 NOOP
08-15 15:17:36.300 14308 14325 V ImapResponseParser: conn29280409<<<#12# [OK, NOOP completed (0.001 + 0.000 secs).]
08-15 15:17:36.306 14308 14325 V RealImapConnection: conn29280409>>> 13 SELECT "INBOX"
08-15 15:17:36.445 14308 14325 V ImapResponseParser: conn29280409<<<#null# [OK, [CLOSED], Previous mailbox closed.]
08-15 15:17:36.446 14308 14325 V ImapResponseParser: conn29280409<<<#null# [FLAGS, [\Answered, \Flagged, \Deleted, \Seen, \Draft, NonJunk, $label1, Junk, $label2, $label4, $label3, $Forwarded, $label5, $MDNSent, $junk]]
08-15 15:17:36.447 14308 14325 V ImapResponseParser: conn29280409<<<#null# [OK, [PERMANENTFLAGS, [\Answered, \Flagged, \Deleted, \Seen, \Draft, NonJunk, $label1, Junk, $label2, $label4, $label3, $Forwarded, $label5, $MDNSent, $junk, \*]], Flags permitted.]
08-15 15:17:36.447 14308 14325 V ImapResponseParser: conn29280409<<<#null# [92675, EXISTS]
08-15 15:17:36.453 14308 14325 V ImapResponseParser: conn29280409<<<#null# [0, RECENT]
08-15 15:17:36.453 14308 14325 V ImapResponseParser: conn29280409<<<#null# [OK, [UNSEEN, 67070], First unseen.]
08-15 15:17:36.454 14308 14325 V ImapResponseParser: conn29280409<<<#null# [OK, [UIDVALIDITY, 1508270677], UIDs valid]
08-15 15:17:36.454 14308 14325 V ImapResponseParser: conn29280409<<<#null# [OK, [UIDNEXT, 109545], Predicted next UID]
08-15 15:17:36.455 14308 14325 V ImapResponseParser: conn29280409<<<#null# [OK, [HIGHESTMODSEQ, 247697], Highest]
08-15 15:17:36.455 14308 14325 V ImapResponseParser: conn29280409<<<#13# [OK, [READ-WRITE], Select completed (0.070 + 0.000 + 0.069 secs).]
08-15 15:17:36.457 14308 14325 D RealImapFolder: Got untagged EXISTS with value 92675 for ea24c898-0407-43a7-a3f0-9c6cbc54a44c:INBOX/MessagingController/conn29280409
08-15 15:17:36.458 14308 14325 D RealImapFolder: Got UidNext = 109545 for ea24c898-0407-43a7-a3f0-9c6cbc54a44c:INBOX/MessagingController/conn29280409
08-15 15:17:36.461 14308 14325 V RealImapConnection: conn29280409>>> 14 NOOP
08-15 15:17:36.495 14308 14325 V ImapResponseParser: conn29280409<<<#14# [OK, NOOP completed (0.001 + 0.000 secs).]
08-15 15:17:36.497 14308 14325 V RealImapConnection: conn29280409>>> 15 UID EXPUNGE 109543
08-15 15:17:36.529 14308 14325 V ImapResponseParser: conn29280409<<<#15# [OK, Expunge completed (0.001 + 0.000 secs).]
08-15 15:17:36.537 14308 14325 D LocalMessage: No headers available for this message!
08-15 15:17:36.547 14308 14325 V LockableDatabase: LockableDatabase: Transaction ended, took 3 ms / com.fsck.k9.mailstore.LocalFolder.destroyMessage(LocalFolder.java:1003)
08-15 15:17:36.560 14308 14325 V LockableDatabase: LockableDatabase: Transaction ended, took 3 ms / com.fsck.k9.mailstore.LocalFolder.destroyMessage(LocalFolder.java:1003)
08-15 15:17:36.565 14308 14325 D MessagingController: Done processing pending command 'move_or_copy'
08-15 15:17:36.566 14308 14325 D MessagingController: Processing pending command 'set_flag'
08-15 15:17:36.569 14308 14325 V RealImapConnection: conn130057912>>> 4 NOOP
08-15 15:17:36.603 14308 14325 V ImapResponseParser: conn130057912<<<#4# [OK, NOOP completed (0.001 + 0.000 secs).]
08-15 15:17:36.604 14308 14325 V RealImapConnection: conn130057912>>> 5 STATUS "INBOX" (UIDVALIDITY)
08-15 15:17:36.637 14308 14325 V ImapResponseParser: conn130057912<<<#null# [STATUS, INBOX, [UIDVALIDITY, 1508270677]]
08-15 15:17:36.638 14308 14325 V ImapResponseParser: conn130057912<<<#5# [OK, Status completed (0.001 + 0.000 secs).]
08-15 15:17:36.639 14308 14325 V RealImapConnection: conn29280409>>> 16 NOOP
08-15 15:17:36.669 14308 14325 V ImapResponseParser: conn29280409<<<#16# [OK, NOOP completed (0.001 + 0.000 secs).]
08-15 15:17:36.670 14308 14325 V RealImapConnection: conn29280409>>> 17 SELECT "INBOX"
08-15 15:17:36.810 14308 14325 V ImapResponseParser: conn29280409<<<#null# [OK, [CLOSED], Previous mailbox closed.]
08-15 15:17:36.811 14308 14325 V ImapResponseParser: conn29280409<<<#null# [FLAGS, [\Answered, \Flagged, \Deleted, \Seen, \Draft, NonJunk, $label1, Junk, $label2, $label4, $label3, $Forwarded, $label5, $MDNSent, $junk]]
08-15 15:17:36.812 14308 14325 V ImapResponseParser: conn29280409<<<#null# [OK, [PERMANENTFLAGS, [\Answered, \Flagged, \Deleted, \Seen, \Draft, NonJunk, $label1, Junk, $label2, $label4, $label3, $Forwarded, $label5, $MDNSent, $junk, \*]], Flags permitted.]
08-15 15:17:36.813 14308 14325 V ImapResponseParser: conn29280409<<<#null# [92675, EXISTS]
08-15 15:17:36.813 14308 14325 V ImapResponseParser: conn29280409<<<#null# [0, RECENT]
08-15 15:17:36.814 14308 14325 V ImapResponseParser: conn29280409<<<#null# [OK, [UNSEEN, 67070], First unseen.]
08-15 15:17:36.815 14308 14325 V ImapResponseParser: conn29280409<<<#null# [OK, [UIDVALIDITY, 1508270677], UIDs valid]
08-15 15:17:36.815 14308 14325 V ImapResponseParser: conn29280409<<<#null# [OK, [UIDNEXT, 109545], Predicted next UID]
08-15 15:17:36.816 14308 14325 V ImapResponseParser: conn29280409<<<#null# [OK, [HIGHESTMODSEQ, 247697], Highest]
08-15 15:17:36.816 14308 14325 V ImapResponseParser: conn29280409<<<#17# [OK, [READ-WRITE], Select completed (0.071 + 0.000 + 0.070 secs).]
08-15 15:17:36.817 14308 14325 D RealImapFolder: Got untagged EXISTS with value 92675 for ea24c898-0407-43a7-a3f0-9c6cbc54a44c:INBOX/MessagingController/conn29280409
08-15 15:17:36.818 14308 14325 D RealImapFolder: Got UidNext = 109545 for ea24c898-0407-43a7-a3f0-9c6cbc54a44c:INBOX/MessagingController/conn29280409
08-15 15:17:36.820 14308 14325 V RealImapConnection: conn29280409>>> 18 NOOP
08-15 15:17:36.852 14308 14325 V ImapResponseParser: conn29280409<<<#18# [OK, NOOP completed (0.001 + 0.000 secs).]
08-15 15:17:36.854 14308 14325 V RealImapConnection: conn29280409>>> 19 UID STORE 109537 +FLAGS.SILENT (\Seen)
08-15 15:17:37.189 14308 14325 V ImapResponseParser: conn29280409<<<#19# [OK, Store completed (0.202 + 0.000 + 0.201 secs).]
08-15 15:17:37.195 14308 14325 D MessagingController: Done processing pending command 'set_flag'
08-15 15:17:37.195 14308 14325 I MessagingController:  Command 'processPendingCommands' completed
08-15 15:17:37.195 14308 14325 I MessagingController: Running command 'queueSetFlag', seq = 370 (background priority)
08-15 15:17:37.199 14308 14325 I MessagingController:  Command 'queueSetFlag' completed
08-15 15:17:37.199 14308 14325 I MessagingController: Running command 'processPendingCommands', seq = 371 (background priority)
08-15 15:17:37.201 14308 14325 D MessagingController: Processing pending command 'set_flag'
08-15 15:17:37.204 14308 14325 V RealImapConnection: conn130057912>>> 6 NOOP
08-15 15:17:37.243 14308 14325 V ImapResponseParser: conn130057912<<<#6# [OK, NOOP completed (0.001 + 0.000 secs).]
08-15 15:17:37.245 14308 14325 V RealImapConnection: conn130057912>>> 7 STATUS "INBOX" (UIDVALIDITY)
08-15 15:17:37.386 14308 14325 V ImapResponseParser: conn130057912<<<#null# [STATUS, INBOX, [UIDVALIDITY, 1508270677]]
08-15 15:17:37.386 14308 14325 V ImapResponseParser: conn130057912<<<#7# [OK, Status completed (0.083 + 0.000 + 0.082 secs).]
08-15 15:17:37.387 14308 14325 V RealImapConnection: conn29280409>>> 20 NOOP
08-15 15:17:37.418 14308 14325 V ImapResponseParser: conn29280409<<<#20# [OK, NOOP completed (0.001 + 0.000 secs).]
08-15 15:17:37.420 14308 14325 V RealImapConnection: conn29280409>>> 21 SELECT "INBOX"
08-15 15:17:37.805 14308 14325 V ImapResponseParser: conn29280409<<<#null# [OK, [CLOSED], Previous mailbox closed.]
08-15 15:17:37.806 14308 14325 V ImapResponseParser: conn29280409<<<#null# [FLAGS, [\Answered, \Flagged, \Deleted, \Seen, \Draft, NonJunk, $label1, Junk, $label2, $label4, $label3, $Forwarded, $label5, $MDNSent, $junk]]
08-15 15:17:37.806 14308 14325 V ImapResponseParser: conn29280409<<<#null# [OK, [PERMANENTFLAGS, [\Answered, \Flagged, \Deleted, \Seen, \Draft, NonJunk, $label1, Junk, $label2, $label4, $label3, $Forwarded, $label5, $MDNSent, $junk, \*]], Flags permitted.]
08-15 15:17:37.806 14308 14325 V ImapResponseParser: conn29280409<<<#null# [92675, EXISTS]
08-15 15:17:37.807 14308 14325 V ImapResponseParser: conn29280409<<<#null# [0, RECENT]
08-15 15:17:37.807 14308 14325 V ImapResponseParser: conn29280409<<<#null# [OK, [UNSEEN, 67070], First unseen.]
08-15 15:17:37.808 14308 14325 V ImapResponseParser: conn29280409<<<#null# [OK, [UIDVALIDITY, 1508270677], UIDs valid]
08-15 15:17:37.808 14308 14325 V ImapResponseParser: conn29280409<<<#null# [OK, [UIDNEXT, 109545], Predicted next UID]
08-15 15:17:37.809 14308 14325 V ImapResponseParser: conn29280409<<<#null# [OK, [HIGHESTMODSEQ, 247699], Highest]
08-15 15:17:37.809 14308 14325 V ImapResponseParser: conn29280409<<<#21# [OK, [READ-WRITE], Select completed (0.292 + 0.000 + 0.291 secs).]
08-15 15:17:37.810 14308 14325 D RealImapFolder: Got untagged EXISTS with value 92675 for ea24c898-0407-43a7-a3f0-9c6cbc54a44c:INBOX/MessagingController/conn29280409
08-15 15:17:37.811 14308 14325 D RealImapFolder: Got UidNext = 109545 for ea24c898-0407-43a7-a3f0-9c6cbc54a44c:INBOX/MessagingController/conn29280409
08-15 15:17:37.813 14308 14325 V RealImapConnection: conn29280409>>> 22 NOOP
08-15 15:17:37.846 14308 14325 V ImapResponseParser: conn29280409<<<#null# [92666, FETCH, [FLAGS, [NonJunk]]]
08-15 15:17:37.846 14308 14325 V ImapResponseParser: conn29280409<<<#null# [92667, FETCH, [FLAGS, [NonJunk]]]
08-15 15:17:37.847 14308 14325 V ImapResponseParser: conn29280409<<<#null# [92668, FETCH, [FLAGS, [NonJunk]]]
08-15 15:17:37.847 14308 14325 V ImapResponseParser: conn29280409<<<#null# [92669, FETCH, [FLAGS, [\Seen, NonJunk]]]
08-15 15:17:37.848 14308 14325 V ImapResponseParser: conn29280409<<<#null# [92670, FETCH, [FLAGS, [NonJunk]]]
08-15 15:17:37.848 14308 14325 V ImapResponseParser: conn29280409<<<#null# [92671, FETCH, [FLAGS, [NonJunk]]]
08-15 15:17:37.848 14308 14325 V ImapResponseParser: conn29280409<<<#null# [92672, FETCH, [FLAGS, [NonJunk]]]
08-15 15:17:37.849 14308 14325 V ImapResponseParser: conn29280409<<<#null# [92673, FETCH, [FLAGS, [NonJunk]]]
08-15 15:17:37.849 14308 14325 V ImapResponseParser: conn29280409<<<#null# [92674, FETCH, [FLAGS, [NonJunk]]]
08-15 15:17:37.850 14308 14325 V ImapResponseParser: conn29280409<<<#null# [92675, FETCH, [FLAGS, [NonJunk]]]
08-15 15:17:37.850 14308 14325 V ImapResponseParser: conn29280409<<<#22# [OK, NOOP completed (0.001 + 0.000 secs).]
08-15 15:17:37.853 14308 14325 V RealImapConnection: conn29280409>>> 23 UID STORE 109540 +FLAGS.SILENT (\Seen)
08-15 15:17:38.110 14308 14325 V ImapResponseParser: conn29280409<<<#23# [OK, Store completed (0.173 + 0.000 + 0.172 secs).]
08-15 15:17:38.117 14308 14325 D MessagingController: Done processing pending command 'set_flag'
08-15 15:17:38.117 14308 14325 I MessagingController:  Command 'processPendingCommands' completed
08-15 15:17:38.118 14308 14325 I MessagingController: Running command 'processPendingCommands', seq = 372 (background priority)
08-15 15:17:38.119 14308 14325 I MessagingController:  Command 'processPendingCommands' completed
08-15 15:17:38.119 14308 14325 I MessagingController: Running command 'queueSetFlag', seq = 373 (background priority)
08-15 15:17:38.123 14308 14325 I MessagingController:  Command 'queueSetFlag' completed
08-15 15:17:38.123 14308 14325 I MessagingController: Running command 'processPendingCommands', seq = 374 (background priority)
08-15 15:17:38.125 14308 14325 D MessagingController: Processing pending command 'set_flag'
08-15 15:17:38.127 14308 14325 V RealImapConnection: conn130057912>>> 8 NOOP
08-15 15:17:38.161 14308 14325 V ImapResponseParser: conn130057912<<<#8# [OK, NOOP completed (0.001 + 0.000 secs).]
08-15 15:17:38.163 14308 14325 V RealImapConnection: conn130057912>>> 9 STATUS "INBOX" (UIDVALIDITY)
08-15 15:17:38.309 14308 14325 V ImapResponseParser: conn130057912<<<#null# [STATUS, INBOX, [UIDVALIDITY, 1508270677]]
08-15 15:17:38.310 14308 14325 V ImapResponseParser: conn130057912<<<#9# [OK, Status completed (0.078 + 0.000 + 0.077 secs).]
08-15 15:17:38.311 14308 14325 V RealImapConnection: conn29280409>>> 24 NOOP
08-15 15:17:38.342 14308 14325 V ImapResponseParser: conn29280409<<<#24# [OK, NOOP completed (0.001 + 0.000 secs).]
08-15 15:17:38.343 14308 14325 V RealImapConnection: conn29280409>>> 25 SELECT "INBOX"
08-15 15:17:38.724 14308 14325 V ImapResponseParser: conn29280409<<<#null# [OK, [CLOSED], Previous mailbox closed.]
08-15 15:17:38.725 14308 14325 V ImapResponseParser: conn29280409<<<#null# [FLAGS, [\Answered, \Flagged, \Deleted, \Seen, \Draft, NonJunk, $label1, Junk, $label2, $label4, $label3, $Forwarded, $label5, $MDNSent, $junk]]
08-15 15:17:38.726 14308 14325 V ImapResponseParser: conn29280409<<<#null# [OK, [PERMANENTFLAGS, [\Answered, \Flagged, \Deleted, \Seen, \Draft, NonJunk, $label1, Junk, $label2, $label4, $label3, $Forwarded, $label5, $MDNSent, $junk, \*]], Flags permitted.]
08-15 15:17:38.726 14308 14325 V ImapResponseParser: conn29280409<<<#null# [92675, EXISTS]
08-15 15:17:38.727 14308 14325 V ImapResponseParser: conn29280409<<<#null# [0, RECENT]
08-15 15:17:38.727 14308 14325 V ImapResponseParser: conn29280409<<<#null# [OK, [UNSEEN, 67070], First unseen.]
08-15 15:17:38.728 14308 14325 V ImapResponseParser: conn29280409<<<#null# [OK, [UIDVALIDITY, 1508270677], UIDs valid]
08-15 15:17:38.728 14308 14325 V ImapResponseParser: conn29280409<<<#null# [OK, [UIDNEXT, 109545], Predicted next UID]
08-15 15:17:38.729 14308 14325 V ImapResponseParser: conn29280409<<<#null# [OK, [HIGHESTMODSEQ, 247700], Highest]
08-15 15:17:38.729 14308 14325 V ImapResponseParser: conn29280409<<<#25# [OK, [READ-WRITE], Select completed (0.271 + 0.000 + 0.270 secs).]
08-15 15:17:38.730 14308 14325 D RealImapFolder: Got untagged EXISTS with value 92675 for ea24c898-0407-43a7-a3f0-9c6cbc54a44c:INBOX/MessagingController/conn29280409
08-15 15:17:38.730 14308 14325 D RealImapFolder: Got UidNext = 109545 for ea24c898-0407-43a7-a3f0-9c6cbc54a44c:INBOX/MessagingController/conn29280409
08-15 15:17:38.733 14308 14325 V RealImapConnection: conn29280409>>> 26 NOOP
08-15 15:17:38.767 14308 14325 V ImapResponseParser: conn29280409<<<#26# [OK, NOOP completed (0.001 + 0.000 secs).]
08-15 15:17:38.769 14308 14325 V RealImapConnection: conn29280409>>> 27 UID STORE 109540 +FLAGS.SILENT (\Seen)
08-15 15:17:38.802 14308 14325 V ImapResponseParser: conn29280409<<<#27# [OK, Store completed (0.001 + 0.000 secs).]
08-15 15:17:38.809 14308 14325 D MessagingController: Done processing pending command 'set_flag'
08-15 15:17:38.809 14308 14325 I MessagingController:  Command 'processPendingCommands' completed
08-15 15:17:38.809 14308 14325 I MessagingController: Running command 'processPendingCommands', seq = 375 (background priority)
08-15 15:17:38.810 14308 14325 I MessagingController:  Command 'processPendingCommands' completed
08-15 15:17:38.811 14308 14325 I MessagingController: Running command 'processPendingCommands', seq = 376 (background priority)
08-15 15:17:38.812 14308 14325 I MessagingController:  Command 'processPendingCommands' completed

Bubu avatar Aug 15 '22 13:08 Bubu

I'm still experiencing this, basically whenever I use k9mail for more than a few seconds. It also keeps happening regardless of my network connectivity. (Both on wifi and mobile data).

I'm wondering if there's actually a regression in k9, or if my server side is having issues. 🤔

Bubu avatar Sep 13 '22 09:09 Bubu

The log doesn't contain any obvious problem. The server doesn't respond to a UID EXPUNGE command within 60 seconds. This times out the connection. But when the command is retried later, it succeeds. There are also several messages marked as read on the server.

When this happens, are the messages marked as read on the server and only show unread in K-9 Mail?

cketti avatar Sep 13 '22 10:09 cketti

When this happens, are the messages marked as read on the server and only show unread in K-9 Mail?

Quite likely. (But it's hard to tell if everything is in the correct state 3 minutes later after reading 15 emails and marking some of them as read and some as unread.)

It always works immediately when using the long press -> mark as read action from the message list though.

There seems to be a problem with the local echo then for the automatic marking on opening a message?

Bubu avatar Sep 13 '22 14:09 Bubu

I believe this was caused by a server issue (incorrectly configured fts backend for dovecot which caused some connection hangs). (It took me 4 months to find the root cause because thunderbird functioned normally 99% of the time)

Bubu avatar Nov 18 '22 11:11 Bubu