3.38: Renamed GMail/Imap folder makes Geary quit trying to update the rest of folders
Bug Summary
If I rename a folder/label on gmail web, then Geary doesn't know what to do when fetching my mail and encountering that label/folder. It will report a "not found" when trying to fetch the old folder name, and quit trying to update the rest of the folders.
I can't reproduce on HEAD, but can in 3.38. Since 3.38 is going into Debian/Ubuntu next LTS, it might be worth figuring out what to backport, if only just as a reference to packagers.
Your installation
- Geary version: 3.38.1
- Installation method: Debian sid package
- Desktop environment: GNOME
- Operating system and version: Debian sid
- Email provider: Gmail
Steps to reproduce
- Rename a folder in gmail web UI
- Open fresh Geary
- See how mail stops updating when the (now missing) folder is fetched
- A warning is presented about a problem occurring, the email activity throbber continues to spin but nothing happens
Relevant logs and/or screenshots
Account identifier: goa_account_1573517291_0 Account provider: GEARY_SERVICE_PROVIDER_GMAIL Service type: GEARY_PROTOCOL_IMAP Service host: imap.gmail.com Error type: GearyEngineError 8 Message: Folder GearyImapEngineGmailFolder(>REDACTED, open_count=1, remote_opened=false) not available
Back trace:
* geary_problem_report_construct
* geary_account_problem_report_construct
* geary_service_problem_report_construct
* geary_imap_engine_refresh_folder_sync_sync_folder_finish
* g_task_return_now
* g_task_return.part.0
* geary_imap_engine_idle_garbage_collection_messages_detached
* g_task_return_now
* g_task_return.part.0
* geary_imap_engine_account_processor_set_logging_parent
* g_task_return_now
* g_task_return.part.0
* geary_imap_engine_minimal_folder_set_use
* g_task_return_now
* g_task_return.part.0
* geary_imap_engine_minimal_folder_close_internal
* g_task_return_now
* g_task_return.part.0
* geary_nonblocking_lock_reset
* geary_scheduler_scheduled_instance_get_type
* g_main_context_dispatch
* g_main_context_iterate.constprop.0
* g_main_context_iteration
* g_application_run
* _vala_main
* __libc_start_main
* _start
Here's a redacted version of the full log, that might be useful. I didn't want to have to check the whole file for redactions, I can share privately if you need it:
[deb] 21:18:26.0501 Geary: [goa_account_1573517291_0] GearyImapEngineGmailAccount: Acquiring folder session for: >INBOX
[deb] 21:18:26.0501 Geary: [goa_account_1573517291_0] [>REDACTED_FOLDER_NAME, open_count=1, remote_opened=false] GearyImapEngineGmailFolder: Opening remote session
[deb] 21:18:26.0501 Geary: [goa_account_1573517291_0] GearyImapEngineGmailAccount: Acquiring folder session for: >REDACTED_FOLDER_NAME
[deb] 21:18:26.0501 Geary.Imap: [goa_account_1573517291_0] [imap] GearyImapClientService: Claiming session with 1 of 1 free
[deb] 21:18:26.0501 Geary.Imap: [goa_account_1573517291_0] [imap] GearyImapClientService: Claiming session with 0 of 1 free
[deb] 21:18:26.0501 Geary.Imap: [goa_account_1573517291_0] [imap] GearyImapClientService: Checking session pool with 0 of 1 free
[deb] 21:18:26.0501 Geary.Imap: [goa_account_1573517291_0] [imap] GearyImapClientService: Opening new session
[deb] 21:18:26.0501 GLib-GIO:GSocketClient: Starting new address enumeration
[deb] 21:18:26.0501 GLib-GIO:GSocketClient: Address enumeration succeeded
[deb] 21:18:26.0501 GLib-GIO:GSocketClient: Starting TCP connection attempt
[deb] 21:18:26.0501 GLib-GIO:GSocketClient: Connection attempt failed: Network is unreachable
[deb] 21:18:26.0501 GLib-GIO:GSocketClient: Starting new address enumeration
[deb] 21:18:26.0501 GLib-GIO:GSocketClient: Address enumeration succeeded
[deb] 21:18:26.0501 GLib-GIO:GSocketClient: Starting TCP connection attempt
[deb] 21:18:26.0502 Geary.Imap.Net: [goa_account_1573517291_0] [imap] [authorized] [0000/imap.gmail.com:993/up] GearyImapClientConnection: SEND: a005 LIST "" INBOX return (special-use)
[deb] 21:18:26.0502 GLib-Net:CLIENT[0x563bb838e200]: starting to write 41 bytes to TLS connection
[deb] 21:18:26.0502 GLib-Net:CLIENT[0x563bb838e200]: claiming operation OP_WRITE
[deb] 21:18:26.0502 GLib-Net:CLIENT[0x563bb838e200]: claiming operation OP_WRITE succeeded
[deb] 21:18:26.0502 GLib-Net:CLIENT[0x563bb838e200]: yielding operation OP_WRITE
[deb] 21:18:26.0502 GLib-Net:CLIENT[0x563bb838e200]: successfully write 41 bytes to TLS connection
[deb] 21:18:26.0503 GLib-Net:CLIENT[0x563bb838e410]: starting to read data from TLS connection
[deb] 21:18:26.0503 GLib-Net:CLIENT[0x563bb838e410]: claiming operation OP_READ
[deb] 21:18:26.0503 GLib-Net:CLIENT[0x563bb838e410]: claiming operation OP_READ succeeded
[deb] 21:18:26.0503 GLib-Net:CLIENT[0x563bb838e410]: yielding operation OP_READ
[deb] 21:18:26.0503 GLib-Net:CLIENT[0x563bb838e410]: successfully read 49 bytes from TLS connection
[deb] 21:18:26.0503 Geary.Imap.Deser: [goa_account_1573517291_0] [imap] [authorized] [0001/imap.gmail.com:993/up] [GEARY_IMAP_DESERIALIZER_STATE_TAG] GearyImapDeserializer: Line: * NAMESPACE (("" "/")) NIL NIL
[deb] 21:18:26.0503 Geary.Imap.Net: [goa_account_1573517291_0] [imap] [authorized] [0001/imap.gmail.com:993/up] GearyImapClientConnection: RECV: * NAMESPACE (("" "/")) NIL NIL
[deb] 21:18:26.0503 Geary.Imap.Deser: [goa_account_1573517291_0] [imap] [authorized] [0001/imap.gmail.com:993/up] [GEARY_IMAP_DESERIALIZER_STATE_TAG] GearyImapDeserializer: Line: a004 OK Success
[deb] 21:18:26.0503 Geary.Imap.Net: [goa_account_1573517291_0] [imap] [authorized] [0001/imap.gmail.com:993/up] GearyImapClientConnection: RECV: a004 OK Success
[deb] 21:18:26.0503 GLib-Net:CLIENT[0x563bb838e410]: starting to read data from TLS connection
[deb] 21:18:26.0503 GLib-Net:CLIENT[0x563bb838e410]: claiming operation OP_READ
[deb] 21:18:26.0503 GLib-Net:CLIENT[0x563bb838e410]: claiming operation OP_READ succeeded
[deb] 21:18:26.0503 GLib-Net:CLIENT[0x563bb838e410]: yielding operation OP_READ
[deb] 21:18:26.0503 GLib-Net:CLIENT[0x563bb838e410]: reading data from TLS connection has failed: WOULD_BLOCK
[deb] 21:18:26.0504 Geary.Imap: [goa_account_1573517291_0] [imap] [authorized] GearyImapClientSession: Default personal namespace: (,/)
[deb] 21:18:26.0504 Geary.Imap.Net: [goa_account_1573517291_0] [imap] [authorized] [0001/imap.gmail.com:993/up] GearyImapClientConnection: SEND: a005 LIST "" REDACTED_FOLDER_NAME return (special-use)
[deb] 21:18:26.0504 GLib-Net:CLIENT[0x563bb838e410]: starting to write 45 bytes to TLS connection
[deb] 21:18:26.0504 GLib-Net:CLIENT[0x563bb838e410]: claiming operation OP_WRITE
[deb] 21:18:26.0504 GLib-Net:CLIENT[0x563bb838e410]: claiming operation OP_WRITE succeeded
[deb] 21:18:26.0504 GLib-Net:CLIENT[0x563bb838e410]: yielding operation OP_WRITE
[deb] 21:18:26.0504 GLib-Net:CLIENT[0x563bb838e410]: successfully write 45 bytes to TLS connection
[deb] 21:18:26.0544 GLib-GIO:GSocketClient: TCP connection successful
[deb] 21:18:26.0544 GLib-GIO:GSocketClient: Starting application layer connection
[deb] 21:18:26.0544 GLib-GIO:GSocketClient: Starting TLS handshake
[deb] 21:18:26.0544 GLib-Net:CLIENT[0x563bb838e620]: Starting asynchronous TLS handshake
[deb] 21:18:26.0545 GLib-Net:CLIENT[0x563bb838e620]: Asynchronous TLS handshake thread starts
[deb] 21:18:26.0545 GLib-Net:CLIENT[0x563bb838e620]: TLS handshake thread starts
[deb] 21:18:26.0545 GLib-Net:CLIENT[0x563bb838e620]: claiming operation OP_HANDSHAKE
[deb] 21:18:26.0545 GLib-Net:CLIENT[0x563bb838e620]: claiming operation OP_HANDSHAKE succeeded
[deb] 21:18:26.0590 GLib-Net:CLIENT[0x563bb838e620]: TLS handshake thread succeeded
[deb] 21:18:26.0590 GLib-Net:CLIENT[0x563bb838e620]: yielding operation OP_HANDSHAKE
[deb] 21:18:26.0591 GLib-Net:CLIENT[0x563bb838e620]: Asynchronous TLS handshake thread completed
[deb] 21:18:26.0591 GLib-Net:CLIENT[0x563bb838e620]: finishing TLS handshake
[deb] 21:18:26.0593 GLib-Net:CLIENT[0x563bb838e620]: TLS handshake has finished successfully
[deb] 21:18:26.0593 GLib-GIO:GSocketClient: TLS handshake succeeded
[deb] 21:18:26.0593 GLib-GIO:GSocketClient: Connection successful!
[deb] 21:18:26.0593 GLib-Net:CLIENT[0x563bb838e620]: starting to read data from TLS connection
[deb] 21:18:26.0593 GLib-Net:CLIENT[0x563bb838e620]: claiming operation OP_READ
[deb] 21:18:26.0593 GLib-Net:CLIENT[0x563bb838e620]: claiming operation OP_READ succeeded
[deb] 21:18:26.0593 GLib-Net:CLIENT[0x563bb838e620]: yielding operation OP_READ
[deb] 21:18:26.0593 GLib-Net:CLIENT[0x563bb838e620]: reading data from TLS connection has failed: WOULD_BLOCK
[deb] 21:18:26.0594 Geary.Imap: [goa_account_1573517291_0] [imap] [connecting] GearyImapClientSession: Connected to imap.gmail.com:993
[deb] 21:18:26.0687 GLib-Net:CLIENT[0x563bb838e200]: starting to read data from TLS connection
[deb] 21:18:26.0687 GLib-Net:CLIENT[0x563bb838e200]: claiming operation OP_READ
[deb] 21:18:26.0687 GLib-Net:CLIENT[0x563bb838e200]: claiming operation OP_READ succeeded
[deb] 21:18:26.0687 GLib-Net:CLIENT[0x563bb838e200]: yielding operation OP_READ
[deb] 21:18:26.0687 GLib-Net:CLIENT[0x563bb838e200]: successfully read 54 bytes from TLS connection
[deb] 21:18:26.0688 Geary.Imap.Deser: [goa_account_1573517291_0] [imap] [authorized] [0000/imap.gmail.com:993/up] [GEARY_IMAP_DESERIALIZER_STATE_TAG] GearyImapDeserializer: Line: * LIST (\HasNoChildren) "/" "INBOX"
[deb] 21:18:26.0688 Geary.Imap.Net: [goa_account_1573517291_0] [imap] [authorized] [0000/imap.gmail.com:993/up] GearyImapClientConnection: RECV: * LIST (\HasNoChildren) "/" "INBOX"
[deb] 21:18:26.0688 Geary.Imap.Deser: [goa_account_1573517291_0] [imap] [authorized] [0000/imap.gmail.com:993/up] [GEARY_IMAP_DESERIALIZER_STATE_TAG] GearyImapDeserializer: Line: a005 OK Success
[deb] 21:18:26.0688 Geary.Imap.Net: [goa_account_1573517291_0] [imap] [authorized] [0000/imap.gmail.com:993/up] GearyImapClientConnection: RECV: a005 OK Success
[deb] 21:18:26.0688 GLib-Net:CLIENT[0x563bb838e200]: starting to read data from TLS connection
[deb] 21:18:26.0688 GLib-Net:CLIENT[0x563bb838e200]: claiming operation OP_READ
[deb] 21:18:26.0688 GLib-Net:CLIENT[0x563bb838e200]: claiming operation OP_READ succeeded
[deb] 21:18:26.0688 GLib-Net:CLIENT[0x563bb838e200]: yielding operation OP_READ
[deb] 21:18:26.0688 GLib-Net:CLIENT[0x563bb838e200]: reading data from TLS connection has failed: WOULD_BLOCK
[deb] 21:18:26.0688 Geary.Imap.Net: [goa_account_1573517291_0] [imap] [authorized] [0000/imap.gmail.com:993/up] GearyImapClientConnection: SEND: a006 STATUS INBOX (MESSAGES RECENT UIDNEXT UIDVALIDITY UNSEEN)
[deb] 21:18:26.0688 GLib-Net:CLIENT[0x563bb838e200]: starting to write 64 bytes to TLS connection
[deb] 21:18:26.0688 GLib-Net:CLIENT[0x563bb838e200]: claiming operation OP_WRITE
[deb] 21:18:26.0688 GLib-Net:CLIENT[0x563bb838e200]: claiming operation OP_WRITE succeeded
[deb] 21:18:26.0688 GLib-Net:CLIENT[0x563bb838e200]: yielding operation OP_WRITE
[deb] 21:18:26.0688 GLib-Net:CLIENT[0x563bb838e200]: successfully write 64 bytes to TLS connection
[deb] 21:18:26.0691 GLib-Net:CLIENT[0x563bb838e410]: starting to read data from TLS connection
[deb] 21:18:26.0691 GLib-Net:CLIENT[0x563bb838e410]: claiming operation OP_READ
[deb] 21:18:26.0691 GLib-Net:CLIENT[0x563bb838e410]: claiming operation OP_READ succeeded
[deb] 21:18:26.0691 GLib-Net:CLIENT[0x563bb838e410]: yielding operation OP_READ
[deb] 21:18:26.0691 GLib-Net:CLIENT[0x563bb838e410]: successfully read 17 bytes from TLS connection
[deb] 21:18:26.0691 Geary.Imap.Deser: [goa_account_1573517291_0] [imap] [authorized] [0001/imap.gmail.com:993/up] [GEARY_IMAP_DESERIALIZER_STATE_TAG] GearyImapDeserializer: Line: a005 OK Success
[deb] 21:18:26.0692 Geary.Imap.Net: [goa_account_1573517291_0] [imap] [authorized] [0001/imap.gmail.com:993/up] GearyImapClientConnection: RECV: a005 OK Success
[deb] 21:18:26.0692 GLib-Net:CLIENT[0x563bb838e410]: starting to read data from TLS connection
[deb] 21:18:26.0692 GLib-Net:CLIENT[0x563bb838e410]: claiming operation OP_READ
[deb] 21:18:26.0692 GLib-Net:CLIENT[0x563bb838e410]: claiming operation OP_READ succeeded
[deb] 21:18:26.0692 GLib-Net:CLIENT[0x563bb838e410]: yielding operation OP_READ
[deb] 21:18:26.0692 GLib-Net:CLIENT[0x563bb838e410]: reading data from TLS connection has failed: WOULD_BLOCK
[deb] 21:18:26.0693 Geary.Imap: [goa_account_1573517291_0] [imap] GearyImapClientService: Returning session with 0 of 2 free
[deb] 21:18:26.0693 Geary.Imap: [goa_account_1573517291_0] [imap] GearyImapClientService: Unreserving session GearyImapClientSession(authorized, GearyImapClientConnection(0001/imap.gmail.com:993/up))
[deb] 21:18:26.0693 Geary: [goa_account_1573517291_0] [>REDACTED_FOLDER_NAME, open_count=1, remote_opened=false] GearyImapEngineGmailFolder: Remote folder not found, forcing closed
[deb] 21:18:26.0693 Geary: [goa_account_1573517291_0] [>REDACTED_FOLDER_NAME, open_count=1, remote_opened=false] GearyImapEngineGmailFolder: Folder closing
[deb] 21:18:26.0693 Geary: [goa_account_1573517291_0] [>REDACTED_FOLDER_NAME, open_count=1, remote_opened=false] GearyImapEngineGmailFolder: Closing replay queue for (flush_pending=false): GearyImapEngineReplayQueue(notification=0 local=0 local_active=false remote=0 remote_active=true)
[deb] 21:18:26.0693 Geary.Imap.Replay: [goa_account_1573517291_0] [>REDACTED_FOLDER_NAME, open_count=1, remote_opened=false] [notification=0 local=0 local_active=false remote=0 remote_active=true] GearyImapEngineReplayQueue: Closing
[deb] 21:18:26.0693 Geary.Imap.Replay: [goa_account_1573517291_0] [>REDACTED_FOLDER_NAME, open_count=1, remote_opened=false] [notification=0 local=1 local_active=false remote=0 remote_active=true] GearyImapEngineReplayQueue: Scheduled: [1] CloseReplayQueue: local_closed: false, remote_closed: false remote_retry_count=0
[deb] 21:18:26.0693 Geary.Imap.Replay: [goa_account_1573517291_0] [>REDACTED_FOLDER_NAME, open_count=1, remote_opened=false] [notification=0 local=0 local_active=true remote=0 remote_active=true] GearyImapEngineReplayQueue: Locally-executing: [1] CloseReplayQueue: local_closed: false, remote_closed: false remote_retry_count=0
[deb] 21:18:26.0693 Geary.Imap.Replay: [goa_account_1573517291_0] [>REDACTED_FOLDER_NAME, open_count=1, remote_opened=false] [notification=0 local=0 local_active=true remote=1 remote_active=true] GearyImapEngineReplayQueue: Locally-executed: [1] CloseReplayQueue: local_closed: true, remote_closed: false remote_retry_count=0 continuing=true
[deb] 21:18:26.0693 Geary.Imap.Replay: [goa_account_1573517291_0] [>REDACTED_FOLDER_NAME, open_count=1, remote_opened=false] [notification=0 local=0 local_active=false remote=1 remote_active=true] GearyImapEngineReplayQueue: ReplayQueue.do_replay_local_async GearyImapEngineReplayQueue(notification=0 local=0 local_active=false remote=1 remote_active=true) exiting
[deb] 21:18:26.0693 Geary.Imap.Replay: [goa_account_1573517291_0] [>REDACTED_FOLDER_NAME, open_count=1, remote_opened=false] [notification=0 local=0 local_active=false remote=1 remote_active=true] GearyImapEngineReplayQueue: Folder GearyImapEngineReplayQueue(notification=0 local=0 local_active=false remote=1 remote_active=true) closed or failed to open, remote replay queue closing: Operation was cancelled
[deb] 21:18:26.0693 Geary.Imap.Replay: [goa_account_1573517291_0] [>REDACTED_FOLDER_NAME, open_count=1, remote_opened=false] [notification=0 local=0 local_active=false remote=1 remote_active=true] GearyImapEngineReplayQueue: Remotely-executing: [0] ServerSearchEmail: criteria=(since 20-Jan-2021 uid 1:120) remote_retry_count=0
[deb] 21:18:26.0693 Geary.Imap.Replay: [goa_account_1573517291_0] [>REDACTED_FOLDER_NAME, open_count=1, remote_opened=false] [notification=0 local=0 local_active=false remote=1 remote_active=true] GearyImapEngineReplayQueue: Backout-out: [0] ServerSearchEmail: criteria=(since 20-Jan-2021 uid 1:120) remote_retry_count=0 err=Folder GearyImapEngineGmailFolder(>REDACTED_FOLDER_NAME, open_count=1, remote_opened=false) not available
[deb] 21:18:26.0693 Geary.Imap.Replay: [goa_account_1573517291_0] [>REDACTED_FOLDER_NAME, open_count=1, remote_opened=false] [notification=0 local=0 local_active=false remote=1 remote_active=true] GearyImapEngineReplayQueue: Backed-out: [0] ServerSearchEmail: criteria=(since 20-Jan-2021 uid 1:120) remote_retry_count=0 err=Folder GearyImapEngineGmailFolder(>REDACTED_FOLDER_NAME, open_count=1, remote_opened=false) not available
[deb] 21:18:26.0694 Geary.Imap.Replay: [goa_account_1573517291_0] [>REDACTED_FOLDER_NAME, open_count=1, remote_opened=false] [notification=0 local=0 local_active=false remote=1 remote_active=true] GearyImapEngineReplayQueue: Remotely-executed: [0] ServerSearchEmail: criteria=(since 20-Jan-2021 uid 1:120) remote_retry_count=0
[deb] 21:18:26.0694 Geary.Imap.Replay: [goa_account_1573517291_0] [>REDACTED_FOLDER_NAME, open_count=1, remote_opened=false] [notification=0 local=0 local_active=false remote=1 remote_active=true] GearyImapEngineReplayQueue: Failed: [0] ServerSearchEmail: criteria=(since 20-Jan-2021 uid 1:120) remote_retry_count=0
[deb] 21:18:26.0694 Geary.Imap.Replay: [goa_account_1573517291_0] [>REDACTED_FOLDER_NAME, open_count=1, remote_opened=false] [notification=0 local=0 local_active=false remote=0 remote_active=true] GearyImapEngineReplayQueue: Remotely-executing: [1] CloseReplayQueue: local_closed: true, remote_closed: false remote_retry_count=0
[deb] 21:18:26.0694 Geary.Imap.Replay: [goa_account_1573517291_0] [>REDACTED_FOLDER_NAME, open_count=1, remote_opened=false] [notification=0 local=0 local_active=false remote=0 remote_active=true] GearyImapEngineReplayQueue: Remotely-executed: [1] CloseReplayQueue: local_closed: true, remote_closed: false remote_retry_count=0
Note that the log stops there, even though the activity indicator keeps spinning.