Start von Icedove bringt Fehlermeldung von Mailserver

[scientific]

Hi Leute!

Ich hatte das Problem schon mit cyrus, jetzt mit dovecot ist es das selbe. Liegt es an Icedove, oder an IMAP?

Ich habe lokal einen Mailserver laufen. Der IMAP-Server (dovecot) wird schon beim booten gestartet. Das Problem ist, wenn ich Icedove starte, dann ist der im zuletzt aktiven Imap-Folder. Es kommt eine Fehlermeldung,

"Der Server des Kontos user@server antwortete: Mailbox doesn't exist: INBOX^bla^blubb"

Ich habe STARTTLS (auf Port 143) mit verschlüsseltem Passwort in Verwendung.

Wenn ich in einen anderen Ordner klicke, werden die Mails anstandslos angezeigt. Klicke ich zurück in den bemängelten (Das ist IMMER der zuletzt geöffnet gewesene!), werden auch dort die Mails ohne weiterer Fehlermeldung angezeigt.

Wie gesagt... das Problem trat in der Kombi auch schon mit Cyrus aber SSL/TLS auf.
Liegt das an Icedove, dass der zu früh (vor erfolgreichem Login) den Folder abfragt, oder ist es ein Problem der Serverkonfiguration, oder liegt es am Protokoll?

PS: Ich habe "Beim Starten auf neue Emails prüfen" deaktiviert. Die erste Abfrage erfolgt erst nach 10 Minuten!
lg scientific

PPS: /var/log/mail.log gibt für den fraglichen Zeitpunkt das aus

Code: Alles auswählen

Dec  1 10:33:19 venus dovecot: imap-login: Login: user=<jakob>, method=CRAM-MD5, rip=::1, lip=::1, mpid=1237, TLS, session=<I+AITSQJIQAAAAAAAAAAAAAAAAAAAAAB>
Dec  1 10:33:33 venus dovecot: imap-login: Login: user=<jakob>, method=CRAM-MD5, rip=127.0.0.1, lip=127.0.0.1, mpid=1649, TLS, session=<78fjTSQJ1gB/AAAB>
Dec  1 10:33:34 venus dovecot: imap-login: Login: user=<jakob>, method=CRAM-MD5, rip=127.0.0.1, lip=127.0.0.1, mpid=1668, TLS, session=<QvnpTSQJ1wB/AAAB>
Dec  1 10:33:34 venus dovecot: imap-login: Login: user=<jakob>, method=CRAM-MD5, rip=127.0.0.1, lip=127.0.0.1, mpid=1691, TLS, session=<ORrwTSQJ2AB/AAAB>
Dec  1 10:33:35 venus dovecot: imap-login: Login: user=<jakob>, method=CRAM-MD5, rip=127.0.0.1, lip=127.0.0.1, mpid=1715, TLS, session=<8lv2TSQJ2QB/AAAB>
Dec  1 10:33:35 venus dovecot: imap-login: Login: user=<jakob>, method=CRAM-MD5, rip=127.0.0.1, lip=127.0.0.1, mpid=1721, TLS, session=<BHr8TSQJ2gB/AAAB>
Dec  1 10:33:35 venus dovecot: imap-login: Login: user=<jakob>, method=CRAM-MD5, rip=127.0.0.1, lip=127.0.0.1, mpid=1735, TLS, session=<560CTiQJ2wB/AAAB>
Dec  1 10:33:36 venus dovecot: imap-login: Login: user=<jakob>, method=CRAM-MD5, rip=127.0.0.1, lip=127.0.0.1, mpid=1750, TLS, session=<LdMITiQJ3AB/AAAB>

PPS: Ich habe jetzt ipv6 deaktiviert. Die Fehlermeldung kommt ebenfalls beim ersten Einlogversuch, nur halt bei der ersten IPv4-Zeile.

[Dimejo]

Wenn ich in einen anderen Ordner klicke, werden die Mails anstandslos angezeigt. Klicke ich zurück in den bemängelten (Das ist IMMER der zuletzt geöffnet gewesene!), werden auch dort die Mails ohne weiterer Fehlermeldung angezeigt.

Als "Workaround" fällt mir da das Addon Folderpane Tools ein.

Wie gesagt... das Problem trat in der Kombi auch schon mit Cyrus aber SSL/TLS auf.
Liegt das an Icedove, dass der zu früh (vor erfolgreichem Login) den Folder abfragt, oder ist es ein Problem der Serverkonfiguration, oder liegt es am Protokoll?

So eine Fehlermeldung habe ich bis jetzt noch nie beobachtet. Allerdings habe ich auch noch nie Thunderbird/Icedove auf einem Mailserver betrieben.

PPS: /var/log/mail.log gibt für den fraglichen Zeitpunkt das aus

Code: Alles auswählen

Dec  1 10:33:19 venus dovecot: imap-login: Login: user=<jakob>, method=CRAM-MD5, rip=::1, lip=::1, mpid=1237, TLS, session=<I+AITSQJIQAAAAAAAAAAAAAAAAAAAAAB>
Dec  1 10:33:33 venus dovecot: imap-login: Login: user=<jakob>, method=CRAM-MD5, rip=127.0.0.1, lip=127.0.0.1, mpid=1649, TLS, session=<78fjTSQJ1gB/AAAB>
Dec  1 10:33:34 venus dovecot: imap-login: Login: user=<jakob>, method=CRAM-MD5, rip=127.0.0.1, lip=127.0.0.1, mpid=1668, TLS, session=<QvnpTSQJ1wB/AAAB>
Dec  1 10:33:34 venus dovecot: imap-login: Login: user=<jakob>, method=CRAM-MD5, rip=127.0.0.1, lip=127.0.0.1, mpid=1691, TLS, session=<ORrwTSQJ2AB/AAAB>
Dec  1 10:33:35 venus dovecot: imap-login: Login: user=<jakob>, method=CRAM-MD5, rip=127.0.0.1, lip=127.0.0.1, mpid=1715, TLS, session=<8lv2TSQJ2QB/AAAB>
Dec  1 10:33:35 venus dovecot: imap-login: Login: user=<jakob>, method=CRAM-MD5, rip=127.0.0.1, lip=127.0.0.1, mpid=1721, TLS, session=<BHr8TSQJ2gB/AAAB>
Dec  1 10:33:35 venus dovecot: imap-login: Login: user=<jakob>, method=CRAM-MD5, rip=127.0.0.1, lip=127.0.0.1, mpid=1735, TLS, session=<560CTiQJ2wB/AAAB>
Dec  1 10:33:36 venus dovecot: imap-login: Login: user=<jakob>, method=CRAM-MD5, rip=127.0.0.1, lip=127.0.0.1, mpid=1750, TLS, session=<LdMITiQJ3AB/AAAB>

Um das Problem nachzuvollziehen wäre es wohl am einfachsten mail_debug zu aktivieren.

[scientific]

Mit mail_debug = yes kommt jetzt zum fraglichen Zeitpunkt bei der fraglichen Fehlermeldung das hier...

Code: Alles auswählen

Dec  1 13:38:58 venus dovecot: imap-login: Login: user=<jakob>, method=CRAM-MD5, rip=::1, lip=::1, mpid=30571, TLS, session=<tSn15CYJkgAAAAAAAAAAAAAAAAAAAAAB>
Dec  1 13:38:58 venus dovecot: imap(jakob): Debug: Effective uid=127, gid=135, home=/var/spool/dovecot/mails/jakob
Dec  1 13:38:58 venus dovecot: imap(jakob): Debug: Namespace inbox: type=private, prefix=, sep=/, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/spool/dovecot/mails/jakob/:INBOX=/var/spool/dovecot/mails/jakob:LAYOUT=fs
Dec  1 13:38:58 venus dovecot: imap(jakob): Debug: fs: root=/var/spool/dovecot/mails/jakob, index=, indexpvt=, control=, inbox=/var/spool/dovecot/mails/jakob, alt=

[Dimejo]

Mit mail_debug = yes kommt jetzt zum fraglichen Zeitpunkt bei der fraglichen Fehlermeldung das hier...

Code: Alles auswählen

Dec  1 13:38:58 venus dovecot: imap-login: Login: user=<jakob>, method=CRAM-MD5, rip=::1, lip=::1, mpid=30571, TLS, session=<tSn15CYJkgAAAAAAAAAAAAAAAAAAAAAB>
Dec  1 13:38:58 venus dovecot: imap(jakob): Debug: Effective uid=127, gid=135, home=/var/spool/dovecot/mails/jakob
Dec  1 13:38:58 venus dovecot: imap(jakob): Debug: Namespace inbox: type=private, prefix=, sep=/, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/var/spool/dovecot/mails/jakob/:INBOX=/var/spool/dovecot/mails/jakob:LAYOUT=fs
Dec  1 13:38:58 venus dovecot: imap(jakob): Debug: fs: root=/var/spool/dovecot/mails/jakob, index=, indexpvt=, control=, inbox=/var/spool/dovecot/mails/jakob, alt=

Mit LAYOUT=fs habe ich leider keine Erfahrungen, also kann ich dazu nichts sagen, aber der Rest sieht für mich normal aus. Aufgefallen ist mir nur, dass mail_home und mail_location scheinbar das selbe Verzeichnis sind. Das sollte man generell vermeiden, dürfte aber unabhängig zu der Fehlermeldung sein.

Ich kann mir nicht vorstellen, dass Dovecot eine Fehlermeldung an Icedove liefert ohne das in einem Log (noch dazu debug) zu vermerken. Vielleicht hilft Dir Debugging using Thunderbird's logging um der Ursache auf die Spur zu kommen.

[scientific]

Hab das mit dem Logging gemacht. Die Ausgabe hier pastebin.php?mode=view&s=38145

Ich werde nicht klug daraus.
Der Ordner, in dem Icedove gestartet wurde (also der zuletzt gewählte vor dem shutdown von icedove) war "Entwürfe/Drafts"

Und hier mit einem anderen Ordner, nämlich mit dem Namen INBOX/AMS

pastebin.php?mode=view&s=38146
Das selbe...

lg scientific

[scientific]

Mir kommt gerade eine Idee.

Bemängelt wird "INBOX^AMS" gefunden wurde aber vorher "INBOX/AMS"
Ich habe "/" als Foldertrenner in dovecot eingestellt, und Icedove verarbeitet das auch gut. Aber ich vermute fast, da ist eine Anfrage, wo Icedove den Punkt als Foldertrenner anfragt...

Muss ich "/" in Icedove irgendwo explizit angeben?

[Dimejo]

Die Logs sehen für mich normal aus - kann dabei keine Probleme sehen. Dovecot übergibt auch immer brav den Separator. Der einzige Unterschied zu meinem Log ist, dass bei mir alle Ordner mit umschließenden doppelten Anführungszeichen ausgegegen werden, und bei Dir nicht. Soweit ich das jetzt hier nachgelesen habe ist das aber völlig legitim.

Das hier finde ich ungewöhnlich:

Code: Alles auswählen

77055744[7f28f2fe5c00]: 6b35000:localhost:A:CreateNewLineFromSocket: * LIST (\Subscribed) "/" Drafts
[...]
77055744[7f28f2fe5c00]: 6b35000:localhost:A:SendData: 9 select "INBOX^Drafts" (CONDSTORE)

Drafts befindet sich laut Dovecot in der Hauptebene (also gleich wie INBOX), Icedove sucht aber eine Ebene unter INBOX.

Muss ich "/" in Icedove irgendwo explizit angeben?

Nein.
Aber wo Du das erwähnst: Hast Du in Icedove unter Server-Einstellungen => Erweitert Änderungen vorgenommen?
Falls Du es noch nicht getan hast könntest Du noch mit einem neuen/leeren Profil testen.

[scientific]

Die Logs sehen für mich normal aus - kann dabei keine Probleme sehen. Dovecot übergibt auch immer brav den Separator. Der einzige Unterschied zu meinem Log ist, dass bei mir alle Ordner mit umschließenden doppelten Anführungszeichen ausgegegen werden, und bei Dir nicht. Soweit ich das jetzt hier nachgelesen habe ist das aber völlig legitim.

Das hier finde ich ungewöhnlich:

Code: Alles auswählen

77055744[7f28f2fe5c00]: 6b35000:localhost:A:CreateNewLineFromSocket: * LIST (\Subscribed) "/" Drafts
[...]
77055744[7f28f2fe5c00]: 6b35000:localhost:A:SendData: 9 select "INBOX^Drafts" (CONDSTORE)

Drafts befindet sich laut Dovecot in der Hauptebene (also gleich wie INBOX), Icedove sucht aber eine Ebene unter INBOX.

Muss ich "/" in Icedove irgendwo explizit angeben?

Nein.
Aber wo Du das erwähnst: Hast Du in Icedove unter Server-Einstellungen => Erweitert Änderungen vorgenommen?
Falls Du es noch nicht getan hast könntest Du noch mit einem neuen/leeren Profil testen.

Muss ich noch machen, das Testen mit einem neuen Profil.
Aber ich habe ein wenig gegoogled, weil mir die Zeile mit dem CONDSTORE spanisch vorkam. Denn egal ob Draft (der tatsächlich falsch liegt) noch im anderen Beispiel mit dem Ordner AMS, ist der Separator "^" und nicht "/".
Ich wurde auch fündig (https://bugzilla.mozilla.org/show_bug.cgi?id=555913). Daraufhin habe ich in den erweiterten Einstellungen einmal

Code: Alles auswählen

mail.server.default.use_condstore

auf false gesetzt. Jetzt ist die Fehlermeldung verschwunden. Aber ob das wirklich DIE Lösung ist???

lg scientific

[scientific]

Kommando zurück... die Fehlermeldung erscheint wieder...

[scientific]

Die Fehlermeldung erscheint nach wie vor unverändert...

[Dimejo]

Habe gerade diesen Bug gefunden. Scheinbar bist Du mit Deinem Problem nicht allein.
https://bugzilla.mozilla.org/show_bug.cgi?id=773579

[scientific]

Fast. Denn es tritt bei mir nur beim Starten von Icedove auf. Danach läuft alles wie es soll...