Hmserver 5.3.2 b1769 stops responding - multiple errors logg

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

Hmserver 5.3.2 b1769 stops responding - multiple errors logg

Post by entropicsinkhole » 2010-04-30 19:08

Hmailserver 5.3.2, build 1769
System specs: Server 2003 SP2, Intel Xeon 3.6GHz (2 Processors), 6GB memory.
Using mysql 5.3.17 (not built-into hmailserver, but on the same server),
Antivirus: Bitdefender SMTP Proxy - Hmailserver does not use this directly--> Bitdefender passes the messages to HMailserver after the scan is complete. There is no live scanner running on the server.

Out of the blue, Hmailserver has stopped responding (both send and receive) twice in the past four days. We have made no changes to the server itself in the last month and the logs don't seem to help me pinpoint the cause of the error. The first time it happened, I was willing to dismiss it as a 'fluke', but now I am concerned that this is going to be an increasing problem. I can't seem to pinpoint the problem.

We have so far fixed the problem by stopping the hmailserver service through the Services console and restarting the MySQL service, then starting the hmailserver service again. If you try to stop it through the Hmail administrator console you get a popup error that says the following: The server threw an exception [Exception from HRESULT: 0x80010105 (RPC_E_SERVERFAULT)]..... and then the Stop button greys out and the service doesn't stop anyway.

Below is what the hmailserver error log has to say. These errors were also experienced the first time this happened and not at all when the problem was not occurring. The mail log itself is too large to post.

Error:

"ERROR" 5120 "2010-04-30 12:28:54.674" "Severity: 2 (High), Code: HM5339, Source: TCPConnection::HandleWrite, Description: An unknown error occurred while handling buffer write."
"ERROR" 5068 "2010-04-30 12:28:54.674" "Severity: 2 (High), Code: HM5339, Source: TCPConnection::HandleWrite, Description: An unknown error occurred while handling buffer write."
"ERROR" 5068 "2010-04-30 12:28:54.690" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 5120 "2010-04-30 12:28:54.690" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 4932 "2010-04-30 12:29:11.846" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 4, Data: LIST. Remote IP: 10.0.4.1"
"ERROR" 5068 "2010-04-30 12:29:12.799" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 4, Data: UIDL. Remote IP: 10.0.4.1"
"ERROR" 1620 "2010-04-30 12:29:32.814" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 4780 "2010-04-30 12:29:32.814" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 4932 "2010-04-30 12:29:35.329" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 4, Data: STAT. Remote IP: 10.0.3.71"
"ERROR" 4932 "2010-04-30 12:29:42.579" "Severity: 2 (High), Code: HM5327, Source: TCPConnection::PostWrite, Description: An unknown error occurred while posting write buffer."
"ERROR" 4932 "2010-04-30 12:29:42.579" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 4, Data: LIST. Remote IP: 24.146.9.151"
"ERROR" 4932 "2010-04-30 12:29:43.501" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 4, Data: LIST. Remote IP: 192.168.10.17"
"ERROR" 4932 "2010-04-30 12:29:45.032" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 4, Data: LIST. Remote IP: 10.0.4.42"
"ERROR" 4852 "2010-04-30 12:29:46.517" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 1620 "2010-04-30 12:29:46.548" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 4932 "2010-04-30 12:30:00.516" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 4, Data: STAT. Remote IP: 192.168.10.45"
"ERROR" 4932 "2010-04-30 12:30:00.798" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 4, Data: LIST. Remote IP: 10.0.4.87"
"ERROR" 1620 "2010-04-30 12:30:04.344" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 1620 "2010-04-30 12:30:04.376" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 4932 "2010-04-30 12:30:07.157" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 4, Data: STAT. Remote IP: 10.0.6.70"
"ERROR" 4436 "2010-04-30 12:30:11.110" "Severity: 1 (Critical), Code: HM5062, Source: IMAPFetch::_ReportCriticalError, Description: ERROR HM10001 - IMAP FETCH: Error when loading MIME message. - D:\hMailServer\Data\mydomain.com\csantary\55\{5510C65E-27C4-4405-A0AA-FF6C48059E91}.eml"
"ERROR" 4436 "2010-04-30 12:30:11.110" "Severity: 1 (Critical), Code: HM5062, Source: IMAPFetch::_ReportCriticalError, Description: ERROR HM10001 - IMAP FETCH: Error when loading MIME message. - D:\hMailServer\Data\mydomain.com\csantary\09\{0906D153-76B3-45EA-9464-1E0126DD44ED}.eml"
"ERROR" 4436 "2010-04-30 12:30:11.110" "Severity: 1 (Critical), Code: HM5062, Source: IMAPFetch::_ReportCriticalError, Description: ERROR HM10001 - IMAP FETCH: Error when loading MIME message. - D:\hMailServer\Data\mydomain.com\csantary\8D\{8D742D7D-D120-449F-97FB-5158BD8F744E}.eml"
"ERROR" 4436 "2010-04-30 12:30:11.110" "Severity: 1 (Critical), Code: HM5062, Source: IMAPFetch::_ReportCriticalError, Description: ERROR HM10001 - IMAP FETCH: Error when loading MIME message. - D:\hMailServer\Data\mydomain.com\csantary\EA\{EA68EE3B-9C49-42FF-8DF9-E2F1AB495B75}.eml"
"ERROR" 4436 "2010-04-30 12:30:11.126" "Severity: 1 (Critical), Code: HM5062, Source: IMAPFetch::_ReportCriticalError, Description: ERROR HM10001 - IMAP FETCH: Error when loading MIME message. - D:\hMailServer\Data\mydomain.com\csantary\82\{82F1A016-F231-40B9-BE95-660F41B4FAF5}.eml"
"ERROR" 4436 "2010-04-30 12:30:11.126" "Severity: 1 (Critical), Code: HM5062, Source: IMAPFetch::_ReportCriticalError, Description: ERROR HM10001 - IMAP FETCH: Error when loading MIME message. - D:\hMailServer\Data\mydomain.com\csantary\CB\{CB73500D-C30D-443D-A044-FE5E8974BA47}.eml"
"ERROR" 4436 "2010-04-30 12:30:11.157" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 150, Data: A009 UID FETCH 1:* (FLAGS UID RFC822.SIZE INTERNALDATE BODY.PEEK[HEADER.FIELDS (Date To Cc From Subject X-Priority Importance Priority Content-Type)]). Remote IP: 10.0.0.40"
"ERROR" 1620 "2010-04-30 12:30:11.688" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 1620 "2010-04-30 12:30:11.719" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 4112 "2010-04-30 12:30:15.141" "Severity: 1 (Critical), Code: HM5062, Source: IMAPFetch::_ReportCriticalError, Description: ERROR HM10001 - IMAP FETCH: Error when loading MIME message. - D:\hMailServer\Data\mydomain.com\csantary\55\{5510C65E-27C4-4405-A0AA-FF6C48059E91}.eml"
"ERROR" 4112 "2010-04-30 12:30:15.141" "Severity: 1 (Critical), Code: HM5062, Source: IMAPFetch::_ReportCriticalError, Description: ERROR HM10001 - IMAP FETCH: Error when loading MIME message. - D:\hMailServer\Data\mydomain.com\csantary\09\{0906D153-76B3-45EA-9464-1E0126DD44ED}.eml"
"ERROR" 4112 "2010-04-30 12:30:15.141" "Severity: 1 (Critical), Code: HM5062, Source: IMAPFetch::_ReportCriticalError, Description: ERROR HM10001 - IMAP FETCH: Error when loading MIME message. - D:\hMailServer\Data\mydomain.com\csantary\8D\{8D742D7D-D120-449F-97FB-5158BD8F744E}.eml"
"ERROR" 4112 "2010-04-30 12:30:15.141" "Severity: 1 (Critical), Code: HM5062, Source: IMAPFetch::_ReportCriticalError, Description: ERROR HM10001 - IMAP FETCH: Error when loading MIME message. - D:\hMailServer\Data\mydomain.com\csantary\EA\{EA68EE3B-9C49-42FF-8DF9-E2F1AB495B75}.eml"
"ERROR" 4112 "2010-04-30 12:30:15.141" "Severity: 1 (Critical), Code: HM5062, Source: IMAPFetch::_ReportCriticalError, Description: ERROR HM10001 - IMAP FETCH: Error when loading MIME message. - D:\hMailServer\Data\mydomain.com\csantary\82\{82F1A016-F231-40B9-BE95-660F41B4FAF5}.eml"
"ERROR" 4112 "2010-04-30 12:30:15.157" "Severity: 1 (Critical), Code: HM5062, Source: IMAPFetch::_ReportCriticalError, Description: ERROR HM10001 - IMAP FETCH: Error when loading MIME message. - D:\hMailServer\Data\mydomain.com\csantary\CB\{CB73500D-C30D-443D-A044-FE5E8974BA47}.eml"
"ERROR" 4112 "2010-04-30 12:30:15.157" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 150, Data: A009 UID FETCH 1:* (FLAGS UID RFC822.SIZE INTERNALDATE BODY.PEEK[HEADER.FIELDS (Date To Cc From Subject X-Priority Importance Priority Content-Type)]). Remote IP: 10.0.0.40"
"ERROR" 4588 "2010-04-30 12:30:19.516" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 4, Data: LIST. Remote IP: 192.168.10.13"
"ERROR" 5100 "2010-04-30 12:30:19.672" "Severity: 1 (Critical), Code: HM5062, Source: IMAPFetch::_ReportCriticalError, Description: ERROR HM10001 - IMAP FETCH: Error when loading MIME message. - D:\hMailServer\Data\mydomain.com\csantary\55\{5510C65E-27C4-4405-A0AA-FF6C48059E91}.eml"
"ERROR" 5100 "2010-04-30 12:30:19.672" "Severity: 1 (Critical), Code: HM5062, Source: IMAPFetch::_ReportCriticalError, Description: ERROR HM10001 - IMAP FETCH: Error when loading MIME message. - D:\hMailServer\Data\mydomain.com\csantary\09\{0906D153-76B3-45EA-9464-1E0126DD44ED}.eml"
"ERROR" 5100 "2010-04-30 12:30:19.672" "Severity: 1 (Critical), Code: HM5062, Source: IMAPFetch::_ReportCriticalError, Description: ERROR HM10001 - IMAP FETCH: Error when loading MIME message. - D:\hMailServer\Data\mydomain.com\csantary\8D\{8D742D7D-D120-449F-97FB-5158BD8F744E}.eml"
"ERROR" 5100 "2010-04-30 12:30:19.672" "Severity: 1 (Critical), Code: HM5062, Source: IMAPFetch::_ReportCriticalError, Description: ERROR HM10001 - IMAP FETCH: Error when loading MIME message. - D:\hMailServer\Data\mydomain.com\csantary\EA\{EA68EE3B-9C49-42FF-8DF9-E2F1AB495B75}.eml"
"ERROR" 5100 "2010-04-30 12:30:19.672" "Severity: 1 (Critical), Code: HM5062, Source: IMAPFetch::_ReportCriticalError, Description: ERROR HM10001 - IMAP FETCH: Error when loading MIME message. - D:\hMailServer\Data\mydomain.com\csantary\82\{82F1A016-F231-40B9-BE95-660F41B4FAF5}.eml"
"ERROR" 5100 "2010-04-30 12:30:19.672" "Severity: 1 (Critical), Code: HM5062, Source: IMAPFetch::_ReportCriticalError, Description: ERROR HM10001 - IMAP FETCH: Error when loading MIME message. - D:\hMailServer\Data\mydomain.com\csantary\CB\{CB73500D-C30D-443D-A044-FE5E8974BA47}.eml"
"ERROR" 5100 "2010-04-30 12:30:19.688" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 150, Data: A009 UID FETCH 1:* (FLAGS UID RFC822.SIZE INTERNALDATE BODY.PEEK[HEADER.FIELDS (Date To Cc From Subject X-Priority Importance Priority Content-Type)]). Remote IP: 10.0.0.40"
"ERROR" 5124 "2010-04-30 12:30:19.766" "Severity: 2 (High), Code: HM5327, Source: TCPConnection::PostWrite, Description: An unknown error occurred while posting write buffer."
"ERROR" 5124 "2010-04-30 12:30:19.766" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 4, Data: LIST. Remote IP: 10.0.2.102"
"ERROR" 4588 "2010-04-30 12:30:25.438" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 4, Data: LIST. Remote IP: 10.0.2.69"
"ERROR" 4032 "2010-04-30 12:30:25.672" "Severity: 2 (High), Code: HM4223, Source: DeliveryTask::DoWork, Description: SMTPDeliverer::DeliverMessage() failed"
"ERROR" 4256 "2010-04-30 12:30:28.719" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 5080 "2010-04-30 12:30:32.016" "Severity: 1 (Critical), Code: HM5062, Source: IMAPFetch::_ReportCriticalError, Description: ERROR HM10001 - IMAP FETCH: Error when loading MIME message. - D:\hMailServer\Data\mydomain.com\csantary\55\{5510C65E-27C4-4405-A0AA-FF6C48059E91}.eml"
"ERROR" 5080 "2010-04-30 12:30:32.016" "Severity: 1 (Critical), Code: HM5062, Source: IMAPFetch::_ReportCriticalError, Description: ERROR HM10001 - IMAP FETCH: Error when loading MIME message. - D:\hMailServer\Data\mydomain.com\csantary\09\{0906D153-76B3-45EA-9464-1E0126DD44ED}.eml"
"ERROR" 5080 "2010-04-30 12:30:32.016" "Severity: 1 (Critical), Code: HM5062, Source: IMAPFetch::_ReportCriticalError, Description: ERROR HM10001 - IMAP FETCH: Error when loading MIME message. - D:\hMailServer\Data\mydomain.com\csantary\8D\{8D742D7D-D120-449F-97FB-5158BD8F744E}.eml"
"ERROR" 5080 "2010-04-30 12:30:32.031" "Severity: 1 (Critical), Code: HM5062, Source: IMAPFetch::_ReportCriticalError, Description: ERROR HM10001 - IMAP FETCH: Error when loading MIME message. - D:\hMailServer\Data\mydomain.com\csantary\EA\{EA68EE3B-9C49-42FF-8DF9-E2F1AB495B75}.eml"
"ERROR" 5080 "2010-04-30 12:30:32.031" "Severity: 1 (Critical), Code: HM5062, Source: IMAPFetch::_ReportCriticalError, Description: ERROR HM10001 - IMAP FETCH: Error when loading MIME message. - D:\hMailServer\Data\mydomain.com\csantary\82\{82F1A016-F231-40B9-BE95-660F41B4FAF5}.eml"
"ERROR" 5080 "2010-04-30 12:30:32.031" "Severity: 1 (Critical), Code: HM5062, Source: IMAPFetch::_ReportCriticalError, Description: ERROR HM10001 - IMAP FETCH: Error when loading MIME message. - D:\hMailServer\Data\mydomain.com\csantary\CB\{CB73500D-C30D-443D-A044-FE5E8974BA47}.eml"
"ERROR" 5080 "2010-04-30 12:30:32.031" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 150, Data: A009 UID FETCH 1:* (FLAGS UID RFC822.SIZE INTERNALDATE BODY.PEEK[HEADER.FIELDS (Date To Cc From Subject X-Priority Importance Priority Content-Type)]). Remote IP: 10.0.0.40"
"ERROR" 1620 "2010-04-30 12:30:33.422" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 4588 "2010-04-30 12:30:40.609" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 4, Data: LIST. Remote IP: 10.0.2.46"
"ERROR" 4796 "2010-04-30 12:31:05.296" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 1620 "2010-04-30 12:31:05.328" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 5160 "2010-04-30 12:31:15.093" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 1620 "2010-04-30 12:31:15.124" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 4532 "2010-04-30 12:31:18.718" "Severity: 1 (Critical), Code: HM5062, Source: IMAPFetch::_ReportCriticalError, Description: ERROR HM10001 - IMAP FETCH: Error when loading MIME message. - D:\hMailServer\Data\mydomain.com\lab2\29\{29F8EF23-F40C-45C3-A5C0-947177FF2002}.eml"
"ERROR" 4880 "2010-04-30 12:31:20.406" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 9000 "2010-04-30 12:31:34.671" "Severity: 2 (High), Code: HM5317, Source: DeliveryTask::DoWork, Description: An error occurred while download messages from external account."
"ERROR" 5292 "2010-04-30 12:31:35.640" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 4, Data: UIDL. Remote IP: 10.0.3.69"
"ERROR" 5292 "2010-04-30 12:31:42.514" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 4, Data: STAT. Remote IP: 24.146.9.151"
"ERROR" 4604 "2010-04-30 12:31:42.874" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 4, Data: LIST. Remote IP: 67.223.77.158"
"ERROR" 4140 "2010-04-30 12:31:43.452" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 4, Data: LIST. Remote IP: 192.168.10.17"
"ERROR" 5292 "2010-04-30 12:31:43.468" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 4, Data: UIDL. Remote IP: 67.223.77.158"
"ERROR" 4672 "2010-04-30 12:31:47.264" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 4908 "2010-04-30 12:32:21.217" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 1620 "2010-04-30 12:32:21.232" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 5104 "2010-04-30 12:32:28.607" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 5092 "2010-04-30 12:32:28.623" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 1620 "2010-04-30 12:32:43.670" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 1620 "2010-04-30 12:32:43.701" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 1620 "2010-04-30 12:32:51.326" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 5316 "2010-04-30 12:32:51.357" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 1620 "2010-04-30 12:32:57.029" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 1620 "2010-04-30 12:32:57.076" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 1620 "2010-04-30 12:33:00.060" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 1620 "2010-04-30 12:33:00.091" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 1620 "2010-04-30 12:33:02.075" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 1620 "2010-04-30 12:33:02.107" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 4252 "2010-04-30 12:33:04.185" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 1620 "2010-04-30 12:33:04.232" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 4944 "2010-04-30 12:33:08.622" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 1620 "2010-04-30 12:33:20.544" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 1620 "2010-04-30 12:33:36.762" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 4572 "2010-04-30 12:33:54.606" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 5104 "2010-04-30 12:33:54.637" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 1620 "2010-04-30 12:33:54.684" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 5208 "2010-04-30 12:33:54.699" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 127.0.0.1"
"ERROR" 1608 "2010-04-30 12:34:01.137" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 4604 "2010-04-30 12:34:01.137" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 5280 "2010-04-30 12:34:01.137" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 5248 "2010-04-30 12:34:01.152" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 4880 "2010-04-30 12:34:02.293" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 10.0.0.100"
"ERROR" 4880 "2010-04-30 12:34:24.730" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 10.0.0.100"
"ERROR" 4880 "2010-04-30 12:34:25.668" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 10.0.0.100"

Bill48105
Developer
Developer
Posts: 6192
Joined: 2010-04-24 23:16
Location: Michigan, USA

Re: Hmserver 5.3.2 b1769 stops responding - multiple errors

Post by Bill48105 » 2010-04-30 20:30

Man hope this isn't a trend starting..

Do the mysql logs tell you anything to narrow it down to hmail for sure? Wonder if too many connections are coming in at once or too many connections into sql for it..

On the other thread which looked like same issue I asked about possibility of hard drive issues. That possible with yours? (IDE/ATAPI/HDD Event log entries, chkdsk ran, surface scan, smart status, etc)
hMailServer build LIVE on my servers: 5.4-B2014050402
#hmailserver on FreeNode IRC https://webchat.freenode.net/?channels=#hmailserver
*** ABSENT FROM hMail! Those in IRC know how to find me if urgent. ***

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

Re: Hmserver 5.3.2 b1769 stops responding - multiple errors

Post by entropicsinkhole » 2010-04-30 21:14

Do the mysql logs tell you anything to narrow it down to hmail for sure?
Nope. We turned on the mysql.err and mysql-slow logs last time this happened just in case we needed them, but they don't report anything out of the ordinary. I'm not sure if there's a more useful log available, but those are the ones that seemed most likely to help (but they didn't :-/ )
On the other thread which looked like same issue I asked about possibility of hard drive issues. That possible with yours?
Nope, no changes or errors reported in hardware. I saw the hard drive thread as well when I was looking for solutions on the forum, and the reason I passed it by was because I didn't have any hard drive related issues.

Bill48105
Developer
Developer
Posts: 6192
Joined: 2010-04-24 23:16
Location: Michigan, USA

Re: Hmserver 5.3.2 b1769 stops responding - multiple errors

Post by Bill48105 » 2010-04-30 21:20

Ok bummer. Hoped to clear a couple of things out of the way before someone more familiar with hmail stepped in. You happen to have hmail logs from just before the problem started to perhaps narrow it down a bit? Maybe looking at logs just before the problem at 2 different times might give a clue as to what was going on that might have led to the problem..
Bill
hMailServer build LIVE on my servers: 5.4-B2014050402
#hmailserver on FreeNode IRC https://webchat.freenode.net/?channels=#hmailserver
*** ABSENT FROM hMail! Those in IRC know how to find me if urgent. ***

^DooM^
Site Admin
Posts: 13861
Joined: 2005-07-29 16:18
Location: UK

Re: Hmserver 5.3.2 b1769 stops responding - multiple errors

Post by ^DooM^ » 2010-04-30 21:26

That looks very broken. After the restart of the service it was working again?

stop button in the hMail admin does not stop the hmail service btw just stops mail being sent/accepted.
If at first you don't succeed, bomb disposal probably isn't for you! ヅ

User avatar
martin
Developer
Developer
Posts: 6834
Joined: 2003-11-21 01:09
Location: Sweden
Contact:

Re: Hmserver 5.3.2 b1769 stops responding - multiple errors

Post by martin » 2010-05-01 19:02

I would suggest that all logging is enabled, and the next time it happens send me the log at martin@hmailserver.com.

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

Re: Hmserver 5.3.2 b1769 stops responding - multiple errors

Post by entropicsinkhole » 2010-05-05 19:08

Sorry for the late response. I was away for a few days. Thankfully, the issue didn't re-occur in my absence
^DooM^ wrote:That looks very broken. After the restart of the service it was working again?
Yep, both times it happened.
martin wrote:I would suggest that all logging is enabled
Happily, I had all logging enabled at the time, so I am going to email you the relevant part of the log from April 30th today.

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

Re: Hmserver 5.3.2 b1769 stops responding - multiple errors

Post by entropicsinkhole » 2010-05-27 19:32

martin wrote:I would suggest that all logging is enabled, and the next time it happens send me the log at martin@hmailserver.com.
Happened again today. Same kinds of errors. Resolved by restarting hmailserver service and MySQL services. Will send you the logs

craigeo
Normal user
Normal user
Posts: 50
Joined: 2010-04-22 22:43

Re: Hmserver 5.3.2 b1769 stops responding - multiple errors

Post by craigeo » 2010-09-21 20:32

Did you get any resolution on this? I have the same problem with similar looking logs.
I have had it happening for weeks.
I have my monitoring automatically restarting the hmailserver service when it fails.

craigeo
Normal user
Normal user
Posts: 50
Joined: 2010-04-22 22:43

Re: Hmserver 5.3.2 b1769 stops responding - multiple errors

Post by craigeo » 2010-09-25 00:03

I have now gone a full 24 hours without an hmail crash.
This is a good sign as I hadn't gone that long in many months.
I did multiple things to try to fix. I have now turned the script back on and will see if the crashing happens again.
Here's the things I did:
1. Disabled a script I had for Sub OnDeliverMessage(oMessage) that would use vb logic to create a path on a network share based on day and time and make a copy of the email.
2. Changed max smtp connections from unlimited to 20, pop from unlimited to 10, & imap from unlimited to 10
3. Unchecked IMAP Idle
4. Changed threading. All (command threads, delivery threads, & asynchronous task threads) from 20 to 4. And Worker thread priority from normal to below normal. Then restarted hmail.

The cpu usage was greatly reduced on the server but mail still flows fine. My system averages about 3600 messages per hour.

Post Reply