hMailServer crashes/stops

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.
blakito
Normal user
Normal user
Posts: 30
Joined: 2010-09-15 14:27

hMailServer crashes/stops

Post by blakito » 2010-11-10 15:49

Hi mates,

Ive been using hMailServer for months without any issues but unfortunately two days ago it started to crash randomly. The only solution is to restart hMailServer service manually.

Nothing changed on the server (hardware/patch/update/version) before/after this issue.

Server configuration:
Intel Xeon X3450 @ 2.67
4Gb Memory ECC
Windows 2008 R2 64Bits
hMailServer latest stable version
Using Plesk, but not integrated
No Antivirus active at the moment
No External Antispam, custom scripts or SSL configured
Around 200 mailboxes from 5 different domains
90% uses Microsoft Mail Clients, 10% IMAP thru Blackberry/iPhone

Last log lines before todays crash:

Code: Select all

"POP3D"	4080	19852	"2010-11-10 00:20:22.627"	"201.22.124.37"	"RECEIVED: QUIT"
"POP3D"	4080	19852	"2010-11-10 00:20:22.627"	"201.22.124.37"	"SENT: +OK POP3 server saying goodbye..."
"IMAPD"	3504	271	"2010-11-10 00:20:23.063"	"67.223.86.3"	"RECEIVED: DONE"
"IMAPD"	3504	271	"2010-11-10 00:20:23.063"	"67.223.86.3"	"SENT: i OK IDLE terminated"
"IMAPD"	2600	271	"2010-11-10 00:20:23.126"	"67.223.86.3"	"RECEIVED: f UID FETCH 7675:* (UID FLAGS)"
"IMAPD"	2600	271	"2010-11-10 00:20:23.126"	"67.223.86.3"	"SENT: * 14 FETCH (UID 7675 FLAGS ())"
"ERROR"	2600	"2010-11-10 00:20:23.126"	"Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 30, Data: f UID FETCH 7675:* (UID FLAGS). Remote IP: 67.223.86.3"
Few lines from the error log:

Code: Select all

"ERROR"	420	"2010-11-10 00:17:55.596"	"Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 17, Data: PASS **password**. Remote IP: 127.0.0.1"
"ERROR"	4080	"2010-11-10 00:17:57.624"	"Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 17, Data: PASS **password**. Remote IP: 127.0.0.1"
"ERROR"	372	"2010-11-10 00:18:16.454"	"Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 17, Data: PASS **password**. Remote IP: 201.22.124.37"
"ERROR"	4076	"2010-11-10 00:19:18.791"	"Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 17, Data: PASS **password**. Remote IP: 201.22.124.37"
"ERROR"	1944	"2010-11-10 00:19:20.305"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR"	2968	"2010-11-10 00:20:13.516"	"Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 12, Data: c CAPABILITY. Remote IP: 67.223.65.30"
"ERROR"	420	"2010-11-10 00:20:20.942"	"Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 17, Data: PASS **password**. Remote IP: 201.22.124.37"
"ERROR"	2600	"2010-11-10 00:20:23.126"	"Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 30, Data: f UID FETCH 7675:* (UID FLAGS). Remote IP: 67.223.86.3"
The weirdest thing is that the **password** is showing up as plain text in error log as a unknown command. This client (201.22.124.37) uses Outlook Express as mail client.

Ive down-graded the performance settings but didnt seem to help much, seems placebo.

Best regards

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

Re: hMailServer crashes/stops

Post by mattg » 2010-11-11 02:04

When you say 'crash' what exactly happens?

User can't log on via POP3?
Refuses new SMTP connects from remote servers?
Administration GUI becomes unresponsive?

Are there any event errors recorded by the OS at the time?
Just 'cause I link to a page and say little else doesn't mean I am not being nice.
https://www.hmailserver.com/documentation

blakito
Normal user
Normal user
Posts: 30
Joined: 2010-09-15 14:27

Re: hMailServer crashes/stops

Post by blakito » 2010-11-11 14:56

Hey matt, thanks for the feedack.

Users cant login anywhere and all emails are rejected (no new email arrives, only if i manually restart hMailServer service).
Administration GUI still operating (at least for read access), but if i press STOP it will hang for a long time before a big error appears.
No Event Log errors, except if i manually restart hMailServer service - then it shows something related to PIPE or TIMEOUT and the server stops.

Looks like im not the only one having crash issues in the past few days ...
hMailServer is working ok since i created this topic (nothing changed), but im not sure for how long.

Best regards

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

Re: hMailServer crashes/stops

Post by mattg » 2010-11-11 15:16

sorry, more questions... :?
what exact version of hMailserver?
What database do you use?

And yes there seems to be a bit of it about.
Just 'cause I link to a page and say little else doesn't mean I am not being nice.
https://www.hmailserver.com/documentation

blakito
Normal user
Normal user
Posts: 30
Joined: 2010-09-15 14:27

Re: hMailServer crashes/stops

Post by blakito » 2010-11-11 18:43

Hey there !

Version: hMailServer 5.3.3, build 1879
Database: MySQL 5.0.45-community-nt

Code: Select all

Tabela	 Ação	Registros	Tipo	Collation	Tamanho	Sobrecarga
	hm_accounts		 		 		 	~142	InnoDB	utf8_general_ci	112.0 KB	-
	hm_acl		 		 		 	~0	InnoDB	utf8_general_ci	48.0 KB	-
	hm_aliases		 		 		 	~5	InnoDB	utf8_general_ci	64.0 KB	-
	hm_blocked_attachments		 		 		 	~13	InnoDB	utf8_general_ci	32.0 KB	-
	hm_dbversion		 		 		 	~1	InnoDB	utf8_general_ci	16.0 KB	-
	hm_distributionlists		 		 		 	~3	InnoDB	utf8_general_ci	64.0 KB	-
	hm_distributionlistsrecipients		 		 		 	~144	InnoDB	utf8_general_ci	48.0 KB	-
	hm_dnsbl		 		 		 	~7	InnoDB	utf8_general_ci	32.0 KB	-
	hm_domains		 		 		 	~8	InnoDB	utf8_general_ci	64.0 KB	-
	hm_domain_aliases		 		 		 	~0	InnoDB	utf8_general_ci	32.0 KB	-
	hm_fetchaccounts		 		 		 	~1	InnoDB	utf8_general_ci	32.0 KB	-
	hm_fetchaccounts_uids		 		 		 	~201	InnoDB	utf8_general_ci	48.0 KB	-
	hm_greylisting_triplets		 		 		 	~4	InnoDB	utf8_general_ci	48.0 KB	-
	hm_greylisting_whiteaddresses		 		 		 	~0	InnoDB	utf8_general_ci	32.0 KB	-
	hm_groups		 		 		 	~0	InnoDB	utf8_general_ci	32.0 KB	-
	hm_group_members		 		 		 	~0	InnoDB	utf8_general_ci	32.0 KB	-
	hm_imapfolders		 		 		 	~177	InnoDB	utf8_general_ci	64.0 KB	-
	hm_incoming_relays		 		 		 	~0	InnoDB	utf8_general_ci	32.0 KB	-
	hm_logon_failures		 		 		 	~0	InnoDB	utf8_general_ci	48.0 KB	-
	hm_messagerecipients		 		 		 	~7	InnoDB	utf8_general_ci	48.0 KB	-
	hm_messages		 		 		 	~9,032	InnoDB	utf8_general_ci	4.0 MB	-
	hm_message_metadata		 		 		 	~0	InnoDB	utf8_general_ci	48.0 KB	-
	hm_routeaddresses		 		 		 	~0	InnoDB	utf8_general_ci	32.0 KB	-
	hm_routes		 		 		 	~0	InnoDB	utf8_general_ci	32.0 KB	-
	hm_rules		 		 		 	~0	InnoDB	utf8_general_ci	48.0 KB	-
	hm_rule_actions		 		 		 	~0	InnoDB	utf8_general_ci	48.0 KB	-
	hm_rule_criterias		 		 		 	~0	InnoDB	utf8_general_ci	48.0 KB	-
	hm_securityranges		 		 		 	~2	InnoDB	utf8_general_ci	48.0 KB	-
	hm_servermessages		 		 		 	~7	InnoDB	utf8_general_ci	32.0 KB	-
	hm_settings		 		 		 	~101	InnoDB	utf8_general_ci	48.0 KB	-
	hm_sslcertificates		 		 		 	~0	InnoDB	utf8_general_ci	32.0 KB	-
	hm_surblservers		 		 		 	~1	InnoDB	utf8_general_ci	32.0 KB	-
	hm_tcpipports		 		 		 	~3	InnoDB	utf8_general_ci	32.0 KB	-
	hm_whitelist		 		 		 	~0	InnoDB	utf8_general_ci	32.0 KB	-
34 tabela(s)	 Soma	~9,859	InnoDB	utf8_general_ci	5.4 MB	0 Bytes
No big deal :(

Thank you very much for helping.

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

Re: hMailServer crashes/stops

Post by Bill48105 » 2010-11-12 17:31

Hey blakito,
I suspect the problem lies with unreliable connections and your logs help confirm that as the IP shown is a RIM/blackberry IP.. Odds are the data connection dropped or didn't complete transfer properly & hmail freaked. I think hmail's responses to problems need to be addressed to help recover more gracefully from these sort of things. Do realize if the connection is unreliable you WILL get errors & there is no way to stop that but seems hmail needs to recover better when it does happen..

Can you please show me the last hmail log lines before it was restarted? Perhaps did you have some IOCP error in there? The logs you posted were just TCP & normally that's not how hmail ends when this happens in cases I've seen..
Thx
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. ***

blakito
Normal user
Normal user
Posts: 30
Joined: 2010-09-15 14:27

Re: hMailServer crashes/stops

Post by blakito » 2010-11-25 15:02

Hey Bill, sorry for the late.

The same issue happened three times this week, again a few minutes ago.
I've been busy on a storage project and couldn't give the mail server the attention it deserves.

There goes:

ERROR_hmailserver_2010-11-25

Code: Select all

"ERROR"	4348	"2010-11-25 10:41:35.207"	"Severity: 2 (High), Code: HM5339, Source: TCPConnection::HandleWrite, Description: An unknown error occurred while handling buffer write."
"ERROR"	4348	"2010-11-25 10:41:35.207"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR"	1792	"2010-11-25 10:41:35.924"	"Severity: 2 (High), Code: HM5334, Source: TCPConnection::CancelLogoutTimer, Description: An unknown error occurred while canceling logout timer."
"ERROR"	1792	"2010-11-25 10:41:35.924"	"Severity: 3 (Medium), Code: HM5141, Source: TCPConnection::HandleRead, Description: An error occurred while handling read operation. Remote IP: 216.59.16.206"
"ERROR"	4504	"2010-11-25 10:41:36.080"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR"	4348	"2010-11-25 10:41:36.080"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR"	4152	"2010-11-25 10:43:24.937"	"Severity: 3 (Medium), Code: HM4303, Source: IMAPCommandIdle::Finish, Description: An unknown error has occurred."
"ERROR"	4152	"2010-11-25 10:43:24.937"	"Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 4, Data: DONE. Remote IP: 67.223.75.233"
"ERROR"	4152	"2010-11-25 10:43:24.937"	"Severity: 3 (Medium), Code: HM5141, Source: TCPConnection::HandleRead, Description: An error occurred while handling read operation. Remote IP: 67.223.75.233"
"ERROR"	4348	"2010-11-25 10:43:45.030"	"Severity: 2 (High), Code: HM5334, Source: TCPConnection::CancelLogoutTimer, Description: An unknown error occurred while canceling logout timer."
"ERROR"	4348	"2010-11-25 10:43:45.030"	"Severity: 3 (Medium), Code: HM5141, Source: TCPConnection::HandleRead, Description: An error occurred while handling read operation. Remote IP: 67.223.78.161"
"ERROR"	2988	"2010-11-25 10:43:45.030"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR"	2948	"2010-11-25 10:43:45.545"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR"	4164	"2010-11-25 10:48:32.445"	"Severity: 2 (High), Code: HM4223, Source: DeliveryTask::DoWork, Description: SMTPDeliverer::DeliverMessage() failed"
hmailserver_2010-11-25 (last lines before crash between 10:43 and 10:48)

Code: Select all

"POP3D"	4152	72932	"2010-11-25 10:41:34.193"	"186.214.157.174"	"RECEIVED: QUIT"
"POP3D"	4152	72932	"2010-11-25 10:41:34.193"	"186.214.157.174"	"SENT: +OK POP3 server saying goodbye..."
"SMTPC"	4256	72931	"2010-11-25 10:41:34.333"	"74.125.45.27"	"RECEIVED: 250 2.1.5 OK q26si7565005ybk.28"
"SMTPC"	4256	72931	"2010-11-25 10:41:34.333"	"74.125.45.27"	"SENT: DATA"
"POP3D"	1888	72933	"2010-11-25 10:41:34.364"	"189.4.226.38"	"RECEIVED: LIST"
"POP3D"	1888	72933	"2010-11-25 10:41:34.364"	"189.4.226.38"	"SENT: +OK 4 messages (9034 octets)"
"POP3D"	1888	72933	"2010-11-25 10:41:34.364"	"189.4.226.38"	"SENT: 1 2251[nl]2 2186[nl]3 2356[nl]4 2241[nl]."
"SMTPC"	1484	72931	"2010-11-25 10:41:34.380"	"74.125.45.27"	"RECEIVED: 354  Go ahead q26si7565005ybk.28"
"POP3D"	392	72918	"2010-11-25 10:41:34.723"	"186.220.50.120"	"RECEIVED: QUIT"
"POP3D"	392	72918	"2010-11-25 10:41:34.739"	"186.220.50.120"	"SENT: +OK POP3 server saying goodbye..."
"SMTPC"	4400	72931	"2010-11-25 10:41:34.770"	"74.125.45.27"	"SENT: [nl]."
"POP3D"	392	72933	"2010-11-25 10:41:34.926"	"189.4.226.38"	"RECEIVED: UIDL 1"
"POP3D"	392	72933	"2010-11-25 10:41:34.926"	"189.4.226.38"	"SENT: +OK 1 54"
"POP3D"	392	72934	"2010-11-25 10:41:34.973"	"127.0.0.1"	"SENT: +OK POP3"
"POP3D"	392	72934	"2010-11-25 10:41:34.973"	"127.0.0.1"	"RECEIVED: CAPA"
"POP3D"	392	72934	"2010-11-25 10:41:34.973"	"127.0.0.1"	"SENT: -ERR Invalid command in current state."
"POP3D"	2988	72934	"2010-11-25 10:41:34.973"	"127.0.0.1"	"RECEIVED: AUTH"
"POP3D"	2988	72934	"2010-11-25 10:41:34.973"	"127.0.0.1"	"SENT: -ERR Invalid command in current state."
"POP3D"	4400	72934	"2010-11-25 10:41:34.973"	"127.0.0.1"	"RECEIVED: USER patricia@globalwine.com.br"
"POP3D"	4400	72934	"2010-11-25 10:41:34.973"	"127.0.0.1"	"SENT: +OK Send your password"
"POP3D"	636	72934	"2010-11-25 10:41:34.973"	"127.0.0.1"	"RECEIVED: PASS ***"
"POP3D"	636	72934	"2010-11-25 10:41:34.973"	"127.0.0.1"	"SENT: +OK Mailbox locked and ready"
"POP3D"	636	72934	"2010-11-25 10:41:34.973"	"127.0.0.1"	"RECEIVED: STAT"
"POP3D"	636	72934	"2010-11-25 10:41:34.973"	"127.0.0.1"	"SENT: +OK 61 9141320"
"POP3D"	4284	72934	"2010-11-25 10:41:35.004"	"127.0.0.1"	"RECEIVED: RETR 1"
"POP3D"	4284	72934	"2010-11-25 10:41:35.004"	"127.0.0.1"	"SENT: ."
"POP3D"	4284	72934	"2010-11-25 10:41:35.004"	"127.0.0.1"	"RECEIVED: RETR 2"
"POP3D"	4284	72934	"2010-11-25 10:41:35.004"	"127.0.0.1"	"SENT: ."
"POP3D"	2988	72934	"2010-11-25 10:41:35.004"	"127.0.0.1"	"RECEIVED: RETR 3"
"POP3D"	2988	72934	"2010-11-25 10:41:35.004"	"127.0.0.1"	"SENT: ."
"SMTPC"	2988	72931	"2010-11-25 10:41:35.004"	"74.125.45.27"	"RECEIVED: 250 2.0.0 OK 1290688889 q26si7565005ybk.28"
"SMTPC"	2988	72931	"2010-11-25 10:41:35.004"	"74.125.45.27"	"SENT: QUIT"
"POP3D"	636	72934	"2010-11-25 10:41:35.004"	"127.0.0.1"	"RECEIVED: RETR 4"
"POP3D"	2988	72934	"2010-11-25 10:41:35.019"	"127.0.0.1"	"SENT: ."
"POP3D"	2988	72934	"2010-11-25 10:41:35.019"	"127.0.0.1"	"RECEIVED: RETR 5"
"POP3D"	2988	72934	"2010-11-25 10:41:35.019"	"127.0.0.1"	"SENT: ."
"POP3D"	2988	72934	"2010-11-25 10:41:35.019"	"127.0.0.1"	"RECEIVED: RETR 6"
"POP3D"	2988	72934	"2010-11-25 10:41:35.019"	"127.0.0.1"	"SENT: ."
"POP3D"	4400	72934	"2010-11-25 10:41:35.019"	"127.0.0.1"	"RECEIVED: RETR 7"
"POP3D"	4400	72934	"2010-11-25 10:41:35.035"	"127.0.0.1"	"SENT: ."
"POP3D"	4400	72934	"2010-11-25 10:41:35.035"	"127.0.0.1"	"RECEIVED: RETR 8"
"POP3D"	4400	72934	"2010-11-25 10:41:35.035"	"127.0.0.1"	"SENT: ."
"POP3D"	4400	72934	"2010-11-25 10:41:35.035"	"127.0.0.1"	"RECEIVED: RETR 9"
"POP3D"	4400	72934	"2010-11-25 10:41:35.035"	"127.0.0.1"	"SENT: ."
"POP3D"	4400	72934	"2010-11-25 10:41:35.035"	"127.0.0.1"	"RECEIVED: RETR 10"
"POP3D"	4400	72934	"2010-11-25 10:41:35.035"	"127.0.0.1"	"SENT: ."
"POP3D"	3356	72934	"2010-11-25 10:41:35.035"	"127.0.0.1"	"RECEIVED: RETR 11"
"POP3D"	3356	72934	"2010-11-25 10:41:35.035"	"127.0.0.1"	"SENT: ."
"POP3D"	2988	72934	"2010-11-25 10:41:35.035"	"127.0.0.1"	"RECEIVED: RETR 12"
"POP3D"	3356	72934	"2010-11-25 10:41:35.051"	"127.0.0.1"	"SENT: ."
"SMTPC"	2988	72931	"2010-11-25 10:41:35.051"	"74.125.45.27"	"RECEIVED: 221 2.0.0 closing connection q26si7565005ybk.28"
"POP3D"	2988	72934	"2010-11-25 10:41:35.051"	"127.0.0.1"	"RECEIVED: RETR 13"
"POP3D"	2988	72934	"2010-11-25 10:41:35.051"	"127.0.0.1"	"SENT: ."
"POP3D"	2988	72934	"2010-11-25 10:41:35.051"	"127.0.0.1"	"RECEIVED: RETR 14"
"POP3D"	4284	72934	"2010-11-25 10:41:35.051"	"127.0.0.1"	"SENT: ."
"APPLICATION"	3220	"2010-11-25 10:41:35.051"	"SMTPDeliverer - Message 311897: Message delivery thread completed."
"POP3D"	2988	72934	"2010-11-25 10:41:35.051"	"127.0.0.1"	"RECEIVED: RETR 15"
"POP3D"	2988	72934	"2010-11-25 10:41:35.051"	"127.0.0.1"	"SENT: ."
"POP3D"	2988	72934	"2010-11-25 10:41:35.051"	"127.0.0.1"	"RECEIVED: RETR 16"
"POP3D"	2988	72934	"2010-11-25 10:41:35.051"	"127.0.0.1"	"SENT: ."
"POP3D"	3356	72934	"2010-11-25 10:41:35.051"	"127.0.0.1"	"RECEIVED: RETR 17"
"POP3D"	3356	72934	"2010-11-25 10:41:35.051"	"127.0.0.1"	"SENT: ."
"POP3D"	3356	72934	"2010-11-25 10:41:35.051"	"127.0.0.1"	"RECEIVED: RETR 18"
"POP3D"	3356	72934	"2010-11-25 10:41:35.066"	"127.0.0.1"	"SENT: ."
"POP3D"	3356	72934	"2010-11-25 10:41:35.066"	"127.0.0.1"	"RECEIVED: RETR 19"
"POP3D"	392	72934	"2010-11-25 10:41:35.066"	"127.0.0.1"	"SENT: ."
"POP3D"	3356	72934	"2010-11-25 10:41:35.066"	"127.0.0.1"	"RECEIVED: RETR 20"
"POP3D"	4400	72934	"2010-11-25 10:41:35.082"	"127.0.0.1"	"SENT: ."
"POP3D"	2988	72934	"2010-11-25 10:41:35.082"	"127.0.0.1"	"RECEIVED: RETR 21"
"POP3D"	4400	72934	"2010-11-25 10:41:35.082"	"127.0.0.1"	"SENT: ."
"POP3D"	4284	72934	"2010-11-25 10:41:35.082"	"127.0.0.1"	"RECEIVED: RETR 22"
"POP3D"	4284	72933	"2010-11-25 10:41:35.097"	"189.4.226.38"	"RECEIVED: UIDL"
"POP3D"	4284	72933	"2010-11-25 10:41:35.097"	"189.4.226.38"	"SENT: +OK 4 messages (9034 octets)[nl]1 54[nl]2 55[nl]3 56[nl]4 57[nl]."
"POP3D"	4152	72934	"2010-11-25 10:41:35.129"	"127.0.0.1"	"SENT: ."
"POP3D"	4152	72934	"2010-11-25 10:41:35.129"	"127.0.0.1"	"RECEIVED: RETR 23"
"POP3D"	3356	72934	"2010-11-25 10:41:35.129"	"127.0.0.1"	"SENT: ."
"POP3D"	3356	72934	"2010-11-25 10:41:35.144"	"127.0.0.1"	"RECEIVED: RETR 24"
"POP3D"	1980	72934	"2010-11-25 10:41:35.144"	"127.0.0.1"	"SENT: ."
"POP3D"	4504	72934	"2010-11-25 10:41:35.144"	"127.0.0.1"	"RECEIVED: RETR 25"
"POP3D"	4504	72934	"2010-11-25 10:41:35.144"	"127.0.0.1"	"SENT: ."
"POP3D"	4504	72934	"2010-11-25 10:41:35.144"	"127.0.0.1"	"RECEIVED: RETR 26"
"POP3D"	4504	72934	"2010-11-25 10:41:35.144"	"127.0.0.1"	"SENT: ."
"POP3D"	4504	72934	"2010-11-25 10:41:35.144"	"127.0.0.1"	"RECEIVED: RETR 27"
"POP3D"	3356	72934	"2010-11-25 10:41:35.144"	"127.0.0.1"	"SENT: ."
"POP3D"	3356	72934	"2010-11-25 10:41:35.160"	"127.0.0.1"	"RECEIVED: RETR 28"
"POP3D"	3356	72934	"2010-11-25 10:41:35.160"	"127.0.0.1"	"SENT: ."
"SMTPD"	4504	72910	"2010-11-25 10:41:35.160"	"186.214.157.174"	"RECEIVED: QUIT"
"SMTPD"	4504	72910	"2010-11-25 10:41:35.160"	"186.214.157.174"	"SENT: 221 goodbye"
"POP3D"	1980	72934	"2010-11-25 10:41:35.160"	"127.0.0.1"	"RECEIVED: RETR 29"
"POP3D"	1980	72934	"2010-11-25 10:41:35.160"	"127.0.0.1"	"SENT: ."
"POP3D"	1980	72934	"2010-11-25 10:41:35.160"	"127.0.0.1"	"RECEIVED: RETR 30"
"POP3D"	1980	72934	"2010-11-25 10:41:35.160"	"127.0.0.1"	"SENT: ."
"POP3D"	4504	72934	"2010-11-25 10:41:35.160"	"127.0.0.1"	"RECEIVED: RETR 31"
"POP3D"	4504	72934	"2010-11-25 10:41:35.175"	"127.0.0.1"	"SENT: ."
"POP3D"	4504	72934	"2010-11-25 10:41:35.175"	"127.0.0.1"	"RECEIVED: RETR 32"
"POP3D"	4504	72934	"2010-11-25 10:41:35.175"	"127.0.0.1"	"SENT: ."
"POP3D"	3096	72934	"2010-11-25 10:41:35.175"	"127.0.0.1"	"RECEIVED: RETR 33"
"POP3D"	3096	72934	"2010-11-25 10:41:35.175"	"127.0.0.1"	"SENT: ."
"POP3D"	4696	72934	"2010-11-25 10:41:35.175"	"127.0.0.1"	"RECEIVED: RETR 34"
"POP3D"	4696	72934	"2010-11-25 10:41:35.175"	"127.0.0.1"	"SENT: ."
"POP3D"	1484	72934	"2010-11-25 10:41:35.175"	"127.0.0.1"	"RECEIVED: RETR 35"
"POP3D"	4696	72934	"2010-11-25 10:41:35.191"	"127.0.0.1"	"SENT: ."
"POP3D"	4504	72934	"2010-11-25 10:41:35.191"	"127.0.0.1"	"RECEIVED: RETR 36"
"POP3D"	4504	72934	"2010-11-25 10:41:35.191"	"127.0.0.1"	"SENT: ."
"POP3D"	3096	72934	"2010-11-25 10:41:35.191"	"127.0.0.1"	"RECEIVED: RETR 37"
"POP3D"	3096	72934	"2010-11-25 10:41:35.191"	"127.0.0.1"	"SENT: ."
"POP3D"	1484	72934	"2010-11-25 10:41:35.191"	"127.0.0.1"	"RECEIVED: RETR 38"
"POP3D"	3096	72934	"2010-11-25 10:41:35.207"	"127.0.0.1"	"SENT: ."
"POP3D"	3096	72934	"2010-11-25 10:41:35.207"	"127.0.0.1"	"RECEIVED: RETR 39"
"ERROR"	4348	"2010-11-25 10:41:35.207"	"Severity: 2 (High), Code: HM5339, Source: TCPConnection::HandleWrite, Description: An unknown error occurred while handling buffer write."
"ERROR"	4348	"2010-11-25 10:41:35.207"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"POP3D"	2948	72933	"2010-11-25 10:41:35.285"	"189.4.226.38"	"RECEIVED: QUIT"
"POP3D"	2948	72933	"2010-11-25 10:41:35.285"	"189.4.226.38"	"SENT: +OK POP3 server saying goodbye..."
"ERROR"	1792	"2010-11-25 10:41:35.924"	"Severity: 2 (High), Code: HM5334, Source: TCPConnection::CancelLogoutTimer, Description: An unknown error occurred while canceling logout timer."
"ERROR"	1792	"2010-11-25 10:41:35.924"	"Severity: 3 (Medium), Code: HM5141, Source: TCPConnection::HandleRead, Description: An error occurred while handling read operation. Remote IP: 216.59.16.206"
"ERROR"	4504	"2010-11-25 10:41:36.080"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR"	4348	"2010-11-25 10:41:36.080"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"SMTPD"	3356	72907	"2010-11-25 10:41:43.131"	"74.125.82.171"	"RECEIVED: QUIT"
"SMTPD"	3356	72907	"2010-11-25 10:41:43.131"	"74.125.82.171"	"SENT: 221 goodbye"
"APPLICATION"	3220	"2010-11-25 10:42:32.365"	"SMTPDeliverer - Message 311898: Delivering message from samantha@espacoz.com.br to liviabrick@gmail.com. File: C:\Program Files (x86)\hMailServer\Data\{48E5795E-1AAA-4D76-82E2-7D929B4AEBF6}.eml"
"IMAPD"	4152	167	"2010-11-25 10:43:24.937"	"67.223.75.233"	"RECEIVED: DONE"
"ERROR"	4152	"2010-11-25 10:43:24.937"	"Severity: 3 (Medium), Code: HM4303, Source: IMAPCommandIdle::Finish, Description: An unknown error has occurred."
"ERROR"	4152	"2010-11-25 10:43:24.937"	"Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 4, Data: DONE. Remote IP: 67.223.75.233"
"ERROR"	4152	"2010-11-25 10:43:24.937"	"Severity: 3 (Medium), Code: HM5141, Source: TCPConnection::HandleRead, Description: An error occurred while handling read operation. Remote IP: 67.223.75.233"
"IMAPD"	636	48814	"2010-11-25 10:43:25.468"	"67.223.80.171"	"RECEIVED: DONE"
"IMAPD"	636	48814	"2010-11-25 10:43:25.468"	"67.223.80.171"	"SENT: i OK IDLE terminated"
"IMAPD"	3096	48814	"2010-11-25 10:43:25.499"	"67.223.80.171"	"RECEIVED: f UID FETCH 1540:* (UID FLAGS)"
"IMAPD"	3096	48814	"2010-11-25 10:43:25.499"	"67.223.80.171"	"SENT: f OK UID completed"
"IMAPD"	4256	48814	"2010-11-25 10:43:25.546"	"67.223.80.171"	"RECEIVED: i IDLE"
"IMAPD"	4256	48814	"2010-11-25 10:43:25.546"	"67.223.80.171"	"SENT: + idling"
"IMAPD"	1484	166	"2010-11-25 10:43:37.027"	"67.223.81.94"	"RECEIVED: DONE"
"IMAPD"	1484	166	"2010-11-25 10:43:37.027"	"67.223.81.94"	"SENT: i OK IDLE terminated"
"IMAPD"	4348	166	"2010-11-25 10:43:37.074"	"67.223.81.94"	"RECEIVED: f UID FETCH 3837:* (UID FLAGS)"
"IMAPD"	4348	166	"2010-11-25 10:43:37.074"	"67.223.81.94"	"SENT: * 55 FETCH (UID 3837 FLAGS ())"
"IMAPD"	4348	166	"2010-11-25 10:43:37.074"	"67.223.81.94"	"SENT: f OK UID completed"
"IMAPD"	4504	166	"2010-11-25 10:43:37.199"	"67.223.81.94"	"RECEIVED: i IDLE"
"IMAPD"	4504	166	"2010-11-25 10:43:37.199"	"67.223.81.94"	"SENT: + idling"
"ERROR"	4348	"2010-11-25 10:43:45.030"	"Severity: 2 (High), Code: HM5334, Source: TCPConnection::CancelLogoutTimer, Description: An unknown error occurred while canceling logout timer."
"ERROR"	4348	"2010-11-25 10:43:45.030"	"Severity: 3 (Medium), Code: HM5141, Source: TCPConnection::HandleRead, Description: An error occurred while handling read operation. Remote IP: 67.223.78.161"
"ERROR"	2988	"2010-11-25 10:43:45.030"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR"	2948	"2010-11-25 10:43:45.545"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"IMAPD"	4256	164	"2010-11-25 10:43:58.602"	"67.223.79.204"	"RECEIVED: DONE"
"IMAPD"	4256	164	"2010-11-25 10:43:58.602"	"67.223.79.204"	"SENT: i OK IDLE terminated"
"IMAPD"	3356	164	"2010-11-25 10:43:58.665"	"67.223.79.204"	"RECEIVED: f UID FETCH 1719:* (UID FLAGS)"
"IMAPD"	3356	164	"2010-11-25 10:43:58.665"	"67.223.79.204"	"SENT: * 180 FETCH (UID 1719 FLAGS ())"
"IMAPD"	3356	164	"2010-11-25 10:43:58.665"	"67.223.79.204"	"SENT: f OK UID completed"
"IMAPD"	3096	164	"2010-11-25 10:43:58.805"	"67.223.79.204"	"RECEIVED: i IDLE"
"IMAPD"	3096	164	"2010-11-25 10:43:58.805"	"67.223.79.204"	"SENT: + idling"
"IMAPD"	636	178	"2010-11-25 10:44:01.363"	"67.223.69.91"	"RECEIVED: DONE"
"IMAPD"	636	178	"2010-11-25 10:44:01.363"	"67.223.69.91"	"SENT: i OK IDLE terminated"
"IMAPD"	1888	178	"2010-11-25 10:44:01.426"	"67.223.69.91"	"RECEIVED: f UID FETCH 3046:* (UID FLAGS)"
"IMAPD"	1888	178	"2010-11-25 10:44:01.426"	"67.223.69.91"	"SENT: * 71 FETCH (UID 3046 FLAGS ())"
"IMAPD"	1888	178	"2010-11-25 10:44:01.426"	"67.223.69.91"	"SENT: f OK UID completed"
"IMAPD"	3356	178	"2010-11-25 10:44:01.566"	"67.223.69.91"	"RECEIVED: i IDLE"
"IMAPD"	3356	178	"2010-11-25 10:44:01.566"	"67.223.69.91"	"SENT: + idling"
"IMAPD"	4256	181	"2010-11-25 10:44:08.149"	"67.223.79.2"	"RECEIVED: DONE"
"IMAPD"	4256	181	"2010-11-25 10:44:08.149"	"67.223.79.2"	"SENT: i OK IDLE terminated"
"IMAPD"	4400	181	"2010-11-25 10:44:08.212"	"67.223.79.2"	"RECEIVED: f UID FETCH 7844:* (UID FLAGS)"
"IMAPD"	4400	181	"2010-11-25 10:44:08.212"	"67.223.79.2"	"SENT: * 232 FETCH (UID 7844 FLAGS ())"
"IMAPD"	4400	181	"2010-11-25 10:44:08.212"	"67.223.79.2"	"SENT: f OK UID completed"
"IMAPD"	1888	181	"2010-11-25 10:44:08.352"	"67.223.79.2"	"RECEIVED: i IDLE"
"IMAPD"	1888	181	"2010-11-25 10:44:08.352"	"67.223.79.2"	"SENT: + idling"
"SMTPD"	2988	71751	"2010-11-25 10:45:05.682"	"186.220.50.120"	"SENT: 421 Connection timeout."
"IMAPD"	1484	48814	"2010-11-25 10:47:55.598"	"67.223.80.171"	"RECEIVED: DONE"
"IMAPD"	1484	48814	"2010-11-25 10:47:55.598"	"67.223.80.171"	"SENT: i OK IDLE terminated"
"IMAPD"	1888	48814	"2010-11-25 10:47:55.645"	"67.223.80.171"	"RECEIVED: f UID FETCH 1540:* (UID FLAGS)"
"IMAPD"	1888	48814	"2010-11-25 10:47:55.645"	"67.223.80.171"	"SENT: f OK UID completed"
"IMAPD"	3096	48814	"2010-11-25 10:47:55.691"	"67.223.80.171"	"RECEIVED: i IDLE"
"IMAPD"	3096	48814	"2010-11-25 10:47:55.691"	"67.223.80.171"	"SENT: + idling"
"IMAPD"	4400	166	"2010-11-25 10:48:07.235"	"67.223.81.94"	"RECEIVED: DONE"
"IMAPD"	4400	166	"2010-11-25 10:48:07.251"	"67.223.81.94"	"SENT: i OK IDLE terminated"
"IMAPD"	4504	166	"2010-11-25 10:48:07.282"	"67.223.81.94"	"RECEIVED: f UID FETCH 3837:* (UID FLAGS)"
"IMAPD"	4504	166	"2010-11-25 10:48:07.282"	"67.223.81.94"	"SENT: * 55 FETCH (UID 3837 FLAGS ())"
"IMAPD"	4504	166	"2010-11-25 10:48:07.282"	"67.223.81.94"	"SENT: f OK UID completed"
"IMAPD"	636	166	"2010-11-25 10:48:07.407"	"67.223.81.94"	"RECEIVED: i IDLE"
"IMAPD"	636	166	"2010-11-25 10:48:07.407"	"67.223.81.94"	"SENT: + idling"
"IMAPD"	1484	164	"2010-11-25 10:48:28.873"	"67.223.79.204"	"RECEIVED: DONE"
"IMAPD"	1484	164	"2010-11-25 10:48:28.873"	"67.223.79.204"	"SENT: i OK IDLE terminated"
"IMAPD"	636	164	"2010-11-25 10:48:28.919"	"67.223.79.204"	"RECEIVED: f UID FETCH 1719:* (UID FLAGS)"
"IMAPD"	636	164	"2010-11-25 10:48:28.919"	"67.223.79.204"	"SENT: * 180 FETCH (UID 1719 FLAGS ())"
"IMAPD"	636	164	"2010-11-25 10:48:28.919"	"67.223.79.204"	"SENT: f OK UID completed"
"IMAPD"	4400	164	"2010-11-25 10:48:29.075"	"67.223.79.204"	"RECEIVED: i IDLE"
"IMAPD"	4400	164	"2010-11-25 10:48:29.075"	"67.223.79.204"	"SENT: + idling"
"IMAPD"	4256	178	"2010-11-25 10:48:31.634"	"67.223.69.91"	"RECEIVED: DONE"
"IMAPD"	4256	178	"2010-11-25 10:48:31.634"	"67.223.69.91"	"SENT: i OK IDLE terminated"
"IMAPD"	4400	178	"2010-11-25 10:48:31.681"	"67.223.69.91"	"RECEIVED: f UID FETCH 3046:* (UID FLAGS)"
"IMAPD"	4400	178	"2010-11-25 10:48:31.681"	"67.223.69.91"	"SENT: * 71 FETCH (UID 3046 FLAGS ())"
"IMAPD"	4400	178	"2010-11-25 10:48:31.681"	"67.223.69.91"	"SENT: f OK UID completed"
"IMAPD"	3356	178	"2010-11-25 10:48:31.837"	"67.223.69.91"	"RECEIVED: i IDLE"
"IMAPD"	3356	178	"2010-11-25 10:48:31.837"	"67.223.69.91"	"SENT: + idling"
"APPLICATION"	4164	"2010-11-25 10:48:32.445"	"SMTPDeliverer - Message 311384: Delivering message from isadora@espacoz.com.br to marcospena.mg@diariosassociados.com.br. File: C:\Program Files (x86)\hMailServer\Data\{5CC675BB-B495-4486-A25F-9C35CF3BEF3F}.eml"
"ERROR"	4164	"2010-11-25 10:48:32.445"	"Severity: 2 (High), Code: HM4223, Source: DeliveryTask::DoWork, Description: SMTPDeliverer::DeliverMessage() failed"
"APPLICATION"	4276	"2010-11-25 10:48:32.445"	"SMTPDeliverer - Message 311395: Delivering message from  to financeiro@wherebrasil.com.br. File: C:\Program Files (x86)\hMailServer\Data\{96AAEF57-DAD3-4837-BA35-D3E604385BEC}.eml"
"IMAPD"	4400	181	"2010-11-25 10:48:38.404"	"67.223.79.2"	"RECEIVED: DONE"
"IMAPD"	4400	181	"2010-11-25 10:48:38.404"	"67.223.79.2"	"SENT: i OK IDLE terminated"
"IMAPD"	636	181	"2010-11-25 10:48:38.467"	"67.223.79.2"	"RECEIVED: f UID FETCH 7844:* (UID FLAGS)"
"IMAPD"	636	181	"2010-11-25 10:48:38.467"	"67.223.79.2"	"SENT: * 232 FETCH (UID 7844 FLAGS ())"
"IMAPD"	636	181	"2010-11-25 10:48:38.467"	"67.223.79.2"	"SENT: f OK UID completed"
"IMAPD"	2948	181	"2010-11-25 10:48:38.607"	"67.223.79.2"	"RECEIVED: i IDLE"
"IMAPD"	2948	181	"2010-11-25 10:48:38.607"	"67.223.79.2"	"SENT: + idling"
"APPLICATION"	4336	"2010-11-25 10:53:47.519"	"Starting servers..."
"APPLICATION"	4336	"2010-11-25 10:53:48.314"	"Servers started."
127.0.0.1 means someone is accessing thru webmail (Horde).

Hope it helps :) <3 hMailServer.

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

Re: hMailServer crashes/stops

Post by Bill48105 » 2010-11-25 17:41

It appears to be following a similar problem we're seeing but have yet to reproduce here to be able to figure out root cause & fix or give work-around. One thing that appears to be common is server is really busy at the time, is the same true for you? Perhaps you can set the POP/IMAP/SMTP connection limits lower to keep cpu usage down & see if that helps? We are working on this but again need to get it to happen on test servers..
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. ***

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

Re: hMailServer crashes/stops

Post by martin » 2010-11-25 18:17

I'm guessing the problem is related to the asynchronous TCP/IP handling. I will try to put together a test suite during the weekend to reproduce this issue. :-\

blakito
Normal user
Normal user
Posts: 30
Joined: 2010-09-15 14:27

Re: hMailServer crashes/stops

Post by blakito » 2010-11-25 18:41

Bill48105 wrote:It appears to be following a similar problem we're seeing but have yet to reproduce here to be able to figure out root cause & fix or give work-around. One thing that appears to be common is server is really busy at the time, is the same true for you? Perhaps you can set the POP/IMAP/SMTP connection limits lower to keep cpu usage down & see if that helps? We are working on this but again need to get it to happen on test servers..
Bill
Hi Bill and Martin, thanks for the fast feedback !

I dont think the server (machine itself) was even close to its resources limit (CPU/MEM), maybe a bottleneck in I/O speed. Im creating a PerfMon log to analyze the next crash.

The first counter-measure for this problem was to reduce threading values, but the connection limits are untouched (0 - ilimited).
What value would you suggest for the above configuration ?

You're welcome to use my server as a sandbox if you need, i can give you remote access anytime.
Some outages for the good of the community wouldn't be a problem.

Best regards

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

Re: hMailServer crashes/stops

Post by martin » 2010-11-25 18:42

blakito,

What kind of volumes are you processing on the server? 10 000 mails a day or ten times as much?

blakito
Normal user
Normal user
Posts: 30
Joined: 2010-09-15 14:27

Re: hMailServer crashes/stops

Post by blakito » 2010-11-25 19:55

martin wrote:blakito,

What kind of volumes are you processing on the server? 10 000 mails a day or ten times as much?
Since 10:53 GMT-3 (last crash), 1562 messages. Probably not more than 5000 for a whole normal work-day.

:oops:

blakito
Normal user
Normal user
Posts: 30
Joined: 2010-09-15 14:27

Re: hMailServer crashes/stops

Post by blakito » 2010-11-29 16:54

Here we go ! Server "crashed" a few minutes ago.

Code: Select all

"ERROR"	2408	"2010-11-29 12:31:27.322"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR"	2408	"2010-11-29 12:31:38.289"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR"	2408	"2010-11-29 12:46:18.302"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."

Code: Select all

"POP3D"	4636	22041	"2010-11-29 12:31:25.294"	"127.0.0.1"	"RECEIVED: DELE 14"
"POP3D"	4636	22041	"2010-11-29 12:31:25.294"	"127.0.0.1"	"SENT: +OK msg deleted"
"POP3D"	3272	22041	"2010-11-29 12:31:25.294"	"127.0.0.1"	"RECEIVED: DELE 18"
"POP3D"	3272	22041	"2010-11-29 12:31:25.294"	"127.0.0.1"	"SENT: +OK msg deleted"
"POP3D"	3272	22041	"2010-11-29 12:31:25.294"	"127.0.0.1"	"RECEIVED: DELE 19"
"POP3D"	3272	22041	"2010-11-29 12:31:25.294"	"127.0.0.1"	"SENT: +OK msg deleted"
"POP3D"	4872	22041	"2010-11-29 12:31:25.356"	"127.0.0.1"	"RECEIVED: QUIT"
"POP3D"	4872	22041	"2010-11-29 12:31:25.356"	"127.0.0.1"	"SENT: +OK POP3 server saying goodbye..."
"POP3D"	3272	22042	"2010-11-29 12:31:26.043"	"186.220.50.120"	"SENT: +OK POP3"
"POP3D"	3352	22042	"2010-11-29 12:31:26.230"	"186.220.50.120"	"RECEIVED: USER juliana.venancio@espacoz.com.br"
"POP3D"	3352	22042	"2010-11-29 12:31:26.230"	"186.220.50.120"	"SENT: +OK Send your password"
"POP3D"	3272	22043	"2010-11-29 12:31:26.292"	"186.213.250.119"	"SENT: +OK POP3"
"POP3D"	3272	22044	"2010-11-29 12:31:26.386"	"186.220.50.120"	"SENT: +OK POP3"
"POP3D"	3284	22042	"2010-11-29 12:31:26.402"	"186.220.50.120"	"RECEIVED: PASS ***"
"POP3D"	3284	22042	"2010-11-29 12:31:26.402"	"186.220.50.120"	"SENT: +OK Mailbox locked and ready"
"POP3D"	3520	22043	"2010-11-29 12:31:26.464"	"186.213.250.119"	"RECEIVED: USER livia@espacoz.com.br"
"POP3D"	3520	22043	"2010-11-29 12:31:26.464"	"186.213.250.119"	"SENT: +OK Send your password"
"POP3D"	4656	22044	"2010-11-29 12:31:26.558"	"186.220.50.120"	"RECEIVED: AUTH "
"POP3D"	4656	22044	"2010-11-29 12:31:26.558"	"186.220.50.120"	"SENT: -ERR Invalid command in current state."
"POP3D"	5020	22042	"2010-11-29 12:31:26.558"	"186.220.50.120"	"RECEIVED: STAT"
"POP3D"	5020	22042	"2010-11-29 12:31:26.558"	"186.220.50.120"	"SENT: +OK 0 0"
"POP3D"	5116	22043	"2010-11-29 12:31:26.636"	"186.213.250.119"	"RECEIVED: PASS ***"
"POP3D"	5116	22043	"2010-11-29 12:31:26.636"	"186.213.250.119"	"SENT: +OK Mailbox locked and ready"
"POP3D"	2408	22044	"2010-11-29 12:31:26.714"	"186.220.50.120"	"RECEIVED: USER marcelo@espacoz.com.br"
"POP3D"	2408	22044	"2010-11-29 12:31:26.714"	"186.220.50.120"	"SENT: +OK Send your password"
"POP3D"	3352	22042	"2010-11-29 12:31:26.745"	"186.220.50.120"	"RECEIVED: QUIT"
"POP3D"	3352	22042	"2010-11-29 12:31:26.745"	"186.220.50.120"	"SENT: +OK POP3 server saying goodbye..."
"POP3D"	1888	22043	"2010-11-29 12:31:26.807"	"186.213.250.119"	"RECEIVED: STAT"
"POP3D"	1888	22043	"2010-11-29 12:31:26.807"	"186.213.250.119"	"SENT: +OK 0 0"
"POP3D"	3240	22044	"2010-11-29 12:31:26.885"	"186.220.50.120"	"RECEIVED: PASS ***"
"POP3D"	3240	22044	"2010-11-29 12:31:26.885"	"186.220.50.120"	"SENT: +OK Mailbox locked and ready"
"POP3D"	3272	22045	"2010-11-29 12:31:26.901"	"127.0.0.1"	"SENT: +OK POP3"
"POP3D"	3272	22045	"2010-11-29 12:31:26.901"	"127.0.0.1"	"RECEIVED: CAPA"
"POP3D"	3272	22045	"2010-11-29 12:31:26.901"	"127.0.0.1"	"SENT: -ERR Invalid command in current state."
"POP3D"	2748	22045	"2010-11-29 12:31:26.901"	"127.0.0.1"	"RECEIVED: AUTH"
"POP3D"	2748	22045	"2010-11-29 12:31:26.901"	"127.0.0.1"	"SENT: -ERR Invalid command in current state."
"POP3D"	3272	22045	"2010-11-29 12:31:26.901"	"127.0.0.1"	"RECEIVED: USER viviane.ferreira@espacoz.com.br"
"POP3D"	3272	22045	"2010-11-29 12:31:26.901"	"127.0.0.1"	"SENT: +OK Send your password"
"POP3D"	3272	22045	"2010-11-29 12:31:26.901"	"127.0.0.1"	"RECEIVED: PASS ***"
"POP3D"	3272	22045	"2010-11-29 12:31:26.901"	"127.0.0.1"	"SENT: +OK Mailbox locked and ready"
"POP3D"	3272	22045	"2010-11-29 12:31:26.901"	"127.0.0.1"	"RECEIVED: STAT"
"POP3D"	3272	22045	"2010-11-29 12:31:26.901"	"127.0.0.1"	"SENT: +OK 38 19875019"
"POP3D"	4872	22045	"2010-11-29 12:31:26.932"	"127.0.0.1"	"RECEIVED: RETR 1"
"POP3D"	3272	22045	"2010-11-29 12:31:26.932"	"127.0.0.1"	"SENT: ."
"POP3D"	5116	22045	"2010-11-29 12:31:26.948"	"127.0.0.1"	"RECEIVED: RETR 2"
"POP3D"	3584	22045	"2010-11-29 12:31:26.963"	"127.0.0.1"	"SENT: ."
"POP3D"	4872	22045	"2010-11-29 12:31:26.963"	"127.0.0.1"	"RECEIVED: RETR 3"
"POP3D"	4872	22045	"2010-11-29 12:31:26.963"	"127.0.0.1"	"SENT: ."
"POP3D"	4872	22045	"2010-11-29 12:31:26.963"	"127.0.0.1"	"RECEIVED: RETR 4"
"POP3D"	3520	22045	"2010-11-29 12:31:27.010"	"127.0.0.1"	"SENT: ."
"POP3D"	3520	22045	"2010-11-29 12:31:27.010"	"127.0.0.1"	"RECEIVED: RETR 5"
"POP3D"	2560	22043	"2010-11-29 12:31:27.026"	"186.213.250.119"	"RECEIVED: QUIT"
"POP3D"	2560	22043	"2010-11-29 12:31:27.026"	"186.213.250.119"	"SENT: +OK POP3 server saying goodbye..."
"POP3D"	2560	22045	"2010-11-29 12:31:27.026"	"127.0.0.1"	"SENT: ."
"POP3D"	4852	22045	"2010-11-29 12:31:27.041"	"127.0.0.1"	"RECEIVED: RETR 6"
"POP3D"	4852	22045	"2010-11-29 12:31:27.041"	"127.0.0.1"	"SENT: ."
"POP3D"	2560	22045	"2010-11-29 12:31:27.041"	"127.0.0.1"	"RECEIVED: RETR 7"
"POP3D"	2560	22045	"2010-11-29 12:31:27.041"	"127.0.0.1"	"SENT: ."
"POP3D"	4852	22045	"2010-11-29 12:31:27.041"	"127.0.0.1"	"RECEIVED: RETR 8"
"POP3D"	2560	22045	"2010-11-29 12:31:27.041"	"127.0.0.1"	"SENT: ."
"POP3D"	4852	22045	"2010-11-29 12:31:27.041"	"127.0.0.1"	"RECEIVED: RETR 9"
"POP3D"	2560	22044	"2010-11-29 12:31:27.041"	"186.220.50.120"	"RECEIVED: STAT"
"POP3D"	2560	22044	"2010-11-29 12:31:27.041"	"186.220.50.120"	"SENT: +OK 0 0"
"POP3D"	4852	22045	"2010-11-29 12:31:27.104"	"127.0.0.1"	"SENT: ."
"POP3D"	4852	22045	"2010-11-29 12:31:27.104"	"127.0.0.1"	"RECEIVED: RETR 10"
"POP3D"	3272	22046	"2010-11-29 12:31:27.135"	"189.4.226.38"	"SENT: +OK POP3"
"POP3D"	5020	22045	"2010-11-29 12:31:27.244"	"127.0.0.1"	"SENT: ."
"POP3D"	2948	22045	"2010-11-29 12:31:27.244"	"127.0.0.1"	"RECEIVED: RETR 11"
"POP3D"	3148	22045	"2010-11-29 12:31:27.244"	"127.0.0.1"	"SENT: ."
"POP3D"	2948	22045	"2010-11-29 12:31:27.244"	"127.0.0.1"	"RECEIVED: RETR 12"
"POP3D"	3352	22046	"2010-11-29 12:31:27.291"	"189.4.226.38"	"RECEIVED: USER priscila@espacoz.com.br"
"POP3D"	3352	22046	"2010-11-29 12:31:27.291"	"189.4.226.38"	"SENT: +OK Send your password"
"POP3D"	4656	22045	"2010-11-29 12:31:27.306"	"127.0.0.1"	"SENT: ."
"POP3D"	4656	22045	"2010-11-29 12:31:27.306"	"127.0.0.1"	"RECEIVED: RETR 13"
"POP3D"	4656	22045	"2010-11-29 12:31:27.306"	"127.0.0.1"	"SENT: ."
"POP3D"	5020	22045	"2010-11-29 12:31:27.306"	"127.0.0.1"	"RECEIVED: RETR 14"
"POP3D"	3352	22045	"2010-11-29 12:31:27.306"	"127.0.0.1"	"SENT: "
"POP3D"	3352	22045	"2010-11-29 12:31:27.306"	"127.0.0.1"	"SENT: ."
"POP3D"	2408	22045	"2010-11-29 12:31:27.306"	"127.0.0.1"	"RECEIVED: RETR 15"
"ERROR"	2408	"2010-11-29 12:31:27.322"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"POP3D"	2408	22044	"2010-11-29 12:31:27.338"	"186.220.50.120"	"RECEIVED: QUIT"
"POP3D"	2408	22044	"2010-11-29 12:31:27.338"	"186.220.50.120"	"SENT: +OK POP3 server saying goodbye..."
"POP3D"	4116	22046	"2010-11-29 12:31:27.462"	"189.4.226.38"	"RECEIVED: PASS ***"
"POP3D"	4116	22046	"2010-11-29 12:31:27.462"	"189.4.226.38"	"SENT: +OK Mailbox locked and ready"
"POP3D"	2560	22046	"2010-11-29 12:31:27.618"	"189.4.226.38"	"RECEIVED: STAT"
"POP3D"	2560	22046	"2010-11-29 12:31:27.618"	"189.4.226.38"	"SENT: +OK 0 0"
"POP3D"	3272	22046	"2010-11-29 12:31:27.774"	"189.4.226.38"	"RECEIVED: QUIT"
"POP3D"	3272	22046	"2010-11-29 12:31:27.774"	"189.4.226.38"	"SENT: +OK POP3 server saying goodbye..."
"IMAPD"	2560	22038	"2010-11-29 12:31:28.773"	"67.223.79.2"	"RECEIVED: q LOGOUT"
"IMAPD"	2560	22038	"2010-11-29 12:31:28.773"	"67.223.79.2"	"SENT: * BYE Have a nice day[nl]q OK Logout completed"
"POP3D"	3272	22047	"2010-11-29 12:31:34.904"	"187.78.55.217"	"SENT: +OK POP3"
"POP3D"	2560	22047	"2010-11-29 12:31:37.540"	"187.78.55.217"	"RECEIVED: USER camila@espacoz.com.br"
"POP3D"	2560	22047	"2010-11-29 12:31:37.540"	"187.78.55.217"	"SENT: +OK Send your password"
"ERROR"	2408	"2010-11-29 12:31:38.289"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"POP3D"	3352	22047	"2010-11-29 12:31:39.771"	"187.78.55.217"	"RECEIVED: PASS ***"
"POP3D"	3352	22047	"2010-11-29 12:31:39.771"	"187.78.55.217"	"SENT: +OK Mailbox locked and ready"
"POP3D"	5020	22047	"2010-11-29 12:31:41.861"	"187.78.55.217"	"RECEIVED: STAT"
"POP3D"	5020	22047	"2010-11-29 12:31:41.861"	"187.78.55.217"	"SENT: +OK 0 0"
"POP3D"	4852	22047	"2010-11-29 12:31:42.267"	"187.78.55.217"	"RECEIVED: QUIT"
"POP3D"	4852	22047	"2010-11-29 12:31:42.267"	"187.78.55.217"	"SENT: +OK POP3 server saying goodbye..."
"SMTPC"	4872	21973	"2010-11-29 12:31:44.123"	"200.188.191.180"	"RECEIVED: 250 2.1.5 <felipebarbosa.mg@diariosassociados.com.br>... Recipient ok"
"SMTPC"	4872	21973	"2010-11-29 12:31:44.123"	"200.188.191.180"	"SENT: RCPT TO:<sergiosilva.mg@diariosassociados.com.br>"
"SMTPC"	4880	21973	"2010-11-29 12:31:45.215"	"200.188.191.180"	"RECEIVED: 250 2.1.5 <sergiosilva.mg@diariosassociados.com.br>... Recipient ok"
"SMTPC"	4880	21973	"2010-11-29 12:31:45.215"	"200.188.191.180"	"SENT: RCPT TO:<silvanomiranda.mg@diariosassociados.com.br>"
"SMTPC"	2408	21973	"2010-11-29 12:31:45.808"	"200.188.191.180"	"RECEIVED: 250 2.1.5 <silvanomiranda.mg@diariosassociados.com.br>... Recipient ok"
"SMTPC"	2408	21973	"2010-11-29 12:31:45.808"	"200.188.191.180"	"SENT: DATA"
"SMTPC"	4872	21973	"2010-11-29 12:31:46.214"	"200.188.191.180"	"RECEIVED: 354 Enter mail, end with "." on a line by itself"
"SMTPC"	2748	21973	"2010-11-29 12:31:53.405"	"200.188.191.180"	"SENT: [nl]."
"SMTPC"	3284	21973	"2010-11-29 12:31:59.817"	"200.188.191.180"	"RECEIVED: 250 2.0.0 oATETdtc004621 Message accepted for delivery"
"SMTPC"	3284	21973	"2010-11-29 12:31:59.817"	"200.188.191.180"	"SENT: QUIT"
"SMTPC"	2408	21973	"2010-11-29 12:32:00.223"	"200.188.191.180"	"RECEIVED: 221 2.0.0 mx1.diariosassociados.com.br closing connection"
"APPLICATION"	5064	"2010-11-29 12:32:00.223"	"SMTPDeliverer - Message 322919: Message delivery thread completed."
"IMAPD"	3352	23	"2010-11-29 12:33:28.550"	"67.223.80.171"	"RECEIVED: DONE"
"IMAPD"	3352	23	"2010-11-29 12:33:28.550"	"67.223.80.171"	"SENT: i OK IDLE terminated"
"IMAPD"	3352	23	"2010-11-29 12:33:28.597"	"67.223.80.171"	"RECEIVED: f UID FETCH 1592:* (UID FLAGS)"
"IMAPD"	3352	23	"2010-11-29 12:33:28.597"	"67.223.80.171"	"SENT: * 34 FETCH (UID 1592 FLAGS ())"
"IMAPD"	3352	23	"2010-11-29 12:33:28.597"	"67.223.80.171"	"SENT: f OK UID completed"
"IMAPD"	1888	23	"2010-11-29 12:33:28.721"	"67.223.80.171"	"RECEIVED: i IDLE"
"IMAPD"	1888	23	"2010-11-29 12:33:28.721"	"67.223.80.171"	"SENT: + idling"
"IMAPD"	2560	19	"2010-11-29 12:33:29.486"	"67.223.81.94"	"RECEIVED: DONE"
"IMAPD"	2560	19	"2010-11-29 12:33:29.486"	"67.223.81.94"	"SENT: i OK IDLE terminated"
"IMAPD"	4880	19	"2010-11-29 12:33:29.533"	"67.223.81.94"	"RECEIVED: f UID FETCH 3996:* (UID FLAGS)"
"IMAPD"	4880	19	"2010-11-29 12:33:29.533"	"67.223.81.94"	"SENT: * 34 FETCH (UID 3996 FLAGS ())"
"IMAPD"	4880	19	"2010-11-29 12:33:29.533"	"67.223.81.94"	"SENT: f OK UID completed"
"IMAPD"	4636	19	"2010-11-29 12:33:29.673"	"67.223.81.94"	"RECEIVED: i IDLE"
"IMAPD"	4636	19	"2010-11-29 12:33:29.673"	"67.223.81.94"	"SENT: + idling"
"IMAPD"	3240	22	"2010-11-29 12:33:58.689"	"67.223.69.91"	"RECEIVED: DONE"
"IMAPD"	3240	22	"2010-11-29 12:33:58.689"	"67.223.69.91"	"SENT: i OK IDLE terminated"
"IMAPD"	2748	22	"2010-11-29 12:33:58.736"	"67.223.69.91"	"RECEIVED: f UID FETCH 3144:* (UID FLAGS)"
"IMAPD"	2748	22	"2010-11-29 12:33:58.736"	"67.223.69.91"	"SENT: * 47 FETCH (UID 3144 FLAGS ())"
"IMAPD"	2748	22	"2010-11-29 12:33:58.736"	"67.223.69.91"	"SENT: f OK UID completed"
"IMAPD"	4116	22	"2010-11-29 12:33:58.892"	"67.223.69.91"	"RECEIVED: i IDLE"
"IMAPD"	4116	22	"2010-11-29 12:33:58.892"	"67.223.69.91"	"SENT: + idling"
"IMAPD"	4116	20	"2010-11-29 12:34:03.026"	"67.223.79.2"	"RECEIVED: DONE"
"IMAPD"	4116	20	"2010-11-29 12:34:03.026"	"67.223.79.2"	"SENT: i OK IDLE terminated"
"IMAPD"	2408	20	"2010-11-29 12:34:03.073"	"67.223.79.2"	"RECEIVED: f UID FETCH 8148:* (UID FLAGS)"
"IMAPD"	2408	20	"2010-11-29 12:34:03.073"	"67.223.79.2"	"SENT: * 100 FETCH (UID 8148 FLAGS ())"
"IMAPD"	2408	20	"2010-11-29 12:34:03.073"	"67.223.79.2"	"SENT: f OK UID completed"
"IMAPD"	4636	20	"2010-11-29 12:34:03.229"	"67.223.79.2"	"RECEIVED: i IDLE"
"IMAPD"	4636	20	"2010-11-29 12:34:03.229"	"67.223.79.2"	"SENT: + idling"
"IMAPD"	3352	18	"2010-11-29 12:34:03.634"	"67.223.75.233"	"RECEIVED: DONE"
"IMAPD"	3352	18	"2010-11-29 12:34:03.634"	"67.223.75.233"	"SENT: i OK IDLE terminated"
"IMAPD"	3352	18	"2010-11-29 12:34:03.681"	"67.223.75.233"	"RECEIVED: f UID FETCH 1895:* (UID FLAGS)"
"IMAPD"	3352	18	"2010-11-29 12:34:03.681"	"67.223.75.233"	"SENT: * 1 FETCH (UID 1895 FLAGS ())"
"IMAPD"	3352	18	"2010-11-29 12:34:03.681"	"67.223.75.233"	"SENT: f OK UID completed"
"IMAPD"	4852	18	"2010-11-29 12:34:03.837"	"67.223.75.233"	"RECEIVED: i IDLE"
"IMAPD"	4852	18	"2010-11-29 12:34:03.837"	"67.223.75.233"	"SENT: + idling"
"IMAPD"	3240	21	"2010-11-29 12:34:08.626"	"67.223.79.204"	"RECEIVED: DONE"
"IMAPD"	3240	21	"2010-11-29 12:34:08.626"	"67.223.79.204"	"SENT: i OK IDLE terminated"
"IMAPD"	2560	21	"2010-11-29 12:34:08.689"	"67.223.79.204"	"RECEIVED: f UID FETCH 1784:* (UID FLAGS)"
"IMAPD"	2560	21	"2010-11-29 12:34:08.689"	"67.223.79.204"	"SENT: * 37 FETCH (UID 1784 FLAGS ())"
"IMAPD"	2560	21	"2010-11-29 12:34:08.689"	"67.223.79.204"	"SENT: f OK UID completed"
"IMAPD"	4116	21	"2010-11-29 12:34:08.829"	"67.223.79.204"	"RECEIVED: i IDLE"
"IMAPD"	4116	21	"2010-11-29 12:34:08.829"	"67.223.79.204"	"SENT: + idling"
"IMAPD"	4116	51	"2010-11-29 12:34:12.495"	"67.223.78.161"	"RECEIVED: DONE"
"IMAPD"	4116	51	"2010-11-29 12:34:12.495"	"67.223.78.161"	"SENT: i OK IDLE terminated"
"IMAPD"	4116	51	"2010-11-29 12:34:12.558"	"67.223.78.161"	"RECEIVED: f UID FETCH 14117:* (UID FLAGS)"
"IMAPD"	4116	51	"2010-11-29 12:34:12.558"	"67.223.78.161"	"SENT: * 280 FETCH (UID 14117 FLAGS ())"
"IMAPD"	4116	51	"2010-11-29 12:34:12.558"	"67.223.78.161"	"SENT: f OK UID completed"
"IMAPD"	4872	51	"2010-11-29 12:34:12.698"	"67.223.78.161"	"RECEIVED: i IDLE"
"IMAPD"	4872	51	"2010-11-29 12:34:12.698"	"67.223.78.161"	"SENT: + idling"
"IMAPD"	4880	22048	"2010-11-29 12:36:10.603"	"127.0.0.1"	"SENT: * OK IMAPrev1"
"IMAPD"	4880	22048	"2010-11-29 12:36:10.603"	"127.0.0.1"	"RECEIVED: 00000000 CAPABILITY"
"IMAPD"	4880	22048	"2010-11-29 12:36:10.603"	"127.0.0.1"	"SENT: * CAPABILITY IMAP4 IMAP4rev1 CHILDREN IDLE QUOTA SORT ACL NAMESPACE RIGHTS=texk[nl]00000000 OK CAPABILITY completed"
"IMAPD"	4872	22048	"2010-11-29 12:36:10.603"	"127.0.0.1"	"RECEIVED: 00000001 LOGIN financeiro@globalwine.com.br ***"
"IMAPD"	4872	22048	"2010-11-29 12:36:10.603"	"127.0.0.1"	"SENT: 00000001 OK LOGIN completed"
"IMAPD"	4872	22048	"2010-11-29 12:36:10.603"	"127.0.0.1"	"RECEIVED: 00000002 CAPABILITY"
"IMAPD"	4872	22048	"2010-11-29 12:36:10.603"	"127.0.0.1"	"SENT: * CAPABILITY IMAP4 IMAP4rev1 CHILDREN IDLE QUOTA SORT ACL NAMESPACE RIGHTS=texk[nl]00000002 OK CAPABILITY completed"
"IMAPD"	4852	22048	"2010-11-29 12:36:10.619"	"127.0.0.1"	"RECEIVED: 00000003 STATUS INBOX (MESSAGES RECENT UNSEEN UIDNEXT UIDVALIDITY)"
"IMAPD"	4852	22048	"2010-11-29 12:36:10.619"	"127.0.0.1"	"SENT: * STATUS "INBOX" (MESSAGES 220 UNSEEN 113 RECENT 0 UIDNEXT 244 UIDVALIDITY 1284324082)[nl]00000003 OK Status completed"
"IMAPD"	3240	22048	"2010-11-29 12:36:10.775"	"127.0.0.1"	"RECEIVED: 00000004 LOGOUT"
"IMAPD"	3240	22048	"2010-11-29 12:36:10.775"	"127.0.0.1"	"SENT: * BYE Have a nice day[nl]00000004 OK Logout completed"
"IMAPD"	3352	23	"2010-11-29 12:37:58.774"	"67.223.80.171"	"RECEIVED: DONE"
"IMAPD"	3352	23	"2010-11-29 12:37:58.774"	"67.223.80.171"	"SENT: i OK IDLE terminated"
"IMAPD"	4880	23	"2010-11-29 12:37:58.820"	"67.223.80.171"	"RECEIVED: f UID FETCH 1592:* (UID FLAGS)"
"IMAPD"	4880	23	"2010-11-29 12:37:58.820"	"67.223.80.171"	"SENT: * 34 FETCH (UID 1592 FLAGS ())"
"IMAPD"	4880	23	"2010-11-29 12:37:58.820"	"67.223.80.171"	"SENT: f OK UID completed"
"IMAPD"	2748	23	"2010-11-29 12:37:58.945"	"67.223.80.171"	"RECEIVED: i IDLE"
"IMAPD"	2748	23	"2010-11-29 12:37:58.945"	"67.223.80.171"	"SENT: + idling"
"IMAPD"	2408	19	"2010-11-29 12:37:59.725"	"67.223.81.94"	"RECEIVED: DONE"
"IMAPD"	2408	19	"2010-11-29 12:37:59.725"	"67.223.81.94"	"SENT: i OK IDLE terminated"
"IMAPD"	1888	19	"2010-11-29 12:37:59.772"	"67.223.81.94"	"RECEIVED: f UID FETCH 3996:* (UID FLAGS)"
"IMAPD"	1888	19	"2010-11-29 12:37:59.772"	"67.223.81.94"	"SENT: * 34 FETCH (UID 3996 FLAGS ())"
"IMAPD"	1888	19	"2010-11-29 12:37:59.772"	"67.223.81.94"	"SENT: f OK UID completed"
"IMAPD"	3352	19	"2010-11-29 12:37:59.897"	"67.223.81.94"	"RECEIVED: i IDLE"
"IMAPD"	3352	19	"2010-11-29 12:37:59.897"	"67.223.81.94"	"SENT: + idling"
"IMAPD"	4880	22049	"2010-11-29 12:38:14.046"	"67.223.75.233"	"SENT: * OK IMAPrev1"
"IMAPD"	4116	22049	"2010-11-29 12:38:14.186"	"67.223.75.233"	"RECEIVED: c CAPABILITY"
"IMAPD"	4116	22049	"2010-11-29 12:38:14.186"	"67.223.75.233"	"SENT: * CAPABILITY IMAP4 IMAP4rev1 CHILDREN IDLE QUOTA SORT ACL NAMESPACE RIGHTS=texk[nl]c OK CAPABILITY completed"
"IMAPD"	2560	22049	"2010-11-29 12:38:14.249"	"67.223.75.233"	"RECEIVED: l LOGIN "tatiana@espacoz.com.br" ***"
"IMAPD"	2560	22049	"2010-11-29 12:38:14.249"	"67.223.75.233"	"SENT: l OK LOGIN completed"
"IMAPD"	3352	22049	"2010-11-29 12:38:14.311"	"67.223.75.233"	"RECEIVED: l LIST "" %"
"IMAPD"	3352	22049	"2010-11-29 12:38:14.311"	"67.223.75.233"	"SENT: * LIST (\HasNoChildren) "." "INBOX"[nl]l OK LIST completed"
"IMAPD"	2408	22049	"2010-11-29 12:38:14.374"	"67.223.75.233"	"RECEIVED: l LIST "" "%.%""
"IMAPD"	2408	22049	"2010-11-29 12:38:14.374"	"67.223.75.233"	"SENT: l OK LIST completed"
"IMAPD"	4880	22049	"2010-11-29 12:38:14.561"	"67.223.75.233"	"RECEIVED: s SELECT "INBOX""
"IMAPD"	4880	22049	"2010-11-29 12:38:14.561"	"67.223.75.233"	"SENT: * 1 EXISTS[nl]* 0 RECENT[nl]* FLAGS (\Deleted \Seen \Draft \Answered \Flagged)[nl]* OK [UIDVALIDITY 1284222064][nl]* OK [UNSEEN 1895][nl]* OK [UIDNEXT 1896][nl]* OK [PERMANENTFLAGS (\Deleted \Seen \Draft \Answered \Flagged)][nl]s OK [READ-WRITE] SELECT completed"
"IMAPD"	4880	22049	"2010-11-29 12:38:14.608"	"67.223.75.233"	"RECEIVED: f UID FETCH 1895 (UID FLAGS RFC822.size BODY.PEEK[HEADER.FIELDS (X-Priority Content-Type From To Cc Bcc subject date Message-ID)])"
"IMAPD"	4880	22049	"2010-11-29 12:38:14.608"	"67.223.75.233"	"SENT: * 1 FETCH (UID 1895 RFC822.SIZE 27185 FLAGS () BODY[HEADER.FIELDS (X-Priority Content-Type From To Cc Bcc subject date Message-ID)] {459}"
"IMAPD"	4880	22049	"2010-11-29 12:38:14.608"	"67.223.75.233"	"SENT: )"
"IMAPD"	4880	22049	"2010-11-29 12:38:14.608"	"67.223.75.233"	"SENT: f OK UID completed"
"IMAPD"	4116	22049	"2010-11-29 12:38:14.810"	"67.223.75.233"	"RECEIVED: d UID STORE 1895 +FLAGS (\Seen)"
"IMAPD"	4116	22049	"2010-11-29 12:38:14.810"	"67.223.75.233"	"SENT: * 1 FETCH (FLAGS (\Seen) UID 1895)"
"IMAPD"	4116	18	"2010-11-29 12:38:14.826"	"67.223.75.233"	"SENT: * 1 FETCH (FLAGS (\Seen) UID 1895)"
"IMAPD"	4116	22049	"2010-11-29 12:38:14.826"	"67.223.75.233"	"SENT: d OK UID completed"
"IMAPD"	3284	22049	"2010-11-29 12:38:14.982"	"67.223.75.233"	"RECEIVED: q LOGOUT"
"IMAPD"	3284	22049	"2010-11-29 12:38:14.982"	"67.223.75.233"	"SENT: * BYE Have a nice day[nl]q OK Logout completed"
"IMAPD"	4852	22	"2010-11-29 12:38:28.944"	"67.223.69.91"	"RECEIVED: DONE"
"IMAPD"	4852	22	"2010-11-29 12:38:28.944"	"67.223.69.91"	"SENT: i OK IDLE terminated"
"IMAPD"	2408	22	"2010-11-29 12:38:29.006"	"67.223.69.91"	"RECEIVED: f UID FETCH 3144:* (UID FLAGS)"
"IMAPD"	2408	22	"2010-11-29 12:38:29.006"	"67.223.69.91"	"SENT: * 47 FETCH (UID 3144 FLAGS ())"
"IMAPD"	2408	22	"2010-11-29 12:38:29.006"	"67.223.69.91"	"SENT: f OK UID completed"
"IMAPD"	3148	22	"2010-11-29 12:38:29.147"	"67.223.69.91"	"RECEIVED: i IDLE"
"IMAPD"	3148	22	"2010-11-29 12:38:29.147"	"67.223.69.91"	"SENT: + idling"
"IMAPD"	4880	22050	"2010-11-29 12:38:32.282"	"67.223.75.233"	"SENT: * OK IMAPrev1"
"IMAPD"	2748	22050	"2010-11-29 12:38:32.329"	"67.223.75.233"	"RECEIVED: c CAPABILITY"
"IMAPD"	2748	22050	"2010-11-29 12:38:32.329"	"67.223.75.233"	"SENT: * CAPABILITY IMAP4 IMAP4rev1 CHILDREN IDLE QUOTA SORT ACL NAMESPACE RIGHTS=texk[nl]c OK CAPABILITY completed"
"IMAPD"	4872	22050	"2010-11-29 12:38:32.392"	"67.223.75.233"	"RECEIVED: l LOGIN "tatiana@espacoz.com.br" ***"
"IMAPD"	4872	22050	"2010-11-29 12:38:32.392"	"67.223.75.233"	"SENT: l OK LOGIN completed"
"IMAPD"	1888	22050	"2010-11-29 12:38:32.438"	"67.223.75.233"	"RECEIVED: l LIST "" %"
"IMAPD"	1888	22050	"2010-11-29 12:38:32.438"	"67.223.75.233"	"SENT: * LIST (\HasNoChildren) "." "INBOX"[nl]l OK LIST completed"
"IMAPD"	2748	22050	"2010-11-29 12:38:32.501"	"67.223.75.233"	"RECEIVED: l LIST "" "%.%""
"IMAPD"	2748	22050	"2010-11-29 12:38:32.501"	"67.223.75.233"	"SENT: l OK LIST completed"
"IMAPD"	3284	22050	"2010-11-29 12:38:32.579"	"67.223.75.233"	"RECEIVED: s SELECT "INBOX""
"IMAPD"	3284	22050	"2010-11-29 12:38:32.579"	"67.223.75.233"	"SENT: * 1 EXISTS[nl]* 0 RECENT[nl]* FLAGS (\Deleted \Seen \Draft \Answered \Flagged)[nl]* OK [UIDVALIDITY 1284222064][nl]* OK [UIDNEXT 1896][nl]* OK [PERMANENTFLAGS (\Deleted \Seen \Draft \Answered \Flagged)][nl]s OK [READ-WRITE] SELECT completed"
"IMAPD"	2560	22050	"2010-11-29 12:38:32.641"	"67.223.75.233"	"RECEIVED: f UID FETCH 1894 (UID FLAGS RFC822.size BODY.PEEK[HEADER.FIELDS (X-Priority Content-Type From To Cc Bcc subject date Message-ID)])"
"IMAPD"	2560	22050	"2010-11-29 12:38:32.641"	"67.223.75.233"	"SENT: f OK UID completed"
"IMAPD"	4116	22050	"2010-11-29 12:38:32.735"	"67.223.75.233"	"RECEIVED: q LOGOUT"
"IMAPD"	4116	22050	"2010-11-29 12:38:32.735"	"67.223.75.233"	"SENT: * BYE Have a nice day[nl]q OK Logout completed"
"IMAPD"	3148	20	"2010-11-29 12:38:33.281"	"67.223.79.2"	"RECEIVED: DONE"
"IMAPD"	3148	20	"2010-11-29 12:38:33.281"	"67.223.79.2"	"SENT: i OK IDLE terminated"
"IMAPD"	4880	20	"2010-11-29 12:38:33.343"	"67.223.79.2"	"RECEIVED: f UID FETCH 8148:* (UID FLAGS)"
"IMAPD"	4880	20	"2010-11-29 12:38:33.343"	"67.223.79.2"	"SENT: * 100 FETCH (UID 8148 FLAGS ())"
"IMAPD"	4880	20	"2010-11-29 12:38:33.343"	"67.223.79.2"	"SENT: f OK UID completed"
"IMAPD"	4852	20	"2010-11-29 12:38:33.499"	"67.223.79.2"	"RECEIVED: i IDLE"
"IMAPD"	4852	20	"2010-11-29 12:38:33.499"	"67.223.79.2"	"SENT: + idling"
"IMAPD"	4872	18	"2010-11-29 12:38:33.889"	"67.223.75.233"	"RECEIVED: DONE"
"IMAPD"	4872	18	"2010-11-29 12:38:33.889"	"67.223.75.233"	"SENT: i OK IDLE terminated"
"IMAPD"	2408	18	"2010-11-29 12:38:33.952"	"67.223.75.233"	"RECEIVED: f UID FETCH 1895:* (UID FLAGS)"
"IMAPD"	2408	18	"2010-11-29 12:38:33.952"	"67.223.75.233"	"SENT: * 1 FETCH (UID 1895 FLAGS (\Seen))"
"IMAPD"	2408	18	"2010-11-29 12:38:33.952"	"67.223.75.233"	"SENT: f OK UID completed"
"IMAPD"	4116	18	"2010-11-29 12:38:34.092"	"67.223.75.233"	"RECEIVED: i IDLE"
"IMAPD"	4116	18	"2010-11-29 12:38:34.092"	"67.223.75.233"	"SENT: + idling"
"IMAPD"	4880	21	"2010-11-29 12:38:38.897"	"67.223.79.204"	"RECEIVED: DONE"
"IMAPD"	4880	21	"2010-11-29 12:38:38.897"	"67.223.79.204"	"SENT: i OK IDLE terminated"
"IMAPD"	4872	21	"2010-11-29 12:38:38.944"	"67.223.79.204"	"RECEIVED: f UID FETCH 1784:* (UID FLAGS)"
"IMAPD"	4872	21	"2010-11-29 12:38:38.944"	"67.223.79.204"	"SENT: * 37 FETCH (UID 1784 FLAGS ())"
"IMAPD"	4872	21	"2010-11-29 12:38:38.944"	"67.223.79.204"	"SENT: f OK UID completed"
"IMAPD"	4852	21	"2010-11-29 12:38:39.084"	"67.223.79.204"	"RECEIVED: i IDLE"
"IMAPD"	4852	21	"2010-11-29 12:38:39.100"	"67.223.79.204"	"SENT: + idling"
"IMAPD"	3284	51	"2010-11-29 12:38:42.750"	"67.223.78.161"	"RECEIVED: DONE"
"IMAPD"	3284	51	"2010-11-29 12:38:42.750"	"67.223.78.161"	"SENT: i OK IDLE terminated"
"IMAPD"	2408	51	"2010-11-29 12:38:42.812"	"67.223.78.161"	"RECEIVED: f UID FETCH 14117:* (UID FLAGS)"
"IMAPD"	2408	51	"2010-11-29 12:38:42.812"	"67.223.78.161"	"SENT: * 280 FETCH (UID 14117 FLAGS ())"
"IMAPD"	2408	51	"2010-11-29 12:38:42.812"	"67.223.78.161"	"SENT: f OK UID completed"
"IMAPD"	2748	51	"2010-11-29 12:38:42.968"	"67.223.78.161"	"RECEIVED: i IDLE"
"IMAPD"	2748	51	"2010-11-29 12:38:42.968"	"67.223.78.161"	"SENT: + idling"
"IMAPD"	4880	22052	"2010-11-29 12:41:14.335"	"127.0.0.1"	"SENT: * OK IMAPrev1"
"IMAPD"	4880	22052	"2010-11-29 12:41:14.335"	"127.0.0.1"	"RECEIVED: 00000000 CAPABILITY"
"IMAPD"	4880	22052	"2010-11-29 12:41:14.335"	"127.0.0.1"	"SENT: * CAPABILITY IMAP4 IMAP4rev1 CHILDREN IDLE QUOTA SORT ACL NAMESPACE RIGHTS=texk[nl]00000000 OK CAPABILITY completed"
"IMAPD"	4116	22052	"2010-11-29 12:41:14.335"	"127.0.0.1"	"RECEIVED: 00000001 LOGIN financeiro@globalwine.com.br ***"
"IMAPD"	4116	22052	"2010-11-29 12:41:14.335"	"127.0.0.1"	"SENT: 00000001 OK LOGIN completed"
"IMAPD"	4116	22052	"2010-11-29 12:41:14.335"	"127.0.0.1"	"RECEIVED: 00000002 CAPABILITY"
"IMAPD"	4116	22052	"2010-11-29 12:41:14.335"	"127.0.0.1"	"SENT: * CAPABILITY IMAP4 IMAP4rev1 CHILDREN IDLE QUOTA SORT ACL NAMESPACE RIGHTS=texk[nl]00000002 OK CAPABILITY completed"
"IMAPD"	3284	22052	"2010-11-29 12:41:14.351"	"127.0.0.1"	"RECEIVED: 00000003 STATUS INBOX (MESSAGES RECENT UNSEEN UIDNEXT UIDVALIDITY)"
"IMAPD"	3284	22052	"2010-11-29 12:41:14.351"	"127.0.0.1"	"SENT: * STATUS "INBOX" (MESSAGES 220 UNSEEN 113 RECENT 0 UIDNEXT 244 UIDVALIDITY 1284324082)[nl]00000003 OK Status completed"
"IMAPD"	4872	22052	"2010-11-29 12:41:14.507"	"127.0.0.1"	"RECEIVED: 00000004 LOGOUT"
"IMAPD"	4872	22052	"2010-11-29 12:41:14.507"	"127.0.0.1"	"SENT: * BYE Have a nice day[nl]00000004 OK Logout completed"
"IMAPD"	2948	23	"2010-11-29 12:42:28.982"	"67.223.80.171"	"RECEIVED: DONE"
"IMAPD"	2948	23	"2010-11-29 12:42:28.982"	"67.223.80.171"	"SENT: i OK IDLE terminated"
"IMAPD"	4872	23	"2010-11-29 12:42:29.028"	"67.223.80.171"	"RECEIVED: f UID FETCH 1592:* (UID FLAGS)"
"IMAPD"	4872	23	"2010-11-29 12:42:29.028"	"67.223.80.171"	"SENT: * 34 FETCH (UID 1592 FLAGS ())"
"IMAPD"	4872	23	"2010-11-29 12:42:29.028"	"67.223.80.171"	"SENT: f OK UID completed"
"IMAPD"	2748	23	"2010-11-29 12:42:29.153"	"67.223.80.171"	"RECEIVED: i IDLE"
"IMAPD"	2748	23	"2010-11-29 12:42:29.153"	"67.223.80.171"	"SENT: + idling"
"IMAPD"	2748	19	"2010-11-29 12:42:29.949"	"67.223.81.94"	"RECEIVED: DONE"
"IMAPD"	2748	19	"2010-11-29 12:42:29.949"	"67.223.81.94"	"SENT: i OK IDLE terminated"
"IMAPD"	4872	19	"2010-11-29 12:42:29.996"	"67.223.81.94"	"RECEIVED: f UID FETCH 3996:* (UID FLAGS)"
"IMAPD"	4872	19	"2010-11-29 12:42:29.996"	"67.223.81.94"	"SENT: * 34 FETCH (UID 3996 FLAGS ())"
"IMAPD"	4872	19	"2010-11-29 12:42:29.996"	"67.223.81.94"	"SENT: f OK UID completed"
"IMAPD"	4880	19	"2010-11-29 12:42:30.120"	"67.223.81.94"	"RECEIVED: i IDLE"
"IMAPD"	4880	19	"2010-11-29 12:42:30.120"	"67.223.81.94"	"SENT: + idling"
"IMAPD"	3284	22	"2010-11-29 12:42:59.199"	"67.223.69.91"	"RECEIVED: DONE"
"IMAPD"	3284	22	"2010-11-29 12:42:59.199"	"67.223.69.91"	"SENT: i OK IDLE terminated"
"IMAPD"	4880	22	"2010-11-29 12:42:59.261"	"67.223.69.91"	"RECEIVED: f UID FETCH 3144:* (UID FLAGS)"
"IMAPD"	4880	22	"2010-11-29 12:42:59.261"	"67.223.69.91"	"SENT: * 47 FETCH (UID 3144 FLAGS ())"
"IMAPD"	4880	22	"2010-11-29 12:42:59.261"	"67.223.69.91"	"SENT: f OK UID completed"
"IMAPD"	2408	22	"2010-11-29 12:42:59.402"	"67.223.69.91"	"RECEIVED: i IDLE"
"IMAPD"	2408	22	"2010-11-29 12:42:59.417"	"67.223.69.91"	"SENT: + idling"
"IMAPD"	2408	20	"2010-11-29 12:43:03.551"	"67.223.79.2"	"RECEIVED: DONE"
"IMAPD"	2408	20	"2010-11-29 12:43:03.551"	"67.223.79.2"	"SENT: i OK IDLE terminated"
"IMAPD"	2408	20	"2010-11-29 12:43:03.614"	"67.223.79.2"	"RECEIVED: f UID FETCH 8148:* (UID FLAGS)"
"IMAPD"	2408	20	"2010-11-29 12:43:03.614"	"67.223.79.2"	"SENT: * 100 FETCH (UID 8148 FLAGS ())"
"IMAPD"	2408	20	"2010-11-29 12:43:03.614"	"67.223.79.2"	"SENT: f OK UID completed"
"IMAPD"	3352	20	"2010-11-29 12:43:03.770"	"67.223.79.2"	"RECEIVED: i IDLE"
"IMAPD"	3352	20	"2010-11-29 12:43:03.770"	"67.223.79.2"	"SENT: + idling"
"IMAPD"	2948	18	"2010-11-29 12:43:04.144"	"67.223.75.233"	"RECEIVED: DONE"
"IMAPD"	2948	18	"2010-11-29 12:43:04.144"	"67.223.75.233"	"SENT: i OK IDLE terminated"
"IMAPD"	4116	18	"2010-11-29 12:43:04.206"	"67.223.75.233"	"RECEIVED: f UID FETCH 1895:* (UID FLAGS)"
"IMAPD"	4116	18	"2010-11-29 12:43:04.206"	"67.223.75.233"	"SENT: * 1 FETCH (UID 1895 FLAGS (\Seen))"
"IMAPD"	4116	18	"2010-11-29 12:43:04.206"	"67.223.75.233"	"SENT: f OK UID completed"
"IMAPD"	2408	18	"2010-11-29 12:43:04.362"	"67.223.75.233"	"RECEIVED: i IDLE"
"IMAPD"	2408	18	"2010-11-29 12:43:04.362"	"67.223.75.233"	"SENT: + idling"
"IMAPD"	2748	21	"2010-11-29 12:43:09.152"	"67.223.79.204"	"RECEIVED: DONE"
"IMAPD"	2748	21	"2010-11-29 12:43:09.152"	"67.223.79.204"	"SENT: i OK IDLE terminated"
"IMAPD"	4880	21	"2010-11-29 12:43:09.198"	"67.223.79.204"	"RECEIVED: f UID FETCH 1784:* (UID FLAGS)"
"IMAPD"	4880	21	"2010-11-29 12:43:09.198"	"67.223.79.204"	"SENT: * 37 FETCH (UID 1784 FLAGS ())"
"IMAPD"	4880	21	"2010-11-29 12:43:09.198"	"67.223.79.204"	"SENT: f OK UID completed"
"IMAPD"	4116	21	"2010-11-29 12:43:09.354"	"67.223.79.204"	"RECEIVED: i IDLE"
"IMAPD"	4116	21	"2010-11-29 12:43:09.354"	"67.223.79.204"	"SENT: + idling"
"IMAPD"	3284	51	"2010-11-29 12:43:13.036"	"67.223.78.161"	"RECEIVED: DONE"
"IMAPD"	3284	51	"2010-11-29 12:43:13.036"	"67.223.78.161"	"SENT: i OK IDLE terminated"
"IMAPD"	4116	51	"2010-11-29 12:43:13.083"	"67.223.78.161"	"RECEIVED: f UID FETCH 14117:* (UID FLAGS)"
"IMAPD"	4116	51	"2010-11-29 12:43:13.083"	"67.223.78.161"	"SENT: * 280 FETCH (UID 14117 FLAGS ())"
"IMAPD"	4116	51	"2010-11-29 12:43:13.083"	"67.223.78.161"	"SENT: f OK UID completed"
"IMAPD"	3352	51	"2010-11-29 12:43:13.223"	"67.223.78.161"	"RECEIVED: i IDLE"
"IMAPD"	3352	51	"2010-11-29 12:43:13.223"	"67.223.78.161"	"SENT: + idling"
"APPLICATION"	5064	"2010-11-29 12:44:19.492"	"SMTPDeliverer - Message 323071: Delivering message from anafonseca@espacoz.com.br to lianaobata@uol.com.br. File: C:\Program Files (x86)\hMailServer\Data\{33AA150A-530F-4F14-A387-4BD4E1F290A1}.eml"
"SMTPC"	4872	22053	"2010-11-29 12:44:19.804"	"200.147.36.15"	"RECEIVED: 220 a2-starfury12.uol.com.br ESMTP"
"SMTPC"	4872	22053	"2010-11-29 12:44:19.804"	"200.147.36.15"	"SENT: HELO srv01.espacoz.com.br"
"SMTPC"	2748	22053	"2010-11-29 12:44:19.960"	"200.147.36.15"	"RECEIVED: 250 a2-starfury12.uol.com.br"
"SMTPC"	2748	22053	"2010-11-29 12:44:19.960"	"200.147.36.15"	"SENT: MAIL FROM:<anafonseca@espacoz.com.br>"
"SMTPC"	2408	22053	"2010-11-29 12:44:20.116"	"200.147.36.15"	"RECEIVED: 250 2.1.0 Ok"
"SMTPC"	2408	22053	"2010-11-29 12:44:20.116"	"200.147.36.15"	"SENT: RCPT TO:<lianaobata@uol.com.br>"
"SMTPC"	4116	22053	"2010-11-29 12:44:20.413"	"200.147.36.15"	"RECEIVED: 250 2.1.5 Ok"
"SMTPC"	4116	22053	"2010-11-29 12:44:20.413"	"200.147.36.15"	"SENT: DATA"
"SMTPC"	4880	22053	"2010-11-29 12:44:20.569"	"200.147.36.15"	"RECEIVED: 354 End data with <CR><LF>.<CR><LF>"
"SMTPC"	4880	22053	"2010-11-29 12:44:20.569"	"200.147.36.15"	"SENT: [nl]."
"SMTPC"	3352	22053	"2010-11-29 12:44:21.333"	"200.147.36.15"	"RECEIVED: 250 2.0.0 Ok: queued as B794D7000104"
"SMTPC"	3352	22053	"2010-11-29 12:44:21.333"	"200.147.36.15"	"SENT: QUIT"
"SMTPC"	2748	22053	"2010-11-29 12:44:21.489"	"200.147.36.15"	"RECEIVED: 221 2.0.0 Bye"
"APPLICATION"	5064	"2010-11-29 12:44:21.505"	"SMTPDeliverer - Message 323071: Message delivery thread completed."
"IMAPD"	2408	16941	"2010-11-29 12:45:58.646"	"187.80.1.237"	"SENT: * BYE You will be disconnected because of timeout (30 minutes)."
"IMAPD"	3284	16986	"2010-11-29 12:46:00.081"	"187.80.1.237"	"SENT: * BYE You will be disconnected because of timeout (30 minutes)."
"IMAPD"	2948	16951	"2010-11-29 12:46:02.530"	"187.80.1.237"	"SENT: * BYE You will be disconnected because of timeout (30 minutes)."
"ERROR"	2408	"2010-11-29 12:46:18.302"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"APPLICATION"	648	"2010-11-29 12:46:49.424"	"Stopping servers..."
Image

blakito
Normal user
Normal user
Posts: 30
Joined: 2010-09-15 14:27

Re: hMailServer crashes/stops

Post by blakito » 2010-11-29 18:55

What a bad day, its crashing every 60 minutes or so. The error log is getting bigger ...

Something related to blackbery servers maybe :?:

Code: Select all

"ERROR"	2408	"2010-11-29 12:31:27.322"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR"	2408	"2010-11-29 12:31:38.289"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR"	2408	"2010-11-29 12:46:18.302"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR"	3148	"2010-11-29 14:29:43.088"	"Severity: 2 (High), Code: HM5339, Source: TCPConnection::HandleWrite, Description: An unknown error occurred while handling buffer write."
"ERROR"	5104	"2010-11-29 14:29:43.088"	"Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 189.4.226.38"
"ERROR"	3148	"2010-11-29 14:29:43.088"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR"	5104	"2010-11-29 14:29:43.088"	"Severity: 3 (Medium), Code: HM5141, Source: TCPConnection::HandleRead, Description: An error occurred while handling read operation. Remote IP: 189.4.226.38"
"ERROR"	1796	"2010-11-29 14:29:43.088"	"Severity: 2 (High), Code: HM5339, Source: TCPConnection::HandleWrite, Description: An unknown error occurred while handling buffer write."
"ERROR"	5104	"2010-11-29 14:29:43.088"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR"	1796	"2010-11-29 14:29:43.088"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR"	2456	"2010-11-29 14:29:43.088"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR"	2456	"2010-11-29 14:29:43.104"	"Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 187.59.9.248"
"ERROR"	2456	"2010-11-29 14:29:43.104"	"Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 186.220.50.120"
"ERROR"	4604	"2010-11-29 14:29:43.229"	"Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 186.220.50.120"
"ERROR"	4604	"2010-11-29 14:29:43.229"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR"	4848	"2010-11-29 14:29:43.416"	"Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 186.213.250.119"
"ERROR"	4848	"2010-11-29 14:29:43.416"	"Severity: 3 (Medium), Code: HM5141, Source: TCPConnection::HandleRead, Description: An error occurred while handling read operation. Remote IP: 186.213.250.119"
"ERROR"	4848	"2010-11-29 14:29:43.431"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR"	4812	"2010-11-29 14:47:55.870"	"Severity: 2 (High), Code: HM5339, Source: TCPConnection::HandleWrite, Description: An unknown error occurred while handling buffer write."
"ERROR"	4812	"2010-11-29 14:47:55.870"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR"	3180	"2010-11-29 14:47:55.870"	"Severity: 2 (High), Code: HM5339, Source: TCPConnection::HandleWrite, Description: An unknown error occurred while handling buffer write."
"ERROR"	2624	"2010-11-29 14:47:55.870"	"Severity: 2 (High), Code: HM5339, Source: TCPConnection::HandleWrite, Description: An unknown error occurred while handling buffer write."
"ERROR"	3180	"2010-11-29 14:47:55.870"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR"	2624	"2010-11-29 14:47:55.870"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR"	2680	"2010-11-29 14:50:34.897"	"Severity: 2 (High), Code: HM5339, Source: TCPConnection::HandleWrite, Description: An unknown error occurred while handling buffer write."
"ERROR"	2680	"2010-11-29 14:50:34.897"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR"	4692	"2010-11-29 14:50:34.897"	"Severity: 2 (High), Code: HM5339, Source: TCPConnection::HandleWrite, Description: An unknown error occurred while handling buffer write."
"ERROR"	4692	"2010-11-29 14:50:34.897"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR"	1980	"2010-11-29 14:50:35.006"	"Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 187.59.9.248"
"ERROR"	1980	"2010-11-29 14:50:35.006"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR"	2624	"2010-11-29 14:50:36.020"	"Severity: 2 (High), Code: HM5339, Source: TCPConnection::HandleWrite, Description: An unknown error occurred while handling buffer write."
"ERROR"	2624	"2010-11-29 14:50:36.036"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR"	3180	"2010-11-29 14:50:36.067"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR"	4692	"2010-11-29 14:50:36.488"	"Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 200.152.203.139"
"ERROR"	4692	"2010-11-29 14:50:36.504"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR"	4080	"2010-11-29 14:50:36.582"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR"	2624	"2010-11-29 14:50:45.302"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR"	1280	"2010-11-29 14:50:46.878"	"Severity: 2 (High), Code: HM5032, Source: DALConnection::Execute, Description: Source: MySQLConnection::TryExecute, Code: HM10048, Description: An unhandled error occurred while executing: delete from hm_securityranges where rangeexpires = 1 AND rangeexpirestime < '2010-11-29 14:50:46'"
"ERROR"	3980	"2010-11-29 14:50:56.503"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR"	3000	"2010-11-29 14:51:03.117"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR"	3472	"2010-11-29 14:51:21.837"	"Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 31, Data: f UID FETCH 14162:* (UID FLAGS). Remote IP: 67.223.78.161"
"ERROR"	3472	"2010-11-29 14:51:21.837"	"Severity: 3 (Medium), Code: HM5141, Source: TCPConnection::HandleRead, Description: An error occurred while handling read operation. Remote IP: 67.223.78.161"
"ERROR"	3472	"2010-11-29 14:51:21.837"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR"	1280	"2010-11-29 14:51:46.891"	"Severity: 2 (High), Code: HM5032, Source: DALConnection::Execute, Description: Source: MySQLConnection::TryExecute, Code: HM10048, Description: An unhandled error occurred while executing: delete from hm_securityranges where rangeexpires = 1 AND rangeexpirestime < '2010-11-29 14:51:46'"
"ERROR"	4540	"2010-11-29 14:52:34.533"	"Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 30, Data: f UID FETCH 8172:* (UID FLAGS). Remote IP: 67.223.79.2"
"ERROR"	4540	"2010-11-29 14:52:34.533"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."

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

Re: hMailServer crashes/stops

Post by martin » 2010-11-29 19:43

We're looking at this problem and I hope we have a fix for it soon.

blakito
Normal user
Normal user
Posts: 30
Joined: 2010-09-15 14:27

Re: hMailServer crashes/stops

Post by blakito » 2010-11-30 13:54

Thanks for the feedback Martin.

Let me know if i can help anyway.

Best regards

adipose
New user
New user
Posts: 24
Joined: 2004-05-28 23:55

Re: hMailServer crashes/stops

Post by adipose » 2010-11-30 22:10

Twice in two days I had a "crash" where connections were being dropped by hmail. Here's the log from right before/after I restarted the service:

Code: Select all

"ERROR"	7744	"2010-11-30 12:01:50.393"	"Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 192.168.3.1"
"ERROR"	7744	"2010-11-30 12:01:50.674"	"Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing buffer. Remote IP: 192.168.3.1"
"APPLICATION"	6024	"2010-11-30 12:01:51.205"	"Stopping servers..."
"ERROR"	5580	"2010-11-30 12:01:51.283"	"Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"APPLICATION"	5788	"2010-11-30 12:02:46.939"	"Starting servers..."
"APPLICATION"	5788	"2010-11-30 12:02:47.782"	"Servers started."

blakito
Normal user
Normal user
Posts: 30
Joined: 2010-09-15 14:27

Re: hMailServer crashes/stops

Post by blakito » 2010-12-02 18:56

Mates, just a little feedback.

Ive switched the Webmail from all domains from Horde to Squirrelmail - somehow, today, no crashes it.

Maybe something related to Horde afterall, very weird cause i've been using it for months ...
Maybe a specific message in a specific user mailbox in a specific situation, you know, that kind of thing heh.

Best regards

texinick
New user
New user
Posts: 25
Joined: 2007-01-15 01:41

Re: hMailServer crashes/stops

Post by texinick » 2010-12-03 00:20

I, too, have been having similar problems. I should have left it all alone, but no, I had to go and update the mail server and update the clamav stuff plus alter a few other anti-spam settings... and since then, hmailserver has been crashing on a daily basis. So whether it's related to the latest version of hmailserver, or clamav I have no idea. However, until I updated, I hadn't been having any problems at all. Our server doesn't receive a huge amount of mail, so I don't think it's being worked too hard.

I also have SquirrelMail installed on the server, so it may just be a red herring @blakito.

I'm receiving calls from clients every two or three days telling me the mail server isn't working again, and it's been going on like this for the last 6 weeks.

I seem to have two issues... the first has only happened two or three times, whereby the following lines show up in the log:


"ERROR" 3928 "2010-12-02 13:25:32.421" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 3960 "2010-12-02 14:45:12.656" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 62, Data: MAIL FROM:<0-ip.co.jp@magerr.combzmail.jp> SIZE=1016 BODY=7BIT. Remote IP: 113.162.143.33"
"ERROR" 3960 "2010-12-02 14:46:05.718" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 45, Data: MAIL FROM: <aconcagua6@blueberryhollowri.com>. Remote IP: 113.162.141.87"
"ERROR" 3960 "2010-12-02 14:47:34.421" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 31, Data: MAIL FROM:<bucklesbp@gmail.com>. Remote IP: 209.85.161.171"

(this goes on until I restarted the server at 09:24, since then, it's back to no messages)

It appears that every single message that passed through the system is written to the logs. This error happened at 13:25 yesterday, and I was notified about the problem this morning at 09:00. The log for today alone (at 09:50) is about 2.6MB. Normal daily logs average at about 2 or 3Kb.

Another log from a few days ago shows this:


"ERROR" 2156 "2010-11-24 01:58:57.062" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 38, Data: MAIL FROM: <lambing@axelschroeder.com>. Remote IP: 110.136.156.245"
"ERROR" 2156 "2010-11-24 01:59:22.421" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 45, Data: MAIL FROM: <ppwglmmqa@professionalsupply.com>. Remote IP: 95.133.92.192"
"ERROR" 904 "2010-11-24 02:00:24.093" "Severity: 2 (High), Code: HM5313, Source: CriticalSection::CriticalSection, Description: Initialization of critical section failed. Out of memory?"
"ERROR" 1420 "2010-11-24 02:00:24.125" "Severity: 2 (High), Code: HM4208, Source: IOCPQueueWorkerTask::DoWork, Description: An unknown error occured while handling asynchronous requests."
"ERROR" 2156 "2010-11-24 02:00:32.062" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 31, Data: MAIL FROM: <no-reply@rolex.com>. Remote IP: 110.137.165.64"
"ERROR" 2156 "2010-11-24 02:00:43.468" "Severity: 3 (Medium), Code: HM5136, Source: TCPConnection::HandleRead, Description: An error occured while parsing data. Data length: 31, Data: MAIL FROM: <no-reply@rolex.com>. Remote IP: 85.134.186.5"

I'm not sure if that was one that we caught in time before it started getting parsing errors on all emails, as the log file for the days probably only had about 40 messages in it.

I'm not sure if anything here helps..

All the best
Nick

texinick
New user
New user
Posts: 25
Joined: 2007-01-15 01:41

Re: hMailServer crashes/stops

Post by texinick » 2010-12-03 00:40

I've enabled debug logging this morning just to see if that sheds any more light on the situation when the server stops working again

N

blakito
Normal user
Normal user
Posts: 30
Joined: 2010-09-15 14:27

Re: hMailServer crashes/stops

Post by blakito » 2010-12-03 14:22

Great feedback tex !

So far so good here. No crashes since Squirrel.

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

Re: hMailServer crashes/stops

Post by Bill48105 » 2010-12-03 19:58

martin & I have been working on it since we were finally able to reproduce it in the lab. We're testing out some changed but it is clearly a timing issue triggering the problems so any changes you make that alter the timing can trigger or fix it. We hope to have a fix available soon but in the meantime if your server is working leave it alone or if you are having this problem hang in there or try to vary the timing a bit. Something I've been meaning to try is putting super small sleeps or loop in onconnect event to see if that can be tweaked just right for now. Clearly not a solution but a workaround for now is better than nothing. Changing/reducing the # of connections for smtp/pop/imap seems to help and sometimes changing thread & priority settings does too but only if set below # of connections you are having which mean some get denied unfortunately.. Again that is JUST if you are having the issue and not guaranteed. Trick is to vary the timing just a slight amount and seems slower is better and local traffic (such as webmail/localhost/local network clients) trigger it much faster than internet-throttled connections. If you have an intelligent switch you can throttle connection that should help for local traffic or if your router lets you throttle especially per-port that might help. For local webmail on same machine, slight edits of the code to slow it down would likely help too.
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. ***

kcadmin
New user
New user
Posts: 17
Joined: 2009-03-29 17:30

Re: hMailServer crashes/stops

Post by kcadmin » 2010-12-04 02:49

Hi Martin and everyone!!!
As with everyone else here, I am suffering the same ills of this issue with my install of hmail 5.3.3-B1879. My sever crashed last nite and yes a simple restart solves the issue, but it is so random one never knows when it will do this.
Currently with the above build the server is using a MySQL database as opposed to the internal one, this server in a 24 hour period processes approximately 25 - 30 K of mail. I am seeing all of the errors and messages that others have posted and it is frustrating at best...I can only imagine what you guys are going through.

I will be waiting patiently for a fix for this issue before it give me more grey hair!!!

Thank you guys for all your efforts to making this a great solution!

Steve

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

Re: hMailServer crashes/stops

Post by Bill48105 » 2010-12-04 05:00

Hey Steve,
Yeah it is fun because it is almost impossible to reproduce as it is but any changes or attempts to track it down alter the timing just enough for it to not happen while testing.. lol

As I said in my previous post, anything you can do to help alter the timing of incoming connections & delivery of emails will likely make it happen or make it stop. Keep in mind it appears this bug has been around for a long time but it happened so rarely that it wasn't really noticed. Not sure exactly what changed recently to make it manifest more often but obviously something did. If the problem can't be fixed for real soon there will be some possible workarounds at least in the meantime. Unfortunately it takes time and that's something there's not a lot of extra of often.
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. ***

texinick
New user
New user
Posts: 25
Joined: 2007-01-15 01:41

Re: hMailServer crashes/stops

Post by texinick » 2010-12-04 05:46

Thanks for your replies Bill. I/we look forward to hearing from you regarding an update or some suggestions as to what we can do.

Thanks again
Nick

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

Re: hMailServer crashes/stops

Post by Bill48105 » 2010-12-04 07:51

Not to suggest it is a fix but as an example on a test machine that I can reproduce the problem essentially 100% of the time if I go to performance -> threading & set to "3 3 3 Lowest" the problem stops for me ON THAT SYSTEM. Another thing I found is if I set hmailserver.exe to "Below Normal" (requires a trick involving running task manager as localsystem otherwise you get access denied) the problem stops ON THAT SYSTEM as well. (I haven't been able to reproduce it on more than 1 to test other variations)

So as you can see just a slight variation in timing & the problem can stop or it can start or possibly get worse. My suggestion for people having the problem is to make note of your settings (backup would be a good idea..) and try some variations to see.

Hoping to hook up with martin again this weekend & see if we can get it nailed down once & for all though.
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. ***

kcadmin
New user
New user
Posts: 17
Joined: 2009-03-29 17:30

Re: hMailServer crashes/stops

Post by kcadmin » 2010-12-04 20:05

Hey Bill and all,

Bout 5 days ago we had a crash and through some research it was suggested I think by martin to change the performance settings...can't remember off the top of my head what they were initially but it was what ever the system defaulted to at install. but we took martin suggested setting and put them into the system and they were:
20
4
5
Normal
And the system stayed stable for 4 days then died again the nite before last. So I don't know! The only thing this new system we have now is running is Roundcube mail for our webmail clients.

Steve

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

Re: hMailServer crashes/stops

Post by Bill48105 » 2010-12-04 20:25

Steve,
I'd try slightly different settings but make note of them & how the server does with them in case you need to revert.. I suspect changing from Normal to Below Normal and lowering the values further might help. (Could make it worse too, nature of timing-related issues) Worth a shot, you can always change them back.. If possible lower the connection limits. I think a reason I don't see the buffer/iocp errors on my live server is because I use ASSP front-end with a lower connection limit than hmail's which leaves room for local users to connect & send when spammers are pounding away. Plus I think ASSP's proxy function kind of rate-limits public email just enough including blocking 90% of connections before they really even get to hmail..

Btw RC could very well be causing you issues too.. Although I use it here with many users my tests lead me to believe the more rapidly commands arrive & data exchanged the more likely the buffer/iocp errors occur. Obviously webmail on same server or local network is likely to mean faster exchange than over the internet.. You could try not allowing RC use for now or edit the source putting in strategically placed sleeps. I realize not a real fix & slows things down but I'd guess you'd rather have working & slow than dead server. :D

Btw something else I wonder is if a restart of the hmail service before it happens might help.. Not likely but worth a shot.
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. ***

kcadmin
New user
New user
Posts: 17
Joined: 2009-03-29 17:30

Re: hMailServer crashes/stops

Post by kcadmin » 2010-12-04 20:30

Bill,

I also am running 3 assp border servers as well...I think our old version was something like 4.4.1xxx not really sure with out looking but it would run for some time before a reboot would be needed. I will take a look at the settings and see what can be done.

Steve

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

Re: hMailServer crashes/stops

Post by Bill48105 » 2010-12-04 21:00

Steve,
Based on my testing I believe this buffer/iocp bug has been around since at least 5.1 but I was able to get earlier versions including 4.4.4 to do a similar thing (different logging or variation in wording I suspect) but for some reason seems to have come to the surface more lately likely due to changes in the code that changed timing just enough or possible external forces such as SA/clam/av/RBL's altering the timing just right. Heck even Windows updates. (At one point I was convinced it was a Windows update that caused the problem but after further testing I realized the problem still there but timing had changed just right, maybe even just from disk fragmentation or memory available etc, that I could eventually get the problem to happen again with different hmail settings & test variables..) I wish I had better advice for you & better yet wish it was fixed for real but for now all I can do is try & help you minimize risk of it happening.
If you use ASSP set the connection limit lower in there & see if that helps. Email queues for a reason & will eventually arrive if legit.. ;)
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. ***

kcadmin
New user
New user
Posts: 17
Joined: 2009-03-29 17:30

Re: hMailServer crashes/stops

Post by kcadmin » 2010-12-06 15:16

Bill,

Don't know if this is relavant to all this but I had three crashes between 6pm last nite and 8am today...but one thing that I did notice evertime that it hangs the IMAP connections are at 10 SMTP and POP are 0

Steve

kcadmin
New user
New user
Posts: 17
Joined: 2009-03-29 17:30

Re: hMailServer crashes/stops

Post by kcadmin » 2010-12-06 16:54

Allow me to restate what I previously posted SMTP and POP3 connections were at zero IMAP was stuck at 10 on each hang....one shouldn't attempt to type without first having coffee and putting on glasses!!!!

Steve

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

Re: hMailServer crashes/stops

Post by Bill48105 » 2010-12-06 17:26

LOL Steve we all have those days. :D
Yeah makes sense, likely you are getting pounded by IMAP connections at that time it crashes. If those imaps are webmail then you can tweak that code to adjust a bit otherwise set max imap <10 but realize you might have people upset when connections are dropped when that limit is reached.. I wonder if there is an IMAP proxy available that could be helpful..

martin didn't surface this weekend so didn't get a chance to chat with him on this so no progress yet unfortunately other than the hints I posted up already as temp work-arounds..
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. ***

adipose
New user
New user
Posts: 24
Joined: 2004-05-28 23:55

Re: hMailServer crashes/stops

Post by adipose » 2010-12-06 19:41

Well, just chiming in here. Since last week when I reported the problem, hmail has gone down daily (sometimes multiple times per day). I use ASSP and perhaps a recent update has caused this issue. Whatever it is is killing us here. We are just about ready to switch to a different solution at this point. I can confirm we don't use ClamAV on the server and we are already using Squirrelmail and not Horde.

We probably have about 50 e-mail users and not a huge load. I just changed the settings to 3/3/3 lowest as recommended. We'll see how long we go before it crashes.

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

Re: hMailServer crashes/stops

Post by Bill48105 » 2010-12-06 19:53

I apologize adipose and understand the frustration but it's being worked on. Realize it appears the bug has been in hmail for a long time now (at least back to 5.1.1 based on my testing) so it's not new.. For some reason recently more people have bet bit by it and as I pointed out a few times now it is all about timing so any changes to timing can cause it to start or cause it to stop.. Upgrade Clam - zap. Update Windows - zap. Add new DNS BL - zap. Change thread #'s - zap. I have successfully caused & stopped the problem with each & every one of those things and others here. At same time there are tons of people who don't get the error at all. It seems luck of the draw on your settings, your hardware, your internet, you users & mail patterns.

I have yet to have the problem occur on my real server & I have 100's of users who use it heavily with about 10k emails per day. But I think the reason I don't get bit is I throttle connections in my router to conserve bandwidth, have ASSP in front of hmail for incoming email & connection limit set in ASSP at 1/2 of hmail's limit so ASSP acts a buffer in ways & also frees up SMTP connections for local users to be able to send even when 1000's of spammers are trying to connect..

Unfortunately there is no sure-fire way to solve the problem, it is really that unique to each location & usage.
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. ***

User avatar
dzekas
Senior user
Senior user
Posts: 2486
Joined: 2005-10-13 21:28
Location: Lithuania

Re: hMailServer crashes/stops

Post by dzekas » 2010-12-06 19:56

adipose wrote:Well, just chiming in here. Since last week when I reported the problem, hmail has gone down daily (sometimes multiple times per day). I use ASSP and perhaps a recent update has caused this issue. Whatever it is is killing us here. We are just about ready to switch to a different solution at this point. I can confirm we don't use ClamAV on the server and we are already using Squirrelmail and not Horde.

We probably have about 50 e-mail users and not a huge load. I just changed the settings to 3/3/3 lowest as recommended. We'll see how long we go before it crashes.
could you look at SquirrelMail user preferences and check values of 'sort' setting? Which ones are more often used?

is message indexing enabled in your hMailServer setup?

is server side sorting enabled in your SquirrelMail setup?

adipose
New user
New user
Posts: 24
Joined: 2004-05-28 23:55

Re: hMailServer crashes/stops

Post by adipose » 2010-12-06 20:15

is message indexing enabled in your hMailServer setup?

No.

is server side sorting enabled in your SquirrelMail setup?[/quote]

$allow_thread_sort = false;
$allow_server_sort = false;

User avatar
dzekas
Senior user
Senior user
Posts: 2486
Joined: 2005-10-13 21:28
Location: Lithuania

Re: hMailServer crashes/stops

Post by dzekas » 2010-12-06 20:22

adipose wrote:
dzekas wrote:is message indexing enabled in your hMailServer setup?
No.
adipose wrote:
dzekas wrote: is server side sorting enabled in your SquirrelMail setup?
$allow_thread_sort = false;
$allow_server_sort = false;
If some users have more messages in their IMAP accounts and do sort emails, webmails will kill your server.

SquirrelMail optimization guides say that you should enable server side sorting, if server supports it.

hMailServer indexing should be enabled in order to improve performance of IMAP SORT. But if your issues are caused by non-responsive MySQL service, indexing won't improve your situation. It will make it worse. If you consider turning on indexing, plan to turn it on not during office hours. It takes some time and CPU resources to index all messages.
Last edited by dzekas on 2010-12-06 20:32, edited 2 times in total.

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

Re: hMailServer crashes/stops

Post by Bill48105 » 2010-12-06 20:22

Enabling message indexing in hmail might help. (Could make it worse too but worth a shot). can always disable it again if needed.
Does Squirrel have its own caching like roundcube does? That might help too.. Again anything to change the timing of the connections to hmailserver can make or break this issue.
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. ***

User avatar
dzekas
Senior user
Senior user
Posts: 2486
Joined: 2005-10-13 21:28
Location: Lithuania

Re: hMailServer crashes/stops

Post by dzekas » 2010-12-06 20:27

Bill48105 wrote:Does Squirrel have its own caching like roundcube does?
SquirrelMail 1.5.x has bigger cache than 1.4.x, but mailbox still must be sorted every time user logs in order to create session cache.

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

Re: hMailServer crashes/stops

Post by Bill48105 » 2010-12-06 20:36

dzekas wrote:SquirrelMail 1.5.x has bigger cache than 1.4.x, but mailbox still must be sorted every time user logs in order to create session cache.
Oh OK thanks. I was wondering more from perspective of altering timing of IMAP access (since it is usually local the timing of it is way more likely to cause the buffer/iocp errors in my testing). So idea was caching might help minimize connections/requests with hmail. Another option is to edit squirrel source to limit connections or slow down transfers.. Again just looking for short-term fixes until real bug is resolved.

Another option is use a proxy which has ability to cache and/or limit and/or throttle but that could be tricky. I see squirrel has proxy but might be nix-only & not sure of full capabilities or if it'd be helpful with this issue. Also found perl-based proxy which might be useful especially if can be run on windows.
Thx
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. ***

User avatar
dzekas
Senior user
Senior user
Posts: 2486
Joined: 2005-10-13 21:28
Location: Lithuania

Re: hMailServer crashes/stops

Post by dzekas » 2010-12-06 20:54

Bill48105 wrote:Another option is to edit squirrel source to limit connections or slow down transfers.
There is no way you can throttle webmail connections. They don't keep persistent connections and have to start new IMAP connection on every page load. You can only make sure that webmails don't overload your server with high CPU/memory usage commands and make sure that PHP has some opcache (sadly windows PHP does not have apc). Maybe you can throttle http connections, but I don't think that admin wants to explain to end users that interface is slow as hell due to throttling.

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

Re: hMailServer crashes/stops

Post by Bill48105 » 2010-12-06 21:40

dzekas wrote:There is no way you can throttle webmail connections. They don't keep persistent connections and have to start new IMAP connection on every page load. You can only make sure that webmails don't overload your server with high CPU/memory usage commands and make sure that PHP has some opcache (sadly windows PHP does not have apc). Maybe you can throttle http connections, but I don't think that admin wants to explain to end users that interface is slow as hell due to throttling.
Not sure I'd agree there.. If source is available one could add sleeps/usleeps in strategically placed locations (top of each index.php for instance or in tight loops etc), show error or sleep with 'please wait' if more than X users at once (RoundCube already has this if user sends too many emails too quickly meant to prevent abuse but idea is the same), etc. Not saying it is pretty but I not impossinle as long as source is available & one makes effort.

As far as users complaining, which would you rather hear? "Why is webmail slow?" vs "Why is webmail & entire mail server down AGAIN?!?!" I pick option 1! ;)

To be clear: At no point do I suggest any of these hacks/munges/work-arounds are ideal, permanent or a substitute for fixing the bug but hey at least I'm trying to help people out in a pinch until it is really fixed!
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. ***

User avatar
dzekas
Senior user
Senior user
Posts: 2486
Joined: 2005-10-13 21:28
Location: Lithuania

Re: hMailServer crashes/stops

Post by dzekas » 2010-12-06 22:05

Bill48105 wrote:RoundCube already has this if user sends too many emails too quickly meant to prevent abuse but idea is the same
SquirrelMail has same feature too. It is some plugin which throttles sent emails.

Idea is not the same. Sent throttling does not slow down connections. It requires extra effort to send emails, if user abuses the system.

SquirrelMail does not use index.php. You are free to place any number of sleeps there. :) It will slow only one real page load (users will soon learn that webmail/src/login.php is faster than webmail/) and some attacks that try to destroy active user sessions. Sleeps set in PHP auto_prepend_file should work without any modification in original files, but not all SquirrelMail scripts start IMAP connections.

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

Re: hMailServer crashes/stops

Post by Bill48105 » 2010-12-06 22:43

dzekas wrote:SquirrelMail has same feature too. It is some plugin which throttles sent emails.

Idea is not the same. Sent throttling does not slow down connections. It requires extra effort to send emails, if user abuses the system.

SquirrelMail does not use index.php. You are free to place any number of sleeps there. :) It will slow only one real page load (users will soon learn that webmail/src/login.php is faster than webmail/) and some attacks that try to destroy active user sessions. Sleeps set in PHP auto_prepend_file should work without any modification in original files, but not all SquirrelMail scripts start IMAP connections.
Obviously you feel need to split hairs.. ;)

I clearly said I don't use squirrel & was giving an EXAMPLE. :P I figured some include file that was always loaded would be best but didn't expect comment to be picked apart.

Either way your comment still contained some useful info including clarification that it'd probably be done in auto_prepend_file. I'm sure more clarification would be needed if someone wanted to actually try it such as likely more specific places to do it like in particular functions to help control more than just imap. Again, the point is to find work-arounds not bicker over petty stuff. Hopefully we can all stick to being helpful. ;)
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. ***

kcadmin
New user
New user
Posts: 17
Joined: 2009-03-29 17:30

Re: hMailServer crashes/stops

Post by kcadmin » 2010-12-08 03:46

Bill ,
Can I come stay with you till this is resolved LOL .....I have a headache!

Steve

kcadmin
New user
New user
Posts: 17
Joined: 2009-03-29 17:30

Re: hMailServer crashes/stops

Post by kcadmin » 2010-12-08 03:53

why do people argue...hell life is bad enough without that crap! We recognize there is an issue and through the good graces of Martin, you and all the others working to solve this issue we will get through it! Hell I like this program cause as it stands right now I don't have to write 2000 accounts back into a unix box LOL!!!!!!

User avatar
dzekas
Senior user
Senior user
Posts: 2486
Joined: 2005-10-13 21:28
Location: Lithuania

Re: hMailServer crashes/stops

Post by dzekas » 2010-12-08 04:52

kcadmin wrote:why do people argue...hell life is bad enough without that crap! We recognize there is an issue and through the good graces of Martin, you and all the others working to solve this issue we will get through it! Hell I like this program cause as it stands right now I don't have to write 2000 accounts back into a unix box LOL!!!!!!
If you use hMailServer 4.4.x, have larger IMAP accounts and webmail that does sorting, your webmail is killing hMailServer. Part of your server admins job is to make sure that application you administer performs in optimal way. You can't just deploy some webmail package, stitch together webserver with PHP and expect it to work correctly on setup with 2k aggressive users.

If you want to fix it, you will have to update to hMailServer 5, configure it properly and make sure that your webmail is set not to toast IMAP server with default user settings. If your users want such email features as faster search or threading, you will have to migrate to Unix or Linux box. hMailServer IMAP has some limits and some IMAP commands are faster than others.

Me and Bill argue, because our experience and preferences are different. You haven't seen real argument, because I choose not push on some things said by Bill.

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

Re: hMailServer crashes/stops

Post by mattg » 2010-12-08 05:10

dzekas wrote:Me and Bill argue, because our experience and preferences are different. You haven't seen real argument, because I choose not push on some things said by Bill.
And me?
I like to watch on and chuckle to myself.

I am certainly very glad of the differing experience and preferences bought by Dzekas and Bill. You both bring heaps to this community, not the least of which is your intelligent discussion about the finer technical details.

I think hMailserver is stronger because of the friendly banter that occurs on this forum. :D

Matt
Just 'cause I link to a page and say little else doesn't mean I am not being nice.
https://www.hmailserver.com/documentation

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

Re: hMailServer crashes/stops

Post by Bill48105 » 2010-12-08 05:30

kcadmin wrote:Bill ,
Can I come stay with you till this is resolved LOL .....I have a headache!

Steve
Sure Steve, the address is:
1600 Pennsylvania Avenue
Washington DC 20500

As far as arguing, if we all try to contribute to solving problems posted (quite often in our own way) and avoid projecting our personal preferences upon others who are also trying to help I think things will be just peachy.
Cheers
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. ***

kcadmin
New user
New user
Posts: 17
Joined: 2009-03-29 17:30

Re: hMailServer crashes/stops

Post by kcadmin » 2010-12-08 23:43

HAHAHA that is a good one only bad feature is I think he has all the rooms booked for Ms. Pelosi's entourage! LOL

no matter today went without a hitch,,,all worked well to this point in the day!

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

Re: hMailServer crashes/stops

Post by Bill48105 » 2010-12-09 00:17

LOL yeah place is probably crowded. :D

Good news you were more stable today. martin & I are working on it still. Actually just got a new updated 5.3.3 build going today we're testing so we'll see..
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. ***

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

Re: hMailServer crashes/stops

Post by Bill48105 » 2010-12-10 20:51

I sent 1 million emails thru the latest test build on 1 computer & 250k on another with 0 buffer/iocp errors. :D
Don't want to be overly optimistic but looking good. (Always chance the timing just changed but I've been pounding it hard with various settings & conditions)

Waiting to hear back from martin on if he's had the same results but fingers crossed! If all looks good we might have updates in our hands soon. :D
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. ***

guppy
New user
New user
Posts: 10
Joined: 2009-08-05 02:46

Re: hMailServer crashes/stops

Post by guppy » 2010-12-11 17:43

I'm having a similar issue. Two weeks running my main hmailserver has crashed on Saturday. I have about 30 domains, with roughly 120 users. The server has mysql and the latest clamwin along with the latest stable version of roundcube 0.4.2 (recently updated). I have two other hmail installations that are running mainly as MX backups using routes. These servers haven't been affected. I have a few extra users using blackberry devices in the last few weeks as well if that helps.

Any help or updates would be greatly appreciated. I've turned on performance today for the first time setting the Threading to below Normal as suggested above in a response.

Regards,
Guppy

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

Re: hMailServer crashes/stops

Post by Bill48105 » 2010-12-11 18:11

Hang in there Guppy, hopefully new releases soon. In meantime un-doing changes you made before problem started is a good idea or tweaking performance/connection settings as you've done might help. Since it is timing related any change can bring this problem on or make it stop..
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. ***

guppy
New user
New user
Posts: 10
Joined: 2009-08-05 02:46

Re: hMailServer crashes/stops

Post by guppy » 2010-12-11 18:23

Thanks guys. I was looking at the beta. The version number is lower than the stable. Is it an older version? Will the beta fix the issue?

Regards,
Guppy

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

Re: hMailServer crashes/stops

Post by Bill48105 » 2010-12-11 18:25

Guppy,
Hold off a bit until martin uploads 5.3.4 which is the new beta. Either way it will be dated today & not months ago..
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. ***

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

Re: hMailServer crashes/stops

Post by Bill48105 » 2010-12-11 18:49

Should probably post a reminder that might be overlooked in a fury to download the latest update:
These are beta releases. This means that they have not been as much tested as the production releases. We recommend production releases for all production environments unless there is a specific reason to use a beta build.
While martin & I have tested the latest build in testing environments and it appears to solve the buffer/iocp problems without noting anything out of the ordinary, keep in mind since it is so new, new/different bugs could be possible so please use with caution and an understanding of the risk of those possibilities with any upgrade but especially non-production releases..

IOW: Think twice before installing on your production server unless you are specifically having the buffer/iocp errors to an extent you believe it is worth the risk vs just sticking with 5.3.3 for now. ;)
Thx
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. ***

kcadmin
New user
New user
Posts: 17
Joined: 2009-03-29 17:30

Re: hMailServer crashes/stops

Post by kcadmin » 2010-12-12 18:24

Bill,

Glad to hear that you are having good luck with this...went 3 days with out a hitch now already it went bye bye's twice today!!! ARRRRGH!!!!

Steve

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

Re: hMailServer crashes/stops

Post by Bill48105 » 2010-12-12 18:54

Steve,
martin posted up 5.3.4 beta today which so far has solved the problem on my test machines after almost 2 million test messages over the course of a few days.. As a minimum if the problem happens the code changes also would help track down more specifically where but looking good in tests.
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. ***

Post Reply