HELP ..... Postfix/Dovecot unable to received from outlook[com] (lost connection after ehlo)

Discussion in 'Server Operation' started by Ardy, Aug 11, 2016.

  1. Ardy

    Ardy New Member

    HELP !!!
    i can't received email from outlook[com] with message lost connection after ehlo. here are some of snippet from the log

    ---------------------------
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] connect from mail-eopbgr20089.outbound.protection.outlook[com][40.107.2.89]
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] match_hostname: mail-eopbgr20089.outbound.protection.outlook[com] ~? xxx.xxxx.xxx.xxx/29
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] match_hostaddr: 40.107.2.89 ~? xxx.xxx.xxx.xxx/29 <- my network
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] match_hostname: mail-eopbgr20089.outbound.protection.outlook[com] ~? 127.0.0.0/8
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] match_hostaddr: 40.107.2.89 ~? 127.0.0.0/8
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] match_list_match: mail-eopbgr20089.outbound.protection.outlook[com]: no match
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] match_list_match: 40.107.2.89: no match
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] auto_clnt_open: connected to private/anvil
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] event_enable_read: fd 21
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] event_extend: fd 21
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] send attr request = connect
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] send attr ident = smtp:40.107.2.89
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] vstream_fflush_some: fd 21 flush 40
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] vstream_buf_get_ready: fd 21 got 25
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] private/anvil: wanted attribute: status
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] input attribute name: status
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] input attribute value: 0
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] private/anvil: wanted attribute: count
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] input attribute name: count
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] input attribute value: 1
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] private/anvil: wanted attribute: rate
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] input attribute name: rate
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] input attribute value: 1
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] private/anvil: wanted attribute: (list terminator)
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] input attribute name: (end)
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] > mail-eopbgr20089.outbound.protection.outlook[com][40.107.2.89]: 220 xxx.xxx[com] ESMTP Postfix
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] xsasl_dovecot_server_create: SASL service=smtp, realm=xxxx[com]
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] name_mask: noanonymous
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] xsasl_dovecot_server_connect: Connecting
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] vstream_fflush_some: fd 20 flush 23
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] vstream_buf_get_ready: fd 20 got 127
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] xsasl_dovecot_server_connect: auth reply: VERSION?1?1
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] xsasl_dovecot_server_connect: auth reply: MECH?NTLM?dictionary?active
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] name_mask: dictionary
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] name_mask: active
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] xsasl_dovecot_server_connect: auth reply: MECH?LOGIN?plaintext
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] name_mask: plaintext
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] xsasl_dovecot_server_connect: auth reply: SPID?2837
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] xsasl_dovecot_server_connect: auth reply: CUID?26266
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] xsasl_dovecot_server_connect: auth reply: COOKIE?7fd3a0151948ad253a004238267819a5
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] xsasl_dovecot_server_connect: auth reply: DONE
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] xsasl_dovecot_server_mech_filter: keep mechanism: NTLM
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] xsasl_dovecot_server_mech_filter: keep mechanism: LOGIN
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] watchdog_pat: 8154930
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] vstream_fflush_some: fd 19 flush 51
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] vstream_buf_get_ready: fd 19 got 52
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] < mail-eopbgr20089.outbound.protection.outlook[com][40.107.2.89]: EHLO EUR02-VE1-obe.outbound.protection.outlook[com]
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] > mail-eopbgr20089.outbound.protection.outlook[com][40.107.2.89]: 250-xxx.xxxxx[com]
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] > mail-eopbgr20089.outbound.protection.outlook[com][40.107.2.89]: 250-PIPELINING
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] > mail-eopbgr20089.outbound.protection.outlook[com][40.107.2.89]: 250-SIZE 52428800
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] > mail-eopbgr20089.outbound.protection.outlook[com][40.107.2.89]: 250-ETRN
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] > mail-eopbgr20089.outbound.protection.outlook[com][40.107.2.89]: 250-STARTTLS
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] > mail-eopbgr20089.outbound.protection.outlook[com][40.107.2.89]: 250-AUTH NTLM LOGIN
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] match_list_match: mail-eopbgr20089.outbound.protection.outlook[com]: no match
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] match_list_match: 40.107.2.89: no match
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] > mail-eopbgr20089.outbound.protection.outlook[com][40.107.2.89]: 250-AUTH=NTLM LOGIN
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] > mail-eopbgr20089.outbound.protection.outlook[com][40.107.2.89]: 250-ENHANCEDSTATUSCODES
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] > mail-eopbgr20089.outbound.protection.outlook[com][40.107.2.89]: 250-8BITMIME
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] > mail-eopbgr20089.outbound.protection.outlook[com][40.107.2.89]: 250 DSN
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] watchdog_pat: 8154930
    Aug 10 15:30:55 surat postfix/smtpd[10191]: [ID 197553 mail.info] vstream_fflush_some: fd 19 flush 186
    Aug 10 15:30:55 surat postfix/smtpd[9991]: [ID 197553 mail.info] > mail-eopbgr30080.outbound.protection.outlook[com][40.107.3.80]: 421 4.4.2 xxx.xxx.xxx.xxx Error: timeout exceeded
    Aug 10 15:30:55 surat postfix/smtpd[9991]: [ID 197553 mail.info] vstream_fflush_some: fd 11 flush 67
    Aug 10 15:30:55 surat postfix/smtpd[9991]: [ID 197553 mail.info] match_hostname: mail-eopbgr30080.outbound.protection.outlook[com] ~? xxx.xxx.xxx.xxx/29
    Aug 10 15:30:55 surat postfix/smtpd[9991]: [ID 197553 mail.info] match_hostaddr: 40.107.3.80 ~? xxx.xxxx.xxx.xxx/29
    Aug 10 15:30:55 surat postfix/smtpd[9991]: [ID 197553 mail.info] match_hostname: mail-eopbgr30080.outbound.protection.outlook[com] ~? 127.0.0.0/8
    Aug 10 15:30:55 surat postfix/smtpd[9991]: [ID 197553 mail.info] match_hostaddr: 40.107.3.80 ~? 127.0.0.0/8
    Aug 10 15:30:55 surat postfix/smtpd[9991]: [ID 197553 mail.info] match_list_match: mail-eopbgr30080.outbound.protection.outlook[com]: no match
    Aug 10 15:30:55 surat postfix/smtpd[9991]: [ID 197553 mail.info] match_list_match: 40.107.3.80: no match
    Aug 10 15:30:55 surat postfix/smtpd[9991]: [ID 197553 mail.info] send attr request = disconnect
    Aug 10 15:30:55 surat postfix/smtpd[9991]: [ID 197553 mail.info] send attr ident = smtp:40.107.3.80
    Aug 10 15:30:55 surat postfix/smtpd[9991]: [ID 197553 mail.info] vstream_fflush_some: fd 21 flush 43
    Aug 10 15:30:55 surat postfix/smtpd[9991]: [ID 197553 mail.info] vstream_buf_get_ready: fd 21 got 10
    Aug 10 15:30:55 surat postfix/smtpd[9991]: [ID 197553 mail.info] private/anvil: wanted attribute: status
    Aug 10 15:30:55 surat postfix/smtpd[9991]: [ID 197553 mail.info] input attribute name: status
    Aug 10 15:30:55 surat postfix/smtpd[9991]: [ID 197553 mail.info] input attribute value: 0
    Aug 10 15:30:55 surat postfix/smtpd[9991]: [ID 197553 mail.info] private/anvil: wanted attribute: (list terminator)
    Aug 10 15:30:55 surat postfix/smtpd[9991]: [ID 197553 mail.info] input attribute name: (end)
    Aug 10 15:30:55 surat postfix/smtpd[9991]: [ID 197553 mail.info] timeout after EHLO from mail-eopbgr30080.outbound.protection.outlook[com][40.107.3.80]

    ----------------------------------------------------------
    postconf -n and master.cf is in the next post due to regulation

    i've been battling this issue for few days now .... please HELP
    Appreciate all kind of input.
     
  2. Ardy

    Ardy New Member

    postconf -n
    # postconf -n
    alias_database = hash:/etc/postfix/aliases
    alias_maps = hash:/etc/postfix/aliases
    alternate_config_directories = /etc/opt/csw/postfix
    always_bcc = xxx@xxxxxx[com]
    broken_sasl_auth_clients = yes
    command_directory = /opt/csw/sbin
    config_directory = /etc/opt/csw/postfix/
    daemon_directory = /opt/csw/libexec/postfix
    data_directory = /var/opt/csw/lib/postfix
    debug_peer_level = 3
    debug_peer_list = outlook[com]
    disable_vrfy_command = yes
    fallback_transport = virtual
    helpful_warnings = no
    home_mailbox = Maildir/
    inet_interfaces = all
    mail_owner = postfix
    mail_spool_directory = /var/spool/mail
    mailbox_command = /opt/csw/libexec/dovecot/deliver
    mailbox_size_limit = 524288000
    mailbox_transport = dovecot
    mailq_path = /opt/csw/bin/mailq
    message_size_limit = 52428800
    mydestination = $myhostname, xxxx[com], localhost.$mydomain, localhost, $mydomain
    mydomain = xxxxxxx[com]
    myhostname = xxxx.xxxxxx[com]
    mynetworks = xxxx.xxxx.xxxx/29, 127.0.0.0/8
    mynetworks_style = subnet
    myorigin = $mydomain
    newaliases_path = /opt/csw/bin/newaliases
    owner_request_special = no
    queue_directory = /var/opt/csw/spool/postfix
    recipient_delimiter = +
    relay_domains = $transport_maps
    relayhost =
    sendmail_path = /opt/csw/sbin/sendmail
    setgid_group = postdrop
    smtp_tls_note_starttls_offer = yes
    smtp_tls_session_cache_database = btree:${data_directory}/smtp_scache
    smtpd_banner = $myhostname ESMTP $mail_name
    smtpd_data_restrictions = reject_unauth_pipelining
    smtpd_helo_required = yes
    smtpd_reject_unlisted_recipient = yes
    smtpd_reject_unlisted_sender = yes
    smtpd_sasl_auth_enable = yes
    smtpd_sasl_authenticated_header = yes
    smtpd_sasl_local_domain = xxxxxx[com]
    smtpd_sasl_path = /var/opt/csw/spool/postfix/private/auth
    smtpd_sasl_security_options = noanonymous
    smtpd_sasl_type = dovecot
    smtpd_sender_restrictions = reject_unknown_sender_domain, check_sender_access hash:/etc/opt/csw/postfix/blacklist
    smtpd_tls_CAfile = /opt/csw/ssl/certs/bundle.pem
    smtpd_tls_auth_only = no
    smtpd_tls_cert_file = /opt/csw/ssl/certs/surat.pem
    smtpd_tls_key_file = /opt/csw/ssl/private/surat.key
    smtpd_tls_loglevel = 3
    smtpd_tls_received_header = yes
    smtpd_tls_security_level = may
    smtpd_tls_session_cache_database = btree:${data_directory}/smtpd_scache
    smtpd_tls_session_cache_timeout = 3600s
    smtpd_use_tls = yes
    tls_random_source = /dev/urandom
    unknown_local_recipient_reject_code = 550
    virtual_mailbox_limit = 550000000
    virtual_minimum_uid = 100
    virtual_transport = dovecot
    -----------------------------------------------------------------------
    my master.cf
    smtp inet n - n - - smtpd
    -o content_filter=spamfilt
    submission inet n - n - - smtpd
    -o syslog_name=postfix/submission
    -o smtpd_tls_security_level=encrypt
    -o smtpd_tls_wrappermode=yes
    -o smtpd_sasl_auth_enable=yes
    -o smtpd_client_restrictions=permit_sasl_authenticated, reject
    -o smtpd_sasl_type=dovecot
    -o smtpd_sasl_path=/var/opt/csw/spool/postfix/private/auth
    -o smtpd_sasl_security_options=noanonymous
    -o smtpd_sender_login_maps=hash:/etc/postfix/virtual
    -o smtpd_sender_restrictions=reject_sender_login_mismatch
    # -o smtpd_recipient_restrictions=permit_mynetworks, reject_non_fqdn_recipient,reject_unknown_recipient_domain
    -o milter_macro_daemon_name=ORIGINATING
    pickup fifo n - n 60 1 pickup
    cleanup unix n - n - 0 cleanup
    qmgr fifo n - n 300 1 qmgr
    #qmgr fifo n - n 300 1 oqmgr
    tlsmgr unix - - n 1000? 1 tlsmgr
    rewrite unix - - n - - trivial-rewrite
    bounce unix - - n - 0 bounce
    defer unix - - n - 0 bounce
    smtps unix n - - - - smtpd
    trace unix - - n - 0 bounce
    verify unix - - n - 1 verify
    flush unix n - n 1000? 0 flush
    proxymap unix - - n - - proxymap
    proxywrite unix - - n - 1 proxymap
    smtp unix - - n - - smtp
    relay unix - - n - - smtp
    -o smtp_fallback_relay=
    # -o smtp_helo_timeout=5 -o smtp_connect_timeout=5
    showq unix n - n - - showq
    error unix - - n - - error
    retry unix - - n - - error
    discard unix - - n - - discard
    local unix - n n - - local
    virtual unix - n n - - virtual
    lmtp unix - - n - - lmtp
    anvil unix - - n - 1 anvil
    scache unix - - n - 1 scache
    dovecot unix - n n - - pipe
    flags=DRhu user=vmail:dovecot argv=/opt/csw/libexec/dovecot/deliver -f ${sender} -d ${user}@${nexthop}
    spamfilt unix - n n - - pipe
    flags=Rq user=spamd argv=/etc/opt/csw/postfix/spamfilt.sh -oi -f ${sender} ${recipient}
    policy unix - n n - - spawn
    user=spamd argv=/opt/csw/bin/perl /etc/opt/csw/postfix/policyd-spf-perl
    ---------------------
     

Share This Page