After upgrade - Sending of message failed - parsing errors

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

After upgrade - Sending of message failed - parsing errors

Post by entropicsinkhole » 2010-03-03 22:11

Hmail server 5.3.2 B1769
Thunderbird 3.0.2-3.0.3
Server 2003

Ever since we upgraded last night, we have been experiencing sporadic errors for different users. The Thunderbird error is: 'Sending of message failed....because the connection to SMTP server mydomain.com was in the middle of the transaction.' It does not happen to everyone, but seems to randomly happen to users.

In the hmailserver normal log, this is one of the 'troubled' entries, which gives the error 'An error occured while parsing data':

"IMAPD" 12924 34264 "2010-03-03 12:51:24.496" "10.0.0.103" "SENT: * OK IMAPrev1"
"IMAPD" 12924 34264 "2010-03-03 12:51:24.668" "10.0.0.103" "RECEIVED: 1 capability"
"IMAPD" 12924 34264 "2010-03-03 12:51:24.668" "10.0.0.103" "SENT: * CAPABILITY IMAP4 IMAP4rev1 CHILDREN IDLE QUOTA SORT ACL NAMESPACE RIGHTS=texk[nl]1 OK CAPABILITY completed"
"IMAPD" 12924 34264 "2010-03-03 12:51:24.730" "10.0.0.103" "RECEIVED: 2 login "myemail@mydomain" ***"
"IMAPD" 12924 34264 "2010-03-03 12:51:24.746" "10.0.0.103" "SENT: 2 OK LOGIN completed"
"IMAPD" 12924 34264 "2010-03-03 12:51:24.761" "10.0.0.103" "RECEIVED: 3 namespace"
"IMAPD" 12924 34264 "2010-03-03 12:51:24.761" "10.0.0.103" "SENT: * NAMESPACE (("" ".")) NIL (("Public" "."))[nl]3 OK namespace command complete"
"IMAPD" 12924 34264 "2010-03-03 12:51:24.793" "10.0.0.103" "RECEIVED: 4 lsub "" "*""
"IMAPD" 12924 34264 "2010-03-03 12:51:24.793" "10.0.0.103" "SENT: * LSUB (\HasNoChildren) "." "Sent"[nl]* LSUB (\HasNoChildren) "." "WPSHC Help Desk"[nl]* LSUB (\HasNoChildren) "." "Drafts"[nl]* LSUB (\HasNoChildren) "." "Deleted"[nl]* LSUB (\HasNoChildren) "." "Templates"[nl]* LSUB (\HasChildren) "." "INBOX"[nl]* LSUB (\HasNoChildren) "." "INBOX.All Users"[nl]* LSUB (\HasNoChildren) "." "INBOX.TO DO"[nl]* LSUB (\HasNoChildren) "." "Trash"[nl]* LSUB (\HasChildren) "." "Public.Test"[nl]* LSUB (\HasNoChildren) "." "Public.Test.denis"[nl]* LSUB (\HasNoChildren) "." "Public.Test.Heather"[nl]* LSUB (\HasChildren \Noselect) "." "Public""
"IMAPD" 12924 34264 "2010-03-03 12:51:24.793" "10.0.0.103" "SENT: 4 OK LSUB completed"
"IMAPD" 12924 34264 "2010-03-03 12:51:24.996" "10.0.0.103" "RECEIVED: 5 lsub "" "Public*""
"IMAPD" 12924 34264 "2010-03-03 12:51:24.996" "10.0.0.103" "SENT: * LSUB (\HasChildren) "." "Public.Test"[nl]* LSUB (\HasNoChildren) "." "Public.Test.denis"[nl]* LSUB (\HasNoChildren) "." "Public.Test.Heather"[nl]* LSUB (\HasChildren \Noselect) "." "Public""
"IMAPD" 12924 34264 "2010-03-03 12:51:24.996" "10.0.0.103" "SENT: 5 OK LSUB completed"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.090" "10.0.0.103" "RECEIVED: 7 select "INBOX""
"DEBUG" 12924 "2010-03-03 12:51:25.090" "Reading message from database"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.090" "10.0.0.103" "SENT: * 33 EXISTS[nl]* 1 RECENT[nl]* FLAGS (\Deleted \Seen \Draft \Answered \Flagged)[nl]* OK [UIDVALIDITY 1253002808][nl]* OK [UNSEEN 5573461][nl]* OK [PERMANENTFLAGS (\Deleted \Seen \Draft \Answered \Flagged)][nl]7 OK [READ-WRITE] SELECT completed"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.121" "10.0.0.103" "RECEIVED: 8 myrights "INBOX""
"IMAPD" 12924 34264 "2010-03-03 12:51:25.121" "10.0.0.103" "SENT: * MYRIGHTS "INBOX" lrswipkxtea[nl]8 OK Myrights complete"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.152" "10.0.0.103" "RECEIVED: 9 getacl "INBOX""
"IMAPD" 12924 34264 "2010-03-03 12:51:25.152" "10.0.0.103" "SENT: * ACL "INBOX"[nl]9 OK GetAcl complete"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.183" "10.0.0.103" "RECEIVED: 10 getquotaroot "INBOX""
"IMAPD" 12924 34264 "2010-03-03 12:51:25.183" "10.0.0.103" "SENT: * QUOTAROOT "INBOX" ""[nl]* QUOTA "" ()[nl]10 OK GETQUOTAROOT completed"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.199" "10.0.0.103" "RECEIVED: 11 UID fetch 1:* (FLAGS)"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.199" "10.0.0.103" "SENT: * 1 FETCH (UID 5573404 FLAGS (\Answered \Seen))"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.199" "10.0.0.103" "SENT: * 2 FETCH (UID 5573412 FLAGS (\Seen))"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.199" "10.0.0.103" "SENT: * 3 FETCH (UID 5573417 FLAGS (\Seen))"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.199" "10.0.0.103" "SENT: * 4 FETCH (UID 5573418 FLAGS (\Seen))"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.199" "10.0.0.103" "SENT: * 5 FETCH (UID 5573419 FLAGS (\Answered \Seen))"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.199" "10.0.0.103" "SENT: * 6 FETCH (UID 5573422 FLAGS (\Seen))"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.199" "10.0.0.103" "SENT: * 7 FETCH (UID 5573424 FLAGS (\Answered \Seen))"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.199" "10.0.0.103" "SENT: * 8 FETCH (UID 5573425 FLAGS (\Answered \Seen))"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.199" "10.0.0.103" "SENT: * 9 FETCH (UID 5573427 FLAGS (\Seen))"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.199" "10.0.0.103" "SENT: * 10 FETCH (UID 5573428 FLAGS (\Seen))"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.199" "10.0.0.103" "SENT: * 11 FETCH (UID 5573434 FLAGS (\Seen))"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.199" "10.0.0.103" "SENT: * 12 FETCH (UID 5573435 FLAGS (\Seen))"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.199" "10.0.0.103" "SENT: * 13 FETCH (UID 5573436 FLAGS (\Seen))"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.199" "10.0.0.103" "SENT: * 14 FETCH (UID 5573437 FLAGS (\Answered \Seen))"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.199" "10.0.0.103" "SENT: * 15 FETCH (UID 5573438 FLAGS (\Seen))"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.215" "10.0.0.103" "SENT: * 16 FETCH (UID 5573439 FLAGS (\Seen))"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.215" "10.0.0.103" "SENT: * 17 FETCH (UID 5573440 FLAGS (\Seen))"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.215" "10.0.0.103" "SENT: * 18 FETCH (UID 5573441 FLAGS (\Seen))"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.215" "10.0.0.103" "SENT: * 19 FETCH (UID 5573442 FLAGS (\Seen))"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.215" "10.0.0.103" "SENT: * 20 FETCH (UID 5573443 FLAGS (\Answered \Seen))"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.215" "10.0.0.103" "SENT: * 21 FETCH (UID 5573444 FLAGS (\Seen))"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.215" "10.0.0.103" "SENT: * 22 FETCH (UID 5573447 FLAGS (\Seen))"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.215" "10.0.0.103" "SENT: * 23 FETCH (UID 5573448 FLAGS (\Seen))"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.215" "10.0.0.103" "SENT: * 24 FETCH (UID 5573449 FLAGS (\Seen))"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.215" "10.0.0.103" "SENT: * 25 FETCH (UID 5573450 FLAGS (\Seen))"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.215" "10.0.0.103" "SENT: * 26 FETCH (UID 5573451 FLAGS (\Seen))"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.215" "10.0.0.103" "SENT: * 27 FETCH (UID 5573452 FLAGS (\Seen))"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.215" "10.0.0.103" "SENT: * 28 FETCH (UID 5573453 FLAGS (\Seen))"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.215" "10.0.0.103" "SENT: * 29 FETCH (UID 5573455 FLAGS (\Seen))"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.215" "10.0.0.103" "SENT: * 30 FETCH (UID 5573458 FLAGS (\Seen))"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.215" "10.0.0.103" "SENT: * 31 FETCH (UID 5573459 FLAGS (\Seen))"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.215" "10.0.0.103" "SENT: * 32 FETCH (UID 5573460 FLAGS (\Seen))"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.215" "10.0.0.103" "SENT: * 33 FETCH (UID 5573461 FLAGS ())"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.215" "10.0.0.103" "SENT: 11 OK UID completed"
"IMAPD" 12924 34264 "2010-03-03 12:51:25.465" "10.0.0.103" "RECEIVED: 12 UID fetch 5573461 (UID RFC822.SIZE FLAGS BODY.PEEK[HEADER.FIELDS (From To Cc Bcc Subject Date Message-ID Priority X-Priority References Newsgroups In-Reply-To Content-Type)])"
"ERROR" 12924 "2010-03-03 12:51:25.465" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 177, Data: 12 UID fetch 5573461 (UID RFC822.SIZE FLAGS BODY.PEEK[HEADER.FIELDS (From To Cc Bcc Subject Date Message-ID Priority X-Priority References Newsgroups In-Reply-To Content-Type)]). Remote IP: 10.0.0.103"
"DEBUG" 12924 "2010-03-03 12:51:25.465" "Ending session 34264"

The hmail error log has the following errors (the whole log itself is too long to post)

"ERROR" 4724 "2010-03-03 12:22:30.487" "Severity: 2 (High), Code: HM5313, Source: CriticalSection::Enter, Description: EnterCriticalSection gave an exception. Out of memory?"
"ERROR" 4724 "2010-03-03 12:22:30.487" "Severity: 2 (High), Code: HM5313, Source: CriticalSection::Enter, Description: EnterCriticalSection gave an exception. Out of memory?"
"ERROR" 4724 "2010-03-03 12:22:30.487" "Severity: 3 (Medium), Code: HM5138, Source: TCPConnection::_ProcessOperationQueue, Description: An error occured while processing the queue. Stage: 1 Remote IP: "
"ERROR" 9428 "2010-03-03 12:38:50.880" "Severity: 2 (High), Code: HM5313, Source: CriticalSection::Enter, Description: EnterCriticalSection gave an exception. Out of memory?"
"ERROR" 9428 "2010-03-03 12:38:50.896" "Severity: 2 (High), Code: HM5313, Source: CriticalSection::Enter, Description: EnterCriticalSection gave an exception. Out of memory?"
"ERROR" 9428 "2010-03-03 12:38:50.896" "Severity: 3 (Medium), Code: HM5138, Source: TCPConnection::_ProcessOperationQueue, Description: An error occured while processing the queue (std exception). Message: invalid vector<T> subscript. Stage: 1 Remote IP: "
"ERROR" 1924 "2010-03-03 12:41:41.566" "Severity: 2 (High), Code: HM5327, Source: TCPConnection::PostWrite, Description: An unknown error occurred while posting write buffer."
"ERROR" 1924 "2010-03-03 12:41:41.581" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 15, Data: TOP 5707 312457. Remote IP: 67.223.86.213"
"ERROR" 14000 "2010-03-03 12:41:41.800" "Severity: 2 (High), Code: HM5339, Source: TCPConnection::HandleWrite, Description: An unknown error occurred while handling buffer write."
"ERROR" 14000 "2010-03-03 12:41:41.831" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 9280 "2010-03-03 12:41:43.628" "Severity: 2 (High), Code: HM5327, Source: TCPConnection::PostWrite, Description: An unknown error occurred while posting write buffer."
"ERROR" 9280 "2010-03-03 12:41:43.628" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 16, Data: TOP 13055 147868. Remote IP: 67.223.90.59"
"ERROR" 1924 "2010-03-03 12:41:43.691" "Severity: 2 (High), Code: HM5328, Source: TCPConnection::PostWrite, Description: An unknown error occurred while posting write buffer."
"ERROR" 1924 "2010-03-03 12:41:43.691" "Severity: 2 (High), Code: HM5327, Source: TCPConnection::PostWrite, Description: An unknown error occurred while posting write buffer."
"ERROR" 1924 "2010-03-03 12:41:43.691" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 7, Data: TOP 1 0. Remote IP: 67.223.86.213"
"ERROR" 4512 "2010-03-03 12:41:53.363" "Severity: 2 (High), Code: HM5327, Source: TCPConnection::PostWrite, Description: An unknown error occurred while posting write buffer."
"ERROR" 4512 "2010-03-03 12:41:53.363" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 16, Data: TOP 13056 324739. Remote IP: 67.223.90.59"
"ERROR" 9100 "2010-03-03 12:42:05.800" "Severity: 2 (High), Code: HM5327, Source: TCPConnection::PostWrite, Description: An unknown error occurred while posting write buffer."
"ERROR" 9100 "2010-03-03 12:42:05.800" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 15, Data: TOP 13057 40422. Remote IP: 67.223.90.59"
"ERROR" 5480 "2010-03-03 12:42:16.159" "Severity: 2 (High), Code: HM5328, Source: TCPConnection::PostWrite, Description: An unknown error occurred while posting write buffer."
"ERROR" 5480 "2010-03-03 12:42:16.159" "Severity: 2 (High), Code: HM5327, Source: TCPConnection::PostWrite, Description: An unknown error occurred while posting write buffer."
"ERROR" 5480 "2010-03-03 12:42:16.159" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 15, Data: TOP 13061 98866. Remote IP: 67.223.90.59"
"ERROR" 4348 "2010-03-03 12:42:26.550" "Severity: 2 (High), Code: HM5327, Source: TCPConnection::PostWrite, Description: An unknown error occurred while posting write buffer."
"ERROR" 4348 "2010-03-03 12:42:26.550" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 15, Data: TOP 13064 37562. Remote IP: 67.223.90.59"
"ERROR" 12924 "2010-03-03 12:44:39.767" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 33, Data: A001 LOGIN "user1" "password". Remote IP: 10.0.0.40"
"ERROR" 12924 "2010-03-03 12:44:46.642" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 44, Data: A021 STATUS "INBOX" (MESSAGES UNSEEN RECENT). Remote IP: 10.0.0.40"
"ERROR" 12924 "2010-03-03 12:44:46.767" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 31, Data: A001 LOGIN "user2" "password". Remote IP: 10.0.0.40"
"ERROR" 4980 "2010-03-03 12:44:48.845" "Severity: 2 (High), Code: HM5327, Source: TCPConnection::PostWrite, Description: An unknown error occurred while posting write buffer."
"ERROR" 4980 "2010-03-03 12:44:48.845" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 15, Data: TOP 5707 312457. Remote IP: 67.223.86.213"
"ERROR" 6648 "2010-03-03 12:44:56.548" "Severity: 2 (High), Code: HM5327, Source: TCPConnection::PostWrite, Description: An unknown error occurred while posting write buffer."
"ERROR" 6648 "2010-03-03 12:44:56.548" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 15, Data: TOP 5708 183251. Remote IP: 67.223.86.213"
"ERROR" 12924 "2010-03-03 12:44:58.376" "Severity: 2 (High), Code: HM5325, Source: TCPConnection::Start, Description: An unknown error occurred while starting connection."
"ERROR" 12924 "2010-03-03 12:44:58.376" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 12924 "2010-03-03 12:45:11.610" "Severity: 2 (High), Code: HM5328, Source: TCPConnection::PostWrite, Description: An unknown error occurred while posting write buffer."
"ERROR" 12924 "2010-03-03 12:45:11.610" "Severity: 2 (High), Code: HM5327, Source: TCPConnection::PostWrite, Description: An unknown error occurred while posting write buffer."
"ERROR" 12924 "2010-03-03 12:45:11.610" "Severity: 2 (High), Code: HM5325, Source: TCPConnection::Start, Description: An unknown error occurred while starting connection."
"ERROR" 12924 "2010-03-03 12:45:11.610" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 9840 "2010-03-03 12:45:31.907" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 152, Data: 27 UID fetch 5568562 (BODY.PEEK[HEADER] BODY.PEEK[1.MIME] BODY.PEEK[1.1.MIME] BODY.PEEK[1.2.MIME] BODY.PEEK[2.MIME] BODY.PEEK[3.MIME] BODY.PEEK[4.MIME]). Remote IP: 10.0.2.93"
"ERROR" 4832 "2010-03-03 12:45:44.766" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 178, Data: 341 UID fetch 5615417 (UID RFC822.SIZE FLAGS BODY.PEEK[HEADER.FIELDS (From To Cc Bcc Subject Date Message-ID Priority X-Priority References Newsgroups In-Reply-To Content-Type)]). Remote IP: 10.0.0.100"
"ERROR" 4496 "2010-03-03 12:49:05.841" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 26, Data: A018 EXAMINE "INBOX.Trash". Remote IP: 10.0.0.40"
"ERROR" 12924 "2010-03-03 12:49:05.951" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 30, Data: A001 LOGIN "user3" "password". Remote IP: 10.0.0.40"
"ERROR" 12924 "2010-03-03 12:49:19.966" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 31, Data: A001 LOGIN "user4" "password". Remote IP: 10.0.0.40"
"ERROR" 12924 "2010-03-03 12:49:26.294" "Severity: 2 (High), Code: HM5325, Source: TCPConnection::Start, Description: An unknown error occurred while starting connection."
"ERROR" 12924 "2010-03-03 12:49:26.294" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 5392 "2010-03-03 12:49:43.935" "Severity: 1 (Critical), Code: HM5062, Source: IMAPFetch::_ReportCriticalError, Description: ERROR HM10002 - IMAP FETCH: Error when creating body structure for message. - D:\hMailServer\Data\mydomain.com.com\user6\4E\{4E980C97-3468-4AFF-AB5D-71AB73AB2634}.eml"
"ERROR" 5392 "2010-03-03 12:49:43.935" "Severity: 1 (Critical), Code: HM5062, Source: IMAPFetch::_ReportCriticalError, Description: ERROR HM10002 - IMAP FETCH: Error when creating body structure for message. - D:\hMailServer\Data\mydomain.com.com\dhildebrandt\B4\{B4223514-6551-45DF-A002-61CBC05C7B08}.eml"
"ERROR" 12924 "2010-03-03 12:49:54.934" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 33, Data: A001 LOGIN "user5" "password". Remote IP: 10.0.0.40"
"ERROR" 12924 "2010-03-03 12:49:58.606" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 33, Data: A001 LOGIN "user5" "password". Remote IP: 10.0.0.40"
"ERROR" 6920 "2010-03-03 12:50:14.340" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 178, Data: 541 UID fetch 5590271 (UID RFC822.SIZE FLAGS BODY.PEEK[HEADER.FIELDS (From To Cc Bcc Subject Date Message-ID Priority X-Priority References Newsgroups In-Reply-To Content-Type)]). Remote IP: 10.0.0.100"
"ERROR" 12924 "2010-03-03 12:50:15.590" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 176, Data: 6 UID fetch 5590271 (UID RFC822.SIZE FLAGS BODY.PEEK[HEADER.FIELDS (From To Cc Bcc Subject Date Message-ID Priority X-Priority References Newsgroups In-Reply-To Content-Type)]). Remote IP: 10.0.0.100"
"ERROR" 472 "2010-03-03 12:51:17.699" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 178, Data: 824 UID fetch 5590273 (UID RFC822.SIZE FLAGS BODY.PEEK[HEADER.FIELDS (From To Cc Bcc Subject Date Message-ID Priority X-Priority References Newsgroups In-Reply-To Content-Type)]). Remote IP: 10.0.0.100"
"ERROR" 12924 "2010-03-03 12:51:19.043" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 176, Data: 6 UID fetch 5590273 (UID RFC822.SIZE FLAGS BODY.PEEK[HEADER.FIELDS (From To Cc Bcc Subject Date Message-ID Priority X-Priority References Newsgroups In-Reply-To Content-Type)]). Remote IP: 10.0.0.100"
"ERROR" 12924 "2010-03-03 12:51:25.465" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 177, Data: 12 UID fetch 5573461 (UID RFC822.SIZE FLAGS BODY.PEEK[HEADER.FIELDS (From To Cc Bcc Subject Date Message-ID Priority X-Priority References Newsgroups In-Reply-To Content-Type)]). Remote IP: 10.0.0.103"
"ERROR" 12924 "2010-03-03 12:51:26.043" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 176, Data: 6 UID fetch 5573461 (UID RFC822.SIZE FLAGS BODY.PEEK[HEADER.FIELDS (From To Cc Bcc Subject Date Message-ID Priority X-Priority References Newsgroups In-Reply-To Content-Type)]). Remote IP: 10.0.0.103"
"ERROR" 12924 "2010-03-03 12:51:34.277" "Severity: 2 (High), Code: HM5325, Source: TCPConnection::Start, Description: An unknown error occurred while starting connection."
"ERROR" 12924 "2010-03-03 12:51:34.277" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 12924 "2010-03-03 12:51:34.683" "Severity: 2 (High), Code: HM5325, Source: TCPConnection::Start, Description: An unknown error occurred while starting connection."
"ERROR" 12924 "2010-03-03 12:51:34.683" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 12924 "2010-03-03 12:51:35.105" "Severity: 2 (High), Code: HM5325, Source: TCPConnection::Start, Description: An unknown error occurred while starting connection."
"ERROR" 12924 "2010-03-03 12:51:35.105" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 12924 "2010-03-03 12:51:35.496" "Severity: 2 (High), Code: HM5325, Source: TCPConnection::Start, Description: An unknown error occurred while starting connection."
"ERROR" 12924 "2010-03-03 12:51:35.496" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 12924 "2010-03-03 12:51:35.902" "Severity: 2 (High), Code: HM5325, Source: TCPConnection::Start, Description: An unknown error occurred while starting connection."
"ERROR" 12924 "2010-03-03 12:51:35.902" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 12924 "2010-03-03 12:51:36.308" "Severity: 2 (High), Code: HM5325, Source: TCPConnection::Start, Description: An unknown error occurred while starting connection."
"ERROR" 12924 "2010-03-03 12:51:36.308" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 12924 "2010-03-03 12:51:36.324" "Severity: 2 (High), Code: HM5325, Source: TCPConnection::Start, Description: An unknown error occurred while starting connection."
"ERROR" 12924 "2010-03-03 12:51:36.324" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 12924 "2010-03-03 12:51:36.339" "Severity: 2 (High), Code: HM5325, Source: TCPConnection::Start, Description: An unknown error occurred while starting connection."
"ERROR" 12924 "2010-03-03 12:51:36.339" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 12924 "2010-03-03 12:51:36.355" "Severity: 2 (High), Code: HM5325, Source: TCPConnection::Start, Description: An unknown error occurred while starting connection."
"ERROR" 12924 "2010-03-03 12:51:36.355" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 12924 "2010-03-03 12:51:36.714" "Severity: 2 (High), Code: HM5325, Source: TCPConnection::Start, Description: An unknown error occurred while starting connection."
"ERROR" 12924 "2010-03-03 12:51:36.714" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 12924 "2010-03-03 12:51:37.121" "Severity: 2 (High), Code: HM5325, Source: TCPConnection::Start, Description: An unknown error occurred while starting connection."
"ERROR" 12924 "2010-03-03 12:51:37.121" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 12924 "2010-03-03 12:51:37.714" "Severity: 2 (High), Code: HM5325, Source: TCPConnection::Start, Description: An unknown error occurred while starting connection."
"ERROR" 12924 "2010-03-03 12:51:37.714" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 12924 "2010-03-03 12:51:37.964" "Severity: 2 (High), Code: HM5325, Source: TCPConnection::Start, Description: An unknown error occurred while starting connection."
"ERROR" 12924 "2010-03-03 12:51:37.964" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 8144 "2010-03-03 12:51:45.792" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 8844 "2010-03-03 12:52:17.042" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 35, Data: RCPT TO: <user7@mydomain.com.com>. Remote IP: 127.0.0.1"
"ERROR" 12924 "2010-03-03 12:52:20.198" "Severity: 2 (High), Code: HM5325, Source: TCPConnection::Start, Description: An unknown error occurred while starting connection."
"ERROR" 12924 "2010-03-03 12:52:20.198" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 8844 "2010-03-03 12:52:28.417" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 35, Data: RCPT TO: <user7@mydomain.com.com>. Remote IP: 127.0.0.1"
"ERROR" 4716 "2010-03-03 12:52:32.073" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 29, Data: RCPT TO:<user8@mydomain.com.com>. Remote IP: 127.0.0.1"
"ERROR" 8844 "2010-03-03 12:52:32.073" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 29, Data: RCPT TO:<user8@mydomain.com.com>. Remote IP: 127.0.0.1"
"ERROR" 12924 "2010-03-03 12:52:32.198" "Severity: 2 (High), Code: HM5325, Source: TCPConnection::Start, Description: An unknown error occurred while starting connection."
"ERROR" 12924 "2010-03-03 12:52:32.198" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 8364 "2010-03-03 12:52:33.386" "Severity: 2 (High), Code: HM4207, Source: MySQLRecordset::GetStringValue, Description: An unknown error occurred while reading value from recordset: accountvacationsubject"
"ERROR" 8364 "2010-03-03 12:52:33.386" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 29, Data: RCPT TO:<user8@mydomain.com.com>. Remote IP: 127.0.0.1"
"ERROR" 8844 "2010-03-03 12:52:33.573" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 8364 "2010-03-03 12:52:36.354" "Severity: 2 (High), Code: HM4207, Source: MySQLRecordset::GetStringValue, Description: An unknown error occurred while reading value from recordset: accountvacationsubject"
"ERROR" 8364 "2010-03-03 12:52:36.354" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 29, Data: RCPT TO:<user8@mydomain.com.com>. Remote IP: 127.0.0.1"
"ERROR" 13524 "2010-03-03 12:52:36.526" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 9376 "2010-03-03 12:52:39.917" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 29, Data: RCPT TO:<user8@mydomain.com.com>. Remote IP: 127.0.0.1"
"ERROR" 8688 "2010-03-03 12:52:40.167" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 12304 "2010-03-03 12:52:42.276" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 16, Data: UGVwc2lkZW5DMQ==. Remote IP: 127.0.0.1"
"ERROR" 9376 "2010-03-03 12:52:42.510" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 35, Data: RCPT TO: <user7@mydomain.com.com>. Remote IP: 127.0.0.1"
"ERROR" 12924 "2010-03-03 12:52:47.964" "Severity: 2 (High), Code: HM5325, Source: TCPConnection::Start, Description: An unknown error occurred while starting connection."
"ERROR" 12924 "2010-03-03 12:52:47.964" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 12864 "2010-03-03 12:52:48.354" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 12924 "2010-03-03 12:53:00.791" "Severity: 2 (High), Code: HM5325, Source: TCPConnection::Start, Description: An unknown error occurred while starting connection."
"ERROR" 12924 "2010-03-03 12:53:00.791" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 12924 "2010-03-03 12:53:01.182" "Severity: 2 (High), Code: HM5325, Source: TCPConnection::Start, Description: An unknown error occurred while starting connection."
"ERROR" 12924 "2010-03-03 12:53:01.182" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 12924 "2010-03-03 12:53:01.588" "Severity: 2 (High), Code: HM5325, Source: TCPConnection::Start, Description: An unknown error occurred while starting connection."

DeanoX
Senior user
Senior user
Posts: 480
Joined: 2005-11-05 00:07
Location: Michigan

Re: After upgrade - Sending of message failed - parsing errors

Post by DeanoX » 2010-03-03 22:27

I assume that when you say you upgraded, that you are referring to hMailServer versions correct?

How much memory is hMail using? I see a few out of memory errors in the log.

Have you checked the mysql db for corruption? There are a few errors about that as well.

I would start with those items first.

DeanoX
Senior user
Senior user
Posts: 480
Joined: 2005-11-05 00:07
Location: Michigan

Re: After upgrade - Sending of message failed - parsing errors

Post by DeanoX » 2010-03-03 22:28

I also see a lot of TCP errors. Are you having any network or hardware problems?

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

Re: After upgrade - Sending of message failed - parsing errors

Post by entropicsinkhole » 2010-03-03 22:48

Actually, I think we've resolved it. The same night we upgraded hmailserver to the new version, we edited some firewall rules that someone caused some (but not all) internal users to resolve our mail server's host name to our external site IP address instead of our internal mail server IP, causing timeouts. It seems to have fixed the one user that it was currently happening to.

Thanks for the suggestions guys!

DeanoX
Senior user
Senior user
Posts: 480
Joined: 2005-11-05 00:07
Location: Michigan

Re: After upgrade - Sending of message failed - parsing errors

Post by DeanoX » 2010-03-03 22:49

Good deal, glad you got it fixed.

Post Reply