Issue with sending emails to specific SMTP server

Discussion in 'Server Operation' started by danield, Feb 18, 2021.

Tags:
  1. danield

    danield New Member

    Hello everyone,
    I was wondering if someone had the same issue or might know what is going here.
    We have two email accounts configured in ispconfig, and when trying to send an email to a specific recipient, the server returns the email with:

    Code:
    dsn=4.7.0, status=deferred (host hqmail.exmaple.com[81.XX.XX.XX] said: 451 4.7.0 Timeout waiting for client input (in reply to end of DATA command)
    I have now enabled debugging in main.cf (debug_peer_list = 81.XX.XX.XX) but I'm not sure what is happening here or what is not happening:

    Code:
    example.com:25: 220 edgemail.example.com Microsoft ESMTP MAIL Service ready at Thu, 18 Feb 2021 13:54:31 +0100
    Feb 18 13:54:32 myhostname postfix/smtp[23655]: > hqmail.example.com[81.XX.XX.XX]:25: EHLO myhostname.demo.com
    example.com:25: 250-edgemail.example.com Hello [145.XX.XX.XX]
    example.com:25: 250-SIZE 41205760
    example.com:25: 250-PIPELINING
    example.com:25: 250-DSN
    example.com:25: 250-ENHANCEDSTATUSCODES
    example.com:25: 250-X-ANONYMOUSTLS
    example.com:25: 250-AUTH
    example.com:25: 250-X-EXPS NTLM
    example.com:25: 250-8BITMIME
    example.com:25: 250-BINARYMIME
    example.com:25: 250-CHUNKING
    example.com:25: 250-XEXCH50
    example.com:25: 250 XSHADOW
    Feb 18 13:54:32 myhostname postfix/smtp[23655]: server features: 0x900f size 41205760
    Feb 18 13:54:32 myhostname postfix/smtp[23655]: Using ESMTP PIPELINING, TCP send buffer size is 46080, PIPELINING buffer size is 4096
    Feb 18 13:54:32 myhostname postfix/smtp[23655]: smtp_stream_setup: maxtime=300 enable_deadline=0
    Feb 18 13:54:32 myhostname postfix/smtp[23655]: > hqmail.example.com[81.XX.XX.XX]:25: MAIL FROM:<[email protected]> SIZE=930261 BODY=8BITMIME
    Feb 18 13:54:32 myhostname postfix/smtp[23655]: > hqmail.example.com[81.XX.XX.XX]:25: RCPT TO:<[email protected]> ORCPT=rfc822;[email protected]
    Feb 18 13:54:32 myhostname postfix/smtp[23655]: > hqmail.example.com[81.XX.XX.XX]:25: DATA
    Feb 18 13:54:32 myhostname postfix/smtp[23655]: smtp_stream_setup: maxtime=300 enable_deadline=0
    example.com:25: 250 2.1.0 Sender OK
    Feb 18 13:54:32 myhostname postfix/smtp[23655]: smtp_stream_setup: maxtime=300 enable_deadline=0
    example.com:25: 250 2.1.5 Recipient OK
    Feb 18 13:54:32 myhostname postfix/smtp[23655]: smtp_stream_setup: maxtime=120 enable_deadline=0
    example.com:25: 354 Start mail input; end with <CRLF>.<CRLF>
    Feb 18 13:54:32 myhostname postfix/smtp[23655]: smtp_stream_setup: maxtime=180 enable_deadline=0
    <--- OTHER LOG ENTRIES, SEEM NOT RELATED TO THIS MESSAGE -->
    Feb 18 13:58:11 myhostname postfix/smtp[23655]: send attr reason = conversation with hqmail.example.com[81.XX.XX.XX] timed out while sending message body
    Feb 18 13:58:11 myhostname postfix/smtp[23655]: private/defer socket: wanted attribute: status
    Feb 18 13:58:11 myhostname postfix/smtp[23655]: input attribute name: status
    Feb 18 13:58:11 myhostname postfix/smtp[23655]: input attribute value: 0
    Feb 18 13:58:11 myhostname postfix/smtp[23655]: private/defer socket: wanted attribute: (list terminator)
    Feb 18 13:58:11 myhostname postfix/smtp[23655]: input attribute name: (end)
    Feb 18 13:58:11 myhostname postfix/smtp[23655]: EEAED80340: to=<[email protected]>, relay=hqmail.example.com[81.XX.XX.XX]:25, delay=192573, delays=192353/0/0.14/220, dsn=4.4.2, status=deferred (conversation with hqmail.example.com[81.XX.XX.XX] timed out while sending message body)
    I do not understand why the connection timed out? Shouldn't have Postfix send the data to the remote server? When not, why there is no other error message in the log? I have also ensured that the remote SMTP server IP is not in the firewall.

    Thanks
     
  2. Jesse Norell

    Jesse Norell ISPConfig Developer Staff Member ISPConfig Developer

    The remote server, or possibly the network connection to it, seems to be having some problem.
    It's trying.
    I'm confused here, as you just posted two error messages from your logs when the problem happened.
     
  3. danield

    danield New Member

    Hi Jesse,
    Thanks for the reply.

    I was just wondering why it could connect to the SMTP server, but then might had an issue with sending data? At least on my SMTP server, no firewall was involved during a quick test.

    With "no error" I was referring that there was no error in the log from Postfix, stating that it could not start data transfer? I do not see any debug message that it even tried to start sending data (or is it the "smtp_stream_setup")? The connection was then closed by the remote server (timed out while sending message body)?

    Thanks
     
  4. Jesse Norell

    Jesse Norell ISPConfig Developer Staff Member ISPConfig Developer

    If the message is there it would be part of the '<--- OTHER LOG ENTRIES, SEEM NOT RELATED TO THIS MESSAGE -->' section. It is implied in the error that it is trying to send or had in fact sent some data: 'conversation with hqmail.example.com[81.XX.XX.XX] timed out while sending message body'.
    That was the case with the first error you posted, the second reported a timeout on your end.
     

Share This Page