Connected to mail.. but not downloading new mail
Hi, I have been using Thunderbird for years and it suddently decided to stop downloading any email.. it says "Connected to mail.xxxx" but doesn't do anything further.
I turned on logging and launched TB via a .bat script (Windows 10) and get these errors:
2022-02-10 17:07:50.844000 UTC - [Parent 5656: IMAP]: D/IMAP ImapThreadMainLoop entering [this=1969feb6800] 2022-02-10 17:07:50.844000 UTC - [Parent 5656: Main Thread]: I/IMAP exceeded connection cache limit:imap://spencer@<myserver.net>@<myserver.net>:993/select>.INBOX 2022-02-10 17:07:50.844000 UTC - [Parent 5656: Main Thread]: I/IMAP queuing url:imap://spencer@<myserver.net>@<myserver.net>:993/select>.INBOX 2022-02-10 17:07:50.844000 UTC - [Parent 5656: Main Thread]: I/IMAP considering playing queued url:imap://spencer@<myserver.net>@<myserver.net>:993/select>.INBOX 2022-02-10 17:07:50.844000 UTC - [Parent 5656: Main Thread]: I/IMAP creating protocol instance to play queued url:imap://spencer@<myserver.net>@<myserver.net>:993/select>.INBOX 2022-02-10 17:07:50.844000 UTC - [Parent 5656: Main Thread]: I/IMAP exceeded connection cache limit:imap://spencer@<myserver.net>@<myserver.net>:993/select>.INBOX 2022-02-10 17:07:50.844000 UTC - [Parent 5656: Main Thread]: I/IMAP failed creating protocol instance to play queued url:imap://spencer@<myserver.net>@<myserver.net>:993/select>.INBOX
I have tried things like setting the "number of server connections to cache" to 1 on my mail account. I have also tried setting "security.tls.version.min" to 1 in the config, neither of which have an effect.
I can access my email by webmail OK, so the server is still operational. Any ideas how to fix this?
Thanks Spencer
Wšě wotmołwy (6)
Does this happen all the time, or was this a one-time event? This doesn't appear to be a TB issue.
david said
Does this happen all the time, or was this a one-time event? This doesn't appear to be a TB issue.
Yes it happens every time I load up TB. The last email I have in there is from 10 days ago and I've been having to use webmail since.
Okay, There are a number of people on this forum with far more knowledge than I who may be able to help if you provide more information, such as the name of ISP, server settings, and the exact text of the message you receive when attempting to retrieve messages.
Well, this is the full log:
2022-02-11 16:42:25.050000 UTC - [Parent 19276: IMAP]: D/IMAP ImapThreadMainLoop entering [this=14f994e5000] 2022-02-11 16:42:25.050000 UTC - [Parent 19276: Main Thread]: I/IMAP exceeded connection cache limit:imap://spencer@<mydomain.net>@mail.<mydomain.net>:993/select>.INBOX 2022-02-11 16:42:25.050000 UTC - [Parent 19276: Main Thread]: I/IMAP queuing url:imap://spencer@<mydomain.net>@mail.<mydomain.net>:993/select>.INBOX 2022-02-11 16:42:25.050000 UTC - [Parent 19276: Main Thread]: I/IMAP considering playing queued url:imap://spencer@<mydomain.net>@mail.<mydomain.net>:993/select>.INBOX 2022-02-11 16:42:25.050000 UTC - [Parent 19276: Main Thread]: I/IMAP creating protocol instance to play queued url:imap://spencer@<mydomain.net>@mail.<mydomain.net>:993/select>.INBOX 2022-02-11 16:42:25.050000 UTC - [Parent 19276: Main Thread]: I/IMAP exceeded connection cache limit:imap://spencer@<mydomain.net>@mail.<mydomain.net>:993/select>.INBOX 2022-02-11 16:42:25.050000 UTC - [Parent 19276: Main Thread]: I/IMAP failed creating protocol instance to play queued url:imap://spencer@<mydomain.net>@mail.<mydomain.net>:993/select>.INBOX 2022-02-11 16:42:25.097000 UTC - [Parent 19276: IMAP]: D/IMAP ImapThreadMainLoop entering [this=14f9953e800] 2022-02-11 16:42:25.097000 UTC - [Parent 19276: Main Thread]: I/IMAP exceeded connection cache limit:imap://freecycle@<mydomain.net>@mail.<mydomain.net>:993/select>.INBOX 2022-02-11 16:42:25.097000 UTC - [Parent 19276: Main Thread]: I/IMAP queuing url:imap://freecycle@<mydomain.net>@mail.<mydomain.net>:993/select>.INBOX 2022-02-11 16:42:25.097000 UTC - [Parent 19276: Main Thread]: I/IMAP considering playing queued url:imap://freecycle@<mydomain.net>@mail.<mydomain.net>:993/select>.INBOX 2022-02-11 16:42:25.097000 UTC - [Parent 19276: Main Thread]: I/IMAP creating protocol instance to play queued url:imap://freecycle@<mydomain.net>@mail.<mydomain.net>:993/select>.INBOX 2022-02-11 16:42:25.097000 UTC - [Parent 19276: Main Thread]: I/IMAP exceeded connection cache limit:imap://freecycle@<mydomain.net>@mail.<mydomain.necreat>:993/select>.INBOX 2022-02-11 16:42:25.097000 UTC - [Parent 19276: Main Thread]: I/IMAP failed creating protocol instance to play queued url:imap://freecycle@<mydomain.net>@mail.<mydomain.net>:993/select>.INBOX 2022-02-11 16:42:25.097000 UTC - [Parent 19276: IMAP]: D/IMAP ImapThreadMainLoop entering [this=14f99547000] 2022-02-11 16:42:25.097000 UTC - [Parent 19276: Main Thread]: I/IMAP exceeded connection cache limit:imap://dmarc@<mydomain.net>@mail.<mydomain.net>:993/select>.INBOX 2022-02-11 16:42:25.097000 UTC - [Parent 19276: Main Thread]: I/IMAP queuing url:imap://dmarc@<mydomain.net>@mail.<mydomain.net>:993/select>.INBOX 2022-02-11 16:42:25.097000 UTC - [Parent 19276: Main Thread]: I/IMAP considering playing queued url:imap://dmarc@<mydomain.net>@mail.<mydomain.net>:993/select>.INBOX 2022-02-11 16:42:25.097000 UTC - [Parent 19276: Main Thread]: I/IMAP creating protocol instance to play queued url:imap://dmarc@<mydomain.net>@mail.<mydomain.net>:993/select>.INBOX 2022-02-11 16:42:25.097000 UTC - [Parent 19276: Main Thread]: I/IMAP exceeded connection cache limit:imap://dmarc@<mydomain.net>@mail.<mydomain.net>:993/select>.INBOX 2022-02-11 16:42:25.097000 UTC - [Parent 19276: Main Thread]: I/IMAP failed creating protocol instance to play queued url:imap://dmarc@<mydomain.net>@mail.<mydomain.net>:993/select>.INBOX 2022-02-11 16:42:25.097000 UTC - [Parent 19276: Main Thread]: I/IMAP 14f994e5000:mail.<mydomain.net>:NA:SetupWithUrlCallback: clearing IMAP_CONNECTION_IS_OPEN 2022-02-11 16:42:25.097000 UTC - [Parent 19276: IMAP]: I/IMAP 14f994e5000:mail.<mydomain.net>:NA:ProcessCurrentURL: entering 2022-02-11 16:42:25.097000 UTC - [Parent 19276: IMAP]: I/IMAP 14f994e5000:mail.<mydomain.net>:NA:ProcessCurrentURL:imap://spencer%40<mydomain>%2Enet@mail.<mydomain.net>:993/discoverallboxes: = currentUrl 2022-02-11 16:42:25.097000 UTC - [Parent 19276: Main Thread]: I/IMAP 14f9953e800:mail.<mydomain.net>:NA:SetupWithUrlCallback: clearing IMAP_CONNECTION_IS_OPEN 2022-02-11 16:42:25.097000 UTC - [Parent 19276: IMAP]: I/IMAP 14f9953e800:mail.<mydomain.net>:NA:ProcessCurrentURL: entering 2022-02-11 16:42:25.097000 UTC - [Parent 19276: IMAP]: I/IMAP 14f9953e800:mail.<mydomain.net>:NA:ProcessCurrentURL:imap://freecycle%40<mydomain>%2Enet@mail.<mydomain.net>:993/discoverallboxes: = currentUrl 2022-02-11 16:42:25.097000 UTC - [Parent 19276: Main Thread]: I/IMAP 14f99547000:mail.<mydomain.net>:NA:SetupWithUrlCallback: clearing IMAP_CONNECTION_IS_OPEN 2022-02-11 16:42:25.097000 UTC - [Parent 19276: IMAP]: I/IMAP 14f99547000:mail.<mydomain.net>:NA:ProcessCurrentURL: entering 2022-02-11 16:42:25.097000 UTC - [Parent 19276: IMAP]: I/IMAP 14f99547000:mail.<mydomain.net>:NA:ProcessCurrentURL:imap://dmarc%40<mydomain>%2Enet@mail.<mydomain.net>:993/discoverallboxes: = currentUrl 2022-02-11 16:42:25.216000 UTC - [Parent 19276: IMAP]: D/IMAP ReadNextLine [rv=0x805a2ff4 stream=14f93165040 nb=0 needmore=1] 2022-02-11 16:42:25.216000 UTC - [Parent 19276: IMAP]: I/IMAP 14f9953e800:mail.<mydomain.net>:NA:CreateNewLineFromSocket: clearing IMAP_CONNECTION_IS_OPEN - rv = 805a2ff4 2022-02-11 16:42:25.216000 UTC - [Parent 19276: IMAP]: D/IMAP ReadNextLine [rv=0x805a2ff4 stream=14f9abf2ee0 nb=0 needmore=1] 2022-02-11 16:42:25.216000 UTC - [Parent 19276: IMAP]: I/IMAP 14f994e5000:mail.<mydomain.net>:NA:CreateNewLineFromSocket: clearing IMAP_CONNECTION_IS_OPEN - rv = 805a2ff4 2022-02-11 16:42:25.216000 UTC - [Parent 19276: IMAP]: D/IMAP ReadNextLine [rv=0x805a2ff4 stream=14f9d9594c0 nb=0 needmore=1] 2022-02-11 16:42:25.216000 UTC - [Parent 19276: IMAP]: I/IMAP 14f99547000:mail.<mydomain.net>:NA:CreateNewLineFromSocket: clearing IMAP_CONNECTION_IS_OPEN - rv = 805a2ff4 2022-02-11 16:42:25.220000 UTC - [Parent 19276: IMAP]: I/IMAP 14f9953e800:mail.<mydomain.net>:NA:TellThreadToDie: close socket connection 2022-02-11 16:42:25.220000 UTC - [Parent 19276: IMAP]: I/IMAP 14f9953e800:mail.<mydomain.net>:NA:CreateNewLineFromSocket: (null) 2022-02-11 16:42:25.220000 UTC - [Parent 19276: IMAP]: D/IMAP SetConnectionStatus(0x805a2ff4) 2022-02-11 16:42:25.220000 UTC - [Parent 19276: IMAP]: D/IMAP URL failed with code 0x805a2ff4 (imap://freecycle%40<mydomain>%2Enet@mail.<mydomain.net>:993/discoverallboxes) 2022-02-11 16:42:25.220000 UTC - [Parent 19276: IMAP]: I/IMAP 14f994e5000:mail.<mydomain.net>:NA:TellThreadToDie: close socket connection 2022-02-11 16:42:25.220000 UTC - [Parent 19276: IMAP]: I/IMAP 14f994e5000:mail.<mydomain.net>:NA:CreateNewLineFromSocket: (null) 2022-02-11 16:42:25.220000 UTC - [Parent 19276: IMAP]: D/IMAP SetConnectionStatus(0x805a2ff4) 2022-02-11 16:42:25.220000 UTC - [Parent 19276: IMAP]: D/IMAP URL failed with code 0x805a2ff4 (imap://spencer%40<mydomain>%2Enet@mail.<mydomain.net>:993/discoverallboxes) 2022-02-11 16:42:25.220000 UTC - [Parent 19276: IMAP]: I/IMAP 14f99547000:mail.<mydomain.net>:NA:TellThreadToDie: close socket connection 2022-02-11 16:42:25.220000 UTC - [Parent 19276: IMAP]: I/IMAP 14f99547000:mail.<mydomain.net>:NA:CreateNewLineFromSocket: (null) 2022-02-11 16:42:25.220000 UTC - [Parent 19276: IMAP]: D/IMAP SetConnectionStatus(0x805a2ff4) 2022-02-11 16:42:25.220000 UTC - [Parent 19276: IMAP]: D/IMAP URL failed with code 0x805a2ff4 (imap://dmarc%40<mydomain>%2Enet@mail.<mydomain.net>:993/discoverallboxes) 2022-02-11 16:42:25.223000 UTC - [Parent 19276: IMAP]: I/IMAP 14f9953e800:mail.<mydomain.net>:NA:ProcessCurrentURL: aborting queued urls 2022-02-11 16:42:25.223000 UTC - [Parent 19276: IMAP]: I/IMAP 14f994e5000:mail.<mydomain.net>:NA:ProcessCurrentURL: aborting queued urls 2022-02-11 16:42:25.223000 UTC - [Parent 19276: IMAP]: D/IMAP ImapThreadMainLoop leaving [this=14f9953e800] 2022-02-11 16:42:25.223000 UTC - [Parent 19276: IMAP]: I/IMAP 14f99547000:mail.<mydomain.net>:NA:ProcessCurrentURL: aborting queued urls 2022-02-11 16:42:25.223000 UTC -
When I load Thunderbird it just says "Connected to..." and sits there indefinitely with a mouse spinner going. I don't get any error message in TB at all.
The mail server is on a virtual server I rent at Ionos and in TB the server settings I use are IMAP port 993, SSL/TLS and Encrypted Password with number of server connections to cache = 1. The firewall config on that virtual server hasn't changed and still allows port 993.
Are you sure the authentication method should be Encrypted Password? Ionos recommends 'normal password'. Encrypted Password authentication is very uncommon.
sfhowes said
Are you sure the authentication method should be Encrypted Password? Ionos recommends 'normal password'. Encrypted Password authentication is very uncommon.
It's been working fine as encrypted since I moved to Ionos a couple of years ago but I've just managed to fix the problem!
I found the mail server logs on my virtual server which said:
Feb 12 07:46:41 server dovecot: imap-login: Disconnected: Connection closed: SSL_accept() failed: error:14094412:SSL routines:ssl3_read_bytes:sslv3 alert bad certificate: SSL alert number 42 (no auth attempts in 0 secs): user=<>, rip=86.151.12.95, lip=213.171.210.120, TLS handshaking: SSL_accept() failed: error:14094412:SSL routines:ssl3_read_bytes:sslv3 alert bad certificate: SSL alert number 42, session=<+m9YYs3X0OtWlwxf>
So, on checking the LetsEncrypt SSL cert in Plesk, while it was showing SSL enabled on "Mail Access (IMAP, POP, SMTP)", it was showing "Wildcard SSL/TLS certificate" not secured. I forced a reissue, making sure the wildcard was ticked, and it's now working! I'm not quite sure why that affected given the mail access was secured but I'll settle for not knowing now it's working!
Thanks for everyone's replies