Closed Bug 1669676 Opened 5 years ago Closed 5 years ago

Thunderbird 78 cannot connect to IMAP server that TB 68 can connect to, but shows no error message

Categories

(Thunderbird :: Untriaged, defect)

Product:
Thunderbird
Component:
Untriaged
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INVALID

People

(Reporter: robert+git, Unassigned, NeedInfo)

Details

(Whiteboard: [tls1.2 required])

User Agent: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:81.0) Gecko/20100101 Firefox/81.0

Steps to reproduce:

Created an account in Thunderbird 78.3.1. Tried to fetch my emails.

The account set to use an IMAP server that is running "Cyrus IMAPD v2.3.11". It has an LetsEncrypt certificate issued to zzzzz.de. According to ssl-tools.net it supports TLSv1.0.

(Hostname changed to zzzzzz.de and login changed to xxxxxx@yyyyyy.de.)

Actual results:

Thunderbird seems to not be able to connect to the Server. No error message is shown.

The log contains the following lines:

[(null) 18856: IMAP]: D/IMAP ImapThreadMainLoop entering [this=0x7f22bfc1a800]
[(null) 18856: Main Thread]: I/IMAP 0x7f22bfc1a800:zzzzzz.de:NA:SetupWithUrlCallback: clearing IMAP_CONNECTION_IS_OPEN
[(null) 18856: IMAP]: I/IMAP 0x7f22bfc1a800:zzzzzz.de:NA:ProcessCurrentURL: entering
[(null) 18856: IMAP]: I/IMAP 0x7f22bfc1a800:zzzzzz.de:NA:ProcessCurrentURL:imap://xxxxxx%40yyyyyy%2Ede@zzzzzz.de:143/select%3E%5EINBOX: = currentUrl
[(null) 18856: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=0x7f22bfc41310 nb=122 needmore=0]
[(null) 18856: IMAP]: I/IMAP 0x7f22bfc1a800:zzzzzz.de:NA:CreateNewLineFromSocket: * OK [CAPABILITY IMAP4 IMAP4rev1 LITERAL+ ID STARTTLS AUTH=PLAIN AUTH=LOGIN SASL-IR] s1 Cyrus IMAP4 v2.3.11 server ready
[(null) 18856: IMAP]: D/IMAP SetConnectionStatus(0x0)
[(null) 18856: IMAP]: D/IMAP SetConnectionStatus(0x0)
[(null) 18856: IMAP]: I/IMAP 0x7f22bfc1a800:zzzzzz.de:NA:SendData: 1 STARTTLS
[(null) 18856: IMAP]: D/IMAP ReadNextLine [rv=0x0 stream=0x7f22bfc41310 nb=32 needmore=0]
[(null) 18856: IMAP]: I/IMAP 0x7f22bfc1a800:zzzzzz.de:NA:CreateNewLineFromSocket: 1 OK Begin TLS negotiation now
[(null) 18856: IMAP]: D/IMAP SetConnectionStatus(0x0)
[(null) 18856: IMAP]: I/IMAP 0x7f22bfc1a800:zzzzzz.de:NA:SendData: 2 capability
[(null) 18856: IMAP]: D/IMAP ReadNextLine [rv=0x805a2ff7 stream=0x7f22bfc41310 nb=0 needmore=1]
[(null) 18856: IMAP]: I/IMAP 0x7f22bfc1a800:zzzzzz.de:NA:CreateNewLineFromSocket: clearing IMAP_CONNECTION_IS_OPEN - rv = 805a2ff7
[(null) 18856: IMAP]: I/IMAP 0x7f22bfc1a800:zzzzzz.de:NA:TellThreadToDie: close socket connection
[(null) 18856: IMAP]: I/IMAP 0x7f22bfc1a800:zzzzzz.de:NA:CreateNewLineFromSocket: (null)
[(null) 18856: IMAP]: D/IMAP SetConnectionStatus(0x805a2ff7)
[(null) 18856: IMAP]: D/IMAP URL failed with code 0x805a2ff7 (imap://xxxxxx%40yyyyyy%2Ede@zzzzzz.de:143/select%3E%5EINBOX)
[(null) 18856: IMAP]: I/IMAP 0x7f22bfc1a800:zzzzzz.de:NA:ProcessCurrentURL: aborting queued urls
[(null) 18856: IMAP]: D/IMAP ImapThreadMainLoop leaving [this=0x7f22bfc1a800]

For comparison, the log from Thunderbird 68:

[(null) 20212: Unnamed thread 0x7fce18b9adc0]: D/IMAP ImapThreadMainLoop entering [this=0x7fce14d8d800]
[(null) 20212: Main Thread]: I/IMAP 0x7fce14d8d800:zzzzzz.de:NA:SetupWithUrlCallback: clearing IMAP_CONNECTION_IS_OPEN
[(null) 20212: Unnamed thread 0x7fce18b9adc0]: I/IMAP 0x7fce14d8d800:zzzzzz.de:NA:ProcessCurrentURL: entering
[(null) 20212: Unnamed thread 0x7fce18b9adc0]: I/IMAP 0x7fce14d8d800:zzzzzz.de:NA:ProcessCurrentURL:imap://xxxxxx%40yyyyyy%2Ede@zzzzzz.de:143/select%3E/INBOX/Entw%26APw-rfe: = currentUrl
[(null) 20212: Unnamed thread 0x7fce18b9adc0]: D/IMAP ReadNextLine [stream=0x7fce157b5280 nb=122 needmore=0]
[(null) 20212: Unnamed thread 0x7fce18b9adc0]: I/IMAP 0x7fce14d8d800:zzzzzz.de:NA:CreateNewLineFromSocket: * OK [CAPABILITY IMAP4 IMAP4rev1 LITERAL+ ID STARTTLS AUTH=PLAIN AUTH=LOGIN SASL-IR] s1 Cyrus IMAP4 v2.3.11
server readyi
[(null) 20212: Unnamed thread 0x7fce18b9adc0]: I/IMAP 0x7fce14d8d800:zzzzzz.de:NA:SendData: 1 STARTTLS
[(null) 20212: Unnamed thread 0x7fce18b9adc0]: D/IMAP ReadNextLine [stream=0x7fce157b5280 nb=32 needmore=0]
[(null) 20212: Unnamed thread 0x7fce18b9adc0]: I/IMAP 0x7fce14d8d800:zzzzzz.de:NA:CreateNewLineFromSocket: 1 OK Begin TLS negotiation now
[(null) 20212: Unnamed thread 0x7fce18b9adc0]: I/IMAP 0x7fce14d8d800:zzzzzz.de:NA:SendData: 2 capability
[(null) 20212: Unnamed thread 0x7fce18b9adc0]: D/IMAP ReadNextLine [stream=0x7fce157b5280 nb=297 needmore=0]
[(null) 20212: Unnamed thread 0x7fce18b9adc0]: I/IMAP 0x7fce14d8d800:zzzzzz.de:NA:CreateNewLineFromSocket: * CAPABILITY IMAP4 IMAP4rev1 LITERAL+ ID AUTH=PLAIN AUTH=LOGIN SASL-IR ACL RIGHTS=kxte QUOTA MAILBOX-REFERR
ALS NAMESPACE UIDPLUS NO_ATOMIC_RENAME UNSELECT CHILDREN MULTIAPPEND BINARY SORT SORT=MODSEQ THREAD=ORDEREDSUBJECT THREAD=REFERENCES ANNOTATEMORE CATENATE CONDSTORE IDLE X-NETSCAPE URLAUTH
[(null) 20212: Unnamed thread 0x7fce18b9adc0]: D/IMAP ReadNextLine [stream=0x7fce157b5280 nb=16 needmore=0]
[(null) 20212: Unnamed thread 0x7fce18b9adc0]: I/IMAP 0x7fce14d8d800:zzzzzz.de:NA:CreateNewLineFromSocket: 2 OK Completed
[(null) 20212: Unnamed thread 0x7fce18b9adc0]: D/IMAP Try to log in
[(null) 20212: Unnamed thread 0x7fce18b9adc0]: D/IMAP IMAP auth: server caps 0x24c7737, pref 0x1006, failed 0x0, avail caps 0x1006
[(null) 20212: Unnamed thread 0x7fce18b9adc0]: D/IMAP (GSSAPI = 0x1000000, CRAM = 0x20000, NTLM = 0x100000, MSN = 0x200000, PLAIN = 0x1000, LOGIN = 0x2, old-style IMAP login = 0x4, auth external IMAP login = 0x20
000000, OAUTH2 = 0x800000000)
[(null) 20212: Unnamed thread 0x7fce18b9adc0]: D/IMAP Trying auth method 0x1000
[(null) 20212: Unnamed thread 0x7fce18b9adc0]: D/IMAP got new password
[(null) 20212: Unnamed thread 0x7fce18b9adc0]: D/IMAP IMAP: trying auth method 0x1000
[(null) 20212: Unnamed thread 0x7fce18b9adc0]: D/IMAP PLAIN auth
[(null) 20212: Unnamed thread 0x7fce18b9adc0]: I/IMAP 0x7fce14d8d800:zzzzzz.de:NA:SendData: 3 authenticate PLAIN
[(null) 20212: Unnamed thread 0x7fce18b9adc0]: D/IMAP ReadNextLine [stream=0x7fce157b5280 nb=4 needmore=0]
[(null) 20212: Unnamed thread 0x7fce18b9adc0]: I/IMAP 0x7fce14d8d800:zzzzzz.de:NA:CreateNewLineFromSocket: +
[(null) 20212: Unnamed thread 0x7fce18b9adc0]: I/IMAP 0x7fce14d8d800:zzzzzz.de:NA:SendData: Logging suppressed for this command (it probably contained authentication information)
[(null) 20212: Unnamed thread 0x7fce18b9adc0]: D/IMAP ReadNextLine [stream=0x7fce157b5280 nb=311 needmore=0]
[(null) 20212: Unnamed thread 0x7fce18b9adc0]: I/IMAP 0x7fce14d8d800:zzzzzz.de:NA:CreateNewLineFromSocket: 3 OK [CAPABILITY IMAP4 IMAP4rev1 LITERAL+ ID LOGINDISABLED ACL RIGHTS=kxte QUOTA MAILBOX-REFERRALS NAMESPAC
E UIDPLUS NO_ATOMIC_RENAME UNSELECT CHILDREN MULTIAPPEND BINARY SORT SORT=MODSEQ THREAD=ORDEREDSUBJECT THREAD=REFERENCES ANNOTATEMORE CATENATE CONDSTORE IDLE X-NETSCAPE URLAUTH] Success (tls protection)
[(null) 20212: Unnamed thread 0x7fce18b9adc0]: D/IMAP login succeeded

Expected results:

Thunderbird 78 should either successfully connect to the IMAP server as did Thunderbird 68 or give an error message hinting to the cause of the connection problem.

TLS 1.2 is now required. You can allow TLS1.0 by setting security.tls.version.min to 1

Whiteboard: [tls1.2 required]

see https://support.mozilla.org/en-US/kb/thunderbird-78-faq#w_after-upgrading-to-thunderbird-78-i-cannot-get-or-send-email-messages

Status: UNCONFIRMED → RESOLVED
Closed: 5 years ago
Resolution: --- → INVALID
Whiteboard: [tls1.2 required] → [support][tls1.2]
Whiteboard: [support][tls1.2] → [tls1.2 required]

Robert, I have the same issue (using TLS 1.2 / TLS 1.3 ) and would like to know how you managed to get the useful log information you put in your post? Console^2 doesn't work on Thunderbird 68 (which I use) but I see you have logs for Thunderbird68 so wondered how you got them? Thanks

Flags: needinfo?(robert+git)

Martin did you try to set the log environment parameter in a console before starting Thunderbird?

For instance like this:

$ MOZ_LOG=POP3:4,IMAP:4,SMTP:4 thunderbird

(In reply to mozffbgs from comment #4)

Martin did you try to set the log environment parameter in a console before starting Thunderbird?

For instance like this:

$ MOZ_LOG=POP3:4,IMAP:4,SMTP:4 thunderbird

I am sorry, I am probably being really dumb but I can't see how to set these things before Thunderbird starts... I am on Windows 10; I have tried to run these things via the command line and via the execution path in the shortcut but it comes back with various errors (MOZ_LOG unrecognised, etc.) . (I am aware that $ is the linux prompt ,. )

No worries. I didn't have to touch Windows since ages but according to a very simple internet search this should work in Windows:

set MOZ_LOG=POP3:4,IMAP:4,SMTP:4

Otherwise the official Mozilla documentation might help:

https://developer.mozilla.org/en-US/docs/Mozilla/Developer_guide/Gecko_Logging#enabling_logging

You need to log in before you can comment on or make changes to this bug.