Forums & Blog

Hi SmarterTools,

I use SmarterMail Professional Edition Version 4.3.2903 and I am experiencing a rather disturbing problem.

I have had users complain about mail not being delivered to the mailboxes. I can see in my log that the mails are received, but they never appear in the users mailbox.

The logs look like this:

Processing file : D:\MailServer\SmarterMail\Logs\2008.01.14-delivery.log
Line 1559 - 15:52:08 [73314] Delivery started for <xxx@xxx.xxx> at 3:52:08 PM
Line 1561 - 15:52:26 [73314] Delivery for <xxx@xxx.xxx> to yyy@yyy.com has completed (Delivered) Filter: None
Line 1563 - 15:52:26 [73314] Delivery finished for <xxx@xxx.xxx> at 3:52:26 PM [id:41173314]
Found 3 occurrences.
Processing file : D:\MailServer\SmarterMail\Logs\2008.01.14-smtpLog.log
Line 3804 - 15:52:06 [xxx.xxx.xxx.xxx][25789060] cmd: MAIL FROM:<<xxx@xxx.xxx>> SIZE=4406
Line 3805 - 15:52:06 [xxx.xxx.xxx.xxx][25789060] rsp: 250 OK <<xxx@xxx.xxx>> Sender ok
Found 2 occurrences.

Are my mail files corrupt or why does the mail never appear in the users inbox? There are no rules on the inbox, and a search in both Outlook and WebMail interface gave no result. The Outlook 2003 connects using an IMAP connection.

Hope you can help me with this. This is a problem that users have reported before, and for a long time suspected.

Regards,
JP

Make sure that your delivery logs set to Detailed and no other application accesing your spool or domain directory like AV. 

Logs are already detailed, but you are right... I didn't include everything. I just included the result from Search & Replace. Should have known better.

No Antivrus accesses my spool directory. Antivirus on clients and ClamAv included in SmarterMail.

Processing file : D:\MailServer\SmarterMail\Logs\2008.01.14-delivery.log
15:52:08 [73314] Delivery started for xxx@xxx.com at 3:52:08 PM
15:52:26 [73314] Starting local delivery to yyy@yyy.com
15:52:26 [73314] Delivery for xxx@xxx.com to yyy@yyy.com has completed (Delivered) Filter: None
15:52:26 [73314] End delivery to yyy@yyy.com
15:52:26 [73314] Delivery finished for xxx@xxx.com at 3:52:26 PM [id:41173314]

Processing file : D:\MailServer\SmarterMail\Logs\2008.01.14-smtpLog.log
15:52:06 [xxx.xxx.xxx.xxx][25789060] rsp: 220 mail.mydomain.com 
15:52:06 [xxx.xxx.xxx.xxx][25789060] connected at 1/14/2008 3:52:06 PM
15:52:06 [xxx.xxx.xxx.xxx][25789060] cmd: EHLO bay0-omc3-s36.bay0.hotmail.com
15:52:06 [xxx.xxx.xxx.xxx][25789060] rsp: 250-mail.mydomain.com Hello [xxx.xxx.xxx.xxx] 250-SIZE 31457280 250-AUTH LOGIN CRAM-MD5 250 OK
15:52:06 [xxx.xxx.xxx.xxx][25789060] cmd: MAIL FROM:<xxx@xxx.com> SIZE=4406
15:52:06 [xxx.xxx.xxx.xxx][25789060] rsp: 250 OK <xxx@xxx.com> Sender ok
15:52:06 [xxx.xxx.xxx.xxx][25789060] cmd: RCPT TO:<yyy@yyy.com>
15:52:06 [xxx.xxx.xxx.xxx][25789060] rsp: 250 OK <yyy@yyy.com> Recipient ok
15:52:07 [xxx.xxx.xxx.xxx][25789060] cmd: DATA
15:52:07 [xxx.xxx.xxx.xxx][25789060] rsp: 354 Start mail input; end with <CRLF>.<CRLF>
15:52:07 [xxx.xxx.xxx.xxx][25789060] rsp: 250 OK
15:52:07 [xxx.xxx.xxx.xxx][25789060] Data transfer succeeded, writing mail to 41173314.eml
15:52:07 [xxx.xxx.xxx.xxx][25789060] cmd: QUIT
15:52:07 [xxx.xxx.xxx.xxx][25789060] rsp: 221 Service closing transmission channel
15:52:07 [xxx.xxx.xxx.xxx][25789060] disconnected at 1/14/2008 3:52:07 PM