Open jancborchardt opened 1 year ago
FYI this might be related to https://github.com/nextcloud/mail/issues/3049 but that issue seems broader, is old already and the discussion is too heated.
I have the same problem here. Running nextcloud 25.0.4 and mail app version 2.2.4
Same issue NC 26.0.0 Mail Version 3.0.2. Docker install using MYSQL backend.
Here are some info from horde.log
Good email beeing fetched :
Thu, 06 Apr 2023 09:20:00 +0000
>> Connection to: imap://mail.REDACTED.TLD/
>> Server connection took 0.0018 seconds.
S: * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE STARTTLS LOGINDISABLED] Dovecot ready.
C: 1 STARTTLS
S: 1 OK Begin TLS negotiation now.
>> Command 1 took 0.0005 seconds.
>> Successfully completed TLS negotiation.
C: 2 CAPABILITY
S: * CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE AUTH=PLAIN AUTH=LOGIN
S: 2 OK Pre-login capabilities listed, post-login capabilities have more.
>> Command 2 took 0.0005 seconds.
C: 3 AUTHENTICATE PLAIN [INITIAL CLIENT RESPONSE (username: myself@REDACTED.TLD)]
S: * FLAGS (\Answered \Flagged \Deleted \Seen \Draft $MDNSent $Forwarded $label1)
S: * OK [PERMANENTFLAGS ()] Read-only mailbox.
S: * 16104 EXISTS
S: * 0 RECENT
S: * OK [UNSEEN 7781] First unseen.
S: * OK [UIDVALIDITY 1357574186] UIDs valid
S: * OK [UIDNEXT 29936] Predicted next UID
S: * OK [HIGHESTMODSEQ 56757] Highest
S: 5 OK [READ-ONLY] Examine completed (0.000 + 0.000 secs).
>> Command 5 took 0.0449 seconds.
>> CACHE: Retrieved messages (HICenv,HICflags,HICdate,HICdg [INBOX; 29921])
C: 6 UID FETCH 29921 (BODY.PEEK[HEADER])
S: 3 OK [CAPABILITY IMAP4rev1 LITERAL+ 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 NOTIFY SPECIAL-USE QUOTA ACL RIGHTS=texk] Logged in
>> Command 3 took 0.006 seconds.
>> CACHE: Using the OCA\Mail\Cache\Cache storage driver.
C: 4 ENABLE QRESYNC
C: 5 EXAMINE INBOX (QRESYNC (1357574186 56757 29885:29900,29906:29918,29921,29923,29926:29930,29933:29934))
S: * ENABLED QRESYNC
S: 4 OK Enabled (0.000 + 0.000 secs).
>> Command 4 took 0.0006 seconds.
S: * 16096 FETCH (UID 29921 BODY[HEADER] {1993}
S: [LITERAL DATA: 1993 bytes]
S: )
S: 6 OK Fetch completed (0.042 + 0.000 + 0.041 secs).
>> Command 6 took 0.0426 seconds.
>> CACHE: Retrieved messages (HICenv,HICflags,HICdate,HICsize,HICstruct,HICdg [INBOX; 29921])
C: 7 UID FETCH 29921 (BODY.PEEK[HEADER])
S: * 16096 FETCH (UID 29921 BODY[HEADER] {1993}
S: [LITERAL DATA: 1993 bytes]
S: )
S: 7 OK Fetch completed (0.001 + 0.000 secs).
>> Command 7 took 0.0011 seconds.
C: 8 UID FETCH 29921 (BODY.PEEK[1] BINARY.SIZE[1] BODY.PEEK[1.MIME])
S: * 16096 FETCH (UID 29921 BODY[1] {530}
S: [LITERAL DATA: 530 bytes]
S: BINARY.SIZE[1] 482 BODY[1.MIME] {88}
S: [LITERAL DATA: 88 bytes]
S: )
S: 8 OK Fetch completed (0.001 + 0.000 secs).
>> Command 8 took 0.001 seconds.
S: * FLAGS (\Answered \Flagged \Deleted \Seen \Draft $MDNSent $Forwarded $label1)
S: * OK [PERMANENTFLAGS ()] Read-only mailbox.
S: * 16104 EXISTS
S: * 0 RECENT
S: * OK [UNSEEN 7781] First unseen.
S: * OK [UIDVALIDITY 1357574186] UIDs valid
C: 9 UID FETCH 29921 (BODY.PEEK[2] BINARY.SIZE[2] BODY.PEEK[2.MIME])
S: * OK [UIDNEXT 29936] Predicted next UID
S: * OK [HIGHESTMODSEQ 56757] Highest
S: 5 OK [READ-ONLY] Examine completed (0.000 + 0.000 secs).
>> Command 5 took 0.0435 seconds.
S: * 16096 FETCH (UID 29921 BODY[2] {21631}
>> CACHE: Retrieved messages (HICenv,HICflags,HICdate,HICdg [INBOX; 29921])
C: 6 UID FETCH 29921 (BODY.PEEK[HEADER])
S: [LITERAL DATA: 21631 bytes]
S: BINARY.SIZE[2] 19572 BODY[2.MIME] {87}
S: [LITERAL DATA: 87 bytes]
S: )
S: 9 OK Fetch completed (0.001 + 0.000 secs).
>> Command 9 took 0.0018 seconds.
C: 10 LOGOUT
S: * BYE Logging out
S: 10 OK Logout completed (0.000 + 0.000 secs).
>> Command 10 took 0.0007 seconds.
S: * 16096 FETCH (UID 29921 BODY[HEADER] {1993}
S: [LITERAL DATA: 1993 bytes]
S: )
S: 6 OK Fetch completed (0.042 + 0.000 + 0.041 secs).
>> Command 6 took 0.0421 seconds.
>> CACHE: Retrieved messages (HICenv,HICflags,HICdate,HICsize,HICstruct,HICdg [INBOX; 29921])
C: 7 UID FETCH 29921 (BODY.PEEK[HEADER])
S: * 16096 FETCH (UID 29921 BODY[HEADER] {1993}
S: [LITERAL DATA: 1993 bytes]
S: )
S: 7 OK Fetch completed (0.001 + 0.000 secs).
>> Command 7 took 0.0011 seconds.
C: 8 UID FETCH 29921 (BODY.PEEK[1] BINARY.SIZE[1] BODY.PEEK[1.MIME])
S: * 16096 FETCH (UID 29921 BODY[1] {530}
S: [LITERAL DATA: 530 bytes]
S: BINARY.SIZE[1] 482 BODY[1.MIME] {88}
S: [LITERAL DATA: 88 bytes]
S: )
S: 8 OK Fetch completed (0.001 + 0.000 secs).
>> Command 8 took 0.0009 seconds.
C: 9 UID FETCH 29921 (BODY.PEEK[2] BINARY.SIZE[2] BODY.PEEK[2.MIME])
S: * 16096 FETCH (UID 29921 BODY[2] {21631}
S: [LITERAL DATA: 21631 bytes]
S: BINARY.SIZE[2] 19572 BODY[2.MIME] {87}
S: [LITERAL DATA: 87 bytes]
S: )
S: 9 OK Fetch completed (0.001 + 0.000 secs).
>> Command 9 took 0.0019 seconds.
C: 10 LOGOUT
S: * BYE Logging out
S: 10 OK Logout completed (0.000 + 0.000 secs).
>> Command 10 took 0.0009 seconds.
Mail moved by another client on the IMAP server (unseen) :
------------------------------
>> Thu, 06 Apr 2023 09:24:09 +0000
>> Connection to: imap://mail.REDACTED.TLD/
>> Server connection took 0.0016 seconds.
S: * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE STARTTLS LOGINDISABLED] Dovecot ready.
C: 1 STARTTLS
S: 1 OK Begin TLS negotiation now.
>> Command 1 took 0.0004 seconds.
>> Successfully completed TLS negotiation.
C: 2 CAPABILITY
S: * CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE AUTH=PLAIN AUTH=LOGIN
S: 2 OK Pre-login capabilities listed, post-login capabilities have more.
>> Command 2 took 0.0005 seconds.
C: 3 AUTHENTICATE PLAIN [INITIAL CLIENT RESPONSE (username: myself@REDACTED.TLD)]
S: 3 OK [CAPABILITY IMAP4rev1 LITERAL+ 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 NOTIFY SPECIAL-USE QUOTA ACL RIGHTS=texk] Logged in
>> Command 3 took 0.0062 seconds.
>> CACHE: Using the OCA\Mail\Cache\Cache storage driver.
C: 4 ENABLE QRESYNC
C: 5 EXAMINE INBOX (QRESYNC (1357574186 56757 29885:29900,29906:29918,29921,29923,29926:29930,29933:29934))
S: * ENABLED QRESYNC
S: 4 OK Enabled (0.000 + 0.000 secs).
>> Command 4 took 0.0006 seconds.
S: * FLAGS (\Answered \Flagged \Deleted \Seen \Draft $MDNSent $Forwarded $label1)
S: * OK [PERMANENTFLAGS ()] Read-only mailbox.
S: * 16104 EXISTS
S: * 0 RECENT
S: * OK [UNSEEN 7781] First unseen.
S: * OK [UIDVALIDITY 1357574186] UIDs valid
S: * OK [UIDNEXT 29936] Predicted next UID
S: * OK [HIGHESTMODSEQ 56757] Highest
S: 5 OK [READ-ONLY] Examine completed (0.000 + 0.000 secs).
>> Command 5 took 0.044 seconds.
C: 6 UID FETCH 29920 (ENVELOPE FLAGS INTERNALDATE BODY.PEEK[HEADER])
S: 6 OK Fetch completed (0.001 + 0.000 secs).
>> Command 6 took 0.0007 seconds.
C: 7 LOGOUT
S: * BYE Logging out
S: 7 OK Logout completed (0.000 + 0.000 secs).
>> Command 7 took 0.0006 seconds.
There the mail is showing as describe in the initial post.
Mail moved by another client on the IMAP server (seen) :
------------------------------
>> Thu, 06 Apr 2023 09:30:19 +0000
>> Connection to: imap://mail.REDACTED.TLD/
>> Server connection took 0.0012 seconds.
S: * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE STARTTLS LOGINDISABLED] Dovecot ready.
C: 1 STARTTLS
S: 1 OK Begin TLS negotiation now.
>> Command 1 took 0.0004 seconds.
>> Successfully completed TLS negotiation.
C: 2 CAPABILITY
S: * CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE AUTH=PLAIN AUTH=LOGIN
S: 2 OK Pre-login capabilities listed, post-login capabilities have more.
>> Command 2 took 0.0005 seconds.
C: 3 AUTHENTICATE PLAIN [INITIAL CLIENT RESPONSE (username: myself@REDACTED.TLD)]
S: 3 OK [CAPABILITY IMAP4rev1 LITERAL+ 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 NOTIFY SPECIAL-USE QUOTA ACL RIGHTS=texk] Logged in
>> Command 3 took 0.0072 seconds.
>> CACHE: Using the OCA\Mail\Cache\Cache storage driver.
C: 4 ENABLE QRESYNC
C: 5 EXAMINE INBOX (QRESYNC (1357574186 56757 29885:29900,29906:29918,29921,29923,29926:29930,29933:29934))
S: * ENABLED QRESYNC
S: 4 OK Enabled (0.000 + 0.000 secs).
>> Command 4 took 0.0006 seconds.
S: * FLAGS (\Answered \Flagged \Deleted \Seen \Draft $MDNSent $Forwarded $label1)
S: * OK [PERMANENTFLAGS ()] Read-only mailbox.
S: * 16104 EXISTS
S: * 0 RECENT
S: * OK [UNSEEN 7781] First unseen.
S: * OK [UIDVALIDITY 1357574186] UIDs valid
S: * OK [UIDNEXT 29936] Predicted next UID
S: * OK [HIGHESTMODSEQ 56757] Highest
S: 5 OK [READ-ONLY] Examine completed (0.000 + 0.000 secs).
>> Command 5 took 0.0429 seconds.
C: 6 UID FETCH 29920 (ENVELOPE FLAGS INTERNALDATE BODY.PEEK[HEADER])
S: 6 OK Fetch completed (0.001 + 0.000 secs).
>> Command 6 took 0.0007 seconds.
C: 7 LOGOUT
S: * BYE Logging out
S: 7 OK Logout completed (0.000 + 0.000 secs).
>> Command 7 took 0.0006 seconds.
Message List
------------------------------
>> Thu, 06 Apr 2023 09:32:13 +0000
>> Connection to: imap://mail.REDACTED.TLD/
>> Server connection took 0.0015 seconds.
S: * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE STARTTLS LOGINDISABLED] Dovecot ready.
C: 1 STARTTLS
S: 1 OK Begin TLS negotiation now.
>> Command 1 took 0.0003 seconds.
>> Successfully completed TLS negotiation.
C: 2 CAPABILITY
S: * CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE AUTH=PLAIN AUTH=LOGIN
S: 2 OK Pre-login capabilities listed, post-login capabilities have more.
>> Command 2 took 0.0006 seconds.
C: 3 AUTHENTICATE PLAIN [INITIAL CLIENT RESPONSE (username: myself@REDACTED.TLD)]
S: 3 OK [CAPABILITY IMAP4rev1 LITERAL+ 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 NOTIFY SPECIAL-USE QUOTA ACL RIGHTS=texk] Logged in
>> Command 3 took 0.006 seconds.
>> CACHE: Using the OCA\Mail\Cache\Cache storage driver.
C: 4 ENABLE QRESYNC
C: 5 STATUS INBOX (MESSAGES RECENT UIDNEXT UIDVALIDITY UNSEEN)
S: * ENABLED QRESYNC
S: 4 OK Enabled (0.000 + 0.000 secs).
>> Command 4 took 0.0007 seconds.
S: * STATUS INBOX (MESSAGES 16104 RECENT 0 UIDNEXT 29936 UIDVALIDITY 1357574186 UNSEEN 404)
S: 5 OK Status completed (0.000 + 0.000 secs).
>> Command 5 took 0.0412 seconds.
C: 6 LOGOUT
S: * BYE Logging out
S: 6 OK Logout completed (0.000 + 0.000 secs).
>> Command 6 took 0.0006 seconds.
There are the nextcloud.log relevant part (log is the same for seen and unseen mail)
tail -f nextcloud.log | grep '"app":"mail"' | grep myself
{"reqId":"Tiy0ReFrAHuxQP9QbGPc","level":0,"time":"2023-04-06T09:39:34+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"GET","url":"/apps/mail/api/messages/227883/thread","message":"Password of provisioned account is up to date","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"0pkMn539ixoGV4URODCd","level":0,"time":"2023-04-06T09:39:34+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"GET","url":"/apps/mail/api/messages/227883/body","message":"Password of provisioned account is up to date","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"0pkMn539ixoGV4URODCd","level":0,"time":"2023-04-06T09:39:34+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"GET","url":"/apps/mail/api/messages/227883/body","message":"findByIds in INBOX got 1 UIDs but found none","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"0pkMn539ixoGV4URODCd","level":3,"time":"2023-04-06T09:39:34+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"GET","url":"/apps/mail/api/messages/227883/body","message":"Could not load message","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","exception":{"Exception":"OCA\\Mail\\Exception\\ServiceException","Message":"Could not load message","Code":0,"Trace":[{"file":"/var/www/nextcloud/apps/mail/lib/Controller/MessagesController.php","line":248,"function":"getImapMessage","class":"OCA\\Mail\\Service\\MailManager","type":"->"},{"file":"/var/www/nextcloud/lib/private/AppFramework/Http/Dispatcher.php","line":225,"function":"getBody","class":"OCA\\Mail\\Controller\\MessagesController","type":"->"},{"file":"/var/www/nextcloud/lib/private/AppFramework/Http/Dispatcher.php","line":133,"function":"executeController","class":"OC\\AppFramework\\Http\\Dispatcher","type":"->"},{"file":"/var/www/nextcloud/lib/private/AppFramework/App.php","line":172,"function":"dispatch","class":"OC\\AppFramework\\Http\\Dispatcher","type":"->"},{"file":"/var/www/nextcloud/lib/private/Route/Router.php","line":298,"function":"main","class":"OC\\AppFramework\\App","type":"::"},{"file":"/var/www/nextcloud/lib/base.php","line":1030,"function":"match","class":"OC\\Route\\Router","type":"->"},{"file":"/var/www/nextcloud/index.php","line":36,"function":"handleRequest","class":"OC","type":"::"}],"File":"/var/www/nextcloud/apps/mail/lib/Service/MailManager.php","Line":187,"Previous":{"Exception":"OCP\\AppFramework\\Db\\DoesNotExistException","Message":"Message does not exist","Code":0,"Trace":[{"file":"/var/www/nextcloud/apps/mail/lib/Service/MailManager.php","line":181,"function":"find","class":"OCA\\Mail\\IMAP\\MessageMapper","type":"->"},{"file":"/var/www/nextcloud/apps/mail/lib/Controller/MessagesController.php","line":248,"function":"getImapMessage","class":"OCA\\Mail\\Service\\MailManager","type":"->"},{"file":"/var/www/nextcloud/lib/private/AppFramework/Http/Dispatcher.php","line":225,"function":"getBody","class":"OCA\\Mail\\Controller\\MessagesController","type":"->"},{"file":"/var/www/nextcloud/lib/private/AppFramework/Http/Dispatcher.php","line":133,"function":"executeController","class":"OC\\AppFramework\\Http\\Dispatcher","type":"->"},{"file":"/var/www/nextcloud/lib/private/AppFramework/App.php","line":172,"function":"dispatch","class":"OC\\AppFramework\\Http\\Dispatcher","type":"->"},{"file":"/var/www/nextcloud/lib/private/Route/Router.php","line":298,"function":"main","class":"OC\\AppFramework\\App","type":"::"},{"file":"/var/www/nextcloud/lib/base.php","line":1030,"function":"match","class":"OC\\Route\\Router","type":"->"},{"file":"/var/www/nextcloud/index.php","line":36,"function":"handleRequest","class":"OC","type":"::"}],"File":"/var/www/nextcloud/apps/mail/lib/IMAP/MessageMapper.php","Line":75},"message":"Could not load message","exception":{},"CustomMessage":"Could not load message"}}
{"reqId":"iSeJ3vycvhBXR2iCDp9J","level":0,"time":"2023-04-06T09:39:47+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"Password of provisioned account is up to date","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"iSeJ3vycvhBXR2iCDp9J","level":0,"time":"2023-04-06T09:39:47+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"Locking mailbox 82 for new messages sync","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"iSeJ3vycvhBXR2iCDp9J","level":0,"time":"2023-04-06T09:39:47+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"Locking mailbox 82 for changed messages sync","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"iSeJ3vycvhBXR2iCDp9J","level":0,"time":"2023-04-06T09:39:47+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"Locking mailbox 82 for vanished messages sync","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"iSeJ3vycvhBXR2iCDp9J","level":0,"time":"2023-04-06T09:39:47+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"Running partial sync for 82","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"iSeJ3vycvhBXR2iCDp9J","level":0,"time":"2023-04-06T09:39:47+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"partial sync 3:INBOX - get all known UIDs took 0s. 6/6MB memory used","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"iSeJ3vycvhBXR2iCDp9J","level":0,"time":"2023-04-06T09:39:47+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"findByIds in INBOX got 0 UIDs but found none","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"iSeJ3vycvhBXR2iCDp9J","level":0,"time":"2023-04-06T09:39:47+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"findByIds in INBOX got 0 UIDs but found none","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"iSeJ3vycvhBXR2iCDp9J","level":0,"time":"2023-04-06T09:39:47+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"partial sync 3:INBOX - get new messages via Horde took 0s. 6/6MB memory used","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"iSeJ3vycvhBXR2iCDp9J","level":0,"time":"2023-04-06T09:39:47+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"partial sync 3:INBOX - persist new messages took 0s. 6/6MB memory used","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"iSeJ3vycvhBXR2iCDp9J","level":0,"time":"2023-04-06T09:39:47+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"findByIds in INBOX got 0 UIDs but found none","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"iSeJ3vycvhBXR2iCDp9J","level":0,"time":"2023-04-06T09:39:47+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"findByIds in INBOX got 1 UIDs (29936) and found 1. minFetched=29936 maxFetched=29936","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"iSeJ3vycvhBXR2iCDp9J","level":0,"time":"2023-04-06T09:39:47+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"partial sync 3:INBOX - get changed messages via Horde took 0s. 7/7MB memory used","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"iSeJ3vycvhBXR2iCDp9J","level":0,"time":"2023-04-06T09:39:47+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"partial sync 3:Surname NAME - Selected Tags for all messages took 0s. 7/7MB memory used","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"iSeJ3vycvhBXR2iCDp9J","level":0,"time":"2023-04-06T09:39:47+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"partial sync 3:Surname NAME - Updated message took 0s. 7/7MB memory used","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"iSeJ3vycvhBXR2iCDp9J","level":0,"time":"2023-04-06T09:39:47+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"partial sync 3:Surname NAME took 0s","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"iSeJ3vycvhBXR2iCDp9J","level":0,"time":"2023-04-06T09:39:47+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"partial sync 3:INBOX - persist changed messages took 0s. 7/7MB memory used","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"iSeJ3vycvhBXR2iCDp9J","level":0,"time":"2023-04-06T09:39:47+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"findByIds in INBOX got 0 UIDs but found none","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
Log from a working mail :
{"reqId":"YatKqJGoYoGuIeJCwfLd","level":0,"time":"2023-04-06T09:44:35+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"GET","url":"/apps/mail/api/messages/228210/thread","message":"Password of provisioned account is up to date","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"0j4yuN6BRRqXOhhyo7A0","level":0,"time":"2023-04-06T09:44:35+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"GET","url":"/apps/mail/api/messages/228210/body","message":"Password of provisioned account is up to date","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"ARhQOh3SSUojOZWpC8kD","level":0,"time":"2023-04-06T09:44:35+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"GET","url":"/apps/mail/api/messages/228210/itineraries","message":"Password of provisioned account is up to date","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"ldIUkAG0uDDmhiegSzSQ","level":0,"time":"2023-04-06T09:44:35+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"GET","url":"/apps/mail/api/messages/228210/html","message":"Password of provisioned account is up to date","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"0j4yuN6BRRqXOhhyo7A0","level":0,"time":"2023-04-06T09:44:35+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"GET","url":"/apps/mail/api/messages/228210/body","message":"findByIds in INBOX got 1 UIDs (29923) and found 1. minFetched=29923 maxFetched=29923","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"FEdQUOOr3rdHykbtzqEa","level":0,"time":"2023-04-06T09:44:36+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"GET","url":"/apps/mail/api/messages/228210/attachment/1.2","message":"Password of provisioned account is up to date","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"ldIUkAG0uDDmhiegSzSQ","level":0,"time":"2023-04-06T09:44:36+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"GET","url":"/apps/mail/api/messages/228210/html","message":"findByIds in INBOX got 1 UIDs (29923) and found 1. minFetched=29923 maxFetched=29923","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"FOMhMHvHA089lJVl18AG","level":0,"time":"2023-04-06T09:44:47+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"GET","url":"/apps/mail/api/messages/227944/thread","message":"Password of provisioned account is up to date","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"jdahZvjxd5mTN3SqLfNy","level":0,"time":"2023-04-06T09:44:47+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"GET","url":"/apps/mail/api/messages/227944/body","message":"Password of provisioned account is up to date","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"73JFYmAkgx6UzAkOOXKr","level":0,"time":"2023-04-06T09:44:47+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"GET","url":"/apps/mail/api/messages/227944/html","message":"Password of provisioned account is up to date","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"XT9hkwVaGj2enTZxLBkr","level":0,"time":"2023-04-06T09:44:47+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"GET","url":"/apps/mail/api/messages/227944/itineraries","message":"Password of provisioned account is up to date","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"jdahZvjxd5mTN3SqLfNy","level":0,"time":"2023-04-06T09:44:47+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"GET","url":"/apps/mail/api/messages/227944/body","message":"findByIds in INBOX got 1 UIDs (29921) and found 1. minFetched=29921 maxFetched=29921","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"73JFYmAkgx6UzAkOOXKr","level":0,"time":"2023-04-06T09:44:47+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"GET","url":"/apps/mail/api/messages/227944/html","message":"findByIds in INBOX got 1 UIDs (29921) and found 1. minFetched=29921 maxFetched=29921","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"E5xmC2438zvAAjIotbFs","level":0,"time":"2023-04-06T09:44:52+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"GET","url":"/apps/mail/api/messages/228307/thread","message":"Password of provisioned account is up to date","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"SrpT64mhmc1dGOdKzyCC","level":0,"time":"2023-04-06T09:44:52+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"GET","url":"/apps/mail/api/avatars/url/internal%40REDACTED.TLD","message":"Password of provisioned account is up to date","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"uefOF4Fycd2Gq162KBoL","level":0,"time":"2023-04-06T09:44:52+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"GET","url":"/apps/mail/api/messages/228307/body","message":"Password of provisioned account is up to date","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"uefOF4Fycd2Gq162KBoL","level":0,"time":"2023-04-06T09:44:52+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"GET","url":"/apps/mail/api/messages/228307/body","message":"findByIds in INBOX got 1 UIDs (29926) and found 1. minFetched=29926 maxFetched=29926","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"OMGn738U9cWGYaCGRFFV","level":0,"time":"2023-04-06T09:44:52+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"GET","url":"/apps/mail/api/messages/228307/itineraries","message":"Password of provisioned account is up to date","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"OMGn738U9cWGYaCGRFFV","level":0,"time":"2023-04-06T09:44:52+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"GET","url":"/apps/mail/api/messages/228307/itineraries","message":"Message does not have an HTML body, can't extract itinerary info","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"OMGn738U9cWGYaCGRFFV","level":1,"time":"2023-04-06T09:44:52+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"GET","url":"/apps/mail/api/messages/228307/itineraries","message":"KItinerary binary adapter is not available, can't extract information","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"OMGn738U9cWGYaCGRFFV","level":0,"time":"2023-04-06T09:44:52+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"GET","url":"/apps/mail/api/messages/228307/itineraries","message":"Extracted 0 itinerary entries from an attachment","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"OMGn738U9cWGYaCGRFFV","level":1,"time":"2023-04-06T09:44:52+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"GET","url":"/apps/mail/api/messages/228307/itineraries","message":"KItinerary binary adapter is not available, can't extract information","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"OMGn738U9cWGYaCGRFFV","level":0,"time":"2023-04-06T09:44:52+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"GET","url":"/apps/mail/api/messages/228307/itineraries","message":"Reduced 0 itinerary entries to 0 entries","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"wVZCJaeirlRg5LfGsaiH","level":0,"time":"2023-04-06T09:44:54+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"Password of provisioned account is up to date","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"wVZCJaeirlRg5LfGsaiH","level":0,"time":"2023-04-06T09:44:54+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"Locking mailbox 82 for new messages sync","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"wVZCJaeirlRg5LfGsaiH","level":0,"time":"2023-04-06T09:44:54+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"Locking mailbox 82 for changed messages sync","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"wVZCJaeirlRg5LfGsaiH","level":0,"time":"2023-04-06T09:44:54+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"Locking mailbox 82 for vanished messages sync","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"wVZCJaeirlRg5LfGsaiH","level":0,"time":"2023-04-06T09:44:54+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"Running partial sync for 82","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"wVZCJaeirlRg5LfGsaiH","level":0,"time":"2023-04-06T09:44:54+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"partial sync 3:INBOX - get all known UIDs took 0s. 6/6MB memory used","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"wVZCJaeirlRg5LfGsaiH","level":0,"time":"2023-04-06T09:44:54+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"findByIds in INBOX got 0 UIDs but found none","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"wVZCJaeirlRg5LfGsaiH","level":0,"time":"2023-04-06T09:44:54+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"findByIds in INBOX got 0 UIDs but found none","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"wVZCJaeirlRg5LfGsaiH","level":0,"time":"2023-04-06T09:44:54+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"partial sync 3:INBOX - get new messages via Horde took 0s. 6/6MB memory used","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"wVZCJaeirlRg5LfGsaiH","level":0,"time":"2023-04-06T09:44:54+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"partial sync 3:INBOX - persist new messages took 0s. 6/6MB memory used","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"wVZCJaeirlRg5LfGsaiH","level":0,"time":"2023-04-06T09:44:54+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"findByIds in INBOX got 0 UIDs but found none","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"wVZCJaeirlRg5LfGsaiH","level":0,"time":"2023-04-06T09:44:54+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"findByIds in INBOX got 1 UIDs (29936) and found 1. minFetched=29936 maxFetched=29936","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"wVZCJaeirlRg5LfGsaiH","level":0,"time":"2023-04-06T09:44:54+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"partial sync 3:INBOX - get changed messages via Horde took 0s. 7/7MB memory used","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"wVZCJaeirlRg5LfGsaiH","level":0,"time":"2023-04-06T09:44:54+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"partial sync 3:Surname NAME - Selected Tags for all messages took 0s. 7/7MB memory used","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"wVZCJaeirlRg5LfGsaiH","level":0,"time":"2023-04-06T09:44:54+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"partial sync 3:Surname NAME - Updated message took 0s. 7/7MB memory used","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"wVZCJaeirlRg5LfGsaiH","level":0,"time":"2023-04-06T09:44:54+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"partial sync 3:Surname NAME took 0s","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"wVZCJaeirlRg5LfGsaiH","level":0,"time":"2023-04-06T09:44:54+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"partial sync 3:INBOX - persist changed messages took 0s. 7/7MB memory used","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"wVZCJaeirlRg5LfGsaiH","level":0,"time":"2023-04-06T09:44:54+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"findByIds in INBOX got 0 UIDs but found none","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
{"reqId":"wVZCJaeirlRg5LfGsaiH","level":0,"time":"2023-04-06T09:44:54+00:00","remoteAddr":"192.168.10.169","user":"myself@REDACTED.TLD","app":"mail","method":"POST","url":"/apps/mail/api/mailboxes/82/sync","message":"findByIds in INBOX got 0 UIDs but found none","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/113.0","version":"24.0.7.1","data":{"app":"mail"}}
Let me know if you want more, I have tons off example.
Server configuration detail
Operating system: Linux 5.4.195-1-pve #1 SMP PVE 5.4.195-1 (Wed, 13 Jul 2022 13:19:46 +0200) i686
Webserver: nginx/1.18.0 (fpm-fcgi)
Database: mysql 10.5.18
PHP version: 7.4.33
Modules loaded: Core, date, libxml, openssl, pcre, zlib, filter, hash, Reflection, SPL, session, standard, sodium, cgi-fcgi, smbclient, mysqlnd, PDO, xml, apcu, bcmath, bz2, calendar, ctype, curl, dom, mbstring, FFI, fileinfo, ftp, gd, geoip, gettext, gmp, iconv, igbinary, imagick, imap, intl, json, ldap, exif, msgpack, mysqli, pdo_mysql, apc, posix, readline, redis, shmop, SimpleXML, sockets, sysvmsg, sysvsem, sysvshm, tokenizer, xmlreader, xmlwriter, xsl, zip, Phar, memcached, libsmbclient, Zend OPcache
Nextcloud version: 24.0.7 - 24.0.7.1
Updated from an older Nextcloud/ownCloud or fresh install:
Where did you install Nextcloud from: unknown
<details><summary>Signing status</summary>
Array ( [deckimportfromtrello] => Array ( [EXCEPTION] => Array ( [class] => OC\IntegrityCheck\Exceptions\InvalidSignatureException [message] => Certificate is not valid for required scope. (Requested: deckimportfromtrello, current: CN=APP_ID) )
)
)
</details>
<details><summary>List of activated apps</summary>
Enabled:
- accessibility: 1.10.0
- admin_audit: 1.14.0
- approval: 1.0.12
- calendar: 3.5.5
- circles: 24.0.1
- cloud_federation_api: 1.7.0
- collectives: 1.6.3
- comments: 1.14.0
- contacts: 4.2.4
- contactsinteraction: 1.5.0
- dashboard: 7.4.0
- dav: 1.22.0
- deck: 1.7.3
- deckimportfromtrello: 1.0.2
- federatedfilesharing: 1.14.0
- federation: 1.14.0
- files: 1.19.0
- files_accesscontrol: 1.14.1
- files_antivirus: 4.0.2
- files_automatedtagging: 1.14.0
- files_external: 1.16.1
- files_fulltextsearch: 24.0.1
- files_markdown: 2.3.6
- files_pdfviewer: 2.5.0
- files_retention: 1.13.2
- files_rightclick: 1.3.0
- files_sharing: 1.16.2
- files_texteditor: 2.15.0
- files_trashbin: 1.14.0
- files_versions: 1.17.0
- files_videoplayer: 1.13.0
- firstrunwizard: 2.13.0
- flow_notifications: 1.4.0
- forms: 2.5.1
- fulltextsearch: 24.0.0
- fulltextsearch_elasticsearch: 24.0.1
- groupfolders: 12.0.3
- guests: 2.3.0
- impersonate: 1.11.0
- logreader: 2.9.0
- lookup_server_connector: 1.12.0
- mail: 1.15.1
- nextcloud_announcements: 1.13.0
- notifications: 2.12.1
- oauth2: 1.12.0
- password_policy: 1.14.0
- privacy: 1.8.0
- provisioning_api: 1.14.0
- qownnotesapi: 22.10.0
- recommendations: 1.3.0
- richdocuments: 6.3.4
- serverinfo: 1.14.0
- settings: 1.6.0
- sharebymail: 1.14.0
- spreed: 14.0.8
- support: 1.7.0
- survey_client: 1.12.0
- systemtags: 1.14.0
- tasks: 0.14.5
- text: 3.5.1
- theming: 1.15.0
- twofactor_backupcodes: 1.13.0
- updatenotification: 1.14.0
- user_ldap: 1.14.1
- user_sql: 4.7.1
- user_status: 1.4.0
- viewer: 1.8.0
- weather_status: 1.4.0
- workflow_ocr: 1.24.6
- workflowengine: 2.6.0
Disabled:
- activity: 2.16.0
- carnet: 0.24.6
- encryption
- photos: 1.6.0
</details>
<details><summary>Configuration (config/config.php)</summary>
{
"instanceid": "***REMOVED SENSITIVE VALUE***",
"passwordsalt": "***REMOVED SENSITIVE VALUE***",
"secret": "***REMOVED SENSITIVE VALUE***",
"trusted_domains": [
"web.REDACTED.TLD",
"up.REDACTED.TLD"
],
"datadirectory": "***REMOVED SENSITIVE VALUE***",
"overwrite.cli.url": "https:\/\/web.REDACTED.TLD\/nextcloud",
"dbtype": "mysql",
"version": "24.0.7.1",
"dbname": "***REMOVED SENSITIVE VALUE***",
"dbhost": "***REMOVED SENSITIVE VALUE***",
"dbport": "",
"dbtableprefix": "oc_",
"dbuser": "***REMOVED SENSITIVE VALUE***",
"dbpassword": "***REMOVED SENSITIVE VALUE***",
"installed": true,
"ldapIgnoreNamingRules": false,
"ldapProviderFactory": "\\OCA\\User_LDAP\\LDAPProviderFactory",
"memcache.local": "\\OC\\Memcache\\APCu",
"memcache.distributed": "\\OC\\Memcache\\Memcached",
"memcached_servers": [
[
"localhost",
11211
]
],
"mail_smtpmode": "smtp",
"mail_smtpauthtype": "LOGIN",
"mail_from_address": "***REMOVED SENSITIVE VALUE***",
"mail_domain": "***REMOVED SENSITIVE VALUE***",
"mail_smtpauth": 1,
"mail_smtphost": "***REMOVED SENSITIVE VALUE***",
"mail_smtpport": "587",
"mail_smtpname": "***REMOVED SENSITIVE VALUE***",
"mail_smtppassword": "***REMOVED SENSITIVE VALUE***",
"mail_smtpsecure": "tls",
"updater.release.channel": "stable",
"maintenance": false,
"theme": "",
"loglevel": 0,
"debug": true,
"default_phone_region": "FR",
"default_locale": "fr_FR",
"trashbin_retention_obligation": "30, auto",
"versions_retention_obligation": "auto, 30",
"mysql.utf8mb4": true,
"app_install_overwrite": [
"workflow_ocr",
"user_sql"
],
"allow_local_remote_servers": true,
"has_rebuilt_cache": true,
"filelocking.enabled": true,
"memcache.locking": "\\OC\\Memcache\\Redis",
"redis": {
"host": "***REMOVED SENSITIVE VALUE***",
"port": 0,
"timeout": 0
}
}
</details>
Cron Configuration: Array ( [backgroundjobs_mode] => cron [lastcron] => 1680774606 )
External storages: yes
REDACTED PART
Encryption: no
User-backends:
OCA\Guests\UserBackend
OC\User\Database
OCA\UserSQL\Backend\UserBackend
Talk configuration:
STUN servers
stun.nextcloud.com:443
TURN servers
no custom server configured
Signaling servers (mode: default):
no custom server configured
IMAP server is Dovecot, and it's in the same LAN.
I'm also seeing this when connecting to a PurelyMail account via imap.
If i archive an email in PurelyMail webmail, then refresh in nextcloud, the email remains in the inbox and cannot be opened for viewing:
Relevant nextcloud log. It's likely failing since it's looking for the message in the inbox but it has already been moved to Archive.
[mail] Error: OCA\Mail\Exception\ServiceException: Could not load message at <<closure>>
0. /var/www/nextcloud/apps/mail/lib/Controller/MessagesController.php line 220
OCA\Mail\Service\MailManager->getImapMessage()
1. /var/www/nextcloud/lib/private/AppFramework/Http/Dispatcher.php line 230
OCA\Mail\Controller\MessagesController->getBody()
2. /var/www/nextcloud/lib/private/AppFramework/Http/Dispatcher.php line 137
OC\AppFramework\Http\Dispatcher->executeController()
3. /var/www/nextcloud/lib/private/AppFramework/App.php line 183
OC\AppFramework\Http\Dispatcher->dispatch()
4. /var/www/nextcloud/lib/private/Route/Router.php line 315
OC\AppFramework\App::main()
5. /var/www/nextcloud/lib/base.php line 1056
OC\Route\Router->match()
6. /var/www/nextcloud/index.php line 36
OC::handleRequest()
Caused by:
OCP\AppFramework\Db\DoesNotExistException: Mail body for this mail(308) could not be loaded at <<closure>>
0. /var/www/nextcloud/apps/mail/lib/IMAP/ImapMessageFetcher.php line 401
OCA\Mail\IMAP\ImapMessageFetcher->loadBodyData()
1. /var/www/nextcloud/apps/mail/lib/IMAP/ImapMessageFetcher.php line 352
OCA\Mail\IMAP\ImapMessageFetcher->handleTextMessage()
2. /var/www/nextcloud/apps/mail/lib/IMAP/ImapMessageFetcher.php line 205
OCA\Mail\IMAP\ImapMessageFetcher->getPart()
3. /var/www/nextcloud/apps/mail/lib/IMAP/MessageMapper.php line 314
OCA\Mail\IMAP\ImapMessageFetcher->fetchMessage()
4. <<closure>>
OCA\Mail\IMAP\MessageMapper->OCA\Mail\IMAP\{closure}("*** sensitive parameters replaced ***")
5. /var/www/nextcloud/apps/mail/lib/IMAP/MessageMapper.php line 315
array_map()
6. /var/www/nextcloud/apps/mail/lib/IMAP/MessageMapper.php line 87
OCA\Mail\IMAP\MessageMapper->findByIds()
7. /var/www/nextcloud/apps/mail/lib/Service/MailManager.php line 193
OCA\Mail\IMAP\MessageMapper->find()
8. /var/www/nextcloud/apps/mail/lib/Controller/MessagesController.php line 220
OCA\Mail\Service\MailManager->getImapMessage()
9. /var/www/nextcloud/lib/private/AppFramework/Http/Dispatcher.php line 230
OCA\Mail\Controller\MessagesController->getBody()
10. /var/www/nextcloud/lib/private/AppFramework/Http/Dispatcher.php line 137
OC\AppFramework\Http\Dispatcher->executeController()
11. /var/www/nextcloud/lib/private/AppFramework/App.php line 183
OC\AppFramework\Http\Dispatcher->dispatch()
12. /var/www/nextcloud/lib/private/Route/Router.php line 315
OC\AppFramework\App::main()
13. /var/www/nextcloud/lib/base.php line 1056
OC\Route\Router->match()
14. /var/www/nextcloud/index.php line 36
OC::handleRequest()
GET /apps/mail/api/messages/472789/body
I've been using NC Mail for a few years now and have learned to deal with most of the issues, but this one has me temporarily using something else. The issue results in many console logs, and an inability to archive messages that show in the NC inbox but are really not there because they have already been archived by my mobile client:
It also sometimes results in an endless flurry of desktop notifications saying there are new messages in my inbox:
I have the same scenario but i can still interact with some of the messages that got deleted
@GretaD Yes, this is what i'm seeing. I'm able to interact with the message although the content is not visible:
For me in same cases, the content of the message is also visible
Not found it says in german for all of them:
I'm really not trying to be annoying - I promise! Just reporting that this issue is still relevant in v3.2.3
Try https://github.com/nextcloud/mail/pull/8575. We think the issue has been identified.
@ChristophWurst I patched the changes into my instance but it doesn't seem to have fixed it for old or new emails despite reloading the page and rebooting nextcloud:
Here's the updated code in my own instance:
/var/www/nextcloud/apps/mail/lib/Service/Sync/ImapToDbSynchronizer.php
@meichthys check again tomorrow. The background sync (cron) has to run.
Ok, Thanks. I did try to manually run the cron job via sudo -u www-data php /var/www/nextcloud/cron.php
but maybe there's a different cron script that needs to run.
I'll check again tomorrow.
This still seems to be an issue in v3.3.0-alpha.1
This PR has not been merged yet, which means it is not included in any release. You have to apply the patch manually and don't update because it will get overwritten again.
I'm also trying it out and so far it seems to work, I'll keep observing..
Interesting. Were you experiencing the issue prior to the patch?
Before upgrading I applied the patch and it wasn't working for me despite waiting for the cron job to run. Was there anything else you needed to do?
Thank you so much @st3iny! :) Can’t wait for this to be deployed
Just to follow up here, I'm still seeing the issue despite applying the patch, rebooting, waiting a few days, and removing/re-adding my mail account, and updating to v3.3.0-alpha.1.
If i archive an email from nextcloud, the other clients properly show it as archived, but if i archive it from my other clients (Thunderbird, Fairmail) then nextcloud still shows it in the inbox and when selected, nextcloud says "Could not Load Message" as indicated above.
I'm just reporting this here in case others are seeing the same thing, but i will wait until the next mail version is released before re-reporting this issue in case i didn't apply the manual patch correctly. Here's the releavant section of my ImapToDbSynchronizer.php
file with patch applied:
The problem also reappeared for me too. :slightly_frowning_face:
@jancborchardt @ChristophWurst Can we re-open this since it doesn't seem to have fixed the issue?
I confirm I still have issues (for me mail deleted from other clients show a "Not found") with Nextcloud 27.0.1 and Mail app 3.2.4.
Also relevant for v3.3.0-alpha.1 @st3iny Could you double check to see if the patch works for you? I use Mail as my daily driver and this one really affects my workflow :/
same here: Nextcloud 26.0.4 Mariadb 10.6 Apache PHP 8.1
mail: 3.2.4
Just some minutes ago i did disable the "RainLoop" App, because it is not recommended (depreciated).
But as far i see, RainLoop did run without any issues.
There was a guy, saying that the issue may come from the cache, when different mapi clients trying to update the cache !?
https://www.andysblog.de/nextcloud-mail-zeigt-bereits-geloeschte-nachrichten-noch-an
So, as i did disable RainLoop now - maybe this helps ?
@Githopp192 good find 👍
I can confirm that clearing the nextcloud mailbox cache does work - until a different client is used again.
In the meantime i did re-enable RAINLOOP Mail App, because it's bug-free.
[mail] Fehler: OCA\Mail\Exception\ServiceException: Could not load message at <
Caused by:
OCP\AppFramework\Db\DoesNotExistException: Message does not exist at <
GET /apps/mail/api/messages/7854/body from xxxxx by xxxxx at 2023-08-03T09:44:15+02:00
[mail] Fehler: Exception: fseek(): supplied resource is not a valid stream resource in file '/var/www/html/nextcloud/apps/mail/vendor/bytestream/horde-mime/lib/Horde/Mime/Part.php' line 1266 at <
Caused by:
TypeError: fseek(): supplied resource is not a valid stream resource at <
GET /apps/mail/api/messages/8364/itineraries from xxxxx by xxxxx at 2023-08-03T09:43:34+02:00
Is there anything we/I can do to help investigating this problem? It is really a show-stopper for this app..
I agree, by now my Nextcloud Mail is totally out of sync with my IMAP account. All other clients (K-9 on my phone, KMail on my workstation, and Roundcube when I am at other PCs) do sync well. Unfortunately I am unable to contribute to fixing the problem.
@bovender you should be able to just clear the cache on the Nextcloud inbox to get synced up. Its not a permanent fix, but better than nothing.
just clear the cache on the Nextcloud inbox
How to do that?
Click three dots next to your mail account folder
Did that already before answering you. There is no entry related to cache. Which menu item to look for?
Oh, sorry, I forgot, I think you need to enable a debug flag in the Nextcloud config. https://www.andysblog.de/nextcloud-mail-zeigt-bereits-geloeschte-nachrichten-noch-an
Added flag and it worked. Thanks for the hint.
I am unable to enable debug mode. Recently, it was still possible to simply open the “corrupted” messages and delete those. This would throw an error, but he corrupted message would still be removed. Thus, I was able to simply open all corrupted messages and delete those manually.
Since one of the last updates, deleting corrupted messages, does not work anymore. After deleting, the message is removed but shortly after pops up again. So I guess the only way is to remove all accounts and add those again.
However, regarding the issue of messages becoming corrupted in the first place, I guess this issue has been resolved. I haven't received any corrupted messages since September 14th. So might have been fixed by some of those updates :tada: :upside_down_face:
Clear local cached data feature works for me and fixes orphaned email that is no longer on the server.
Can this feature be made permanent so debug mode can be turned off?
It would be better to fix the cause than to make a quick "fix" permanent.
Try #8575. We think the issue has been identified.
Sorry but I am on 3.4.0 which includes the patch and I still have the same issue.
Same here
3.4.0 here too and yes, I have the same problem on a fresh install - 2 IMAP accounts set up and issue with them both.
NB. I use Windows based app (emClient) to access the same accounts.
It seems to work with the inbox but not with other folders, maybe this information is of some use.
It seems to work with the inbox but not with other folders, maybe this information is of some use.
For info, I'm having issues with Inbox on both accounts
Yeah, I also just realized that it works sometimes and sometimes not, so it doesn't seem to be related to the folder.. :slightly_frowning_face:
Caching is involved in the Mail (background) sync. Just a wild guess but what do you all use for the local cache of Nextcloud? https://docs.nextcloud.com/server/latest/admin_manual/configuration_server/caching_configuration.html
Background: APCu cache is lost after the cron background job invocation: https://github.com/nextcloud/server/issues/40800
Interesting thought! Mine is the standard deployment of AIO nextcloud, so I am using AOCu, yes ....
'memcache.local' => '\\OC\\Memcache\\APCu',
Caching is involved in the Mail (background) sync. Just a wild guess but what do you all use for the local cache of Nextcloud? https://docs.nextcloud.com/server/latest/admin_manual/configuration_server/caching_configuration.html
Background: APCu cache is lost after the cron background job invocation: nextcloud/server#40800
I use Redis on my server. I have the same issue with 3.4.0
I also use redis (I'm using the nextcloud VM)
Steps to reproduce
Expected behavior
No non-existent mails should show up in Mail, especially not indicated as unread. If the preview line can not be fetched, maybe it’s better to not show it in the message list to begin with? (It could still be loaded in later if the mail is retrieved.)
Actual behavior
The 3 mails of upgrade, activity, and invitation are actually deleted. They show up in the list without preview text line, and indicated as unread. When opening, they show "Not found":![image](https://user-images.githubusercontent.com/925062/224956859-aa8b5af0-a33d-46fc-9306-02ab23948349.png)
Mail app version
Current on cloud.nc.com
Mailserver or service
No response
Operating system
No response
PHP engine version
None
Web server
None
Database
None
Additional info
No response