0005769: SOGo messes up IMAP subscriptions when randomly fails to open folder - SOGo | BTS

ID	Project	Category	View Status	Date Submitted	Last Update

0005769	SOGo	Backend Mail	public	2023-05-17 08:42	2023-12-18 15:46

Reporter	xblax	Assigned To	qhivert
Priority	normal	Severity	major	Reproducibility	random
Status	closed	Resolution	duplicate
OS	Debian	OS Version	Buster
Product Version	5.8.2

Summary	0005769: SOGo messes up IMAP subscriptions when randomly fails to open folder
Description	I have a problem that SOGo randomly messes up my IMAP subscriptions - this then negatively affects other mail clients, because the folders "disappear" until manually subscribed again. I enabled SOGo debug logging for requests and mail to track down what happens (see log in additional information). When I click on folders in the web interface at some random points SOGo fails to open the folder. The problem appears to be-in the back-end. When the web-interface requests to open folder "INBOX.Foo.Bar" on the IMAP side SOGo tries to select folder "INBOXFooBar" which obviously fails due to the missing dots. But as a result of this failure SOGo then unsubscribes from "INBOX.Foo.Bar". I think the automatic unsubscribe isn't a good idea, but not the root cause here. I have no idea why this happens - it seems to be totally random. When I try to open the same folder a second time it usually works, but then it's already unsubscribed. This commit https://github.com/Alinto/sogo/commit/373ac51e62b8d50b9a8aa938c048dbf53bdbb992 sounds related, but obviously isn't helping in my case. I believe I first noticed the missing folder subscription after I upgraded SOGo from 5.4 to 5.8. There is no improvement with 5.8.2.
Additional Information	May 17 09:51:48 sogod [7172]: \|SOGo\| starting method 'POST' on uri '/SOGo/so/example@example.com/Mail/0/folderINBOX/folderFoo/folderBar/view' May 17 09:51:48 sogod [7172]: [ERROR] <0x0x561e31739270[NGImap4Connection]> could not select URL: imap://example%40example.com@localhost/INBOX/Foo/Bar/?tls=NO&tlsVerifyMode=default: {RawResponse = "{ResponseResult = {description = \"Mailbox doesn't exist: INBOXFooBar (0.002 + 0.044 + 0.044 secs).\"; result = no; tagId = 4; }; }"; reason = "Mailbox doesn't exist: INBOXFooBar (0.002 + 0.044 + 0.044 secs)."; result = 0; } May 17 09:51:48 sogod [7172]: \|SOGo\| request took 0.249417 seconds to execute May 17 09:51:48 sogod [7172]: localhost "POST /SOGo/so/example@example.com/Mail/0/folderINBOX/folderFoo/folderBar/view HTTP/1.0" 500 71/58 S[0x561e30b7cd00]: OK [CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE LITERAL+ STARTTLS AUTH=PLAIN] Dovecot (Debian) ready. C[0x561e3058bc30]: 1 login "example@example.com" "password" S[0x561e30b7cd00]: 1 OK [CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS THREAD=ORDEREDSUBJECT MULTIAPPEND URL-PARTIAL CATENATE UNSELECT CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS BINARY MOVE SNIPPET=FUZZY LITERAL+ NOTIFY SPECIAL-USE QUOTA] Logged in C[0x561e3058bc30]: 2 ID ("x-originating-ip" "localhost") S[0x561e30b7cd00]: ID ("name" "Dovecot") S[0x561e30b7cd00]: 2 OK ID completed (0.001 + 0.043 + 0.042 secs). C[0x561e3058bc30]: 3 LIST "" "" S[0x561e30b7cd00]: LIST (\Noselect) "." "" S[0x561e30b7cd00]: 3 OK List completed (0.001 + 0.041 + 0.040 secs). C[0x561e3058bc30]: 4 select "INBOXFooBar" S[0x561e30b7cd00]: 4 NO Mailbox doesn't exist: INBOXFooBar (0.002 + 0.044 + 0.044 secs). C[0x561e3058bc30]: 5 unsubscribe "INBOX.Foo.Bar" S[0x561e30b7cd00]: 5 OK Unsubscribe completed (0.025 + 0.000 + 0.024 secs). C[0x561e3058bc30]: 6 logout S[0x561e30b7cd00]:
Tags	No tags attached.

Date Modified	Username	Field	Change
2023-05-17 08:42	xblax	New Issue
2023-12-18 15:45	qhivert	Relationship added	related to 0005900
2023-12-18 15:45	qhivert	Note Added: 0017509
2023-12-18 15:46	qhivert	Assigned To	=> qhivert
2023-12-18 15:46	qhivert	Status	new => closed
2023-12-18 15:46	qhivert	Resolution	open => duplicate