View Issue Details

Jump to NotesJump to History
IDProjectCategoryView StatusDate SubmittedLast Update
0005769SOGoBackend Mailpublic2023-05-17 08:422023-05-17 08:42
Reporterxblax Assigned To 
PrioritynormalSeveritymajorReproducibilityrandom
Status newResolutionopen 
OSDebianOS VersionBuster 
Product Version5.8.2 
Summary0005769: 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]:

TagsNo tags attached.

Activities

There are no notes attached to this issue.

Issue History

Date Modified Username Field Change
2023-05-17 08:42 xblax New Issue