hMailServer Outbound Message Failure to 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
xoneeleven
New user
New user
Posts: 1
Joined: 2018-12-18 22:40

hMailServer Outbound Message Failure to Deliver

Post by xoneeleven » 2018-12-18 22:50

Hello All,
We are experiencing an issue where outbound messages are not being delivered. In a nutshell, the the remote (receiving the messages) mail server receiving the messages cannot deliver the messages to the users.
The error essentially reports that our mail server disconnects too early for them to deliver the message.
Here is a portion of the debug logs. Thanks in advance for the help on this.

"DEBUG" 7432 "2018-12-18 12:00:59.335" "Adding task AsynchronousTask to work queue Asynchronous task queue"
"DEBUG" 6936 "2018-12-18 12:00:59.335" "Executing task AsynchronousTask in work queue Asynchronous task queue"
"DEBUG" 6936 "2018-12-18 12:00:59.335" "Saving message: {B4BD4CD2-1755-4234-BCEE-DC7C1F9BEED8}.eml"
"DEBUG" 6936 "2018-12-18 12:00:59.335" "Requesting SMTPDeliveryManager to start message delivery"
"SMTPD" 6936 67 "2018-12-18 12:00:59.335" "192.168.1.8" "SENT: 250 Queued (0.032 seconds)"
"DEBUG" 7252 "2018-12-18 12:00:59.335" "Adding task DeliveryTask to work queue SMTP delivery queue"
"DEBUG" 7296 "2018-12-18 12:00:59.351" "Executing task DeliveryTask in work queue SMTP delivery queue"

"DEBUG" 7296 "2018-12-18 12:00:59.351" "Applying rules"
"DEBUG" 7296 "2018-12-18 12:00:59.351" "Performing local delivery"
"DEBUG" 7296 "2018-12-18 12:00:59.351" "Local delivery completed"
"TCPIP" 7296 "2018-12-18 12:00:59.351" "DNS MX lookup: hotmail.com"
"TCPIP" 7296 "2018-12-18 12:00:59.351" "DNS - MX Result: 2 IP addresses were found."
"DEBUG" 7296 "2018-12-18 12:00:59.367" "Starting external delivery process. Server: hotmail-com.olc.protection.outlook.com (104.47.50.33), Port: 25, Security: 2, User name: "
"DEBUG" 7296 "2018-12-18 12:00:59.367" "Creating session 70"
"TCPIP" 7296 "2018-12-18 12:00:59.367" "Connecting to 104.47.50.33:25..."
"SMTPD" 7376 67 "2018-12-18 12:01:01.866" "192.168.1.8" "RECEIVED: QUIT"
"SMTPD" 7376 67 "2018-12-18 12:01:01.866" "192.168.1.8" "SENT: 221 goodbye"
"DEBUG" 7392 "2018-12-18 12:01:01.866" "Ending session 67"
"DEBUG" 7392 "2018-12-18 12:01:02.382" "SMTPDeliverer - Message 11 - Connection failed: Host name: 104.47.50.33, message: A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond"
"DEBUG" 7392 "2018-12-18 12:01:02.382" "Ending session 68"
"DEBUG" 7300 "2018-12-18 12:01:02.382" "External delivery process completed"
"DEBUG" 7300 "2018-12-18 12:01:02.382" "Starting external delivery process. Server: hotmail-com.olc.protection.outlook.com (104.47.49.33), Port: 25, Security: 2, User name: "
"DEBUG" 7300 "2018-12-18 12:01:02.382" "Creating session 71"
"TCPIP" 7300 "2018-12-18 12:01:02.382" "Connecting to 104.47.49.33:25..."
"DEBUG" 7392 "2018-12-18 12:01:20.412" "SMTPDeliverer - Message 12 - Connection failed: Host name: 104.47.50.33, message: A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond"
"DEBUG" 7392 "2018-12-18 12:01:20.412" "Ending session 70"
"DEBUG" 7296 "2018-12-18 12:01:20.412" "External delivery process completed"
"DEBUG" 7296 "2018-12-18 12:01:20.412" "Starting external delivery process. Server: hotmail-com.olc.protection.outlook.com (104.47.49.33), Port: 25, Security: 2, User name: "
"DEBUG" 7296 "2018-12-18 12:01:20.412" "Creating session 72"
"TCPIP" 7296 "2018-12-18 12:01:20.412" "Connecting to 104.47.49.33:25..."
"DEBUG" 7392 "2018-12-18 12:01:23.414" "SMTPDeliverer - Message 11 - Connection failed: Host name: 104.47.49.33, message: A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond"
"DEBUG" 7392 "2018-12-18 12:01:23.414" "Ending session 71"
"DEBUG" 7300 "2018-12-18 12:01:23.414" "External delivery process completed"
"DEBUG" 7300 "2018-12-18 12:01:23.414" "Summarizing delivery result"
"DEBUG" 7300 "2018-12-18 12:01:23.414" "Summarized delivery results"
"TCPIP" 7300 "2018-12-18 12:01:23.414" "DNS MX lookup: idahosky.com"
"TCPIP" 7300 "2018-12-18 12:01:23.414" "DNS - MX Result: 1 IP addresses were found."
"DEBUG" 7300 "2018-12-18 12:01:23.414" "Starting external delivery process. Server: mail.idahosky.com (74.220.219.222), Port: 25, Security: 2, User name: "
"DEBUG" 7300 "2018-12-18 12:01:23.429" "Creating session 73"
"TCPIP" 7300 "2018-12-18 12:01:23.429" "Connecting to 74.220.219.222:25..."
"DEBUG" 7392 "2018-12-18 12:01:41.445" "SMTPDeliverer - Message 12 - Connection failed: Host name: 104.47.49.33, message: A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond"
"DEBUG" 7392 "2018-12-18 12:01:41.445" "Ending session 72"
"DEBUG" 7296 "2018-12-18 12:01:41.445" "External delivery process completed"
"DEBUG" 7296 "2018-12-18 12:01:41.445" "Summarizing delivery result"
"DEBUG" 7296 "2018-12-18 12:01:41.445" "Summarized delivery results"
"TCPIP" 7296 "2018-12-18 12:01:41.445" "DNS MX lookup: idahosky.com"
"TCPIP" 7296 "2018-12-18 12:01:41.460" "DNS - MX Result: 1 IP addresses were found."
"DEBUG" 7296 "2018-12-18 12:01:41.460" "Starting external delivery process. Server: mail.idahosky.com (74.220.219.222), Port: 25, Security: 2, User name: "
"DEBUG" 7296 "2018-12-18 12:01:41.460" "Creating session 74"
"TCPIP" 7296 "2018-12-18 12:01:41.460" "Connecting to 74.220.219.222:25..."
"DEBUG" 7392 "2018-12-18 12:01:44.461" "SMTPDeliverer - Message 11 - Connection failed: Host name: 74.220.219.222, message: A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond"
"DEBUG" 7392 "2018-12-18 12:01:44.461" "Ending session 73"
"DEBUG" 7300 "2018-12-18 12:01:44.461" "External delivery process completed"
"DEBUG" 7300 "2018-12-18 12:01:44.461" "Summarizing delivery result"
"DEBUG" 7300 "2018-12-18 12:01:44.461" "Summarized delivery results"
"DEBUG" 7300 "2018-12-18 12:01:44.461" "SD::RescheduleDelivery_"
"DEBUG" 7300 "2018-12-18 12:01:44.461" "Retrieving retry options."
"DEBUG" 7300 "2018-12-18 12:01:44.461" "Starting rescheduling."
"APPLICATION" 7300 "2018-12-18 12:01:44.477" "SMTPDeliverer - Message 11: Message could not be delivered. Scheduling it for later delivery in 60 minutes."
"DEBUG" 7300 "2018-12-18 12:01:44.477" "PersistentMessage::SetNextTryTime()"
"DEBUG" 7300 "2018-12-18 12:01:44.477" "PersistentMessage::~SetNextTryTime()"
"DEBUG" 7300 "2018-12-18 12:01:44.477" "Message rescheduled for later delivery."
"APPLICATION" 7300 "2018-12-18 12:01:44.477" "SMTPDeliverer - Message 11: Message delivery thread completed."
"DEBUG" 7392 "2018-12-18 12:02:02.507" "SMTPDeliverer - Message 12 - Connection failed: Host name: 74.220.219.222, message: A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond"
"DEBUG" 7392 "2018-12-18 12:02:02.507" "Ending session 74"
"DEBUG" 7296 "2018-12-18 12:02:02.507" "External delivery process completed"
"DEBUG" 7296 "2018-12-18 12:02:02.507" "Summarizing delivery result"
"DEBUG" 7296 "2018-12-18 12:02:02.507" "Summarized delivery results"
"DEBUG" 7296 "2018-12-18 12:02:02.507" "SD::RescheduleDelivery_"
"DEBUG" 7296 "2018-12-18 12:02:02.507" "Retrieving retry options."
"DEBUG" 7296 "2018-12-18 12:02:02.507" "Starting rescheduling."
"APPLICATION" 7296 "2018-12-18 12:02:02.507" "SMTPDeliverer - Message 12: Message could not be delivered. Scheduling it for later delivery in 60 minutes."
"DEBUG" 7296 "2018-12-18 12:02:02.523" "PersistentMessage::SetNextTryTime()"
"DEBUG" 7296 "2018-12-18 12:02:02.523" "PersistentMessage::~SetNextTryTime()"
"DEBUG" 7296 "2018-12-18 12:02:02.523" "Message rescheduled for later delivery."
"APPLICATION" 7296 "2018-12-18 12:02:02.523" "SMTPDeliverer - Message 12: Message delivery thread completed."

User avatar
jimimaseye
Moderator
Moderator
Posts: 7828
Joined: 2011-09-08 17:48

Re: hMailServer Outbound Message Failure to Deliver

Post by jimimaseye » 2018-12-18 23:20

run this and post the results: viewtopic.php?f=20&t=30914
HMS 5.6.6 B2383 on Win Server 2008 R2 Foundation, + 5.6.7-B2415 on test.
SpamassassinForWindows 3.4.0 spamd service
AV: Clamwin + Clamd service + sanesecurity defs : https://www.hmailserver.com/forum/viewtopic.php?f=21&t=26829

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

Re: hMailServer Outbound Message Failure to Deliver

Post by mattg » 2018-12-18 23:37

ALSO run the internal diagnostics and post the results.
That looks like port 25 outbound is blocked.
Either by your ISP, or perhaps your antivirus
Just 'cause I link to a page and say little else doesn't mean I am not being nice.
https://www.hmailserver.com/documentation

User avatar
Dravion
Senior user
Senior user
Posts: 1199
Joined: 2015-09-26 11:50
Location: Germany
Contact:

Re: hMailServer Outbound Message Failure to Deliver

Post by Dravion » 2018-12-19 04:48

Timeouts of the Remote Party means, the connection is unstable or willfully rejected (mostly by Firewall of the Remotesystem). But i dont think its willfully because Hotmail would issue a Errortext why it rejected your hMailServer but it didnt.

So, we need a few infos about your Broadband Connection.

1)
Is your hMailServer running on a Computer in your Companys Office or not?

2)
Does your Company use DSL, Wireless or PLC Uplinks for your Cooperate Network?
64-Bit builds of hMailserver

hMailServer-5.6.+ (HCD) https://github.com/hMailServer-ComDevs/hmailserver
hMailServer-5.6.+ (LTS) https://github.com/Dravion/hMailServer/releases

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

Re: hMailServer Outbound Message Failure to Deliver

Post by mattg » 2018-12-19 04:58

I don't see any actual connection to hotmail, just attempts that eventually abort

again
mattg wrote:
2018-12-18 23:37
That looks like port 25 outbound is blocked.
Just 'cause I link to a page and say little else doesn't mean I am not being nice.
https://www.hmailserver.com/documentation

User avatar
Dravion
Senior user
Senior user
Posts: 1199
Joined: 2015-09-26 11:50
Location: Germany
Contact:

Re: hMailServer Outbound Message Failure to Deliver

Post by Dravion » 2018-12-19 06:06

True, it stops at the connect level.
64-Bit builds of hMailserver

hMailServer-5.6.+ (HCD) https://github.com/hMailServer-ComDevs/hmailserver
hMailServer-5.6.+ (LTS) https://github.com/Dravion/hMailServer/releases

Post Reply