IMAP - new mail not received by mail client

Use this forum if you have installed hMailServer and want to ask a question related to a production release of hMailServer. Before posting, please read the troubleshooting guide. A large part of all reported issues are already described in detail here.
Post Reply
entropicsinkhole
Normal user
Normal user
Posts: 119
Joined: 2007-05-28 21:10

IMAP - new mail not received by mail client

Post by entropicsinkhole » 2011-10-05 16:54

Hmailserver (version 5.3.3 b1913, then tried 5.4 b1931)

Problem: Mail clients using IMAP (Thunderbird 3.1.1, 6.0, 7.0, 7.0.1; EM Client 3.0) often do not receive new mail until they restart client program (ie. settings to 'automatically download mail' and clicking the Get Mail/Send/Receive buttons do NOT work). Sometimes it happens all at once, but sometimes one person's client stops receiving new mail while another's is fine. Webmail (using Squirrelmail) is unaffected.

Other Useful Information:
-a few months back, we had to upgrade to hmailserver version 5.3.3 b1913 because we had issues with hmailserver crashing when it had 5-6 connections open - to my knowledge, until now, we have not had any more IMAP (or other) issues
-currently, we regularly have between 80-90 IMAP connections open

Steps I have already tried:

1) I initially looked at the mail client as the problem (since webmail was fine) We normally use Thunderbird, so we tried different versions. When the problem still occurred, we tried a new program: EM Client. The problem recurred.

2) Turned off desktop antiviruses

3) Checked for firewall settings

4) Rebooted server (several times)

5) Upgraded hmailserver from 5.3.3 b1913 to version 5.4 b1931

Below is a snippet of the hmailserver log (there are no errors in the error logs) -- the particular user was trying to get new mail (after not receiving our test emails), using the Get Mail button. He had not restarted Thunderbird at the time. The whole log is too big to post (since i have debug set to record everything), but if you want to see it let me know and I can email it.

"TCPIP" 3208 "2011-10-05 09:42:53.464" "TCP - 10.0.2.156 connected to 10.0.0.40:143."
"DEBUG" 3208 "2011-10-05 09:42:53.464" "Creating session 2958"
"IMAPD" 3208 2958 "2011-10-05 09:42:53.464" "10.0.2.156" "SENT: * OK IMAPrev1"
"IMAPD" 3208 2958 "2011-10-05 09:42:53.464" "10.0.2.156" "RECEIVED: 1 capability"
"IMAPD" 3208 2958 "2011-10-05 09:42:53.464" "10.0.2.156"

"IMAPD" 3208 2958 "2011-10-05 09:42:53.464" "10.0.2.156" "RECEIVED: 3 login "scampeau" ***"
"IMAPD" 3208 2958 "2011-10-05 09:42:53.464" "10.0.2.156" "SENT: 3 OK LOGIN completed"

"IMAPD" 3304 2958 "2011-10-05 09:42:53.480" "10.0.2.156" "RECEIVED: 4 append "Sent" (\Seen) {544}"

"DEBUG" 3208 "2011-10-05 09:42:53.511" "Saving message: {89EB4B23-8324-459F-B486-869A1D21CF18}.eml"

"IMAPD" 3208 2958 "2011-10-05 09:42:53.526" "10.0.2.156" "SENT: 4 OK APPEND completed"

"IMAPD" 3208 2958 "2011-10-05 09:42:53.573" "10.0.2.156" "RECEIVED: 5 logout"
"IMAPD" 3208 2958 "2011-10-05 09:42:53.573" "10.0.2.156" "SENT: * BYE Have a nice day[nl]5 OK Logout completed"
"DEBUG" 3208 "2011-10-05 09:42:53.573" "Closing TCP/IP socket"
"DEBUG" 3208 "2011-10-05 09:42:53.573" "Ending session 2958"


I also have some Thunderbird logs that may be helpful to look at, but again, they're too large to post. The folks at Mozilla bugzilla took a look at it (https://bugzilla.mozilla.org/show_bug.cgi?id=690868) and from their logs say that the server is not telling Thunderbird about new messages (they haven't seen the hmailserver logs).

Anyway, I'm open to suggestions!

User avatar
mattg
Moderator
Moderator
Posts: 20781
Joined: 2007-06-14 05:12
Location: 'The Outback' Australia

Re: IMAP - new mail not received by mail client

Post by mattg » 2011-10-05 17:09

In settings >> Protocols >> IMAP >> Advanced

what do you have checked?
Just 'cause I link to a page and say little else doesn't mean I am not being nice.
https://www.hmailserver.com/documentation

entropicsinkhole
Normal user
Normal user
Posts: 119
Joined: 2007-05-28 21:10

Re: IMAP - new mail not received by mail client

Post by entropicsinkhole » 2011-10-05 17:14

mattg wrote:In settings >> Protocols >> IMAP >> Advanced

what do you have checked?
Everything (IMAP Sort, Quota, Idle, ACL)

entropicsinkhole
Normal user
Normal user
Posts: 119
Joined: 2007-05-28 21:10

Re: IMAP - new mail not received by mail client

Post by entropicsinkhole » 2011-10-05 18:09

After reviewing my error logs, I may have found the culprit --HOPEFULLY. Only one user was having errors, so at first I just dismissed them. Prior to the upgrade to 3.4 b1931, we had these errors, which didn't point to specific problem files.

"ERROR" 3316 "2011-10-03 17:31:04.959" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 49, Data: 7 UID fetch 5570430 (UID RFC822.SIZE BODY.PEEK[]). Remote IP: 10.0.2.116"
"ERROR" 3384 "2011-10-03 17:37:17.399" "Severity: 3 (Medium), Code: HM4227, Source: File::ReadFile, Description: An unknown error occurred while reading file from disk."

Following the upgrade to 3.4 b1931, the messages were more specific, leading us to delete an enormous video file. I'm HOPING that this big file was causing the server to choke for everyone. I will keep you posted

Unable to retrieve folder list for account. Folder recursion not parseable. Account: 837, Parent: -1"
"ERROR" 6372 "2011-10-04 15:34:01.793" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 49, Data: 9 UID fetch 5570431 (UID RFC822.SIZE BODY.PEEK[]). Remote IP: 10.0.2.116"
"ERROR" 3524 "2011-10-04 15:34:03.137" "Severity: 3 (Medium), Code: HM5042, Source: File::ReadTextFile, Description: An unknown error occurred while reading a text file from disk."
"ERROR" 3524 "2011-10-04 15:34:03.168" "Severity: 1 (Critical), Code: HM5062, Source: IMAPFetch::_ReportCriticalError, Description: ERROR HM10001 - IMAP FETCH: Error when loading MIME message. - D:\hMailServer\Data\domain.com\user\B4\{B4CE25A7-8631-4ED4-B892-BA5D6B42EB15}.eml"
"ERROR" 3524 "2011-10-04 15:34:03.965" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 49, Data: 7 UID fetch 5570431 (UID RFC822.SIZE BODY.PEEK[]). Remote IP: 10.0.2.116"
"ERROR" 3524 "2011-10-04 15:34:04.122" "Severity: 3 (Medium), Code: HM4227, Source: File::ReadFile, Description: An unknown error occurred while reading file from disk."

Post Reply