OK, smtpd[9647]: disconnect happened at 13:04:23. There's no smtpd[9647] entries after 10:15:16.
Oct 31 10:15:16 mailgate2 postfix/smtpd[9647]: connect from ttsenmta2.ttasia.com[210.17.183.8] Oct 31 10:15:17 mailgate2 postfix/smtpd[9647]: 4803D7F20: client=ttsenmta2.ttasia.com[210.17.183.8] Oct 31 10:15:17 mailgate2 postfix/cleanup[9648]: 4803D7F20: message-id=<002601cdb70d$8e5363e0$aafa2ba0$@com.tw> … Oct 31 13:04:23 mailgate2 postfix/smtpd[9647]: disconnect from ttsenmta2.ttasia.com[210.17.183.8] Oct 31 13:04:23 mailgate2 postfix/qmgr[4869]: 4803D7F20: from=<v...@otogarment.com>, size=595890, nrcpt=3 (queue active) Oct 31 13:04:23 mailgate2 postfix/smtp[10206]: 4803D7F20: to=<iris...@ss-intl.com>, relay=192.168.1.21[192.168.1.21]:25, delay=10147, delays=10146/0/0/0.3,dsn=2.0.0, status=sent (250 Mail queued for delivery) Oct 31 13:04:23 mailgate2 postfix/smtp[10206]: 4803D7F20: to=<rick...@ss-intl.com>, relay=192.168.1.21[192.168.1.21]:25, delay=10147, delays=10146/0/0/0.3, dsn=2.0.0, status=sent (250 Mail queued for delivery) Oct 31 13:04:23 mailgate2 postfix/smtp[10206]: 4803D7F20: to=<sherry...@ss-intl.com>, relay=192.168.1.21[192.168.1.21]:25, delay=10147, delays=10146/0/0/0.3, dsn=2.0.0, status=sent (250 Mail queued for delivery) Oct 31 13:04:23 mailgate2 postfix/qmgr[4869]: 4803D7F20: removed Oct 31 13:04:27 mailgate2 postfix/smtpd[9647]: connect from ttsenmta4.ttasia.com[210.17.183.10] Oct 31 13:04:27 mailgate2 postfix/smtpd[9647]: F2D257F20: client=ttsenmta4.ttasia.com[210.17.183.10] Here's the postconf -n output: alias_database = hash:/etc/postfix/aliases alias_maps = hash:/etc/postfix/aliases command_directory = /usr/sbin config_directory = /etc/postfix daemon_directory = /usr/libexec/postfix data_directory = /var/lib/postfix debug_peer_level = 3 default_destination_concurrency_limit = 100 default_destination_recipient_limit = 300 disable_vrfy_command = yes html_directory = /usr/share/doc/postfix-2.5.6-documentation/html local_recipient_maps = local_transport = error:local mail delivery is disabled on this machine mail_owner = postfix mailq_path = /usr/bin/mailq.postfix manpage_directory = /usr/share/man message_size_limit = 838860800 mydestination = $myhostname, localhost.$mydomain, localhost, $mydomain, ss-intl.com, fredho.net mydomain = ss-intl.com myhostname = mailgate2.ss-intl.com mynetworks = 127.0.0.0/8, 202.64.220.35, 202.64.220.53, 192.168.1.0/24 myorigin = $mydomain newaliases_path = /usr/bin/newaliases.postfix notify_classes = bounce, policy queue_directory = /var/spool/postfix readme_directory = /usr/share/doc/postfix-2.5.6-documentation/readme relay_domains = $mydestination relayhost = ss-intl.com.outbound10.ttasia.com sample_directory = /etc/postfix sendmail_path = /usr/sbin/sendmail.postfix setgid_group = postdrop smtp_connect_timeout = 300s smtp_data_done_timeout = 800s smtp_data_init_timeout = 300s smtp_data_xfer_timeout = 240s smtpd_banner = $myhostname ESMTP $mail_name smtpd_delay_reject = yes smtpd_helo_required = yes smtpd_helo_restrictions = permit_mynetworks, reject_non_fqdn_hostname, reject_invalid_hostname smtpd_policy_service_timeout = 300s smtpd_proxy_timeout = 300s smtpd_recipient_restrictions = permit_mynetworks, reject_unauth_pipelining, reject_non_fqdn_recipient, reject_unknown_recipient_domain, reject_unknown_sender_domain, reject_unauth_destination smtpd_sender_restrictions = reject_non_fqdn_sender, warn_if_reject reject_unknown_sender_domain, permit smtpd_timeout = 600s strict_rfc821_envelopes = yes transport_maps = hash:/etc/postfix/transport unknown_address_reject_code = 550 unknown_hostname_reject_code = 550 unknown_local_recipient_reject_code = 550 Regards,Fred On Thu, 11/01/2012 12:21 AM, Viktor Dukhovni <postfix-us...@dukhovni.org> wrote: > On Wed, Oct 31, 2012 at 11:32:20PM +0800, Fred Ho wrote: > > > Oct 31 10:15:16 mailgate2 postfix/smtpd[9647]: > > connect from ttsenmta2.ttasia.com[210.17.183.8] > > Oct 31 10:15:17 mailgate2 postfix/smtpd[9647]: 4803D7F20: > > client=ttsenmta2.ttasia.com[210.17.183.8] > > Oct 31 10:15:17 mailgate2 postfix/cleanup[9648]: 4803D7F20: > > message-id=<002601cdb70d$8e5363e0$aafa2ba0$@com.tw> > > Oct 31 13:04:23 mailgate2 postfix/qmgr[4869]: 4803D7F20: > > from=<v...@otogarment.com>, size=595890, nrcpt=3 (queue active) > > Oct 31 13:04:23 mailgate2 postfix/smtp[10206]: 4803D7F20: > > to=<i...@ss-intl.com>, relay=192.168.1.21[192.168.1.21]:25, > > delay=10147, delays=10146/0/0/0.3, dsn=2.0.0, status=sent > > (250 Mail queued for delivery) > > Oct 31 13:04:23 mailgate2 postfix/smtp[10206]: 4803D7F20: > > to=<rick...@ss-intl.com>, relay=192.168.1.21[192.168.1.21]:25, > > delay=10147, delays=10146/0/0/0.3, dsn=2.0.0, status=sent > > (250 Mail queued for delivery) > > Oct 31 13:04:23 mailgate2 postfix/smtp[10206]: 4803D7F20: > > to=<sherry...@ss-intl.com>, relay=192.168.1.21[192.168.1.21]:25, > > delay=10147, delays=10146/0/0/0.3, dsn=2.0.0, status=sent > > (250 Mail queued for delivery) > > Oct 31 13:04:23 mailgate2 postfix/qmgr[4869]: 4803D7F20: removed > > Can you also post the corresponding > > Oct 31 XX:XX:XX mailgate2 postfix/smtpd[9647]: > disconnect from ttsenmta2.ttasia.com[210.17.183.8] > > log entry (match on the process id 9647 and client IP)? It is > possible that the SMTP server did not receive the "DOT<CRLF>" until > shortly before 13:04:23 when the client disconnected. > > As Ralf points out, part of the issue could be a firewall that > fails to propagate pipelined "DOT<CRLF>QUIT<CRLF>" until > the client disconnects. > > Another possibility is that the cleanup service was blocked syncing > the message to disk or waiting for an inflow delay token. Make sure > your inflow_delay is not misconfigured, and that your system does > not lose clock or file readable events, ... > > > Oct 31 10:30:23 mailgate2 postfix/smtpd[9688]: > > connect from ttsenmta2.ttasia.com[210.17.183.8] > > Oct 31 10:30:23 mailgate2 postfix/smtpd[9688]: F097C7F1F: > > client=ttsenmta2.ttasia.com[210.17.183.8] > > Oct 31 10:30:24 mailgate2 postfix/cleanup[9689]: F097C7F1F: > > message-id=<002601cdb70d$8e5363e0$aafa2ba0$@com.tw> > > Oct 31 10:30:26 mailgate2 postfix/qmgr[4869]: F097C7F1F: > > from=<v...@otogarment.com>, size=595890, nrcpt=3 (queue active) > > Oct 31 10:30:26 mailgate2 postfix/smtpd[9688]: > > disconnect from ttsenmta2.ttasia.com[210.17.183.8] > > Oct 31 10:30:26 mailgate2 postfix/smtp[9685]: F097C7F1F: > > to=<i...@ss-intl.com>, relay=192.168.1.21[192.168.1.21]:25, > > delay=2.6, delays=2.3/0/0/0.3, dsn=2.0.0, status=sent > > (250 Mail queued for delivery) > > [...] > > Oct 31 10:30:26 mailgate2 postfix/qmgr[4869]: F097C7F1F: removed > > > > Can someone explain why there's a delay in delivery at 10:15:17 > > until 13:04? > > The ISP said they didn't receive the 250 OK after end of DATA > > and hence retry later at 10:30 which was delivered immediately. > > -- > Viktor. > >
