Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Mailbox folders shown, but messages list is empty #1515

Closed
stellarpower opened this issue Mar 24, 2024 · 15 comments
Closed

Mailbox folders shown, but messages list is empty #1515

stellarpower opened this issue Mar 24, 2024 · 15 comments
Labels
external Issue is outside our code

Comments

@stellarpower
Copy link

stellarpower commented Mar 24, 2024

Describe the bug
Have made a throwaway account on a mail server. Can log in successfully, and I can see folders and the number of messages in there:
image

However, I only get an empty list for the messages. Seems to be working as normal for a different mailserver (don't know what software it is running on the backend), but not this one using maddy.

IMAP folder configuration:

maddy imap-mboxes list [email protected]
INBOX	[\HasNoChildren]
Sent	[\Sent \HasNoChildren]
Trash	[\Trash \HasNoChildren]
Junk	[\Junk \HasNoChildren]
Drafts	[\Drafts \HasNoChildren]
Archive	[\Archive \HasNoChildren]
CanYouSeeThis	[\HasNoChildren]
INBOX.Subfolder	[\HasNoChildren]

Logs look okay to me (Subfolder was added afterwards, so not shown):

[INFO]: [SM:2.35.4][IP:1.2.3.4][PID:32][web server/version][fpm-fcgi][Streams:tcp,udp,unix,udg,ssl,tls,tlsv1.0,tlsv1.1,tlsv1.2,tlsv1.3][POST http://nextcloud.example.org/apps/snappymail/?/Json/&q[]=/0/]
Nextcloud[DEBUG]: integrated
JSON[INFO]: Action: DoMessageList
POST[INFO]: {"folder":"CanYouSeeThis","offset":0,"limit":20,"uidNext":2,"sort":"","search":"","useThreads":1,"threadUid":0,"Action":"MessageList"}
IMAP[INFO]: Start connection to "ssl://mail.example.org:993"
IMAP[INFO]: Connect (success)
IMAP[DEBUG]: 0.017798900604248 (raw connection)
IMAP[INFO]: < * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT AUTH=PLAIN AUTH=LOGIN COMPRESS] IMAP4rev1 Service Ready\r\n
IMAP[DEBUG]: 0.018355131149292 (*)
IMAP[INFO]: > TAG1 AUTHENTICATE PLAIN *******\r\n
IMAP[INFO]: < TAG1 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT I18NLEVEL=1 SORT THREAD=ORDEREDSUBJECT COMPRESS NAMESPACE] AUTHENTICATE completed\r\n
IMAP[DEBUG]: 0.84976696968079 (TAG1)
IMAP[INFO]: > TAG2 STATUS "CanYouSeeThis" (MESSAGES UNSEEN UIDNEXT UIDVALIDITY APPENDLIMIT)\r\n
IMAP[INFO]: < * STATUS "CanYouSeeThis" (UIDVALIDITY 939538905 APPENDLIMIT 0 MESSAGES 1 UNSEEN 1 UIDNEXT 2)\r\n
IMAP[INFO]: < TAG2 OK STATUS completed\r\n
IMAP[DEBUG]: 0.042237997055054 (TAG2)
IMAP[INFO]: > TAG3 SELECT "CanYouSeeThis"\r\n
IMAP[INFO]: < * FLAGS (\Seen \Answered \Flagged \Deleted \Draft)\r\n
IMAP[INFO]: < * OK [PERMANENTFLAGS (\Seen \Answered \Flagged \Deleted \Draft \*)] Flags permitted.\r\n
IMAP[INFO]: < * OK [UNSEEN 1] Message 1 is first unseen\r\n
IMAP[INFO]: < * OK [UIDNEXT 2] Predicted next UID\r\n
IMAP[INFO]: < * OK [UIDVALIDITY 939538905] UIDs valid\r\n
IMAP[INFO]: < * 1 EXISTS\r\n
IMAP[INFO]: < * 0 RECENT\r\n
IMAP[INFO]: < TAG3 OK [READ-WRITE] SELECT completed\r\n
IMAP[DEBUG]: 0.044865131378174 (TAG3)
[INFO]: Get Serialized UIDS from cache ("CanYouSeeThis" / REVERSE DATE / UNDELETED) [count:0]
[INFO]: ThreadsMapSorted/ALL/CanYouSeeThis/3804fa8b72ecfe709a917f6d1a0b8aab
[INFO]: Get Serialized Thread UIDS from cache ("CanYouSeeThis" / ALL) [count:0]
IMAP[INFO]: > TAG4 UID SEARCH UNSEEN UNDELETED\r\n
IMAP[INFO]: < * SEARCH 1\r\n
IMAP[INFO]: < TAG4 OK UID SEARCH completed\r\n
IMAP[DEBUG]: 0.045835018157959 (TAG4)
JSON[INFO]: {"Action":"MessageList","Result":{"@Object":"Collection\/MessageCollection","@Collection":[],"totalEmails":1,"totalThreads":0,"threadUid":0,"newMessages":[],"offset":0,"limit":20,"search":"","sort":"REVERSE DATE","limited":false,"folder":{"id":null,"name":"CanYouSeeThis","uidNext":2,"uidValidity":93...
[INFO]: Memory peak usage: 8MB
[INFO]: Time delta: 1.0739300251007
IMAP[INFO]: > TAG5 LOGOUT\r\n
IMAP[INFO]: < * BYE Closing connection\r\n
IMAP[INFO]: < TAG5 OK LOGOUT completed\r\n
IMAP[DEBUG]: 0.00052976608276367 (TAG5)
IMAP[INFO]: Disconnected from "ssl://mail.example.org:993" (success)
IMAP[DEBUG]: 1.0052978992462 (net session)

Thanks!

  • Browser: firefox 116, UBuntu
  • IMAP daemon: Maddy
  • PHP version: Not sure.
  • SnappyMail Version: 2.35.4 Nextcloud
  • Mode: nextcloud
@the-djmaze the-djmaze added the bug Something isn't working label Mar 24, 2024
@the-djmaze
Copy link
Owner

You have:

  1. TAG1 AUTHENTICATE
  2. TAG2 STATUS
  3. TAG3 SELECT
  4. Serialized UIDS from cache ("CanYouSeeThis" / REVERSE DATE / UNDELETED) [count:0]
  5. Serialized Thread UIDS from cache ("CanYouSeeThis" / ALL) [count:0]
  6. TAG4 UID SEARCH UNSEEN UNDELETED
  7. TAG5 LOGOUT

Instead, it should have not used the cache as there is 1 message.
It should have been:

  1. TAG1 AUTHENTICATE
  2. TAG2 STATUS
  3. TAG3 SELECT
  4. TAG4 UID SORT (REVERSE DATE) UTF-8 UNDELETED
  5. TAG5 UID THREAD REFS UTF-8 ALL
  6. TAG6 UID SEARCH UNSEEN UNDELETED

I will look and see why the cache is not using the folder Etag

@stellarpower
Copy link
Author

stellarpower commented Mar 24, 2024

Thanks for looking into it! I think I saw a mention of disabling the cache on some of the other similar issues. Can I simply turn it off in the admin page to try as a workaround?

Edit: tried disabling, behaviour looks the same.

@stellarpower
Copy link
Author

Want me to try hotpatching the changes from this commit in?

@the-djmaze
Copy link
Owner

No, just wait a day ;)

@the-djmaze
Copy link
Owner

Here you go, try with v2.36.0

@stellarpower
Copy link
Author

No dice, I'm afraid.

[2024-03-27 13:39:30.062][62b9e362] [INFO]: [SM:2.36.0][IP:1.2.3.4][PID:79][server/version][fpm-fcgi][Streams:tcp,udp,unix,udg,ssl,tls,tlsv1.0,tlsv1.1,tlsv1.2,tlsv1.3][POST http://nextcloud.example.org/apps/snappymail/?admin/Json/&q[]=/0/]
[2024-03-27 13:39:30.062][62b9e362] Nextcloud[DEBUG]: integrated
[2024-03-27 13:39:30.063][62b9e362] JSON[INFO]: Action: DoAdminSettingsSet
[2024-03-27 13:39:30.063][62b9e362] POST[INFO]: {"config":{"webmail":{"app_path":"\/custom_apps\/snappymail\/app\/",allow_additional_accounts":true,"allow_additional_identities":true,"messages_per_page":20,"message_read_delay":5,"attachment_size_limit":25,"compress_output":false},"interface":{"show_attachment_thumbnail":true},"contacts":{"enable":false,"allow_sync":false,"sync_interval":20,"type":"sqlite","pdo_dsn":"host=127.0.0.1;port=3306;dbname=snappymail","pdo_user":"root","pdo_password":"","mysql_ssl_ca":"","mysql_ssl_verify":true,"mysql_ssl_ciphers":"","sqlite_global":false,"suggestions_limit":30},"security":{"custom_server_signature":"SnappyMail","x_xss_protection_header":"1; mode=block","gnupg":true,"openpgp":false,"auto_verify_signatures":false,"allow_admin_panel":true,"admin_login":"admin","admin_totp":"","force_https":false,"hide_x_mailer_header":true,"max_sys_getloadavg":0,"content_security_policy":"","csp_report":false,"encrypt_cipher":"aes-256-cbc-hmac-sha1","cookie_samesite":"Strict","secfetch_allow":""},"admin_panel":{"host":"","key":"admin","allow_update":false,"language":"en"},"ssl":{"verify_certificate":true,"allow_self_signed":false,"security_level":1,"cafile":"","capath":"","local_cert":"","disable_compression":true},"capa":{"dangerous_actions":true,"attachments_actions":true},"login":{"default_domain":"mail.example.org","allow_languages_on_login":true,"determine_user_language":true,"determine_user_domain":true,"sign_me_auto":"DefaultOff"},"plugins":{"enable":true,"enabled_list":"nextcloud"},"defaults":{"view_editor_type":"Html","view_layout":1,"view_use_checkboxes":true,"view_show_next_message":true,"autologout":30,"view_html":true,"show_images":false,"view_images":"ask","contacts_autosave":true,"mail_list_grouped":false,"mail_use_threads":false,"allow_draft_autosave":true,"mail_reply_same_folder":false,"msg_default_action":1,"collapse_blockquotes":true,"allow_spellcheck":false},"logs":{"enable":true,"path":"","level":7,"hide_passwords":true,"time_zone":"UTC","filename":"log-{date:Y-m-d}.txt","auth_logging":false,"auth_logging_filename":"fail2ban\/auth-{date:Y-m-d}.txt","auth_logging_format":"[{date:Y-m-d H:i:s}] Auth failed: ip={request:ip} user={imap:login} host={imap:host} port={imap:port}","auth_syslog":false},"debug":{"enable":true,"javascript":false,"css":false},"cache":{"enable":false,"path":"","index":"v1","fast_cache_index":"v1","http":true,"http_expires":3600,"server_uids":false,"system_data":false},"imap":{"use_force_selection":false,"use_expunge_all_on_delete":false,"message_list_fast_simple_search":true,"message_list_permanent_filter":"","message_all_headers":false,"show_login_alert":true,"fetch_new_messages":true},"labs":{"date_from_headers":true,"allow_message_append":false,"login_fault_delay":1,"log_ajax_response_write_limit":300,"smtp_show_server_errors":false,"mail_func_clear_headers":true,"mail_func_additional_parameters":false,"folders_spec_limit":50,"curl_proxy":"","curl_proxy_auth":"","custom_login_link":"","custom_logout_link":"","http_client_ip_check_proxy":false,"use_local_proxy_for_external_images":true,"image_exif_auto_rotate":false,"cookie_default_path":"","cookie_default_secure":false,"replace_env_in_configuration":"","boundary_prefix":"","dev_email":"","dev_password":""}},"Action":"AdminSettingsSet"}
[2024-03-27 13:39:30.064][62b9e362] JSON[INFO]: {"Action":"AdminSettingsSet","Result":true,"epoch":1711546770}
[2024-03-27 13:39:30.064][62b9e362] [INFO]: Memory peak usage: 10MB
[2024-03-27 13:39:30.064][62b9e362] [INFO]: Time delta: 0.11238598823547
[2024-03-27 13:39:37.248][c915a178] [INFO]: [SM:2.36.0][IP:1.2.3.4][PID:28][server/version][fpm-fcgi][Streams:tcp,udp,unix,udg,ssl,tls,tlsv1.0,tlsv1.1,tlsv1.2,tlsv1.3][GET http://nextcloud.example.org/apps/snappymail/]
[2024-03-27 13:39:37.249][c915a178] Nextcloud[DEBUG]: integrated
[2024-03-27 13:39:37.255][c915a178] PLUGIN[INFO]: Hook: filter.language
[2024-03-27 13:39:37.264][c915a178] [INFO]: Caught SIGCHLD
[2024-03-27 13:39:37.363][c915a178] [INFO]: Memory peak usage: 12MB
[2024-03-27 13:39:37.363][c915a178] [INFO]: Time delta: 0.20549201965332
[2024-03-27 13:39:37.911][468975a6] [INFO]: [SM:2.36.0][IP:1.2.3.4][PID:63][server/version][fpm-fcgi][Streams:tcp,udp,unix,udg,ssl,tls,tlsv1.0,tlsv1.1,tlsv1.2,tlsv1.3][GET http://nextcloud.example.org/apps/snappymail/?/AppData/0/37835809779609075/]
[2024-03-27 13:39:37.912][468975a6] Nextcloud[DEBUG]: integrated
[2024-03-27 13:39:37.922][468975a6] [INFO]: Caught SIGCHLD
[2024-03-27 13:39:37.923][468975a6] PLUGIN[INFO]: Hook: filter.language
[2024-03-27 13:39:37.924][468975a6] PLUGIN[INFO]: Hook: filter.app-data
[2024-03-27 13:39:37.924][468975a6] APPDATA[INFO]: {"Auth":true,"title":"example.org Webmail","loadingDescription":"example.org Webmail","Plugins":[],"System":{"version":"2.36.0","token":"b056b7b1ecdeb0831749e8b59a45e59ccff44287","webPath":"\/custom_apps\/snappymail\/app\/","webVersionPath":"\/custom_apps\/snappymail\/app\/snappymail\/v\/2.36.0\/","allowAppendMessage":false,"folderSpecLimit":50,"listPermanentFiltered":false,"attachmentsActions":["zip"],"customLogoutLink":"",},"allowLanguagesOnLogin":true,"accountSignMe":true,"allowSpellcheck":true,"ViewHTML":true,"ViewImages":"always","ViewImagesWhitelist":"","RemoveColors":false,"AllowStyles":false,"ListInlineAttachments":false,"CollapseBlockquotes":true,"MaxBlockquotesLevel":0,"simpleAttachmentsList":false,"listGrouped":true,"MessagesPerPage":20,"messageNewWindow":0,"messageReadAuto":1,"MessageReadDelay":5,"MsgDefaultAction":1,"SoundNotification":true,"NotificationSound":"new-mail","DesktopNotifications":true,"Layout":1,"EditorDefaultType":"Html","editorWysiwyg":"Squire","UseCheckboxesInList":true,"showNextMessage":true,"AutoLogout":300,"AllowDraftAutosave":true,"ContactsAutosave":true,"Email":"[email protected]","accountHash":"3a....e7","mainEmail":"[email protected]","contactsAllowed":false,"HideUnsubscribed":false,"useThreads":true,"threadAlgorithm":"","ReplySameFolder":true,"HideDeleted":true,"ShowUnreadCount":false,"UnhideKolabFolders":false,"CheckMailInterval":15,"SentFolder":"Sent","DraftsFolder":"Drafts","JunkFolder":"Junk","TrashFolder":"Trash","ArchiveFolder":"Archive","hourCycle":"","requestReadReceipt":false,"requestDsn":false,"requireTLS":false,"pgpSign":false,"pgpEncrypt":false,"Resizer4Width":220,"Resizer5Width":320,"Resizer5Height":0,"fontSansSerif":"","fontSerif":"","fontMono":"","newMailSounds":["alert","new-mail","ping"],"proxyExternalImages":true,"autoVerifySignatures":false,"allowLanguagesOnSettings":true,"Capa":{"AdditionalAccounts":true,"AttachmentThumbnails":true,"AttachmentsActions":true,"Contacts":false,"DangerousActions":true,"GnuPG":false,"Identities":true,"OpenPGP":false,"Sieve":false,"Themes":true,"UserBackground":false,"Kolab":false},"attachmentLimit":26214400,"phpUploadSizes":{"upload_max_filesize":"512M","post_max_size":"512M"},"Theme":"example.org","language":"en","clientLanguage":"en","PluginsLink":"Plugins\/0\/User\/3...7a\/","StaticLibsJs":"\/custom_apps\/snappymail\/app\/snappymail\/v\/2.36.0\/static\/js\/libs.js","Nextcloud":{"UID":"User","WebDAV":"https:\/\/nextcloud.example.org\/remote.php\/dav","CalDAV":false}}
[2024-03-27 13:39:37.924][468975a6] [INFO]: Memory peak usage: 10MB
[2024-03-27 13:39:37.924][468975a6] [INFO]: Time delta: 0.095468044281006
[2024-03-27 13:39:38.364][4d31ff4e] [INFO]: [SM:2.36.0][IP:1.2.3.4][PID:74][server/version][fpm-fcgi][Streams:tcp,udp,unix,udg,ssl,tls,tlsv1.0,tlsv1.1,tlsv1.2,tlsv1.3][GET http://nextcloud.example.org/apps/snappymail/?/Plugins/0/User/3f....a7a/]
[2024-03-27 13:39:38.365][4d31ff4e] Nextcloud[DEBUG]: integrated
[2024-03-27 13:39:38.366][4d31ff4e] [INFO]: Memory peak usage: 14MB
[2024-03-27 13:39:38.366][4d31ff4e] [INFO]: Time delta: 0.053082942962646
[2024-03-27 13:39:38.540][449c86d0] [INFO]: [SM:2.36.0][IP:1.2.3.4][PID:47][server/version][fpm-fcgi][Streams:tcp,udp,unix,udg,ssl,tls,tlsv1.0,tlsv1.1,tlsv1.2,tlsv1.3][GET http://nextcloud.example.org/apps/snappymail/?/Css/0/User/-/example.org/-/1711546778412/Hash/-/Json/]



[2024-03-27 13:39:38.541][449c86d0] Nextcloud[DEBUG]: integrated
[2024-03-27 13:39:38.544][449c86d0] [INFO]: Memory peak usage: 16MB
[2024-03-27 13:39:38.544][449c86d0] [INFO]: Time delta: 0.078697919845581
[2024-03-27 13:39:38.578][bc805a8b] [INFO]: [SM:2.36.0][IP:1.2.3.4][PID:56][server/version][fpm-fcgi][Streams:tcp,udp,unix,udg,ssl,tls,tlsv1.0,tlsv1.1,tlsv1.2,tlsv1.3][POST http://nextcloud.example.org/apps/snappymail/?/Json/&q[]=/0/]
[2024-03-27 13:39:38.579][bc805a8b] Nextcloud[DEBUG]: integrated



[2024-03-27 13:39:38.581][bc805a8b] JSON[INFO]: Action: DoFolders
[2024-03-27 13:39:38.581][bc805a8b] POST[INFO]: {"Action":"Folders"}
[2024-03-27 13:39:38.582][bc805a8b] IMAP[INFO]: Start connection to "ssl://mail.example.org:993"
[2024-03-27 13:39:38.707][bc805a8b] IMAP[INFO]: Connect (success)
[2024-03-27 13:39:38.707][bc805a8b] IMAP[DEBUG]: 0.1244809627533 (raw connection)
[2024-03-27 13:39:38.708][bc805a8b] IMAP[INFO]: < * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT AUTH=PLAIN AUTH=LOGIN COMPRESS] IMAP4rev1 Service Ready\r\n
[2024-03-27 13:39:38.708][bc805a8b] IMAP[DEBUG]: 0.1258602142334 (*)
[2024-03-27 13:39:38.709][bc805a8b] IMAP[INFO]: > TAG1 AUTHENTICATE PLAIN *******\r\n
[2024-03-27 13:39:39.564][bc805a8b] IMAP[INFO]: < TAG1 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT I18NLEVEL=1 SORT THREAD=ORDEREDSUBJECT COMPRESS NAMESPACE] AUTHENTICATE completed\r\n
[2024-03-27 13:39:39.564][bc805a8b] IMAP[DEBUG]: 0.85509490966797 (TAG1)
[2024-03-27 13:39:39.564][bc805a8b] IMAP[INFO]: > TAG2 LIST "" "*"\r\n
[2024-03-27 13:39:39.565][bc805a8b] IMAP[INFO]: < * LIST (\HasChildren) "." INBOX\r\n
[2024-03-27 13:39:39.611][bc805a8b] IMAP[INFO]: < * LIST (\Sent \HasNoChildren) "." "Sent"\r\n
[2024-03-27 13:39:39.611][bc805a8b] IMAP[INFO]: < * LIST (\Trash \HasNoChildren) "." "Trash"\r\n
[2024-03-27 13:39:39.611][bc805a8b] IMAP[INFO]: < * LIST (\Junk \HasNoChildren) "." "Junk"\r\n
[2024-03-27 13:39:39.611][bc805a8b] IMAP[INFO]: < * LIST (\Drafts \HasNoChildren) "." "Drafts"\r\n
[2024-03-27 13:39:39.612][bc805a8b] IMAP[INFO]: < * LIST (\Archive \HasNoChildren) "." "Archive"\r\n
[2024-03-27 13:39:39.612][bc805a8b] IMAP[INFO]: < * LIST (\HasNoChildren) "." "CanYouSeeThis"\r\n
[2024-03-27 13:39:39.612][bc805a8b] IMAP[INFO]: < * LIST (\HasNoChildren) "." "INBOX.HELLO"\r\n
[2024-03-27 13:39:39.612][bc805a8b] IMAP[INFO]: < TAG2 OK LIST completed\r\n
[2024-03-27 13:39:39.612][bc805a8b] IMAP[DEBUG]: 0.047532081604004 (TAG2)
[2024-03-27 13:39:39.612][bc805a8b] IMAP[INFO]: > TAG3 NAMESPACE\r\n
[2024-03-27 13:39:39.612][bc805a8b] IMAP[INFO]: < * NAMESPACE (("" ".")) NIL NIL\r\n
[2024-03-27 13:39:39.655][bc805a8b] IMAP[INFO]: < TAG3 OK NAMESPACE completed\r\n
[2024-03-27 13:39:39.655][bc805a8b] IMAP[DEBUG]: 0.043246030807495 (TAG3)
[2024-03-27 13:39:39.656][bc805a8b] JSON[INFO]: {"Action":"Folders","Result":{"@Object":"Collection\/FolderCollection","@Collection":[{"@Object":"Object\/Folder","name":"INBOX","fullName":"INBOX","delimiter":".","attributes":["\\haschildren"],"metadata":[],"uidNext":null,"totalEmails":null,"unreadEmails":null,"id":null,"size":null,"role":"inbox",...
[2024-03-27 13:39:39.656][bc805a8b] [INFO]: Memory peak usage: 10MB
[2024-03-27 13:39:39.656][bc805a8b] [INFO]: Time delta: 1.1897220611572
[2024-03-27 13:39:39.656][bc805a8b] IMAP[INFO]: > TAG4 LOGOUT\r\n
[2024-03-27 13:39:39.657][bc805a8b] IMAP[INFO]: < * BYE Closing connection\r\n
[2024-03-27 13:39:39.657][bc805a8b] IMAP[INFO]: < TAG4 OK LOGOUT completed\r\n
[2024-03-27 13:39:39.657][bc805a8b] IMAP[DEBUG]: 0.00061988830566406 (TAG4)
[2024-03-27 13:39:39.657][bc805a8b] IMAP[INFO]: Disconnected from "ssl://mail.example.org:993" (success)
[2024-03-27 13:39:39.657][bc805a8b] IMAP[DEBUG]: 1.0748710632324 (net session)





[2024-03-27 13:39:39.805][af6edfea] [INFO]: [SM:2.36.0][IP:1.2.3.4][PID:40][server/version][fpm-fcgi][Streams:tcp,udp,unix,udg,ssl,tls,tlsv1.0,tlsv1.1,tlsv1.2,tlsv1.3][POST http://nextcloud.example.org/apps/snappymail/?/Json/&q[]=/0/]
[2024-03-27 13:39:39.806][af6edfea] Nextcloud[DEBUG]: integrated
[2024-03-27 13:39:39.810][af6edfea] JSON[INFO]: Action: DoAccountsAndIdentities
[2024-03-27 13:39:39.810][af6edfea] POST[INFO]: {"Action":"AccountsAndIdentities"}
[2024-03-27 13:39:39.812][af6edfea] [INFO]: Caught SIGCHLD
[2024-03-27 13:39:39.814][af6edfea] JSON[INFO]: {"Action":"AccountsAndIdentities","Result":{"Accounts":[],"Identities":[{"@Object":"Object\/Identity","id":"","label":"","email":"[email protected]","name":"User","replyTo":"","bcc":"","signature":":HTML:<!-- ... -->"
[2024-03-27 13:39:39.814][af6edfea] [INFO]: Memory peak usage: 12MB
[2024-03-27 13:39:39.814][af6edfea] [INFO]: Time delta: 0.078556060791016
[2024-03-27 13:39:39.853][22ce27d6] [INFO]: [SM:2.36.0][IP:1.2.3.4][PID:30][server/version][fpm-fcgi][Streams:tcp,udp,unix,udg,ssl,tls,tlsv1.0,tlsv1.1,tlsv1.2,tlsv1.3][POST http://nextcloud.example.org/apps/snappymail/?/Json/&q[]=/0/]
[2024-03-27 13:39:39.854][22ce27d6] Nextcloud[DEBUG]: integrated
[2024-03-27 13:39:39.856][22ce27d6] JSON[INFO]: Action: DoSMimeGetCertificates
[2024-03-27 13:39:39.856][22ce27d6] POST[INFO]: {"Action":"SMimeGetCertificates"}
[2024-03-27 13:39:39.856][22ce27d6] JSON[INFO]: {"Action":"SMimeGetCertificates","Result":[],"epoch":1711546779}
[2024-03-27 13:39:39.857][22ce27d6] [INFO]: Memory peak usage: 14MB
[2024-03-27 13:39:39.857][22ce27d6] [INFO]: Time delta: 0.12071299552917
[2024-03-27 13:39:39.891][df40b378] [INFO]: [SM:2.36.0][IP:1.2.3.4][PID:62][server/version][fpm-fcgi][Streams:tcp,udp,unix,udg,ssl,tls,tlsv1.0,tlsv1.1,tlsv1.2,tlsv1.3][POST http://nextcloud.example.org/apps/snappymail/?/Json/&q[]=/0/]
[2024-03-27 13:39:39.892][df40b378] Nextcloud[DEBUG]: integrated
[2024-03-27 13:39:39.896][df40b378] JSON[INFO]: Action: DoMessageList
[2024-03-27 13:39:39.896][df40b378] POST[INFO]: {"folder":"INBOX","offset":0,"limit":20,"uidNext":0,"sort":"","search":"","useThreads":1,"threadAlgorithm":"","threadUid":0,"Action":"MessageList"}


[2024-03-27 13:39:39.897][df40b378] IMAP[INFO]: Start connection to "ssl://mail.example.org:993"
[2024-03-27 13:39:39.932][df40b378] IMAP[INFO]: Connect (success)
[2024-03-27 13:39:39.932][df40b378] IMAP[DEBUG]: 0.035167932510376 (raw connection)
[2024-03-27 13:39:39.933][df40b378] IMAP[INFO]: < * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT AUTH=PLAIN AUTH=LOGIN COMPRESS] IMAP4rev1 Service Ready\r\n
[2024-03-27 13:39:39.933][df40b378] IMAP[DEBUG]: 0.03602409362793 (*)
[2024-03-27 13:39:39.934][df40b378] IMAP[INFO]: > TAG1 AUTHENTICATE PLAIN *******\r\n
[2024-03-27 13:39:40.775][df40b378] IMAP[INFO]: < TAG1 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT I18NLEVEL=1 SORT THREAD=ORDEREDSUBJECT COMPRESS NAMESPACE] AUTHENTICATE completed\r\n
[2024-03-27 13:39:40.775][df40b378] IMAP[DEBUG]: 0.84134602546692 (TAG1)
[2024-03-27 13:39:40.776][df40b378] IMAP[INFO]: > TAG2 STATUS "INBOX" (MESSAGES UNSEEN UIDNEXT UIDVALIDITY APPENDLIMIT)\r\n
[2024-03-27 13:39:40.776][df40b378] IMAP[INFO]: < * STATUS INBOX (MESSAGES 1 UNSEEN 1 UIDNEXT 4 UIDVALIDITY 680226926 APPENDLIMIT 0)\r\n
[2024-03-27 13:39:40.778][9df626a4] [INFO]: [SM:2.36.0][IP:1.2.3.4][PID:88][server/version][fpm-fcgi][Streams:tcp,udp,unix,udg,ssl,tls,tlsv1.0,tlsv1.1,tlsv1.2,tlsv1.3][POST http://nextcloud.example.org/apps/snappymail/?/Json/&q[]=/0/]
[2024-03-27 13:39:40.778][9df626a4] Nextcloud[DEBUG]: integrated
[2024-03-27 13:39:40.781][9df626a4] JSON[INFO]: Action: DoFolderInformationMultiply
[2024-03-27 13:39:40.781][9df626a4] POST[INFO]: {"folders":["INBOX","Archive","Drafts","Junk","Sent","Trash"],"Action":"FolderInformationMultiply"}
[2024-03-27 13:39:40.782][9df626a4] IMAP[INFO]: Start connection to "ssl://mail.example.org:993"
[2024-03-27 13:39:40.804][9df626a4] IMAP[INFO]: Connect (success)
[2024-03-27 13:39:40.804][9df626a4] IMAP[DEBUG]: 0.022788047790527 (raw connection)
[2024-03-27 13:39:40.805][9df626a4] IMAP[INFO]: < * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT AUTH=LOGIN AUTH=PLAIN COMPRESS] IMAP4rev1 Service Ready\r\n
[2024-03-27 13:39:40.805][9df626a4] IMAP[DEBUG]: 0.02348804473877 (*)
[2024-03-27 13:39:40.805][9df626a4] IMAP[INFO]: > TAG1 AUTHENTICATE PLAIN *******\r\n
[2024-03-27 13:39:40.819][df40b378] IMAP[INFO]: < TAG2 OK STATUS completed\r\n
[2024-03-27 13:39:40.819][df40b378] IMAP[DEBUG]: 0.043405055999756 (TAG2)
[2024-03-27 13:39:40.819][df40b378] IMAP[INFO]: > TAG3 SELECT "INBOX"\r\n
[2024-03-27 13:39:40.820][df40b378] IMAP[INFO]: < * FLAGS (\Seen \Answered \Flagged \Deleted \Draft)\r\n
[2024-03-27 13:39:40.867][df40b378] IMAP[INFO]: < * OK [PERMANENTFLAGS (\Seen \Answered \Flagged \Deleted \Draft \*)] Flags permitted.\r\n
[2024-03-27 13:39:40.867][df40b378] IMAP[INFO]: < * OK [UNSEEN 1] Message 1 is first unseen\r\n
[2024-03-27 13:39:40.867][df40b378] IMAP[INFO]: < * 0 RECENT\r\n
[2024-03-27 13:39:40.868][df40b378] IMAP[INFO]: < * OK [UIDNEXT 4] Predicted next UID\r\n
[2024-03-27 13:39:40.868][df40b378] IMAP[INFO]: < * OK [UIDVALIDITY 680226926] UIDs valid\r\n
[2024-03-27 13:39:40.868][df40b378] IMAP[INFO]: < * 1 EXISTS\r\n
[2024-03-27 13:39:40.868][df40b378] IMAP[INFO]: < TAG3 OK [READ-WRITE] SELECT completed\r\n
[2024-03-27 13:39:40.868][df40b378] IMAP[DEBUG]: 0.048326969146729 (TAG3)
[2024-03-27 13:39:40.868][df40b378] IMAP[INFO]: > TAG4 UID SORT (REVERSE DATE) UTF-8 UNDELETED\r\n
[2024-03-27 13:39:40.869][df40b378] IMAP[INFO]: < * SORT\r\n
[2024-03-27 13:39:40.911][df40b378] IMAP[INFO]: < TAG4 OK UID SORT completed\r\n
[2024-03-27 13:39:40.911][df40b378] IMAP[DEBUG]: 0.043040990829468 (TAG4)
[2024-03-27 13:39:40.912][df40b378] IMAP[INFO]: > TAG5 UID THREAD ORDEREDSUBJECT UTF-8 ALL\r\n
[2024-03-27 13:39:40.912][df40b378] IMAP[INFO]: < * THREAD (3)\r\n
[2024-03-27 13:39:40.912][df40b378] IMAP[INFO]: < TAG5 OK UID THREAD completed\r\n
[2024-03-27 13:39:40.912][df40b378] IMAP[DEBUG]: 0.00072717666625977 (TAG5)
[2024-03-27 13:39:40.912][df40b378] IMAP[INFO]: > TAG6 UID SEARCH UNSEEN UNDELETED\r\n
[2024-03-27 13:39:40.913][df40b378] IMAP[INFO]: < * SEARCH 3\r\n
[2024-03-27 13:39:40.955][df40b378] IMAP[INFO]: < TAG6 OK UID SEARCH completed\r\n
[2024-03-27 13:39:40.955][df40b378] IMAP[DEBUG]: 0.042876005172729 (TAG6)
[2024-03-27 13:39:40.955][df40b378] JSON[INFO]: {"Action":"MessageList","Result":{"@Object":"Collection\/MessageCollection","@Collection":[],"totalEmails":1,"totalThreads":0,"threadUid":0,"newMessages":[],"offset":0,"limit":20,"search":"","sort":"REVERSE DATE","limited":false,"folder":{"id":null,"name":"INBOX","uidNext":4,"uidValidity":680226926,...
[2024-03-27 13:39:40.955][df40b378] [INFO]: Memory peak usage: 12MB
[2024-03-27 13:39:40.955][df40b378] [INFO]: Time delta: 1.2063400745392
[2024-03-27 13:39:40.956][df40b378] IMAP[INFO]: > TAG7 LOGOUT\r\n
[2024-03-27 13:39:40.956][df40b378] IMAP[INFO]: < * BYE Closing connection\r\n
[2024-03-27 13:39:40.956][df40b378] IMAP[INFO]: < TAG7 OK LOGOUT completed\r\n
[2024-03-27 13:39:40.956][df40b378] IMAP[DEBUG]: 0.00042510032653809 (TAG7)
[2024-03-27 13:39:40.956][df40b378] IMAP[INFO]: Disconnected from "ssl://mail.example.org:993" (success)


[2024-03-27 13:39:40.956][df40b378] IMAP[DEBUG]: 1.0590250492096 (net session)
[2024-03-27 13:39:41.664][9df626a4] IMAP[INFO]: < TAG1 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT I18NLEVEL=1 SORT THREAD=ORDEREDSUBJECT COMPRESS NAMESPACE] AUTHENTICATE completed\r\n
[2024-03-27 13:39:41.664][9df626a4] IMAP[DEBUG]: 0.85858297348022 (TAG1)
[2024-03-27 13:39:41.665][9df626a4] IMAP[INFO]: > TAG2 STATUS "INBOX" (MESSAGES UNSEEN UIDNEXT UIDVALIDITY APPENDLIMIT)\r\n
[2024-03-27 13:39:41.666][9df626a4] IMAP[INFO]: < * STATUS INBOX (UIDNEXT 4 UIDVALIDITY 680226926 APPENDLIMIT 0 MESSAGES 1 UNSEEN 1)\r\n
[2024-03-27 13:39:41.707][9df626a4] IMAP[INFO]: < TAG2 OK STATUS completed\r\n
[2024-03-27 13:39:41.707][9df626a4] IMAP[DEBUG]: 0.042474031448364 (TAG2)
[2024-03-27 13:39:41.708][9df626a4] IMAP[INFO]: > TAG3 STATUS "Archive" (MESSAGES UNSEEN UIDNEXT UIDVALIDITY APPENDLIMIT)\r\n
[2024-03-27 13:39:41.708][9df626a4] IMAP[INFO]: < * STATUS "Archive" (MESSAGES 0 UNSEEN 0 UIDNEXT 1 UIDVALIDITY 2562237424 APPENDLIMIT 0)\r\n
[2024-03-27 13:39:41.751][9df626a4] IMAP[INFO]: < TAG3 OK STATUS completed\r\n
[2024-03-27 13:39:41.751][9df626a4] IMAP[DEBUG]: 0.043759107589722 (TAG3)
[2024-03-27 13:39:41.752][9df626a4] IMAP[INFO]: > TAG4 STATUS "Drafts" (MESSAGES UNSEEN UIDNEXT UIDVALIDITY APPENDLIMIT)\r\n
[2024-03-27 13:39:41.752][9df626a4] IMAP[INFO]: < * STATUS "Drafts" (MESSAGES 0 UNSEEN 0 UIDNEXT 1 UIDVALIDITY 1683403967 APPENDLIMIT 0)\r\n
[2024-03-27 13:39:41.795][9df626a4] IMAP[INFO]: < TAG4 OK STATUS completed\r\n
[2024-03-27 13:39:41.795][9df626a4] IMAP[DEBUG]: 0.043941974639893 (TAG4)
[2024-03-27 13:39:41.796][9df626a4] IMAP[INFO]: > TAG5 STATUS "Junk" (MESSAGES UNSEEN UIDNEXT UIDVALIDITY APPENDLIMIT)\r\n
[2024-03-27 13:39:41.796][9df626a4] IMAP[INFO]: < * STATUS "Junk" (UNSEEN 0 UIDNEXT 1 UIDVALIDITY 1204539417 APPENDLIMIT 0 MESSAGES 0)\r\n
[2024-03-27 13:39:41.839][9df626a4] IMAP[INFO]: < TAG5 OK STATUS completed\r\n
[2024-03-27 13:39:41.839][9df626a4] IMAP[DEBUG]: 0.043870210647583 (TAG5)
[2024-03-27 13:39:41.840][9df626a4] IMAP[INFO]: > TAG6 STATUS "Sent" (MESSAGES UNSEEN UIDNEXT UIDVALIDITY APPENDLIMIT)\r\n
[2024-03-27 13:39:41.841][9df626a4] IMAP[INFO]: < * STATUS "Sent" (UIDNEXT 5 UIDVALIDITY 220200829 APPENDLIMIT 0 MESSAGES 4 UNSEEN 0)\r\n
[2024-03-27 13:39:41.883][9df626a4] IMAP[INFO]: < TAG6 OK STATUS completed\r\n
[2024-03-27 13:39:41.883][9df626a4] IMAP[DEBUG]: 0.043843984603882 (TAG6)
[2024-03-27 13:39:41.884][9df626a4] IMAP[INFO]: > TAG7 STATUS "Trash" (MESSAGES UNSEEN UIDNEXT UIDVALIDITY APPENDLIMIT)\r\n
[2024-03-27 13:39:41.885][9df626a4] IMAP[INFO]: < * STATUS "Trash" (APPENDLIMIT 0 MESSAGES 0 UNSEEN 0 UIDNEXT 1 UIDVALIDITY 529866282)\r\n
[2024-03-27 13:39:41.927][9df626a4] IMAP[INFO]: < TAG7 OK STATUS completed\r\n
[2024-03-27 13:39:41.927][9df626a4] IMAP[DEBUG]: 0.043905019760132 (TAG7)
[2024-03-27 13:39:41.928][9df626a4] JSON[INFO]: {"Action":"FolderInformationMultiply","Result":[{"id":null,"name":"INBOX","uidNext":4,"uidValidity":680226926,"totalEmails":1,"unreadEmails":1,"appendLimit":0,"etag":"19ae2d3e3d1e82ba47c6067d0eee537c"},{"id":null,"name":"Archive","uidNext":1,"uidValidity":2562237424,"totalEmails":0,"unreadEmails":0,...
[2024-03-27 13:39:41.928][9df626a4] [INFO]: Memory peak usage: 10MB
[2024-03-27 13:39:41.928][9df626a4] [INFO]: Time delta: 1.200248003006
[2024-03-27 13:39:41.928][9df626a4] IMAP[INFO]: > TAG8 LOGOUT\r\n
[2024-03-27 13:39:41.929][9df626a4] IMAP[INFO]: < * BYE Closing connection\r\n
[2024-03-27 13:39:41.929][9df626a4] IMAP[INFO]: < TAG8 OK LOGOUT completed\r\n
[2024-03-27 13:39:41.929][9df626a4] IMAP[DEBUG]: 0.0010170936584473 (TAG8)
[2024-03-27 13:39:41.930][9df626a4] IMAP[INFO]: Disconnected from "ssl://mail.example.org:993" (success)



[2024-03-27 13:39:41.930][9df626a4] IMAP[DEBUG]: 1.1482028961182 (net session)
[2024-03-27 13:39:42.045][a534be81] [INFO]: [SM:2.36.0][IP:1.2.3.4][PID:72][server/version][fpm-fcgi][Streams:tcp,udp,unix,udg,ssl,tls,tlsv1.0,tlsv1.1,tlsv1.2,tlsv1.3][GET http://nextcloud.example.org/apps/snappymail/?/Json/&q[]=/0/MessageList/&q[]=/eyJmb2xkZXIiOiJJTkJPWCIsIm9mZnNldCI6MCwibGltaXQiOjIwLCJ1aWROZXh0Ijo0LCJzb3J0IjoiIiwic2VhcmNoIjoiIiwidXNlVGhyZWFkcyI6MSwidGhyZWFkQWxnb3JpdGhtIjoiIiwidGhyZWFkVWlkIjowLCJoYXNoIjoiMTlhZTJkM2UzZDFlODJiYTQ3YzYwNjdkMGVlZTUzN2MtM2E4YjdiMGE2NDAzZTY2OTZhN2I5NzNjMjczMjFmNzQwNjk4ZGVlNyJ9]
[2024-03-27 13:39:42.046][a534be81] Nextcloud[DEBUG]: integrated


[2024-03-27 13:39:42.049][a534be81] JSON[INFO]: Action: DoMessageList
[2024-03-27 13:39:42.050][a534be81] IMAP[INFO]: Start connection to "ssl://mail.example.org:993"
[2024-03-27 13:39:42.091][a534be81] IMAP[INFO]: Connect (success)
[2024-03-27 13:39:42.091][a534be81] IMAP[DEBUG]: 0.041411876678467 (raw connection)
[2024-03-27 13:39:42.092][a534be81] IMAP[INFO]: < * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT AUTH=PLAIN AUTH=LOGIN COMPRESS] IMAP4rev1 Service Ready\r\n
[2024-03-27 13:39:42.092][a534be81] IMAP[DEBUG]: 0.042243957519531 (*)
[2024-03-27 13:39:42.093][a534be81] IMAP[INFO]: > TAG1 AUTHENTICATE PLAIN *******\r\n
[2024-03-27 13:39:42.937][a534be81] IMAP[INFO]: < TAG1 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT I18NLEVEL=1 SORT THREAD=ORDEREDSUBJECT COMPRESS NAMESPACE] AUTHENTICATE completed\r\n
[2024-03-27 13:39:42.937][a534be81] IMAP[DEBUG]: 0.8445930480957 (TAG1)
[2024-03-27 13:39:42.938][a534be81] IMAP[INFO]: > TAG2 STATUS "INBOX" (MESSAGES UNSEEN UIDNEXT UIDVALIDITY APPENDLIMIT)\r\n
[2024-03-27 13:39:42.939][a534be81] IMAP[INFO]: < * STATUS INBOX (UIDNEXT 4 UIDVALIDITY 680226926 APPENDLIMIT 0 MESSAGES 1 UNSEEN 1)\r\n
[2024-03-27 13:39:42.979][a534be81] IMAP[INFO]: < TAG2 OK STATUS completed\r\n
[2024-03-27 13:39:42.979][a534be81] IMAP[DEBUG]: 0.041115045547485 (TAG2)
[2024-03-27 13:39:42.979][a534be81] IMAP[INFO]: > TAG3 SELECT "INBOX"\r\n
[2024-03-27 13:39:42.980][a534be81] IMAP[INFO]: < * FLAGS (\Seen \Answered \Flagged \Deleted \Draft)\r\n
[2024-03-27 13:39:43.023][a534be81] IMAP[INFO]: < * OK [PERMANENTFLAGS (\Seen \Answered \Flagged \Deleted \Draft \*)] Flags permitted.\r\n
[2024-03-27 13:39:43.023][a534be81] IMAP[INFO]: < * OK [UNSEEN 1] Message 1 is first unseen\r\n
[2024-03-27 13:39:43.024][a534be81] IMAP[INFO]: < * 1 EXISTS\r\n
[2024-03-27 13:39:43.024][a534be81] IMAP[INFO]: < * 0 RECENT\r\n
[2024-03-27 13:39:43.024][a534be81] IMAP[INFO]: < * OK [UIDNEXT 4] Predicted next UID\r\n
[2024-03-27 13:39:43.024][a534be81] IMAP[INFO]: < * OK [UIDVALIDITY 680226926] UIDs valid\r\n
[2024-03-27 13:39:43.024][a534be81] IMAP[INFO]: < TAG3 OK [READ-WRITE] SELECT completed\r\n
[2024-03-27 13:39:43.024][a534be81] IMAP[DEBUG]: 0.044770002365112 (TAG3)
[2024-03-27 13:39:43.025][a534be81] IMAP[INFO]: > TAG4 UID SORT (REVERSE DATE) UTF-8 UNDELETED\r\n
[2024-03-27 13:39:43.027][a534be81] IMAP[INFO]: < * SORT\r\n
[2024-03-27 13:39:43.067][a534be81] IMAP[INFO]: < TAG4 OK UID SORT completed\r\n
[2024-03-27 13:39:43.067][a534be81] IMAP[DEBUG]: 0.042256116867065 (TAG4)
[2024-03-27 13:39:43.068][a534be81] IMAP[INFO]: > TAG5 UID THREAD ORDEREDSUBJECT UTF-8 ALL\r\n
[2024-03-27 13:39:43.069][a534be81] IMAP[INFO]: < * THREAD (3)\r\n
[2024-03-27 13:39:43.111][a534be81] IMAP[INFO]: < TAG5 OK UID THREAD completed\r\n
[2024-03-27 13:39:43.111][a534be81] IMAP[DEBUG]: 0.043631076812744 (TAG5)
[2024-03-27 13:39:43.112][a534be81] IMAP[INFO]: > TAG6 UID SEARCH UNSEEN UNDELETED\r\n
[2024-03-27 13:39:43.114][a534be81] IMAP[INFO]: < * SEARCH 3\r\n
[2024-03-27 13:39:43.155][a534be81] IMAP[INFO]: < TAG6 OK UID SEARCH completed\r\n
[2024-03-27 13:39:43.155][a534be81] IMAP[DEBUG]: 0.04366397857666 (TAG6)
[2024-03-27 13:39:43.156][a534be81] JSON[INFO]: {"Action":"MessageList","Result":{"@Object":"Collection\/MessageCollection","@Collection":[],"totalEmails":1,"totalThreads":0,"threadUid":0,"newMessages":[],"offset":0,"limit":20,"search":"","sort":"REVERSE DATE","limited":false,"folder":{"id":null,"name":"INBOX","uidNext":4,"uidValidity":680226926,...
[2024-03-27 13:39:43.156][a534be81] [INFO]: Memory peak usage: 8MB
[2024-03-27 13:39:43.156][a534be81] [INFO]: Time delta: 1.1890280246735
[2024-03-27 13:39:43.156][a534be81] IMAP[INFO]: > TAG7 LOGOUT\r\n
[2024-03-27 13:39:43.157][a534be81] IMAP[INFO]: < * BYE Closing connection\r\n
[2024-03-27 13:39:43.157][a534be81] IMAP[INFO]: < TAG7 OK LOGOUT completed\r\n
[2024-03-27 13:39:43.157][a534be81] IMAP[DEBUG]: 0.0010051727294922 (TAG7)
[2024-03-27 13:39:43.158][a534be81] IMAP[INFO]: Disconnected from "ssl://mail.example.org:993" (success)


[2024-03-27 13:39:43.158][a534be81] IMAP[DEBUG]: 1.1076281070709 (net session)
[2024-03-27 13:40:14.836][7b31552e] [INFO]: [SM:2.36.0][IP:1.2.3.4][PID:83][server/version][fpm-fcgi][Streams:tcp,udp,unix,udg,ssl,tls,tlsv1.0,tlsv1.1,tlsv1.2,tlsv1.3][POST http://nextcloud.example.org/apps/snappymail/?/Json/&q[]=/0/]
[2024-03-27 13:40:14.837][7b31552e] Nextcloud[DEBUG]: integrated
[2024-03-27 13:40:14.842][7b31552e] JSON[INFO]: Action: DoAppDelayStart
[2024-03-27 13:40:14.842][7b31552e] POST[INFO]: {"Action":"AppDelayStart"}
[2024-03-27 13:40:14.842][7b31552e] COOKIE[DEBUG]: set smtoken
[2024-03-27 13:40:14.842][7b31552e] JSON[INFO]: {"Action":"AppDelayStart","Result":true,"epoch":1711546814}
[2024-03-27 13:40:14.842][7b31552e] [INFO]: Memory peak usage: 14MB
[2024-03-27 13:40:14.842][7b31552e] [INFO]: Time delta: 0.12250399589539

@the-djmaze the-djmaze added the external Issue is outside our code label Mar 28, 2024
@the-djmaze
Copy link
Owner

the-djmaze commented Mar 28, 2024

Hi Maurice Moss,

Maddy says:

Note: IMAP storage is "beta"

So they probably have an issue with the implementation of UIDVALDITY.
Please report there, maybe it can improve their beta?

@the-djmaze the-djmaze removed the bug Something isn't working label Mar 28, 2024
@stellarpower
Copy link
Author

Thanks, do you think this is related?
foxcpp/maddy#591

I've had no problems using Thunderbird as a client, is there any value in trying to get and compare the logs between the two?

Tnetennba

@stellarpower
Copy link
Author

Apologies if this is a little funky, my ISP is really screwing me over right now, so there may be some signs of a dropped connection in here.

This is what Thunderbird has to say, however. Does it look like there's a differnece between how they query messages and how SnappyMail does?:

2024-03-28 10:28:10.307508 UTC - [Parent 474262: Main Thread]: I/IMAP check for auth'd:imap://[email protected]:993/discoverallboxes
2024-03-28 10:28:10.307877 UTC - [Parent 474262: IMAP]: D/IMAP ImapThreadMainLoop entering [this=7f9fdc166b00]
2024-03-28 10:28:10.308035 UTC - [Parent 474262: Main Thread]: I/IMAP check for auth'd:imap://[email protected]:993/select>.INBOX
2024-03-28 10:28:10.308038 UTC - [Parent 474262: Main Thread]: I/IMAP no auth, cnt 1 & no TLS error - q URL:imap://[email protected]:993/select>.INBOX
2024-03-28 10:28:10.308039 UTC - [Parent 474262: Main Thread]: I/IMAP queuing url:imap://[email protected]:993/select>.INBOX
2024-03-28 10:28:10.457327 UTC - [Parent 474262: Main Thread]: I/IMAP 7f9fdc166b00:example.org:NA:SetupWithUrlCallback: clearing IMAP_CONNECTION_IS_OPEN
2024-03-28 10:28:10.457364 UTC - [Parent 474262: Main Thread]: I/IMAP 7f9fdc166b00:example.org:NA:SetupSinkProxy: got m_imapMailFolderSink
2024-03-28 10:28:10.457396 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:NA:ProcessCurrentURL: entering
2024-03-28 10:28:10.457404 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:NA:ProcessCurrentURL:imap://user%40example.org:993/discoverallboxes:  = currentUrl
2024-03-28 10:28:10.990022 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fd999f920 nb=141 needmore=0]
2024-03-28 10:28:10.990030 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:NA:CreateNewLineFromSocket: * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT AUTH=PLAIN AUTH=LOGIN COMPRESS] IMAP4rev1 Service Ready
2024-03-28 10:28:10.990031 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:10.990033 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:11.010131 UTC - [Parent 474262: IMAP]: D/IMAP Try to log in
2024-03-28 10:28:11.010138 UTC - [Parent 474262: IMAP]: D/IMAP IMAP auth: server caps 0x40085027, pref 0x1006, failed 0x0, avail caps 0x1006
2024-03-28 10:28:11.010140 UTC - [Parent 474262: IMAP]: D/IMAP (GSSAPI = 0x1000000, CRAM = 0x20000, NTLM = 0x100000, MSN = 0x200000, PLAIN = 0x1000, LOGIN = 0x2, old-style IMAP login = 0x4, auth external IMAP login = 0x20000000, OAUTH2 = 0x800000000)
2024-03-28 10:28:11.010141 UTC - [Parent 474262: IMAP]: D/IMAP Trying auth method 0x1000
2024-03-28 10:28:11.074181 UTC - [Parent 474262: IMAP]: D/IMAP got new password
2024-03-28 10:28:11.076261 UTC - [Parent 474262: IMAP]: D/IMAP IMAP: trying auth method 0x1000
2024-03-28 10:28:11.076268 UTC - [Parent 474262: IMAP]: D/IMAP PLAIN auth
2024-03-28 10:28:11.076275 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:NA:SendData: 33 authenticate PLAIN
2024-03-28 10:28:11.095481 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fd999f920 nb=3 needmore=0]
2024-03-28 10:28:11.095491 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:NA:CreateNewLineFromSocket: +
2024-03-28 10:28:11.095492 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:11.095522 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:NA:SendData: Logging suppressed for this command (it probably contained authentication information)
2024-03-28 10:28:11.957328 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fd999f920 nb=168 needmore=0]
2024-03-28 10:28:11.957336 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:NA:CreateNewLineFromSocket: 33 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT I18NLEVEL=1 SORT THREAD=ORDEREDSUBJECT COMPRESS NAMESPACE] AUTHENTICATE completed
2024-03-28 10:28:11.957337 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:11.957404 UTC - [Parent 474262: IMAP]: D/IMAP login succeeded
2024-03-28 10:28:11.957443 UTC - [Parent 474262: Main Thread]: I/IMAP considering playing queued url:imap://[email protected]:993/select>.INBOX
2024-03-28 10:28:11.957494 UTC - [Parent 474262: Main Thread]: I/IMAP creating protocol instance to play queued url:imap://[email protected]:993/select>.INBOX
2024-03-28 10:28:11.957498 UTC - [Parent 474262: Main Thread]: I/IMAP check for auth'd:imap://[email protected]:993/select>.INBOX
2024-03-28 10:28:11.957631 UTC - [Parent 474262: Main Thread]: I/IMAP playing queued url:imap://[email protected]:993/select>.INBOX
2024-03-28 10:28:11.957718 UTC - [Parent 474262: IMAP]: D/IMAP ImapThreadMainLoop entering [this=7f9fd6cf7d00]
2024-03-28 10:28:11.957765 UTC - [Parent 474262: Main Thread]: I/IMAP didn't need to run:imap://[email protected]:993/select>.INBOX
2024-03-28 10:28:11.957783 UTC - [Parent 474262: Main Thread]: I/IMAP 7f9fd6cf7d00:example.org:NA:SetupWithUrlCallback: clearing IMAP_CONNECTION_IS_OPEN
2024-03-28 10:28:11.957802 UTC - [Parent 474262: Main Thread]: I/IMAP 7f9fd6cf7d00:example.org:NA:SetupSinkProxy: got m_imapMailFolderSink
2024-03-28 10:28:11.957818 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf7d00:example.org:NA:ProcessCurrentURL: entering
2024-03-28 10:28:11.957825 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf7d00:example.org:NA:ProcessCurrentURL:imap://user%40example.org:993/select%3E.INBOX:  = currentUrl
2024-03-28 10:28:11.957850 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:SendData: 34 namespace
2024-03-28 10:28:11.976034 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fd999f920 nb=32 needmore=0]
2024-03-28 10:28:11.976043 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:CreateNewLineFromSocket: * NAMESPACE (("" ".")) NIL NIL
2024-03-28 10:28:11.976045 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:12.017080 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fd97d0ac0 nb=141 needmore=0]
2024-03-28 10:28:12.017089 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf7d00:example.org:NA:CreateNewLineFromSocket: * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT AUTH=LOGIN AUTH=PLAIN COMPRESS] IMAP4rev1 Service Ready
2024-03-28 10:28:12.017090 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:12.017093 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:12.017181 UTC - [Parent 474262: IMAP]: D/IMAP Try to log in
2024-03-28 10:28:12.017184 UTC - [Parent 474262: IMAP]: D/IMAP IMAP auth: server caps 0x40085027, pref 0x1006, failed 0x0, avail caps 0x1006
2024-03-28 10:28:12.017186 UTC - [Parent 474262: IMAP]: D/IMAP (GSSAPI = 0x1000000, CRAM = 0x20000, NTLM = 0x100000, MSN = 0x200000, PLAIN = 0x1000, LOGIN = 0x2, old-style IMAP login = 0x4, auth external IMAP login = 0x20000000, OAUTH2 = 0x800000000)
2024-03-28 10:28:12.017187 UTC - [Parent 474262: IMAP]: D/IMAP Trying auth method 0x1000
2024-03-28 10:28:12.017247 UTC - [Parent 474262: IMAP]: D/IMAP got new password
2024-03-28 10:28:12.017432 UTC - [Parent 474262: IMAP]: D/IMAP IMAP: trying auth method 0x1000
2024-03-28 10:28:12.017433 UTC - [Parent 474262: IMAP]: D/IMAP PLAIN auth
2024-03-28 10:28:12.017436 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf7d00:example.org:NA:SendData: 82 authenticate PLAIN
2024-03-28 10:28:12.037246 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fd999f920 nb=27 needmore=0]
2024-03-28 10:28:12.037253 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:CreateNewLineFromSocket: 34 OK NAMESPACE completed
2024-03-28 10:28:12.037255 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:12.037475 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:SendData: 35 list "" "*"
2024-03-28 10:28:12.057742 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fd999f920 nb=33 needmore=0]
2024-03-28 10:28:12.057751 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:CreateNewLineFromSocket: * LIST (\HasChildren) "." INBOX
2024-03-28 10:28:12.057794 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:12.057832 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fd999f920 nb=42 needmore=0]
2024-03-28 10:28:12.057833 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:CreateNewLineFromSocket: * LIST (\Sent \HasNoChildren) "." "Sent"
2024-03-28 10:28:12.057834 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:12.120548 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fd999f920 nb=44 needmore=0]
2024-03-28 10:28:12.120557 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:CreateNewLineFromSocket: * LIST (\Trash \HasNoChildren) "." "Trash"
2024-03-28 10:28:12.120558 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:12.120587 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fd999f920 nb=42 needmore=0]
2024-03-28 10:28:12.120588 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:CreateNewLineFromSocket: * LIST (\Junk \HasNoChildren) "." "Junk"
2024-03-28 10:28:12.120589 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:12.120594 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fd999f920 nb=46 needmore=0]
2024-03-28 10:28:12.120595 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:CreateNewLineFromSocket: * LIST (\Drafts \HasNoChildren) "." "Drafts"
2024-03-28 10:28:12.120597 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:12.120603 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fd999f920 nb=48 needmore=0]
2024-03-28 10:28:12.120604 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:CreateNewLineFromSocket: * LIST (\Archive \HasNoChildren) "." "Archive"
2024-03-28 10:28:12.120606 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:12.120611 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fd999f920 nb=45 needmore=0]
2024-03-28 10:28:12.120612 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:CreateNewLineFromSocket: * LIST (\HasNoChildren) "." "CanYouSeeThis"
2024-03-28 10:28:12.120613 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:12.120620 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fd999f920 nb=43 needmore=0]
2024-03-28 10:28:12.120621 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:CreateNewLineFromSocket: * LIST (\HasNoChildren) "." "INBOX.HELLO"
2024-03-28 10:28:12.120622 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:12.120625 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fd999f920 nb=22 needmore=0]
2024-03-28 10:28:12.120626 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:CreateNewLineFromSocket: 35 OK LIST completed
2024-03-28 10:28:12.120627 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:12.120635 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:SendData: 36 lsub "" "*"
2024-03-28 10:28:12.139784 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fd999f920 nb=33 needmore=0]
2024-03-28 10:28:12.139793 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:CreateNewLineFromSocket: * LSUB (\HasChildren) "." INBOX
2024-03-28 10:28:12.139795 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:12.200571 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fd999f920 nb=42 needmore=0]
2024-03-28 10:28:12.200580 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:CreateNewLineFromSocket: * LSUB (\Sent \HasNoChildren) "." "Sent"
2024-03-28 10:28:12.200582 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:12.200675 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fd999f920 nb=44 needmore=0]
2024-03-28 10:28:12.200731 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:CreateNewLineFromSocket: * LSUB (\Trash \HasNoChildren) "." "Trash"
2024-03-28 10:28:12.200732 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:12.200832 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fd999f920 nb=42 needmore=0]
2024-03-28 10:28:12.200833 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:CreateNewLineFromSocket: * LSUB (\Junk \HasNoChildren) "." "Junk"
2024-03-28 10:28:12.200834 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:12.200870 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fd999f920 nb=46 needmore=0]
2024-03-28 10:28:12.200871 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:CreateNewLineFromSocket: * LSUB (\Drafts \HasNoChildren) "." "Drafts"
2024-03-28 10:28:12.200872 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:12.200890 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fd999f920 nb=48 needmore=0]
2024-03-28 10:28:12.200891 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:CreateNewLineFromSocket: * LSUB (\Archive \HasNoChildren) "." "Archive"
2024-03-28 10:28:12.200892 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:12.200908 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fd999f920 nb=45 needmore=0]
2024-03-28 10:28:12.200909 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:CreateNewLineFromSocket: * LSUB (\HasNoChildren) "." "CanYouSeeThis"
2024-03-28 10:28:12.200910 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:12.200928 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fd999f920 nb=43 needmore=0]
2024-03-28 10:28:12.200929 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:CreateNewLineFromSocket: * LSUB (\HasNoChildren) "." "INBOX.HELLO"
2024-03-28 10:28:12.200930 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:12.200946 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fd999f920 nb=22 needmore=0]
2024-03-28 10:28:12.200947 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:CreateNewLineFromSocket: 36 OK LSUB completed
2024-03-28 10:28:12.200948 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:12.200959 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:SendData: 37 list "" "INBOX"
2024-03-28 10:28:12.220813 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fd999f920 nb=33 needmore=0]
2024-03-28 10:28:12.220825 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:CreateNewLineFromSocket: * LIST (\HasChildren) "." INBOX
2024-03-28 10:28:12.220827 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:12.220961 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fd999f920 nb=22 needmore=0]
2024-03-28 10:28:12.220963 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:CreateNewLineFromSocket: 37 OK LIST completed
2024-03-28 10:28:12.220964 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:12.257309 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fd97d0ac0 nb=3 needmore=0]
2024-03-28 10:28:12.257327 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf7d00:example.org:NA:CreateNewLineFromSocket: +
2024-03-28 10:28:12.257331 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:12.257353 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf7d00:example.org:NA:SendData: Logging suppressed for this command (it probably contained authentication information)
2024-03-28 10:28:13.110181 UTC - [Parent 474262: Main Thread]: D/IMAP proposed url = INBOX folder for connection  has To Wait = true can run = false
2024-03-28 10:28:13.110189 UTC - [Parent 474262: Main Thread]: I/IMAP queuing url:imap://[email protected]:993/select>.INBOX
2024-03-28 10:28:13.110243 UTC - [Parent 474262: Main Thread]: I/IMAP considering playing queued url:imap://[email protected]:993/select>.INBOX
2024-03-28 10:28:13.110247 UTC - [Parent 474262: Main Thread]: I/IMAP creating protocol instance to play queued url:imap://[email protected]:993/select>.INBOX
2024-03-28 10:28:13.110271 UTC - [Parent 474262: Main Thread]: D/IMAP proposed url = INBOX folder for connection  has To Wait = true can run = false
2024-03-28 10:28:13.110275 UTC - [Parent 474262: Main Thread]: I/IMAP failed creating protocol instance to play queued url:imap://[email protected]:993/select>.INBOX
2024-03-28 10:28:23.839912 UTC - [Parent 474262: Main Thread]: D/IMAPAutoSync Updating folder: imap://user%[email protected]/Drafts
2024-03-28 10:28:23.839932 UTC - [Parent 474262: Main Thread]: D/IMAPAutoSync *** Sync State set to status issued for  Folder: imap://user%[email protected]/Drafts ***
2024-03-28 10:28:23.840038 UTC - [Parent 474262: Main Thread]: D/IMAP proposed url = Drafts folder for connection  has To Wait = false can run = false
2024-03-28 10:28:23.840409 UTC - [Parent 474262: Main Thread]: I/IMAP 7f9fdc166b00:example.org:A:SetupSinkProxy: got m_imapMailFolderSink
2024-03-28 10:28:23.840445 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:ProcessCurrentURL: entering
2024-03-28 10:28:23.840452 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:ProcessCurrentURL:imap://user%40example.org:993/folderstatus%3E.Drafts:  = currentUrl
2024-03-28 10:28:23.840647 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:SendData: 38 STATUS "Drafts" (UIDNEXT MESSAGES UNSEEN RECENT)
2024-03-28 10:28:23.860113 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fd999f920 nb=60 needmore=0]
2024-03-28 10:28:23.860123 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:CreateNewLineFromSocket: * STATUS "Drafts" (UIDNEXT 4 MESSAGES 3 UNSEEN 3 RECENT 0)
2024-03-28 10:28:23.860126 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:23.878107 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fd999f920 nb=24 needmore=0]
2024-03-28 10:28:23.878122 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:CreateNewLineFromSocket: 38 OK STATUS completed
2024-03-28 10:28:23.878127 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:23.878561 UTC - [Parent 474262: Main Thread]: D/IMAPAutoSync folder imap://user%[email protected]/Drafts status changed serverNextUID = 4 lastNextUID = 4
2024-03-28 10:28:23.878564 UTC - [Parent 474262: Main Thread]: D/IMAPAutoSync serverTotal = 3 lastServerTotal = 3 serverRecent = 0 lastServerRecent = 0
2024-03-28 10:28:23.878567 UTC - [Parent 474262: Main Thread]: D/IMAPAutoSync *** Sync State set to update issued for  Folder: imap://user%[email protected]/Drafts ***
2024-03-28 10:28:23.878739 UTC - [Parent 474262: Main Thread]: D/IMAP proposed url = Drafts folder for connection  has To Wait = false can run = false
2024-03-28 10:28:23.879023 UTC - [Parent 474262: IMAP]: D/IMAP ImapThreadMainLoop entering [this=7f9fd2256f00]
2024-03-28 10:28:23.879277 UTC - [Parent 474262: Main Thread]: I/IMAP 7f9fd2256f00:example.org:NA:SetupWithUrlCallback: clearing IMAP_CONNECTION_IS_OPEN
2024-03-28 10:28:23.879308 UTC - [Parent 474262: Main Thread]: I/IMAP 7f9fd2256f00:example.org:NA:SetupSinkProxy: got m_imapMailFolderSink
2024-03-28 10:28:23.879339 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd2256f00:example.org:NA:ProcessCurrentURL: entering
2024-03-28 10:28:23.879344 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd2256f00:example.org:NA:ProcessCurrentURL:imap://user%40example.org:993/select%3E.Drafts:  = currentUrl
2024-03-28 10:28:23.879479 UTC - [Parent 474262: Main Thread]: I/IMAP considering playing queued url:imap://[email protected]:993/select>.INBOX
2024-03-28 10:28:23.879483 UTC - [Parent 474262: Main Thread]: I/IMAP creating protocol instance to play queued url:imap://[email protected]:993/select>.INBOX
2024-03-28 10:28:23.879519 UTC - [Parent 474262: Main Thread]: D/IMAP proposed url = INBOX folder for connection  has To Wait = false can run = false
2024-03-28 10:28:23.879535 UTC - [Parent 474262: Main Thread]: D/IMAP proposed url = INBOX folder for connection  has To Wait = true can run = false
2024-03-28 10:28:23.879538 UTC - [Parent 474262: Main Thread]: I/IMAP failed creating protocol instance to play queued url:imap://[email protected]:993/select>.INBOX
2024-03-28 10:28:23.938184 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fcba39560 nb=141 needmore=0]
2024-03-28 10:28:23.938193 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd2256f00:example.org:NA:CreateNewLineFromSocket: * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT AUTH=PLAIN AUTH=LOGIN COMPRESS] IMAP4rev1 Service Ready
2024-03-28 10:28:23.938194 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:23.938196 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:23.938233 UTC - [Parent 474262: IMAP]: D/IMAP Try to log in
2024-03-28 10:28:23.938235 UTC - [Parent 474262: IMAP]: D/IMAP IMAP auth: server caps 0x40085027, pref 0x1006, failed 0x0, avail caps 0x1006
2024-03-28 10:28:23.938237 UTC - [Parent 474262: IMAP]: D/IMAP (GSSAPI = 0x1000000, CRAM = 0x20000, NTLM = 0x100000, MSN = 0x200000, PLAIN = 0x1000, LOGIN = 0x2, old-style IMAP login = 0x4, auth external IMAP login = 0x20000000, OAUTH2 = 0x800000000)
2024-03-28 10:28:23.938238 UTC - [Parent 474262: IMAP]: D/IMAP Trying auth method 0x1000
2024-03-28 10:28:23.938279 UTC - [Parent 474262: IMAP]: D/IMAP got new password
2024-03-28 10:28:23.938300 UTC - [Parent 474262: IMAP]: D/IMAP IMAP: trying auth method 0x1000
2024-03-28 10:28:23.938306 UTC - [Parent 474262: IMAP]: D/IMAP PLAIN auth
2024-03-28 10:28:23.938309 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd2256f00:example.org:NA:SendData: 14 authenticate PLAIN
2024-03-28 10:28:23.957149 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fcba39560 nb=3 needmore=0]
2024-03-28 10:28:23.957154 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd2256f00:example.org:NA:CreateNewLineFromSocket: +
2024-03-28 10:28:23.957156 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:23.957163 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd2256f00:example.org:NA:SendData: Logging suppressed for this command (it probably contained authentication information)
2024-03-28 10:28:24.825430 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fcba39560 nb=168 needmore=0]
2024-03-28 10:28:24.825440 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd2256f00:example.org:NA:CreateNewLineFromSocket: 14 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT I18NLEVEL=1 SORT THREAD=ORDEREDSUBJECT COMPRESS NAMESPACE] AUTHENTICATE completed
2024-03-28 10:28:24.825443 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:24.825494 UTC - [Parent 474262: IMAP]: D/IMAP login succeeded
2024-03-28 10:28:24.825567 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd2256f00:example.org:A:SendData: 15 select "Drafts"
2024-03-28 10:28:24.844862 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fcba39560 nb=52 needmore=0]
2024-03-28 10:28:24.844871 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd2256f00:example.org:A:CreateNewLineFromSocket: * FLAGS (\Seen \Answered \Flagged \Deleted \Draft)
2024-03-28 10:28:24.844873 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:24.909339 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fcba39560 nb=86 needmore=0]
2024-03-28 10:28:24.909347 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd2256f00:example.org:A:CreateNewLineFromSocket: * OK [PERMANENTFLAGS (\Seen \Answered \Flagged \Deleted \Draft \*)] Flags permitted.
2024-03-28 10:28:24.909395 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:24.909402 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fcba39560 nb=43 needmore=0]
2024-03-28 10:28:24.909404 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd2256f00:example.org:A:CreateNewLineFromSocket: * OK [UNSEEN 1] Message 1 is first unseen
2024-03-28 10:28:24.909404 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:24.909408 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fcba39560 nb=12 needmore=0]
2024-03-28 10:28:24.909409 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd2256f00:example.org:A:CreateNewLineFromSocket: * 3 EXISTS
2024-03-28 10:28:24.909410 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:24.909412 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fcba39560 nb=12 needmore=0]
2024-03-28 10:28:24.909413 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd2256f00:example.org:A:CreateNewLineFromSocket: * 0 RECENT
2024-03-28 10:28:24.909414 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:24.909416 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fcba39560 nb=37 needmore=0]
2024-03-28 10:28:24.909417 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd2256f00:example.org:A:CreateNewLineFromSocket: * OK [UIDNEXT 4] Predicted next UID
2024-03-28 10:28:24.909418 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:24.909420 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fcba39560 nb=42 needmore=0]
2024-03-28 10:28:24.909421 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd2256f00:example.org:A:CreateNewLineFromSocket: * OK [UIDVALIDITY 1683403967] UIDs valid
2024-03-28 10:28:24.909421 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:24.909423 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fcba39560 nb=37 needmore=0]
2024-03-28 10:28:24.909424 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd2256f00:example.org:A:CreateNewLineFromSocket: 15 OK [READ-WRITE] SELECT completed
2024-03-28 10:28:24.909425 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:24.909429 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd2256f00:example.org:S-Drafts:SelectMailbox: got m_imapMailFolderSinkSelected
2024-03-28 10:28:24.909440 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd2256f00:example.org:S-Drafts:SendData: 16 UID fetch 1:* (FLAGS)
2024-03-28 10:28:24.927832 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fcba39560 nb=34 needmore=0]
2024-03-28 10:28:24.927841 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd2256f00:example.org:S-Drafts:CreateNewLineFromSocket: * 1 FETCH (FLAGS (\Draft) UID 1)
2024-03-28 10:28:24.927843 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:24.992858 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fcba39560 nb=34 needmore=0]
2024-03-28 10:28:24.992868 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd2256f00:example.org:S-Drafts:CreateNewLineFromSocket: * 2 FETCH (FLAGS (\Draft) UID 2)
2024-03-28 10:28:24.992870 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:24.992882 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fcba39560 nb=34 needmore=0]
2024-03-28 10:28:24.992884 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd2256f00:example.org:S-Drafts:CreateNewLineFromSocket: * 3 FETCH (FLAGS (\Draft) UID 3)
2024-03-28 10:28:24.992885 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:24.992889 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fcba39560 nb=27 needmore=0]
2024-03-28 10:28:24.992890 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd2256f00:example.org:S-Drafts:CreateNewLineFromSocket: 16 OK UID FETCH completed
2024-03-28 10:28:24.992932 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:25.006231 UTC - [Parent 474262: Main Thread]: I/IMAP considering playing queued url:imap://[email protected]:993/select>.INBOX
2024-03-28 10:28:25.006240 UTC - [Parent 474262: Main Thread]: I/IMAP creating protocol instance to play queued url:imap://[email protected]:993/select>.INBOX
2024-03-28 10:28:25.006441 UTC - [Parent 474262: Main Thread]: D/IMAP proposed url = INBOX folder for connection Drafts has To Wait = false can run = false
2024-03-28 10:28:25.006466 UTC - [Parent 474262: Main Thread]: D/IMAP proposed url = INBOX folder for connection  has To Wait = true can run = false
2024-03-28 10:28:25.006470 UTC - [Parent 474262: Main Thread]: I/IMAP failed creating protocol instance to play queued url:imap://[email protected]:993/select>.INBOX
2024-03-28 10:28:25.006485 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd2256f00:example.org:S-Drafts:ImapThreadMainLoop: idlePending set
2024-03-28 10:28:25.040960 UTC - [Parent 474262: Main Thread]: D/IMAPAutoSync Updating folder: imap://user%[email protected]/Archive
2024-03-28 10:28:25.040980 UTC - [Parent 474262: Main Thread]: D/IMAPAutoSync *** Sync State set to status issued for  Folder: imap://user%[email protected]/Archive ***
2024-03-28 10:28:25.041067 UTC - [Parent 474262: Main Thread]: D/IMAP proposed url = Archive folder for connection Drafts has To Wait = false can run = false
2024-03-28 10:28:25.041079 UTC - [Parent 474262: Main Thread]: D/IMAP proposed url = Archive folder for connection  has To Wait = false can run = false
2024-03-28 10:28:25.041371 UTC - [Parent 474262: Main Thread]: I/IMAP 7f9fdc166b00:example.org:A:SetupSinkProxy: got m_imapMailFolderSink
2024-03-28 10:28:25.041402 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:ProcessCurrentURL: entering
2024-03-28 10:28:25.041406 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:ProcessCurrentURL:imap://user%40example.org:993/folderstatus%3E.Archive:  = currentUrl
2024-03-28 10:28:25.041511 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:SendData: 39 STATUS "Archive" (UIDNEXT MESSAGES UNSEEN RECENT)
2024-03-28 10:28:25.060358 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fd999f920 nb=61 needmore=0]
2024-03-28 10:28:25.060369 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:CreateNewLineFromSocket: * STATUS "Archive" (MESSAGES 0 UNSEEN 0 RECENT 0 UIDNEXT 1)
2024-03-28 10:28:25.060371 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:25.078581 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fd999f920 nb=24 needmore=0]
2024-03-28 10:28:25.078589 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:CreateNewLineFromSocket: 39 OK STATUS completed
2024-03-28 10:28:25.078592 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:25.078810 UTC - [Parent 474262: Main Thread]: D/IMAPAutoSync *** Sync State set to idle for  Folder: imap://user%[email protected]/Archive ***
2024-03-28 10:28:25.078942 UTC - [Parent 474262: Main Thread]: I/IMAP considering playing queued url:imap://[email protected]:993/select>.INBOX
2024-03-28 10:28:25.078945 UTC - [Parent 474262: Main Thread]: I/IMAP creating protocol instance to play queued url:imap://[email protected]:993/select>.INBOX
2024-03-28 10:28:25.079003 UTC - [Parent 474262: Main Thread]: D/IMAP proposed url = INBOX folder for connection Drafts has To Wait = false can run = false
2024-03-28 10:28:25.079029 UTC - [Parent 474262: Main Thread]: D/IMAP proposed url = INBOX folder for connection  has To Wait = true can run = false
2024-03-28 10:28:25.079031 UTC - [Parent 474262: Main Thread]: I/IMAP failed creating protocol instance to play queued url:imap://[email protected]:993/select>.INBOX
2024-03-28 10:28:25.441594 UTC - [Parent 474262: Main Thread]: D/IMAPAutoSync Updating folder: imap://user%[email protected]/Sent
2024-03-28 10:28:25.441621 UTC - [Parent 474262: Main Thread]: D/IMAPAutoSync *** Sync State set to status issued for  Folder: imap://user%[email protected]/Sent ***
2024-03-28 10:28:25.441738 UTC - [Parent 474262: Main Thread]: D/IMAP proposed url = Sent folder for connection Drafts has To Wait = false can run = false
2024-03-28 10:28:25.441757 UTC - [Parent 474262: Main Thread]: D/IMAP proposed url = Sent folder for connection  has To Wait = false can run = false
2024-03-28 10:28:25.442254 UTC - [Parent 474262: Main Thread]: I/IMAP 7f9fdc166b00:example.org:A:SetupSinkProxy: got m_imapMailFolderSink
2024-03-28 10:28:25.442287 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:ProcessCurrentURL: entering
2024-03-28 10:28:25.442291 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:ProcessCurrentURL:imap://user%40example.org:993/folderstatus%3E.Sent:  = currentUrl
2024-03-28 10:28:25.442407 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:SendData: 40 STATUS "Sent" (UIDNEXT MESSAGES UNSEEN RECENT)
2024-03-28 10:28:25.461360 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fd999f920 nb=58 needmore=0]
2024-03-28 10:28:25.461368 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:CreateNewLineFromSocket: * STATUS "Sent" (UIDNEXT 6 MESSAGES 5 UNSEEN 0 RECENT 1)
2024-03-28 10:28:25.461369 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:25.461379 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fd999f920 nb=24 needmore=0]
2024-03-28 10:28:25.461381 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:CreateNewLineFromSocket: 40 OK STATUS completed
2024-03-28 10:28:25.461382 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:25.461554 UTC - [Parent 474262: Main Thread]: D/IMAPAutoSync folder imap://user%[email protected]/Sent status changed serverNextUID = 6 lastNextUID = 6
2024-03-28 10:28:25.461557 UTC - [Parent 474262: Main Thread]: D/IMAPAutoSync serverTotal = 5 lastServerTotal = 5 serverRecent = 1 lastServerRecent = 0
2024-03-28 10:28:25.461559 UTC - [Parent 474262: Main Thread]: D/IMAPAutoSync *** Sync State set to update issued for  Folder: imap://user%[email protected]/Sent ***
2024-03-28 10:28:25.461690 UTC - [Parent 474262: Main Thread]: D/IMAP proposed url = Sent folder for connection Drafts has To Wait = false can run = false
2024-03-28 10:28:25.461703 UTC - [Parent 474262: Main Thread]: D/IMAP proposed url = Sent folder for connection  has To Wait = false can run = false
2024-03-28 10:28:25.461916 UTC - [Parent 474262: IMAP]: D/IMAP ImapThreadMainLoop entering [this=7f9fd6cf6100]
2024-03-28 10:28:25.462127 UTC - [Parent 474262: Main Thread]: I/IMAP 7f9fd6cf6100:example.org:NA:SetupWithUrlCallback: clearing IMAP_CONNECTION_IS_OPEN
2024-03-28 10:28:25.462157 UTC - [Parent 474262: Main Thread]: I/IMAP 7f9fd6cf6100:example.org:NA:SetupSinkProxy: got m_imapMailFolderSink
2024-03-28 10:28:25.462168 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf6100:example.org:NA:ProcessCurrentURL: entering
2024-03-28 10:28:25.462170 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf6100:example.org:NA:ProcessCurrentURL:imap://user%40example.org:993/select%3E.Sent:  = currentUrl
2024-03-28 10:28:25.462229 UTC - [Parent 474262: Main Thread]: I/IMAP considering playing queued url:imap://[email protected]:993/select>.INBOX
2024-03-28 10:28:25.462231 UTC - [Parent 474262: Main Thread]: I/IMAP creating protocol instance to play queued url:imap://[email protected]:993/select>.INBOX
2024-03-28 10:28:25.462261 UTC - [Parent 474262: Main Thread]: D/IMAP proposed url = INBOX folder for connection  has To Wait = false can run = false
2024-03-28 10:28:25.462325 UTC - [Parent 474262: Main Thread]: D/IMAP proposed url = INBOX folder for connection Drafts has To Wait = false can run = false
2024-03-28 10:28:25.462335 UTC - [Parent 474262: Main Thread]: D/IMAP proposed url = INBOX folder for connection  has To Wait = true can run = false
2024-03-28 10:28:25.462337 UTC - [Parent 474262: Main Thread]: I/IMAP failed creating protocol instance to play queued url:imap://[email protected]:993/select>.INBOX
2024-03-28 10:28:26.545469 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fcba39240 nb=141 needmore=0]
2024-03-28 10:28:26.545481 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf6100:example.org:NA:CreateNewLineFromSocket: * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT AUTH=PLAIN AUTH=LOGIN COMPRESS] IMAP4rev1 Service Ready
2024-03-28 10:28:26.545484 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:26.545486 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:26.545597 UTC - [Parent 474262: IMAP]: D/IMAP Try to log in
2024-03-28 10:28:26.545601 UTC - [Parent 474262: IMAP]: D/IMAP IMAP auth: server caps 0x40085027, pref 0x1006, failed 0x0, avail caps 0x1006
2024-03-28 10:28:26.545605 UTC - [Parent 474262: IMAP]: D/IMAP (GSSAPI = 0x1000000, CRAM = 0x20000, NTLM = 0x100000, MSN = 0x200000, PLAIN = 0x1000, LOGIN = 0x2, old-style IMAP login = 0x4, auth external IMAP login = 0x20000000, OAUTH2 = 0x800000000)
2024-03-28 10:28:26.545607 UTC - [Parent 474262: IMAP]: D/IMAP Trying auth method 0x1000
2024-03-28 10:28:26.545677 UTC - [Parent 474262: IMAP]: D/IMAP got new password
2024-03-28 10:28:26.545705 UTC - [Parent 474262: IMAP]: D/IMAP IMAP: trying auth method 0x1000
2024-03-28 10:28:26.545715 UTC - [Parent 474262: IMAP]: D/IMAP PLAIN auth
2024-03-28 10:28:26.545720 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf6100:example.org:NA:SendData: 66 authenticate PLAIN
2024-03-28 10:28:27.006563 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd2256f00:example.org:S-Drafts:SendData: 17 IDLE
2024-03-28 10:28:27.024883 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fcba39560 nb=10 needmore=0]
2024-03-28 10:28:27.024894 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd2256f00:example.org:S-Drafts:CreateNewLineFromSocket: + idling
2024-03-28 10:28:27.024897 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:27.024907 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd2256f00:example.org:S-Drafts:HandleIdleResponses: idle mode async waiting
2024-03-28 10:28:28.373486 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fcba39240 nb=3 needmore=0]
2024-03-28 10:28:28.373495 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf6100:example.org:NA:CreateNewLineFromSocket: +
2024-03-28 10:28:28.373497 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:28.373511 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf6100:example.org:NA:SendData: Logging suppressed for this command (it probably contained authentication information)
2024-03-28 10:28:29.229981 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fcba39240 nb=168 needmore=0]
2024-03-28 10:28:29.229991 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf6100:example.org:NA:CreateNewLineFromSocket: 66 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT I18NLEVEL=1 SORT THREAD=ORDEREDSUBJECT COMPRESS NAMESPACE] AUTHENTICATE completed
2024-03-28 10:28:29.229993 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:29.230100 UTC - [Parent 474262: IMAP]: D/IMAP login succeeded
2024-03-28 10:28:29.230193 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf6100:example.org:A:SendData: 67 select "Sent"
2024-03-28 10:28:29.249334 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fcba39240 nb=52 needmore=0]
2024-03-28 10:28:29.249340 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf6100:example.org:A:CreateNewLineFromSocket: * FLAGS (\Seen \Answered \Flagged \Deleted \Draft)
2024-03-28 10:28:29.249386 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:29.309392 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fcba39240 nb=86 needmore=0]
2024-03-28 10:28:29.309401 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf6100:example.org:A:CreateNewLineFromSocket: * OK [PERMANENTFLAGS (\Seen \Answered \Flagged \Deleted \Draft \*)] Flags permitted.
2024-03-28 10:28:29.309402 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:29.309410 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fcba39240 nb=41 needmore=0]
2024-03-28 10:28:29.309411 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf6100:example.org:A:CreateNewLineFromSocket: * OK [UIDVALIDITY 220200829] UIDs valid
2024-03-28 10:28:29.309412 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:29.309417 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fcba39240 nb=12 needmore=0]
2024-03-28 10:28:29.309418 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf6100:example.org:A:CreateNewLineFromSocket: * 5 EXISTS
2024-03-28 10:28:29.309418 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:29.309421 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fcba39240 nb=12 needmore=0]
2024-03-28 10:28:29.309422 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf6100:example.org:A:CreateNewLineFromSocket: * 1 RECENT
2024-03-28 10:28:29.309423 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:29.309425 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fcba39240 nb=37 needmore=0]
2024-03-28 10:28:29.309426 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf6100:example.org:A:CreateNewLineFromSocket: * OK [UIDNEXT 6] Predicted next UID
2024-03-28 10:28:29.309426 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:29.309429 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fcba39240 nb=37 needmore=0]
2024-03-28 10:28:29.309430 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf6100:example.org:A:CreateNewLineFromSocket: 67 OK [READ-WRITE] SELECT completed
2024-03-28 10:28:29.309430 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:29.309436 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf6100:example.org:S-Sent:SelectMailbox: got m_imapMailFolderSinkSelected
2024-03-28 10:28:29.309447 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf6100:example.org:S-Sent:SendData: 68 UID fetch 1:* (FLAGS)
2024-03-28 10:28:29.328676 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fcba39240 nb=41 needmore=0]
2024-03-28 10:28:29.328687 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf6100:example.org:S-Sent:CreateNewLineFromSocket: * 1 FETCH (FLAGS (\Seen \Recent) UID 1)
2024-03-28 10:28:29.328690 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:29.392654 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fcba39240 nb=33 needmore=0]
2024-03-28 10:28:29.392664 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf6100:example.org:S-Sent:CreateNewLineFromSocket: * 2 FETCH (FLAGS (\Seen) UID 2)
2024-03-28 10:28:29.392667 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:29.392691 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fcba39240 nb=33 needmore=0]
2024-03-28 10:28:29.392693 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf6100:example.org:S-Sent:CreateNewLineFromSocket: * 3 FETCH (FLAGS (\Seen) UID 3)
2024-03-28 10:28:29.392695 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:29.392701 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fcba39240 nb=33 needmore=0]
2024-03-28 10:28:29.392703 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf6100:example.org:S-Sent:CreateNewLineFromSocket: * 4 FETCH (FLAGS (\Seen) UID 4)
2024-03-28 10:28:29.392753 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:29.392758 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fcba39240 nb=33 needmore=0]
2024-03-28 10:28:29.392760 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf6100:example.org:S-Sent:CreateNewLineFromSocket: * 5 FETCH (FLAGS (\Seen) UID 5)
2024-03-28 10:28:29.392761 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:29.392766 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fcba39240 nb=27 needmore=0]
2024-03-28 10:28:29.392768 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf6100:example.org:S-Sent:CreateNewLineFromSocket: 68 OK UID FETCH completed
2024-03-28 10:28:29.392769 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:29.404909 UTC - [Parent 474262: Main Thread]: I/IMAP considering playing queued url:imap://[email protected]:993/select>.INBOX
2024-03-28 10:28:29.404919 UTC - [Parent 474262: Main Thread]: I/IMAP creating protocol instance to play queued url:imap://[email protected]:993/select>.INBOX
2024-03-28 10:28:29.404974 UTC - [Parent 474262: Main Thread]: D/IMAP proposed url = INBOX folder for connection Sent has To Wait = false can run = false
2024-03-28 10:28:29.404999 UTC - [Parent 474262: Main Thread]: D/IMAP proposed url = INBOX folder for connection Drafts has To Wait = false can run = false
2024-03-28 10:28:29.405027 UTC - [Parent 474262: Main Thread]: D/IMAP proposed url = INBOX folder for connection  has To Wait = true can run = false
2024-03-28 10:28:29.405032 UTC - [Parent 474262: Main Thread]: I/IMAP failed creating protocol instance to play queued url:imap://[email protected]:993/select>.INBOX
2024-03-28 10:28:29.405049 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf6100:example.org:S-Sent:ImapThreadMainLoop: idlePending set
2024-03-28 10:28:29.443568 UTC - [Parent 474262: Main Thread]: D/IMAPAutoSync Updating folder: imap://user%[email protected]/INBOX
2024-03-28 10:28:29.443585 UTC - [Parent 474262: Main Thread]: D/IMAPAutoSync *** Sync State set to status issued for  Folder: imap://user%[email protected]/INBOX ***
2024-03-28 10:28:29.443686 UTC - [Parent 474262: Main Thread]: D/IMAP proposed url = INBOX folder for connection Sent has To Wait = false can run = false
2024-03-28 10:28:29.443701 UTC - [Parent 474262: Main Thread]: D/IMAP proposed url = INBOX folder for connection Drafts has To Wait = false can run = false
2024-03-28 10:28:29.443717 UTC - [Parent 474262: Main Thread]: D/IMAP proposed url = INBOX folder for connection  has To Wait = true can run = false
2024-03-28 10:28:29.443722 UTC - [Parent 474262: Main Thread]: I/IMAP queuing url:imap://[email protected]:993/folderstatus>.INBOX
2024-03-28 10:28:29.443726 UTC - [Parent 474262: Main Thread]: I/IMAP considering playing queued url:imap://[email protected]:993/select>.INBOX
2024-03-28 10:28:29.443729 UTC - [Parent 474262: Main Thread]: I/IMAP creating protocol instance to play queued url:imap://[email protected]:993/select>.INBOX
2024-03-28 10:28:29.443742 UTC - [Parent 474262: Main Thread]: D/IMAP proposed url = INBOX folder for connection Sent has To Wait = false can run = false
2024-03-28 10:28:29.443754 UTC - [Parent 474262: Main Thread]: D/IMAP proposed url = INBOX folder for connection Drafts has To Wait = false can run = false
2024-03-28 10:28:29.443767 UTC - [Parent 474262: Main Thread]: D/IMAP proposed url = INBOX folder for connection  has To Wait = true can run = false
2024-03-28 10:28:29.443770 UTC - [Parent 474262: Main Thread]: I/IMAP failed creating protocol instance to play queued url:imap://[email protected]:993/select>.INBOX
2024-03-28 10:28:31.405139 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf6100:example.org:S-Sent:SendData: 69 IDLE
2024-03-28 10:28:31.423671 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=7f9fcba39240 nb=10 needmore=0]
2024-03-28 10:28:31.423736 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf6100:example.org:S-Sent:CreateNewLineFromSocket: + idling
2024-03-28 10:28:31.423739 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x0)
2024-03-28 10:28:31.423750 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf6100:example.org:S-Sent:HandleIdleResponses: idle mode async waiting
2024-03-28 10:29:52.286752 UTC - [Parent 474262: IMAP]: D/IMAP ReadNextLine [rv=0x804b000e stream=7f9fd97d0ac0 nb=0 needmore=1]
2024-03-28 10:29:52.286772 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf7d00:example.org:NA:CreateNewLineFromSocket: clearing IMAP_CONNECTION_IS_OPEN - rv = 804b000e
2024-03-28 10:29:52.286987 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf7d00:example.org:NA:TellThreadToDie: close socket connection
2024-03-28 10:29:52.286991 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf7d00:example.org:NA:CreateNewLineFromSocket: (null)
2024-03-28 10:29:52.286993 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x804b000e)
2024-03-28 10:29:52.287009 UTC - [Parent 474262: IMAP]: D/IMAP authlogin failed
2024-03-28 10:29:52.287012 UTC - [Parent 474262: IMAP]: D/IMAP Marking auth method 0x1000 failed
2024-03-28 10:29:52.287015 UTC - [Parent 474262: IMAP]: D/IMAP IMAP auth: server caps 0x40085027, pref 0x1006, failed 0x1000, avail caps 0x6
2024-03-28 10:29:52.287019 UTC - [Parent 474262: IMAP]: D/IMAP (GSSAPI = 0x1000000, CRAM = 0x20000, NTLM = 0x100000, MSN = 0x200000, PLAIN = 0x1000, LOGIN = 0x2, old-style IMAP login = 0x4, auth external IMAP login = 0x20000000, OAUTH2 = 0x800000000)
2024-03-28 10:29:52.287021 UTC - [Parent 474262: IMAP]: D/IMAP Trying auth method 0x2
2024-03-28 10:29:52.287023 UTC - [Parent 474262: IMAP]: E/IMAP login failed entirely
2024-03-28 10:29:52.288186 UTC - [Parent 474262: IMAP]: D/IMAP SetConnectionStatus(0x80004005)
2024-03-28 10:29:52.288589 UTC - [Parent 474262: Main Thread]: I/IMAP creating protocol instance to retry queued url:imap://[email protected]:993/select>.INBOX
2024-03-28 10:29:52.288649 UTC - [Parent 474262: Main Thread]: D/IMAP proposed url = INBOX folder for connection Sent has To Wait = false can run = false
2024-03-28 10:29:52.288666 UTC - [Parent 474262: Main Thread]: D/IMAP proposed url = INBOX folder for connection Drafts has To Wait = false can run = false
2024-03-28 10:29:52.288782 UTC - [Parent 474262: Main Thread]: I/IMAP retrying  url:imap://[email protected]:993/select>.INBOX
2024-03-28 10:29:52.288852 UTC - [Parent 474262: IMAP]: D/IMAP ImapThreadMainLoop entering [this=7f9fcb9d6600]
2024-03-28 10:29:52.288953 UTC - [Parent 474262: IMAP]: D/IMAP ImapThreadMainLoop leaving [this=7f9fd6cf7d00]
2024-03-28 10:29:52.288994 UTC - [Parent 474262: Main Thread]: I/IMAP 7f9fcb9d6600:example.org:NA:SetupWithUrlCallback: clearing IMAP_CONNECTION_IS_OPEN
2024-03-28 10:29:52.289022 UTC - [Parent 474262: Main Thread]: I/IMAP 7f9fcb9d6600:example.org:NA:SetupSinkProxy: got m_imapMailFolderSink
2024-03-28 10:29:52.289037 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fcb9d6600:example.org:NA:ProcessCurrentURL: entering
2024-03-28 10:29:52.289041 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fcb9d6600:example.org:NA:ProcessCurrentURL:imap://user%40example.org:993/select%3E.INBOX:  = currentUrl
2024-03-28 10:30:04.878822 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf6100:example.org:S-Sent:SendData: DONE
2024-03-28 10:30:04.878822 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fdc166b00:example.org:A:SendData: 41 logout
2024-03-28 10:30:04.878840 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd2256f00:example.org:S-Drafts:SendData: DONE
2024-03-28 10:30:04.878854 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd6cf6100:example.org:S-Sent:SendData: 70 close
2024-03-28 10:30:04.878883 UTC - [Parent 474262: IMAP]: I/IMAP 7f9fd2256f00:example.org:S-Drafts:SendData: 18 close
2024-03-28 10:30:04.878884 UTC - [Parent 4742

@stellarpower
Copy link
Author

stellarpower commented Apr 5, 2024

Looks like it's a duplicate of this, given that snappymail is forked from Rainloop's base. I seem to be on the latest image though.

@the-djmaze
Copy link
Owner

the-djmaze commented Apr 5, 2024

If it is related to UNDELETED, then go to your user settings and change the setting to show deleted.
Screenshot_2024-04-05-19-01-19-919_org mozilla focus-edit

@the-djmaze
Copy link
Owner

the-djmaze commented Apr 6, 2024

Looking at your log a second time, i've noticed the problem.

> TAG3 SELECT "INBOX"\r\n
< * FLAGS (\Seen \Answered \Flagged \Deleted \Draft)\r\n
< * OK [PERMANENTFLAGS (\Seen \Answered \Flagged \Deleted \Draft \*)] Flags permitted.\r\n
< * OK [UNSEEN 1] Message 1 is first unseen\r\n
< * 0 RECENT\r\n
< * OK [UIDNEXT 4] Predicted next UID\r\n
< * OK [UIDVALIDITY 680226926] UIDs valid\r\n
< * 1 EXISTS\r\n
< TAG3 OK [READ-WRITE] SELECT completed\r\n
> TAG4 UID SORT (REVERSE DATE) UTF-8 UNDELETED\r\n
< * SORT\r\n
< TAG4 OK UID SORT completed\r\n

SORT should return the UID's but returns none.
So this is a bug in maddy.

@stellarpower
Copy link
Author

Sorry for the delay, I didn't get notified of this.

Thanks for looking into it! Appreciate the help.

@stellarpower
Copy link
Author

stellarpower commented May 11, 2024

So, after getting a go container, writing some scripts to debug the protocol, working it all out - seems the issue might be fixed in the master branch of maddy already 😅. But actually, I see I am getting the same from my live server. This is basically using telnet:

openssl s_client -crlf -connect example.com:993

read R BLOCK
* OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT AUTH=LOGIN AUTH=PLAIN COMPRESS] IMAP4rev1 Service Ready
LoginTag LOGIN [email protected] password
LoginTag OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR CHILDREN UNSELECT MOVE IDLE APPENDLIMIT I18NLEVEL=1 SORT THREAD=ORDEREDSUBJECT COMPRESS NAMESPACE] LOGIN completed
SelectTag SELECT INBOX
* FLAGS (\Seen \Answered \Flagged \Deleted \Draft)
* OK [PERMANENTFLAGS (\Seen \Answered \Flagged \Deleted \Draft \*)] Flags permitted.
* OK [UNSEEN 4] Message 4 is first unseen
* OK [UIDVALIDITY 3475350738] UIDs valid
* 35 EXISTS
* 2 RECENT
* OK [UIDNEXT 36] Predicted next UID
SelectTag OK [READ-WRITE] SELECT completed
SortTag UID SORT (REVERSE DATE) UTF-8 ALL
* SORT 5 4 3 2 23 6 21 19 33 29 25 27 7 31 8 35 9 20 10 22 11 24 12 26 13 28 14 30 15 32 16 34 17 1 18
SortTag OK UID SORT completed

Is this the sort of output you'd expect? As the UIDs weren't in the logs from snappymail above. I'll try and build the master and test again in the wild shortly.

Thank for your help!

@stellarpower
Copy link
Author

Okay, all seems to be working now. I don't really know what happened, but I noticed that I spent an hour or so being unable to login - and noticed that the logs showed SnappyMail querying the wrong domain - in essence we have our server set up on mailtesting.example.com, whilst mail.example.com continues with the old system. I kept getting server timeouts and authentication failures - and eventually realised in the logs that it was not querying the mailtesting subdomain or some reason - even though I logged in with my full email address in the login page.

Given that I haven't changed anything re the sorting, I wonder if it's in part the same issue, and I simply didn't scan it by eye when looking through, as the sort seems to return the correct output without my having updated the mailtesting container - only played around locally.

Honestly not sure what has gone wrong, but if it's resolved now I'm just gonna move on ;)

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
external Issue is outside our code
Projects
None yet
Development

No branches or pull requests

2 participants