Mail not deliver

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

Mail not deliver

Post by aklryan » 2005-10-21 10:59

Hi,

I frequent experience mail not deliver especially to a domain
Messages error from the bounce back mail:

"Error Description: Error message from remote server
Message: 421 SMTP service not available, closing transmission channel."

Sometime I do get error like no response from remote server.

No problem when I send through using yahoo or gmail.

Any reason why this happen when send through hmail?

From the debug log :

"DEBUG" 2640 "2005-10-21 16:32:39.093" "SD:~DeliverMessage"
"DEBUG" 3128 "2005-10-21 16:32:39.093" "SD:_DeliverToExternalAccounts"
"TCPIP" 3128 "2005-10-21 16:32:39.093" "DNS - MX Lookup: bt.infonet.com"
"TCPIP" 3128 "2005-10-21 16:32:39.093" "DNS - MX Result: 2 servers"
"DEBUG" 3128 "2005-10-21 16:32:39.093" "SD:_InitiateExternalConnection"
"DEBUG" 3128 "2005-10-21 16:32:39.093" "SocketConnection::SocketConnection()"
"DEBUG" 3128 "2005-10-21 16:32:39.109" "SD:_InitiateExternalConnection - Connected"
"DEBUG" 3128 "2005-10-21 16:32:49.140" "SMTPCC::_ParseASCII()"
"SMTPC" 3128 "2005-10-21 16:32:49.140" "192.157.130.21" "RECEIVED: 421 SMTP service not available, closing transmission channel"
"DEBUG" 3128 "2005-10-21 16:32:49.140" "SD:~_InitiateExternalConnection-5"
"DEBUG" 3128 "2005-10-21 16:32:49.140" "SocketConnection::~SocketConnection()"
"DEBUG" 3128 "2005-10-21 16:32:49.140" "SD:_InitiateExternalConnection"
"DEBUG" 3128 "2005-10-21 16:32:49.140" "SocketConnection::SocketConnection()"
"DEBUG" 3128 "2005-10-21 16:32:49.140" "SD:_InitiateExternalConnection - Connected"
"DEBUG" 3128 "2005-10-21 16:32:59.171" "SMTPCC::_ParseASCII()"
"SMTPC" 3128 "2005-10-21 16:32:59.171" "192.157.130.154" "RECEIVED: 421 SMTP service not available, closing transmission channel"
"DEBUG" 3128 "2005-10-21 16:32:59.171" "SD:~_InitiateExternalConnection-5"
"DEBUG" 3128 "2005-10-21 16:32:59.171" "SocketConnection::~SocketConnection()"
"DEBUG" 3128 "2005-10-21 16:32:59.171" "SD:~_DeliverToExternalAccounts-2"
"DEBUG" 3128 "2005-10-21 16:32:59.171" "SD:_CollectDeliveryResult"
"DEBUG" 3128 "2005-10-21 16:32:59.171" "SD:~_CollectDeliveryResult"
"DEBUG" 3128 "2005-10-21 16:32:59.171" "SD:_RescheduleDelivery"
"APPLICATION" 3128 "2005-10-21 16:32:59.171" "SMTPDeliverer - Message 14944: Message could not be delivered. Scheduling it for later delivery."
"DEBUG" 3128 "2005-10-21 16:32:59.171" "PersistentMessage::SetNextTryTime()"
"DEBUG" 3128 "2005-10-21 16:32:59.171" "PersistentMessage::~SetNextTryTime()"
"DEBUG" 3128 "2005-10-21 16:32:59.171" "SD:~_RescheduleDelivery-1"
"APPLICATION" 3128 "2005-10-21 16:32:59.171" "SMTPDeliverer - Message 14944: Message delivery thread completed."
"DEBUG" 3128 "2005-10-21 16:32:59.171" "SD:~DeliverMessage"
Top
User avatar
martin
Developer
Developer
Posts: 6837
Joined: 2003-11-21 01:09
Location: Sweden
Contact:
Contact martin

Post by martin » 2005-10-21 12:32

It's the recipient server that says 421 SMTP service not available, closing transmission channel.. There's no way to know why it says this except for asking the administrators of the recipients server. The administrators may be using some kind of blacklist and your IP may have gotten blacklisted.
Top
aklryan
New user
New user
Posts: 17
Joined: 2005-05-10 12:01

Post by aklryan » 2005-10-24 08:51

Hi Martin,

Thanks for your reply. I will check with them.
Top
aklryan
New user
New user
Posts: 17
Joined: 2005-05-10 12:01

Post by aklryan » 2005-10-25 17:30

Hi Martin,

I got this error also:
Error Type: SMTP
Error Description: Error message from remote server
Message: There was a timeout while talking to the remote server..

I am still in the mid of monitor the where went wrong. I got the above error also when sent to another domain.
I got in touch with one of the remote side administrator and they mention that they did not blacklist us. They suspect may be problem lie on the ISP or some mail route from somewhere.

If you have any idea where the problem lie, do give me some hints.

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

Post by martin » 2005-10-25 17:36

Your last error message just indicates a timeout. That can occur if the network goes down temporarly, if the remote server reboots while you're trying to communicate with it and a lot of other reasons. :\

If you send to other domains, does it work properly then?
Top
aklryan
New user
New user
Posts: 17
Joined: 2005-05-10 12:01

Post by aklryan » 2005-10-25 17:56

Hi Martin,
I am sure their server is not down. I can send the mail out instantly from my yahoo mail. Too bad I did not turn on the debug log. So far this is the problem domain encounter, whenever I send to them, my mail can be seem in the undeliever mail there. I recently just got another domian, enounter the timout error but I happend to send once only so i cannot conclude that the timout happend nearly always to them.

I will have to send some test mail to further monitor the mail and do some log collection. Keep you posted when got some result.

Thanks
Top
aklryan
New user
New user
Posts: 17
Joined: 2005-05-10 12:01

Post by aklryan » 2005-10-26 05:28

hi Martin,

I think I find my problem. My FW is blocking site on the packet they don't handle. I think i ave fix that.

Really thanks for your kindly support.
Top
User avatar
martin
Developer
Developer
Posts: 6837
Joined: 2003-11-21 01:09
Location: Sweden
Contact:
Contact martin

Post by martin » 2005-10-26 12:11

Let me guess. A cisco router?
Top
aklryan
New user
New user
Posts: 17
Joined: 2005-05-10 12:01

Post by aklryan » 2005-10-27 06:29

Hi Martin,

You mean at my side? Not a Cisco router, just a normal router and behind is my FW before goes in my LAN. :wink:
Top
User avatar
matty
Senior user
Senior user
Posts: 330
Joined: 2005-08-22 16:29
Location: New York
Contact:
Contact matty

Post by matty » 2005-10-27 15:02

I think martin is asking about the cisco router because of this issue:

http://www.hmailserver.com/forum/viewtopic.php?p=16347

You said your router is not Cisco, is your firewall a Cisco Firewall?
Top
aklryan
New user
New user
Posts: 17
Joined: 2005-05-10 12:01

Post by aklryan » 2005-10-28 10:42

hi matty,

not a Cisco pix also. I am using Watchguard. I may have solve that problem i faced initial but looks now i have a another difficulty when try to send to another domain. My mails keeps getting bounce back, timeout. Never successful send to them once.
I am still try to look for their administrator, whether he spamfilter has blacklist our domain. I do notice there are using antivirus and spamfilter software for mail server.

I don't think i am getting the similar problem found in the thread u given. But I will take note my FW also.
Top
aklryan
New user
New user
Posts: 17
Joined: 2005-05-10 12:01

Post by aklryan » 2005-10-28 11:15

"SMTPD" 980 "2005-10-28 17:11:35.343" "10.10.10.68" "SENT: 220 Welcome to blitz email"
"SMTPD" 980 "2005-10-28 17:11:35.359" "10.10.10.68" "RECEIVED: EHLO blitzgrp.com"
"SMTPD" 980 "2005-10-28 17:11:35.359" "10.10.10.68" "SENT: 250-hmailserver[nl]250-SIZE[nl]250 AUTH LOGIN"
"SMTPD" 980 "2005-10-28 17:11:35.375" "10.10.10.68" "RECEIVED: AUTH LOGIN"
"SMTPD" 980 "2005-10-28 17:11:35.375" "10.10.10.68" "SENT: 334 VXNlcm5hbWU6"
"SMTPD" 980 "2005-10-28 17:11:35.375" "10.10.10.68" "RECEIVED: cnlhbkBibGl0emdycC5jb20="
"SMTPD" 980 "2005-10-28 17:11:35.375" "10.10.10.68" "SENT: 334 UGFzc3dvcmQ6"
"SMTPD" 980 "2005-10-28 17:11:35.375" "10.10.10.68" "RECEIVED: a29hbGExMjM="
"SMTPD" 980 "2005-10-28 17:11:35.375" "10.10.10.68" "SENT: 235 authenticated."
"SMTPD" 980 "2005-10-28 17:11:35.375" "10.10.10.68" "RECEIVED: MAIL FROM:<ryan@blitzgrp.com> SIZE=1023"
"SMTPD" 980 "2005-10-28 17:11:35.390" "10.10.10.68" "SENT: 250 ryan@blitzgrp.com... Sender OK"
"SMTPD" 980 "2005-10-28 17:11:35.390" "10.10.10.68" "RECEIVED: RCPT TO:<Jasni.Hj.Abd.Hamid@ibb.com.bn>"
"SMTPD" 980 "2005-10-28 17:11:35.390" "10.10.10.68" "SENT: 250 OK its for Jasni.Hj.Abd.Hamid@ibb.com.bn"
"SMTPD" 980 "2005-10-28 17:11:35.390" "10.10.10.68" "RECEIVED: DATA"
"SMTPD" 980 "2005-10-28 17:11:35.390" "10.10.10.68" "SENT: 354 ok send! end with <crlf>.<crlf>"
"DEBUG" 980 "2005-10-28 17:11:35.562" "PMADO:SaveObject()"
"DEBUG" 980 "2005-10-28 17:11:35.562" "PMADO:AddObject()"
"DEBUG" 980 "2005-10-28 17:11:35.562" "Adding message to database. File: D:\Program Files\hMailServer\Data\{0ABDBC0C-6420-4647-967B-2DF86FC7E33B}.eml"
"DEBUG" 980 "2005-10-28 17:11:35.562" "PMADO:~AddObject()"
"DEBUG" 980 "2005-10-28 17:11:35.562" "PMADO:~SaveObject()"
"DEBUG" 980 "2005-10-28 17:11:35.562" "Message added. File: D:\Program Files\hMailServer\Data\{0ABDBC0C-6420-4647-967B-2DF86FC7E33B}.eml"
"SMTPD" 980 "2005-10-28 17:11:35.562" "10.10.10.68" "SENT: 250 Message queued (0.172 seconds)"
"DEBUG" 2396 "2005-10-28 17:11:35.562" "PersistentMessage::ReadObject()"
"DEBUG" 2396 "2005-10-28 17:11:35.562" "PersistentMessage::~ReadObject()"
"DEBUG" 1044 "2005-10-28 17:11:35.562" "SD:DeliverMessage"
"APPLICATION" 1044 "2005-10-28 17:11:35.562" "SMTPDeliverer - Message 19398: Delivering message from ryan@blitzgrp.com to Jasni.Hj.Abd.Hamid@ibb.com.bn. File: D:\Program Files\hMailServer\Data\{0ABDBC0C-6420-4647-967B-2DF86FC7E33B}.eml"
"SMTPD" 980 "2005-10-28 17:11:35.562" "10.10.10.68" "RECEIVED: QUIT"
"SMTPD" 980 "2005-10-28 17:11:35.562" "10.10.10.68" "SENT: 221 goodbye"
"TCPIP" 980 "2005-10-28 17:11:35.562" "SMTPD - Connection to client 10.10.10.68 closed."
"DEBUG" 980 "2005-10-28 17:11:35.562" "SocketConnection::~SocketConnection()"
"DEBUG" 1044 "2005-10-28 17:11:35.562" "CustomVirusScanner::Scan()"
"DEBUG" 1044 "2005-10-28 17:11:35.625" "CustomVirusScanner::Scan() - C:\clamav-devel\bin\clamdscan.exe --no-summary --stdout "D:\Program Files\hMailServer\Data\{0ABDBC0C-6420-4647-967B-2DF86FC7E33B}.eml" - Returned 0"
"DEBUG" 1044 "2005-10-28 17:11:35.625" "CustomVirusScanner::~Scan()"
"DEBUG" 1044 "2005-10-28 17:11:35.625" "RuleApplier::ApplyRules"
"DEBUG" 1044 "2005-10-28 17:11:35.625" "RuleApplier::~ApplyRules"
"DEBUG" 1044 "2005-10-28 17:11:35.625" "SD:_DeliverToExternalAccounts"
"TCPIP" 1044 "2005-10-28 17:11:35.625" "DNS - MX Lookup: ibb.com.bn"
"TCPIP" 1044 "2005-10-28 17:11:35.828" "DNS - MX Result: 1 servers"
"DEBUG" 1044 "2005-10-28 17:11:35.828" "SD:_InitiateExternalConnection"
"DEBUG" 1044 "2005-10-28 17:11:35.828" "SocketConnection::SocketConnection()"
"DEBUG" 1044 "2005-10-28 17:11:35.828" "SD:_InitiateExternalConnection - Connected"
"DEBUG" 936 "2005-10-28 17:11:35.828" "SMTPCC:~_SendData()"
"DEBUG" 936 "2005-10-28 17:11:35.828" "SMTPCC::~_ParseASCII() - E11"
"DEBUG" 936 "2005-10-28 17:11:36.796" "SMTPCC::_ParseASCII()"
"SMTPC" 936 "2005-10-28 17:11:36.796" "69.64.50.213" "RECEIVED: 250 ok 1130489979 qp 31192"
"DEBUG" 936 "2005-10-28 17:11:36.796" "SMTPCC:_SendData()"
"SMTPC" 936 "2005-10-28 17:11:36.796" "69.64.50.213" "SENT: QUIT"
"DEBUG" 936 "2005-10-28 17:11:36.796" "SMTPCC:~_SendData()"
"DEBUG" 936 "2005-10-28 17:11:36.796" "SMTPCC::~_ParseASCII() - E12"
"DEBUG" 936 "2005-10-28 17:11:36.796" "SD:~_InitiateExternalConnection-5"
"DEBUG" 936 "2005-10-28 17:11:36.796" "SocketConnection::~SocketConnection()"
"DEBUG" 936 "2005-10-28 17:11:36.796" "SD:~_DeliverToExternalAccounts-1"
"DEBUG" 936 "2005-10-28 17:11:36.796" "SD:_CollectDeliveryResult"
"DEBUG" 936 "2005-10-28 17:11:36.796" "SD:~_CollectDeliveryResult"
"DEBUG" 936 "2005-10-28 17:11:36.796" "PersistentMessage::DeleteObject()"
"DEBUG" 936 "2005-10-28 17:11:36.796" "PersistentMessage::DeleteFile()"
"DEBUG" 936 "2005-10-28 17:11:36.796" "PersistentMessage::~DeleteFile() - E3"
"DEBUG" 936 "2005-10-28 17:11:36.796" "PersistentMessage::DeleteObject() - E5"
"APPLICATION" 936 "2005-10-28 17:11:36.796" "SMTPDeliverer - Message 19396: Message delivery thread completed."
"DEBUG" 936 "2005-10-28 17:11:36.796" "SD:~DeliverMessage"
"DEBUG" 1044 "2005-10-28 17:11:37.968" "SMTPCC::_ParseASCII()"
"SMTPC" 1044 "2005-10-28 17:11:37.968" "202.152.77.82" "RECEIVED: 220-smtp.ibb.com.bn ESMTP ibb.com.bn; Fri, 28 Oct 2005 17:00:50 +0800"
"DEBUG" 1044 "2005-10-28 17:11:37.968" "SMTPCC::~_ParseASCII() - E1"
"DEBUG" 1044 "2005-10-28 17:11:37.968" "SMTPCC::_ParseASCII()"
"SMTPC" 1044 "2005-10-28 17:11:37.968" "202.152.77.82" "RECEIVED: 220-******************************************************************"
"DEBUG" 1044 "2005-10-28 17:11:37.968" "SMTPCC::~_ParseASCII() - E1"
"DEBUG" 1044 "2005-10-28 17:11:37.968" "SMTPCC::_ParseASCII()"
"SMTPC" 1044 "2005-10-28 17:11:37.968" "202.152.77.82" "RECEIVED: 220-* Secure Mail Server *"
"DEBUG" 1044 "2005-10-28 17:11:37.968" "SMTPCC::~_ParseASCII() - E1"
"DEBUG" 1044 "2005-10-28 17:11:37.968" "SMTPCC::_ParseASCII()"
"SMTPC" 1044 "2005-10-28 17:11:37.968" "202.152.77.82" "RECEIVED: 220-* *"
"DEBUG" 1044 "2005-10-28 17:11:37.968" "SMTPCC::~_ParseASCII() - E1"
"DEBUG" 1044 "2005-10-28 17:11:37.968" "SMTPCC::_ParseASCII()"
"SMTPC" 1044 "2005-10-28 17:11:37.968" "202.152.77.82" "RECEIVED: 220-* All connections are logged! *"
"DEBUG" 1044 "2005-10-28 17:11:37.968" "SMTPCC::~_ParseASCII() - E1"
"DEBUG" 1044 "2005-10-28 17:11:37.968" "SMTPCC::_ParseASCII()"
"SMTPC" 1044 "2005-10-28 17:11:37.968" "202.152.77.82" "RECEIVED: 220-* This server uses antivirus and antispam technology *"
"DEBUG" 1044 "2005-10-28 17:11:37.968" "SMTPCC::~_ParseASCII() - E1"
"DEBUG" 1044 "2005-10-28 17:11:37.968" "SMTPCC::_ParseASCII()"
"SMTPC" 1044 "2005-10-28 17:11:37.968" "202.152.77.82" "RECEIVED: 220-******************************************************************"
"DEBUG" 1044 "2005-10-28 17:11:37.968" "SMTPCC::~_ParseASCII() - E1"
"DEBUG" 1044 "2005-10-28 17:11:37.968" "SMTPCC::_ParseASCII()"
"SMTPC" 1044 "2005-10-28 17:11:37.968" "202.152.77.82" "RECEIVED: 220 "
"DEBUG" 1044 "2005-10-28 17:11:37.968" "SMTPCC:_SendData()"
"SMTPC" 1044 "2005-10-28 17:11:37.968" "202.152.77.82" "SENT: HELO mail.blitzgrp.com"
"DEBUG" 1044 "2005-10-28 17:11:37.968" "SMTPCC:~_SendData()"
"DEBUG" 1044 "2005-10-28 17:11:37.968" "SMTPCC::~_ParseASCII() - E2"
"DEBUG" 1044 "2005-10-28 17:11:38.187" "SMTPCC::_ParseASCII()"
"SMTPC" 1044 "2005-10-28 17:11:38.187" "202.152.77.82" "RECEIVED: 250 smtp.ibb.com.bn Hello blitzgrp.com [203.125.61.190], pleased to meet you."
"DEBUG" 1044 "2005-10-28 17:11:38.187" "SMTPCC:_SendData()"
"SMTPC" 1044 "2005-10-28 17:11:38.187" "202.152.77.82" "SENT: MAIL FROM:<ryan@blitzgrp.com>"
"DEBUG" 1044 "2005-10-28 17:11:38.187" "SMTPCC:~_SendData()"
"DEBUG" 1044 "2005-10-28 17:11:38.187" "SMTPCC::~_ParseASCII() - E6"
"TCPIP" 992 "2005-10-28 17:11:38.671" "POP3D - Connection from 10.10.10.68 accepted."
"DEBUG" 936 "2005-10-28 17:11:38.671" "SocketConnection::SocketConnection()"
"DEBUG" 936 "2005-10-28 17:11:38.718" "FML::Acquire: 1-0-E"
"DEBUG" 936 "2005-10-28 17:11:38.718" "FML::Acquire: 1-0-E OK"
"DEBUG" 936 "2005-10-28 17:11:38.718" "FML::Release: 1-0-E"
"DEBUG" 936 "2005-10-28 17:11:38.718" "FML::Release: 1-0-E OK"
"TCPIP" 936 "2005-10-28 17:11:38.718" "POP3D - Connection to client 10.10.10.68 closed."
"DEBUG" 936 "2005-10-28 17:11:38.718" "SocketConnection::~SocketConnection()"
"TCPIP" 992 "2005-10-28 17:11:55.562" "POP3D - Connection from 58.187.54.62 accepted."
"DEBUG" 792 "2005-10-28 17:11:55.562" "SocketConnection::SocketConnection()"
"DEBUG" 792 "2005-10-28 17:11:56.656" "FML::Acquire: 3-0-E"
"DEBUG" 792 "2005-10-28 17:11:56.656" "FML::Acquire: 3-0-E OK"
"DEBUG" 792 "2005-10-28 17:11:56.656" "FML::Release: 3-0-E"
"DEBUG" 792 "2005-10-28 17:11:56.656" "FML::Release: 3-0-E OK"
"TCPIP" 792 "2005-10-28 17:11:56.656" "POP3D - Connection to client 58.187.54.62 closed."
"DEBUG" 792 "2005-10-28 17:11:56.656" "SocketConnection::~SocketConnection()"

Mail still in the undeliver queue. Any clue? Likely problem on the remote server?
Top
Post Reply

Return to “General discussions”