GNOME Bugzilla – Bug 766509
Crash when INBOX has NIL delimiter
Last modified: 2016-07-18 12:17:18 UTC
Created attachment 327965 [details] Complete core dump Setting an @tiscali.it email account and Geary crash with a core dump error. Geary 0.11 on Fedora 23. geary --debug ![crt] 12:43:31 0,000005 geary_imap_mailbox_specifier_construct_from_folder_path: assertion 'delim != NULL' failed ![crt] 12:43:31 0,000010 geary_imap_create_command_construct: assertion 'GEARY_IMAP_IS_MAILBOX_SPECIFIER (mailbox)' failed ![crt] 12:43:31 0,000082 geary_imap_client_session_check_unsupported_send_command: assertion 'GEARY_IMAP_IS_COMMAND (cmd)' failed ![crt] 12:43:31 0,000027 geary_imap_client_session_send_command_operation_construct: assertion 'GEARY_IMAP_IS_COMMAND (cmd)' failed ![crt] 12:43:31 0,000002 geary_nonblocking_batch_add: assertion 'GEARY_NONBLOCKING_IS_BATCH_OPERATION (op)' failed ** ERROR:/builddir/build/BUILD/geary-0.11.0/src/engine/imap/api/imap-account.vala:529:geary_imap_account_send_command_async_co: assertion failed: (responses.size == 1) Annullato (core dump creato)
Hi Janvitus, thanks for reporting this issue. The mail server seems to be returning an unexpected response to one of the IMAP commands sent to it. Can you please run geary using the following command: > geary -d --log-network | tee geary.out This will create a file called geary.out - open it in a text editor to search and replace any personal information in the file then attach it to this bug. Thanks!
Created attachment 328050 [details] geary.out
This happen also with @poste.it.
Hey, thanks for the logs. When Geary first establishes a connection it lists the INBOX to determine what delimiter the server is using, per Bug 757166. This is a problem since Dovecot in this instance is returning NIL for the delimiter for the INBOX, and hence causing the crash down a little bit further the track. I'll get a patch together hopefully in the next day or two that should fix this, if you are able to test it out and see if it fixes the issue. Apologies for taking a long time to look at this, I've ben pretty busy.
Ok, thanks :)
Created attachment 330807 [details] [review] Don't crash when LIST on the INBOX returns a NIL delim This patch was written against master, but should also apply to the geary-0.11 branch. Please try applying the patch and recompile and let me know if it fixes the problem. Thanks!
I have packaged Geary 0.11.1 with your patch for Ubuntu 16.04. https://launchpad.net/~janvitus/+archive/ubuntu/nsfw/+sourcepub/6666685/+listing-archive-extra Now, Geary don't crash at startup but not retrieve mail messages from Inbox. the folder remain empty. geary --debug [msg] 14:46:10 1,126037 geary-application.vala:192: Geary 0.11.1 prefix=/usr exec_dir=/usr/bin is_installed=true [deb] 14:46:14 4,136846 libmessagingmenu.vala:30: Registered messaging-menu indicator [deb] 14:46:14 0,030229 unity-launcher.vala:44: set unity launcher entry count to 0 [deb] 14:46:15 0,392593 imap-client-session-manager.vala:525: Checking if IMAP host imap.tiscali.it/default:993 reachable... [deb] 14:46:16 1,304423 conversation-web-view.vala:207: Loading new message viewer style from /home/xxxxxx/.config/geary/user-message.css... [deb] 14:46:16 0,135596 db-versioned-database.vala:77: VersionedDatabase.upgrade: current database schema for /home/xxxxxx/.local/share/geary/xxx.xxx@tiscali.it/geary.db: 24 [msg] 14:46:17 0,301531 imap-client-session-manager.vala:536: IMAP host imap.tiscali.it/default:993 considered reachable [deb] 14:46:17 0,073346 imap-db-gc.vala:105: [GC:/home/xxxxxx/.local/share/geary/xxx.xxx@tiscali.it/geary.db] GC state: last_reap_time=never last_vacuum_time=never reaped_messages_since=0 free_page_bytes=1888256 [deb] 14:46:17 0,000988 imap-db-gc.vala:119: [GC:/home/xxxxxx/.local/share/geary/xxx.xxx@tiscali.it/geary.db] Recommending reaping: never completed [deb] 14:46:17 0,000004 imap-db-gc.vala:138: [GC:/home/xxxxxx/.local/share/geary/xxx.xxx@tiscali.it/geary.db] Database never vacuumed (0 messages reaped) [deb] 14:46:17 0,000031 imap-db-gc.vala:254: [GC:/home/xxxxxx/.local/share/geary/xxx.xxx@tiscali.it/geary.db] Starting garbage collection of IMAP database [deb] 14:46:17 0,000061 imap-db-gc.vala:283: [GC:/home/xxxxxx/.local/share/geary/xxx.xxx@tiscali.it/geary.db] Garbage collector reaping date: 2016-06-04T14:46:17+0200 (1465044377) [msg] 14:46:17 0,078638 imap-db-gc.vala:312: [GC:/home/xxxxxx/.local/share/geary/xxx.xxx@tiscali.it/geary.db] Found 0 email messages ready for reaping [msg] 14:46:17 0,000100 imap-db-gc.vala:343: [GC:/home/xxxxxx/.local/share/geary/xxx.xxx@tiscali.it/geary.db] Reaped completed: 0 messages [deb] 14:46:17 0,041835 smtp-outbox-folder.vala:139: Starting outbox postman [msg] 14:46:18 0,732854 imap-db-gc.vala:366: [GC:/home/xxxxxx/.local/share/geary/xxx.xxx@tiscali.it/geary.db] Completed: Reaped 0 attachment files [deb] 14:46:18 0,048453 geary-controller.vala:1318: Folder Other:xxx.xxx@tiscali.it:INBOX (open_count=0 remote_opened=false) selected [deb] 14:46:18 0,000008 geary-controller.vala:1366: Switching to Other:xxx.xxx@tiscali.it:INBOX (open_count=0 remote_opened=false)... [deb] 14:46:18 0,093710 imap-engine-minimal-folder.vala:543: Not opening Other:xxx.xxx@tiscali.it:INBOX (open_count=2 remote_opened=true): already open [deb] 14:46:18 0,001339 imap-engine-minimal-folder.vala:641: Fetching STATUS for remote Other:xxx.xxx@tiscali.it:INBOX (open_count=2 remote_opened=true) from local [deb] 14:46:18 0,000073 geary-controller.vala:1451: Switched to Other:xxx.xxx@tiscali.it:INBOX (open_count=2 remote_opened=true) [deb] 14:46:18 0,003294 app-conversation-monitor.vala:309: ConversationMonitor seeding with local email for Other:xxx.xxx@tiscali.it:INBOX (open_count=2 remote_opened=true) [deb] 14:46:18 0,785491 imap-client-session.vala:1469: [0000/imap.tiscali.it/default:993 GEARY_IMAP_CLIENT_SESSION_STATE_CONNECTING] Connected to imap.tiscali.it/default:993 [deb] 14:46:18 0,000010 imap-client-session.vala:624: [0000/imap.tiscali.it/default:993 GEARY_IMAP_CLIENT_SESSION_STATE_CONNECTING] Connected [deb] 14:46:18 0,005641 imap-client-session.vala:1505: [0000/imap.tiscali.it/default:993 GEARY_IMAP_CLIENT_SESSION_STATE_CONNECTING] ok #1: NAMESPACE IMAP4rev1 "AUTH=PLAIN" "AUTH=DIGEST-MD5" "AUTH=CRAM-MD5" SORT UIDPLUS CHILDREN QUOTA [deb] 14:46:18 0,000381 imap-engine-minimal-folder.vala:645: Fetching information for remote folder Other:xxx.xxx@tiscali.it:INBOX (open_count=2 remote_opened=true) [deb] 14:46:18 0,000008 imap-account.vala:283: Listing children to find INBOX [deb] 14:46:18 0,000086 imap-client-session.vala:718: [0000/imap.tiscali.it/default:993 GEARY_IMAP_CLIENT_SESSION_STATE_NOAUTH] use_starttls=false is_ssl=true starttls=false [deb] 14:46:18 0,000006 imap-client-session.vala:746: [0000/imap.tiscali.it/default:993 GEARY_IMAP_CLIENT_SESSION_STATE_NOAUTH] No STARTTLS attempted [deb] 14:46:18 0,000633 app-conversation-monitor.vala:315: ConversationMonitor seeded for Other:xxx.xxx@tiscali.it:INBOX (open_count=2 remote_opened=true) [deb] 14:46:19 0,003826 app-conversation-monitor.vala:706: ConversationMonitor (already opened) reseeding latest 50 emails on opened Other:xxx.xxx@tiscali.it:INBOX (open_count=2 remote_opened=true) [deb] 14:46:19 0,121113 imap-client-session.vala:1505: [0000/imap.tiscali.it/default:993 GEARY_IMAP_CLIENT_SESSION_STATE_AUTHORIZING] ok #2: NAMESPACE IMAP4rev1 "AUTH=PLAIN" "AUTH=DIGEST-MD5" "AUTH=CRAM-MD5" SORT UIDPLUS CHILDREN QUOTA [deb] 14:46:19 0,000152 imap-client-session.vala:782: [0000/imap.tiscali.it/default:993 GEARY_IMAP_CLIENT_SESSION_STATE_AUTHORIZED] No compression available [deb] 14:46:19 0,067917 imap-client-session.vala:1469: [0001/imap.tiscali.it/default:993 GEARY_IMAP_CLIENT_SESSION_STATE_CONNECTING] Connected to imap.tiscali.it/default:993 [deb] 14:46:19 0,000006 imap-client-session.vala:624: [0001/imap.tiscali.it/default:993 GEARY_IMAP_CLIENT_SESSION_STATE_CONNECTING] Connected [deb] 14:46:19 0,084886 imap-client-session.vala:1505: [0001/imap.tiscali.it/default:993 GEARY_IMAP_CLIENT_SESSION_STATE_CONNECTING] ok #1: NAMESPACE IMAP4rev1 "AUTH=PLAIN" "AUTH=DIGEST-MD5" "AUTH=CRAM-MD5" SORT UIDPLUS CHILDREN QUOTA [deb] 14:46:19 0,000237 imap-client-session.vala:718: [0001/imap.tiscali.it/default:993 GEARY_IMAP_CLIENT_SESSION_STATE_NOAUTH] use_starttls=false is_ssl=true starttls=false [deb] 14:46:19 0,000007 imap-client-session.vala:746: [0001/imap.tiscali.it/default:993 GEARY_IMAP_CLIENT_SESSION_STATE_NOAUTH] No STARTTLS attempted [msg] 14:46:19 0,073881 imap-db-gc.vala:375: [GC:/home/xxxxxx/.local/share/geary/xxx.xxx@tiscali.it/geary.db] Deleted 0 empty attachment directories [deb] 14:46:19 0,019104 imap-client-session.vala:1505: [0001/imap.tiscali.it/default:993 GEARY_IMAP_CLIENT_SESSION_STATE_AUTHORIZING] ok #2: NAMESPACE IMAP4rev1 "AUTH=PLAIN" "AUTH=DIGEST-MD5" "AUTH=CRAM-MD5" SORT UIDPLUS CHILDREN QUOTA [deb] 14:46:19 0,000440 imap-client-session.vala:782: [0001/imap.tiscali.it/default:993 GEARY_IMAP_CLIENT_SESSION_STATE_AUTHORIZED] No compression available [deb] 14:46:19 0,110357 imap-db-gc.vala:256: [GC:/home/xxxxxx/.local/share/geary/xxx.xxx@tiscali.it/geary.db] Completed garbage collection of IMAP database [deb] 14:46:19 0,098523 imap-account.vala:170: [IMAP Account for xxx.xxx@tiscali.it] INBOX specifier: INBOX [deb] 14:46:19 0,000004 imap-account.vala:172: [IMAP Account for xxx.xxx@tiscali.it] Hierarchy delimiter: / [deb] 14:46:20 0,520719 imap-engine-minimal-folder.vala:649: Opening remote folder INBOX ![crt] 14:46:20 0,000156 geary_imap_mailbox_specifier_construct_from_folder_path: assertion 'delim != NULL' failed ![crt] 14:46:20 0,000016 geary_imap_select_command_construct: assertion 'GEARY_IMAP_IS_MAILBOX_SPECIFIER (mailbox)' failed [deb] 14:46:20 0,000104 imap-client-session-manager.vala:390: [ClientSessionManager/imap.tiscali.it/default:993 2 sessions, 2 reserved] Unreserving session 0000/imap.tiscali.it/default:993 GEARY_IMAP_CLIENT_SESSION_STATE_AUTHORIZED [deb] 14:46:20 0,000052 imap-engine-minimal-folder.vala:722: Soft failure opening or preparing remote folder Other:xxx.xxx@tiscali.it:INBOX (open_count=2 remote_opened=true), closing: Cannot perform operation GEARY_IMAP_CLIENT_SESSION_EVENT_SELECT while session is GEARY_IMAP_CLIENT_SESSION_STATE_AUTHORIZED [deb] 14:46:20 0,000424 imap-engine-minimal-folder.vala:883: Closing replay queue for Other:xxx.xxx@tiscali.it:INBOX (open_count=0 remote_opened=true) (flush_pending=false): ReplayQueue:Other:xxx.xxx@tiscali.it:INBOX (open_count=0 remote_opened=true) (notification=0 local=0 local_active=false remote=0 remote_active=true) [deb] 14:46:20 0,000196 imap-engine-replay-queue.vala:483: Folder ReplayQueue:Other:xxx.xxx@tiscali.it:INBOX (open_count=0 remote_opened=true) (notification=0 local=1 local_active=false remote=0 remote_active=true) closed or failed to open, remote replay queue closing: Other:xxx.xxx@tiscali.it:INBOX (open_count=0 remote_opened=true) failed to open [deb] 14:46:20 0,000268 imap-engine-replay-queue.vala:452: ReplayQueue.do_replay_local_async ReplayQueue:Other:xxx.xxx@tiscali.it:INBOX (open_count=0 remote_opened=true) (notification=0 local=0 local_active=false remote=1 remote_active=false) exiting [deb] 14:46:20 0,000042 app-conversation-monitor.vala:454: Error while assembling conversations in Other:xxx.xxx@tiscali.it:INBOX (open_count=0 remote_opened=true): Folder Other:xxx.xxx@tiscali.it:INBOX (open_count=0 remote_opened=true) not available [deb] 14:46:20 0,000009 geary-controller.vala:1455: Scan error: Folder Other:xxx.xxx@tiscali.it:INBOX (open_count=0 remote_opened=true) not available [deb] 14:46:20 0,000464 app-conversation-monitor.vala:711: Reseed error: Folder Other:xxx.xxx@tiscali.it:INBOX (open_count=0 remote_opened=true) not available [deb] 14:46:20 0,000897 imap-engine-replay-queue.vala:561: ReplayQueue.do_replay_remote_async ReplayQueue:Other:xxx.xxx@tiscali.it:INBOX (open_count=0 remote_opened=true) (notification=0 local=0 local_active=false remote=0 remote_active=false) exiting [deb] 14:46:20 0,000239 imap-engine-minimal-folder.vala:886: Closed replay queue for Other:xxx.xxx@tiscali.it:INBOX (open_count=0 remote_opened=true): ReplayQueue:Other:xxx.xxx@tiscali.it:INBOX (open_count=0 remote_opened=true) (notification=0 local=0 local_active=false remote=0 remote_active=false) [deb] 14:46:20 0,000039 imap-engine-minimal-folder.vala:943: Folder Other:xxx.xxx@tiscali.it:INBOX (open_count=0 remote_opened=false) closed [deb] 14:46:20 0,809105 imap-engine-generic-account.vala:693: Previously used Draft for special folder GEARY_SPECIAL_FOLDER_TYPE_DRAFTS [deb] 14:46:20 0,000017 imap-engine-generic-account.vala:733: Promoting Draft to special folder GEARY_SPECIAL_FOLDER_TYPE_DRAFTS [deb] 14:46:20 0,000399 imap-engine-generic-account.vala:693: Previously used Sent for special folder GEARY_SPECIAL_FOLDER_TYPE_SENT [deb] 14:46:20 0,000015 imap-engine-generic-account.vala:733: Promoting Sent to special folder GEARY_SPECIAL_FOLDER_TYPE_SENT [deb] 14:46:20 0,000391 imap-engine-generic-account.vala:693: Previously used Spam for special folder GEARY_SPECIAL_FOLDER_TYPE_SPAM [deb] 14:46:20 0,000020 imap-engine-generic-account.vala:733: Promoting Spam to special folder GEARY_SPECIAL_FOLDER_TYPE_SPAM [deb] 14:46:20 0,000409 imap-engine-generic-account.vala:693: Previously used Trash for special folder GEARY_SPECIAL_FOLDER_TYPE_TRASH [deb] 14:46:20 0,000022 imap-engine-generic-account.vala:733: Promoting Trash to special folder GEARY_SPECIAL_FOLDER_TYPE_TRASH [deb] 14:46:20 0,000341 imap-engine-generic-account.vala:693: Previously used Archivio for special folder GEARY_SPECIAL_FOLDER_TYPE_ARCHIVE [deb] 14:46:20 0,000015 imap-engine-generic-account.vala:733: Promoting Archivio to special folder GEARY_SPECIAL_FOLDER_TYPE_ARCHIVE [deb] 14:46:21 0,148546 app-conversation-monitor.vala:454: Error while assembling conversations in Other:xxx.xxx@tiscali.it:INBOX (open_count=0 remote_opened=false): list_email_by_id_async failed: folder Other:xxx.xxx@tiscali.it:INBOX (open_count=0 remote_opened=false) is not open [deb] 14:46:21 0,000008 geary-controller.vala:1455: Scan error: list_email_by_id_async failed: folder Other:xxx.xxx@tiscali.it:INBOX (open_count=0 remote_opened=false) is not open [deb] 14:46:21 0,000269 app-conversation-monitor.vala:776: Error filling conversation window: list_email_by_id_async failed: folder Other:xxx.xxx@tiscali.it:INBOX (open_count=0 remote_opened=false) is not open [deb] 14:46:22 1,628377 imap-db-account.vala:1553: xxx.xxx@tiscali.it: Populating search table [deb] 14:46:22 0,000010 imap-db-account.vala:1587: xxx.xxx@tiscali.it: Searching for up to 50 missing indexed messages... [deb] 14:46:22 0,123052 imap-db-account.vala:1570: xxx.xxx@tiscali.it: Done populating search table [deb] 14:46:28 5,879645 imap-engine-account-synchronizer.vala:323: No oldest message found for Other:xxx.xxx@tiscali.it:INBOX (open_count=0 remote_opened=false), synchronizing... [deb] 14:46:28 0,000111 imap-engine-account-synchronizer.vala:372: Background sync'ing Other:xxx.xxx@tiscali.it:INBOX (open_count=1 remote_opened=false) [deb] 14:46:28 0,000017 imap-engine-minimal-folder.vala:522: wait_for_open_async Other:xxx.xxx@tiscali.it:INBOX (open_count=1 remote_opened=false): opening remote on demand... [deb] 14:46:28 0,000184 imap-engine-minimal-folder.vala:641: Fetching STATUS for remote Other:xxx.xxx@tiscali.it:INBOX (open_count=1 remote_opened=true) from local [deb] 14:46:28 0,001514 imap-engine-minimal-folder.vala:645: Fetching information for remote folder Other:xxx.xxx@tiscali.it:INBOX (open_count=1 remote_opened=true) [deb] 14:46:28 0,000118 imap-engine-minimal-folder.vala:649: Opening remote folder INBOX ![crt] 14:46:28 0,000131 geary_imap_mailbox_specifier_construct_from_folder_path: assertion 'delim != NULL' failed ![crt] 14:46:28 0,000012 geary_imap_select_command_construct: assertion 'GEARY_IMAP_IS_MAILBOX_SPECIFIER (mailbox)' failed [deb] 14:46:28 0,000119 imap-client-session-manager.vala:390: [ClientSessionManager/imap.tiscali.it/default:993 2 sessions, 2 reserved] Unreserving session 0000/imap.tiscali.it/default:993 GEARY_IMAP_CLIENT_SESSION_STATE_AUTHORIZED [deb] 14:46:28 0,000073 imap-engine-minimal-folder.vala:722: Soft failure opening or preparing remote folder Other:xxx.xxx@tiscali.it:INBOX (open_count=1 remote_opened=true), closing: Cannot perform operation GEARY_IMAP_CLIENT_SESSION_EVENT_SELECT while session is GEARY_IMAP_CLIENT_SESSION_STATE_AUTHORIZED [deb] 14:46:28 0,000450 imap-engine-minimal-folder.vala:883: Closing replay queue for Other:xxx.xxx@tiscali.it:INBOX (open_count=0 remote_opened=true) (flush_pending=false): ReplayQueue:Other:xxx.xxx@tiscali.it:INBOX (open_count=0 remote_opened=true) (notification=0 local=0 local_active=false remote=0 remote_active=false) [deb] 14:46:28 0,000272 imap-engine-account-synchronizer.vala:351: Error background syncing folder Other:xxx.xxx@tiscali.it:INBOX (open_count=0 remote_opened=true): Other:xxx.xxx@tiscali.it:INBOX (open_count=0 remote_opened=true) failed to open [deb] 14:46:28 0,000154 imap-engine-replay-queue.vala:452: ReplayQueue.do_replay_local_async ReplayQueue:Other:xxx.xxx@tiscali.it:INBOX (open_count=0 remote_opened=true) (notification=0 local=0 local_active=false remote=1 remote_active=false) exiting [deb] 14:46:28 0,000145 imap-engine-replay-queue.vala:561: ReplayQueue.do_replay_remote_async ReplayQueue:Other:xxx.xxx@tiscali.it:INBOX (open_count=0 remote_opened=true) (notification=0 local=0 local_active=false remote=0 remote_active=false) exiting [deb] 14:46:28 0,000037 imap-engine-minimal-folder.vala:886: Closed replay queue for Other:xxx.xxx@tiscali.it:INBOX (open_count=0 remote_opened=true): ReplayQueue:Other:xxx.xxx@tiscali.it:INBOX (open_count=0 remote_opened=true) (notification=0 local=0 local_active=false remote=0 remote_active=false) [deb] 14:46:28 0,000017 imap-engine-minimal-folder.vala:943: Folder Other:xxx.xxx@tiscali.it:INBOX (open_count=0 remote_opened=false) closed
Created attachment 330845 [details] [review] Don't crash when LIST on the INBOX returns a NIL delim Updated patch that should fix the critical "assertion 'delim != NULL' failed" error reported in the log. Can you try this one out instead and see if it works better? Thanks!
Ok, with the new patch seems to work :)
Great! I've pushed this to master as e9a0b2a. Will cherry-pick to the geary-0.11 branch after it's been tested a bit more widely.
Pushed to geary-0.11 as 885f936.