Default SMTP Route Not working in v2.00

Issue #283 resolved
Roshan Jonah created an issue

The Default SMTP route is not working in v2.00. I upgraded it and I added the appropriate credentials. It saved without any errors but when I send, it is sending from my own server IP. It is not making use of the external service I am using. I tried using other mail servers too but just would not route through those too. I think it is not saving the information correctly.

skitch.png

Comments (5)

  1. SH repo owner

    Are you sending emails to remote domains? Local domains are delivered localy obviously.

    Please attach log/delivery files affecting this - they are at log/delivery in your datadir, all (conn,del,tx) starting with something like this 21460EB5-7DD2-4938-A5EF-12E5056D5172...

  2. Scott MacDonald

    This is exactly the same behavior that I am seeing. Poste.io v2 (PRO and FREE) no longer work correctly with a smarthost (Default SMTP route) configured. Any attempts at sending email from a local domain to a remote domain results in the email being delivered by the local host and not the configured external SMTP smart host / relay (In my case this is MailChannels SMTP service).

    Further to and just as an FYI - Issue 283 and 281 were logged for the same bug.

    Below I've included the log from the email I sent as a local user which was to be delivered to a remote domain (gmail) and the local host delivered it without using the configured Smarthost.

     Connection A183D908-33EB-4D75-A215-4C4D2F355331
    2018-04-23 11:20:37     [core] [NOTICE] - connect ip=142.140.83.191 port=57153 local_ip=:: local_port=25
    2018-04-23 11:20:37     [core] [DEBUG] - running connect_init hooks
    2018-04-23 11:20:37     [core] [DEBUG] - running connect_init hook in guard plugin
    2018-04-23 11:20:37     [core] [DEBUG] - hook=connect_init plugin=guard function=hook_connect_init params="" retval=CONT msg=""
    2018-04-23 11:20:37     [core] [DEBUG] - running connect_init hook in early_talker plugin
    2018-04-23 11:20:42     [core] [DEBUG] - hook=connect_init plugin=early_talker function=early_talker params="" retval=CONT msg=""
    2018-04-23 11:20:42     [core] [DEBUG] - running connect_init hook in fcrdns plugin
    2018-04-23 11:20:42     [core] [DEBUG] - hook=connect_init plugin=fcrdns function=initialize_fcrdns params="" retval=CONT msg=""
    2018-04-23 11:20:42     [core] [DEBUG] - running connect_init hook in karma plugin
    2018-04-23 11:20:42     [karma] [DEBUG] - psubscribed to result-A183D908-33EB-4D75-A215-4C4D2F355331 *
    2018-04-23 11:20:42     [core] [DEBUG] - hook=connect_init plugin=karma function=results_init params="" retval=CONT msg=""
    2018-04-23 11:20:42     [core] [DEBUG] - running connect_init hook in karma plugin
    2018-04-23 11:20:42     [core] [DEBUG] - hook=connect_init plugin=karma function=ip_history_from_redis params="" retval=CONT msg=""
    2018-04-23 11:20:42     [core] [DEBUG] - running connect_init_respond
    2018-04-23 11:20:42     [core] [DEBUG] - running lookup_rdns hooks
    2018-04-23 11:20:42     [core] [DEBUG] - running lookup_rdns hook in p0f plugin
    2018-04-23 11:20:42     [p0f] [INFO] - os="Windows NT kernel" link_type="generic tunnel or VPN" distance=18 total_conn=1
    2018-04-23 11:20:42     [core] [DEBUG] - hook=lookup_rdns plugin=p0f function=hook_lookup_rdns params="" retval=CONT msg=""
    2018-04-23 11:20:42     [core] [DEBUG] - running lookup_rdns hook in fcrdns plugin
    2018-04-23 11:20:42     [fcrdns] [DEBUG] - rdns lookup: 142.140.83.191
    2018-04-23 11:20:42     [fcrdns] [INFO] - ptr_multidomain: false, has_rdns: false, ptr_name_has_ips: false, fail:has_rdns
    2018-04-23 11:20:42     [core] [DEBUG] - hook=lookup_rdns plugin=fcrdns function=do_dns_lookups params="" retval=CONT msg=""
    2018-04-23 11:20:42     [core] [DEBUG] - running lookup_rdns hook in data.uribl plugin
    2018-04-23 11:20:42     [core] [DEBUG] - hook=lookup_rdns plugin=data.uribl function=hook_lookup_rdns params="" retval=CONT msg=""
    2018-04-23 11:20:42     [core] [DEBUG] - running lookup_rdns hook in asn plugin
    2018-04-23 11:20:42     [asn] [DEBUG] - zone: origin.asn.cymru.com
    2018-04-23 11:20:42     [asn] [INFO] - asn: 808, net: 142.140.0.0/17
    2018-04-23 11:20:42     [core] [DEBUG] - hook=lookup_rdns plugin=asn function=lookup_via_dns params="" retval=CONT msg=""
    2018-04-23 11:20:42     [core] [DEBUG] - running connect hooks
    2018-04-23 11:20:42     [core] [DEBUG] - running connect hook in limits plugin
    2018-04-23 11:20:42     [limits] [DEBUG] - IP limit: null
    2018-04-23 11:20:42     [core] [DEBUG] - hook=connect plugin=limits function=hook_connect params="" retval=CONT msg=""
    2018-04-23 11:20:42     [core] [DEBUG] - running connect hook in guard plugin
    2018-04-23 11:20:42     [core] [DEBUG] - hook=connect plugin=guard function=hook_connect params="" retval=CONT msg=""
    2018-04-23 11:20:42     [core] [DEBUG] - running connect hook in dnsbl plugin
    2018-04-23 11:20:42     [core] [DEBUG] - hook=connect plugin=dnsbl function=connect_first params="" retval=CONT msg=""
    2018-04-23 11:20:42     [core] [DEBUG] - running connect hook in relay plugin
    2018-04-23 11:20:42     [relay] [DEBUG] - checking 142.140.83.191 in relay_acl_allow
    2018-04-23 11:20:42     [relay] [DEBUG] - checking if 142.140.83.191 is in 127.0.0.1/32
    2018-04-23 11:20:42     [relay] [DEBUG] - checking if 142.140.83.191 is in 172.17.0.2/32
    2018-04-23 11:20:42     [core] [DEBUG] - hook=connect plugin=relay function=acl params="" retval=CONT msg=""
    2018-04-23 11:20:42     [core] [DEBUG] - running connect hook in karma plugin
    2018-04-23 11:20:42     [karma] [DEBUG] - tarpit capped to: 4
    2018-04-23 11:20:42     [karma] [DEBUG] - tarpitting connect for 4s
    2018-04-23 11:20:46     [karma] [DEBUG] - tarpit connect end
    2018-04-23 11:20:46     [core] [DEBUG] - hook=connect plugin=karma function=hook_connect params="" retval=CONT msg=""
    2018-04-23 11:20:46     [core] [DEBUG] - running connect hook in max_unrecognized_commands plugin
    2018-04-23 11:20:46     [core] [DEBUG] - hook=connect plugin=max_unrecognized_commands function=hook_connect params="" retval=CONT msg=""
    2018-04-23 11:20:46     220 mx01.cyberteer.com ESMTP Haraka ready
    2018-04-23 11:20:46     EHLO ON34C02159586VX
    2018-04-23 11:20:46     [core] [DEBUG] - running ehlo hooks
    2018-04-23 11:20:46     [core] [DEBUG] - running ehlo hook in helo.checks plugin
    2018-04-23 11:20:46     [core] [DEBUG] - hook=ehlo plugin=helo.checks function=proto_mismatch_esmtp params=ON34C02159586VX retval=CONT msg=""
    2018-04-23 11:20:46     [core] [DEBUG] - running ehlo hook in helo.checks plugin
    2018-04-23 11:20:46     [core] [DEBUG] - hook=ehlo plugin=helo.checks function=init params=ON34C02159586VX retval=CONT msg=""
    2018-04-23 11:20:46     [core] [DEBUG] - running ehlo hook in helo.checks plugin
    2018-04-23 11:20:46     [core] [DEBUG] - hook=ehlo plugin=helo.checks function=match_re params=ON34C02159586VX retval=CONT msg=""
    2018-04-23 11:20:46     [core] [DEBUG] - running ehlo hook in helo.checks plugin
    2018-04-23 11:20:46     [core] [DEBUG] - hook=ehlo plugin=helo.checks function=bare_ip params=ON34C02159586VX retval=CONT msg=""
    2018-04-23 11:20:46     [core] [DEBUG] - running ehlo hook in helo.checks plugin
    2018-04-23 11:20:46     [core] [DEBUG] - hook=ehlo plugin=helo.checks function=dynamic params=ON34C02159586VX retval=CONT msg=""
    2018-04-23 11:20:46     [core] [DEBUG] - running ehlo hook in helo.checks plugin
    2018-04-23 11:20:46     [core] [DEBUG] - hook=ehlo plugin=helo.checks function=big_company params=ON34C02159586VX retval=CONT msg=""
    2018-04-23 11:20:46     [core] [DEBUG] - running ehlo hook in helo.checks plugin
    2018-04-23 11:20:46     [core] [DEBUG] - hook=ehlo plugin=helo.checks function=literal_mismatch params=ON34C02159586VX retval=CONT msg=""
    2018-04-23 11:20:46     [core] [DEBUG] - running ehlo hook in helo.checks plugin
    2018-04-23 11:20:46     [core] [DEBUG] - hook=ehlo plugin=helo.checks function=valid_hostname params=ON34C02159586VX retval=CONT msg=""
    2018-04-23 11:20:46     [core] [DEBUG] - running ehlo hook in helo.checks plugin
    2018-04-23 11:20:46     [core] [DEBUG] - hook=ehlo plugin=helo.checks function=rdns_match params=ON34C02159586VX retval=CONT msg=""
    2018-04-23 11:20:46     [core] [DEBUG] - running ehlo hook in helo.checks plugin
    2018-04-23 11:20:46     [core] [DEBUG] - hook=ehlo plugin=helo.checks function=forward_dns params=ON34C02159586VX retval=CONT msg=""
    2018-04-23 11:20:46     [core] [DEBUG] - running ehlo hook in helo.checks plugin
    2018-04-23 11:20:46     [core] [DEBUG] - hook=ehlo plugin=helo.checks function=host_mismatch params=ON34C02159586VX retval=CONT msg=""
    2018-04-23 11:20:46     [core] [DEBUG] - running ehlo hook in helo.checks plugin
    2018-04-23 11:20:46     [helo.checks] [INFO] - helo_host: ON34C02159586VX, pass:match_re, bare_ip, big_co(not), literal_mismatch, rdns_match(org_dom), host_mismatch, fail:valid_hostname(no_dot), forward_dns(invalid_hostname), skip:dynamic(no dots)
    2018-04-23 11:20:46     [core] [DEBUG] - hook=ehlo plugin=helo.checks function=emit_log params=ON34C02159586VX retval=CONT msg=""
    2018-04-23 11:20:46     [core] [DEBUG] - running ehlo hook in spf plugin
    2018-04-23 11:20:46     [spf] [INFO] - identity=helo ip=142.140.83.191 domain="ON34C02159586VX" mfrom= result=None
    2018-04-23 11:20:46     [spf] [INFO] - scope: helo, result: None, domain: ON34C02159586VX
    2018-04-23 11:20:46     [core] [DEBUG] - hook=ehlo plugin=spf function=hook_ehlo params=ON34C02159586VX retval=CONT msg=""
    2018-04-23 11:20:46     [core] [DEBUG] - running ehlo hook in data.uribl plugin
    2018-04-23 11:20:46     [data.uribl] [DEBUG] - (helo) found 1 items for lookup
    2018-04-23 11:20:46     [data.uribl] [DEBUG] - (helo) checking: on34c02159586vx
    2018-04-23 11:20:46     [core] [DEBUG] - hook=ehlo plugin=data.uribl function=hook_ehlo params=ON34C02159586VX retval=CONT msg=""
    2018-04-23 11:20:46     [core] [DEBUG] - running ehlo hook in karma plugin
    2018-04-23 11:20:46     [karma] [DEBUG] - tarpit capped to: 4
    2018-04-23 11:20:46     [karma] [DEBUG] - tarpitting ehlo for 4s
    2018-04-23 11:20:50     [karma] [DEBUG] - tarpit ehlo end
    2018-04-23 11:20:50     [core] [DEBUG] - hook=ehlo plugin=karma function=hook_ehlo params=ON34C02159586VX retval=CONT msg=""
    2018-04-23 11:20:50     [core] [DEBUG] - running capabilities hooks
    2018-04-23 11:20:50     [core] [DEBUG] - running capabilities hook in tls plugin
    2018-04-23 11:20:50     [core] [DEBUG] - hook=capabilities plugin=tls function=advertise_starttls params="" retval=CONT msg=""
    2018-04-23 11:20:50     [core] [DEBUG] - running capabilities hook in auth/poste plugin
    2018-04-23 11:20:50     [core] [DEBUG] - hook=capabilities plugin=auth/poste function=hook_capabilities params="" retval=CONT msg=""
    2018-04-23 11:20:50     250-mx01.cyberteer.com Hello [142.140.83.191], Haraka is at your service.
    2018-04-23 11:20:50     250-PIPELINING
    2018-04-23 11:20:50     250-8BITMIME
    2018-04-23 11:20:50     250-SMTPUTF8
    2018-04-23 11:20:50     250-SIZE 0
    2018-04-23 11:20:50     250 STARTTLS
    2018-04-23 11:20:51     STARTTLS
    2018-04-23 11:20:51     [core] [DEBUG] - running unrecognized_command hooks
    2018-04-23 11:20:51     [core] [DEBUG] - running unrecognized_command hook in tls plugin
    2018-04-23 11:20:51     220 Go ahead.
    2018-04-23 11:20:51     [tls] [INFO] - secured: cipher=ECDHE-RSA-AES256-GCM-SHA384 version=TLSv1/SSLv3 verified=false
    2018-04-23 11:20:51     [core] [INFO] - hook=unrecognized_command plugin=tls function=upgrade_connection params=STARTTLS retval=OK msg=""
    2018-04-23 11:20:51     EHLO ON34C02159586VX
    2018-04-23 11:20:51     [core] [DEBUG] - running ehlo hooks
    2018-04-23 11:20:51     [core] [DEBUG] - running ehlo hook in helo.checks plugin
    2018-04-23 11:20:51     [core] [DEBUG] - hook=ehlo plugin=helo.checks function=proto_mismatch_esmtp params=ON34C02159586VX retval=CONT msg=""
    2018-04-23 11:20:51     [core] [DEBUG] - running ehlo hook in helo.checks plugin
    2018-04-23 11:20:51     [core] [DEBUG] - hook=ehlo plugin=helo.checks function=init params=ON34C02159586VX retval=CONT msg=""
    2018-04-23 11:20:51     [core] [DEBUG] - running ehlo hook in helo.checks plugin
    2018-04-23 11:20:51     [core] [DEBUG] - hook=ehlo plugin=helo.checks function=match_re params=ON34C02159586VX retval=CONT msg=""
    2018-04-23 11:20:51     [core] [DEBUG] - running ehlo hook in helo.checks plugin
    2018-04-23 11:20:51     [core] [DEBUG] - hook=ehlo plugin=helo.checks function=bare_ip params=ON34C02159586VX retval=CONT msg=""
    2018-04-23 11:20:51     [core] [DEBUG] - running ehlo hook in helo.checks plugin
    2018-04-23 11:20:51     [core] [DEBUG] - hook=ehlo plugin=helo.checks function=dynamic params=ON34C02159586VX retval=CONT msg=""
    2018-04-23 11:20:51     [core] [DEBUG] - running ehlo hook in helo.checks plugin
    2018-04-23 11:20:51     [core] [DEBUG] - hook=ehlo plugin=helo.checks function=big_company params=ON34C02159586VX retval=CONT msg=""
    2018-04-23 11:20:51     [core] [DEBUG] - running ehlo hook in helo.checks plugin
    2018-04-23 11:20:51     [core] [DEBUG] - hook=ehlo plugin=helo.checks function=literal_mismatch params=ON34C02159586VX retval=CONT msg=""
    2018-04-23 11:20:51     [core] [DEBUG] - running ehlo hook in helo.checks plugin
    2018-04-23 11:20:51     [core] [DEBUG] - hook=ehlo plugin=helo.checks function=valid_hostname params=ON34C02159586VX retval=CONT msg=""
    2018-04-23 11:20:51     [core] [DEBUG] - running ehlo hook in helo.checks plugin
    2018-04-23 11:20:51     [core] [DEBUG] - hook=ehlo plugin=helo.checks function=rdns_match params=ON34C02159586VX retval=CONT msg=""
    2018-04-23 11:20:51     [core] [DEBUG] - running ehlo hook in helo.checks plugin
    2018-04-23 11:20:51     [core] [DEBUG] - hook=ehlo plugin=helo.checks function=forward_dns params=ON34C02159586VX retval=CONT msg=""
    2018-04-23 11:20:51     [core] [DEBUG] - running ehlo hook in helo.checks plugin
    2018-04-23 11:20:51     [core] [DEBUG] - hook=ehlo plugin=helo.checks function=host_mismatch params=ON34C02159586VX retval=CONT msg=""
    2018-04-23 11:20:51     [core] [DEBUG] - running ehlo hook in helo.checks plugin
    2018-04-23 11:20:51     [helo.checks] [INFO] - helo_host: ON34C02159586VX, multi: true, pass:match_re, bare_ip, big_co(not), literal_mismatch, rdns_match(org_dom), host_mismatch, host_mismatch, fail:valid_hostname(no_dot), forward_dns(invalid_hostname), skip:dynamic(no dots)
    2018-04-23 11:20:51     [core] [DEBUG] - hook=ehlo plugin=helo.checks function=emit_log params=ON34C02159586VX retval=CONT msg=""
    2018-04-23 11:20:51     [core] [DEBUG] - running ehlo hook in spf plugin
    2018-04-23 11:20:51     [spf] [INFO] - identity=helo ip=142.140.83.191 domain="ON34C02159586VX" mfrom= result=None
    2018-04-23 11:20:51     [spf] [INFO] - scope: helo, result: None, domain: ON34C02159586VX
    2018-04-23 11:20:51     [core] [DEBUG] - hook=ehlo plugin=spf function=hook_ehlo params=ON34C02159586VX retval=CONT msg=""
    2018-04-23 11:20:51     [core] [DEBUG] - running ehlo hook in data.uribl plugin
    2018-04-23 11:20:51     [data.uribl] [DEBUG] - (helo) found 1 items for lookup
    2018-04-23 11:20:51     [data.uribl] [DEBUG] - (helo) checking: on34c02159586vx
    2018-04-23 11:20:51     [core] [DEBUG] - hook=ehlo plugin=data.uribl function=hook_ehlo params=ON34C02159586VX retval=CONT msg=""
    2018-04-23 11:20:51     [core] [DEBUG] - running ehlo hook in karma plugin
    2018-04-23 11:20:51     [karma] [DEBUG] - tarpit capped to: 4
    2018-04-23 11:20:51     [karma] [DEBUG] - tarpitting ehlo for 4s
    2018-04-23 11:20:55     [karma] [DEBUG] - tarpit ehlo end
    2018-04-23 11:20:55     [core] [DEBUG] - hook=ehlo plugin=karma function=hook_ehlo params=ON34C02159586VX retval=CONT msg=""
    2018-04-23 11:20:55     [core] [DEBUG] - running capabilities hooks
    2018-04-23 11:20:55     [core] [DEBUG] - running capabilities hook in tls plugin
    2018-04-23 11:20:55     [core] [DEBUG] - hook=capabilities plugin=tls function=advertise_starttls params="" retval=CONT msg=""
    2018-04-23 11:20:55     [core] [DEBUG] - running capabilities hook in auth/poste plugin
    2018-04-23 11:20:55     [core] [DEBUG] - hook=capabilities plugin=auth/poste function=hook_capabilities params="" retval=CONT msg=""
    2018-04-23 11:20:55     250-mx01.cyberteer.com Hello [142.140.83.191], Haraka is at your service.
    2018-04-23 11:20:55     250-PIPELINING
    2018-04-23 11:20:55     250-8BITMIME
    2018-04-23 11:20:55     250-SMTPUTF8
    2018-04-23 11:20:55     250-SIZE 0
    2018-04-23 11:20:55     250 AUTH PLAIN LOGIN
    2018-04-23 11:20:55     AUTH PLAIN AHRtcHVzckBjeWJlcnRlZXIuY29tAHRlc3R0ZXN0
    2018-04-23 11:20:55     [core] [DEBUG] - running unrecognized_command hooks
    2018-04-23 11:20:55     [core] [DEBUG] - running unrecognized_command hook in tls plugin
    2018-04-23 11:20:55     [core] [DEBUG] - hook=unrecognized_command plugin=tls function=upgrade_connection params=AUTH retval=CONT msg=""
    2018-04-23 11:20:55     [core] [DEBUG] - running unrecognized_command hook in auth/poste plugin
    2018-04-23 11:20:55     [auth/poste] [INFO] - passwords match
    2018-04-23 11:20:55     235 Authentication successful
    2018-04-23 11:20:55     [core] [INFO] - hook=unrecognized_command plugin=auth/poste function=hook_unrecognized_command params=AUTH retval=OK msg=""
    2018-04-23 11:20:55     MAIL FROM:<tmpusr@cyberteer.com>
    2018-04-23 11:20:55     [core] [TX] - A183D908-33EB-4D75-A215-4C4D2F355331.1 (current tx)
     Transaction A183D908-33EB-4D75-A215-4C4D2F355331.1
    2018-04-23 11:20:55     [core] [DEBUG] - running mail hooks
    2018-04-23 11:20:55     [core] [DEBUG] - running mail hook in limits plugin
    2018-04-23 11:20:55     [limits] [DEBUG] - Box limit: null
    2018-04-23 11:20:55     [limits] [DEBUG] - Domain limit: null
    2018-04-23 11:20:55     [core] [DEBUG] - hook=mail plugin=limits function=hook_mail params=<tmpusr@cyberteer.com> retval=CONT msg=""
    2018-04-23 11:20:55     [core] [DEBUG] - running mail hook in mail_from.is_resolvable plugin
    2018-04-23 11:20:55     [mail_from.is_resolvable] [DEBUG] - cyberteer.com: MX 0 mail.cyberteer.com => 66.70.213.65
    2018-04-23 11:20:55     [mail_from.is_resolvable] [DEBUG] - cyberteer.com: 66.70.213.65
    2018-04-23 11:20:55     [core] [DEBUG] - hook=mail plugin=mail_from.is_resolvable function=hook_mail params=<tmpusr@cyberteer.com> retval=CONT msg=""
    2018-04-23 11:20:55     [core] [DEBUG] - running mail hook in spf plugin
    2018-04-23 11:20:55     [spf] [INFO] - identity=mfrom ip=142.140.83.191 domain="cyberteer.com" mfrom=<tmpusr@cyberteer.com> result=Fail
    2018-04-23 11:20:55     [spf] [INFO] - scope: mfrom, result: Fail, domain: cyberteer.com
    2018-04-23 11:20:55     [core] [DEBUG] - hook=mail plugin=spf function=hook_mail params=<tmpusr@cyberteer.com> retval=CONT msg=""
    2018-04-23 11:20:55     [core] [DEBUG] - running mail hook in data.uribl plugin
    2018-04-23 11:20:55     [data.uribl] [DEBUG] - (envfrom) found 1 items for lookup
    2018-04-23 11:20:55     [data.uribl] [DEBUG] - (envfrom) checking: cyberteer.com
    2018-04-23 11:20:55     [data.uribl] [DEBUG] - cyberteer.com.dbl.spamhaus.org. => (Error: queryA ENOTFOUND cyberteer.com.dbl.spamhaus.org.)
    2018-04-23 11:20:55     [core] [DEBUG] - hook=mail plugin=data.uribl function=hook_mail params=<tmpusr@cyberteer.com> retval=CONT msg=""
    2018-04-23 11:20:55     [core] [DEBUG] - running mail hook in known-senders plugin
    2018-04-23 11:20:55     [core] [DEBUG] - hook=mail plugin=known-senders function=is_authenticated params=<tmpusr@cyberteer.com> retval=CONT msg=""
    2018-04-23 11:20:55     [core] [DEBUG] - running mail hook in bounce plugin
    2018-04-23 11:20:55     [core] [DEBUG] - hook=mail plugin=bounce function=reject_all params=<tmpusr@cyberteer.com> retval=CONT msg=""
    2018-04-23 11:20:55     [core] [DEBUG] - running mail hook in log plugin
    2018-04-23 11:20:55     MAIL FROM:<tmpusr@cyberteer.com>
    2018-04-23 11:20:55     [core] [DEBUG] - hook=mail plugin=log function=hook_mail params=<tmpusr@cyberteer.com> retval=CONT msg=""
    2018-04-23 11:20:55     [core] [DEBUG] - running mail hook in dovecot_quota plugin
    2018-04-23 11:20:55     [core] [DEBUG] - hook=mail plugin=dovecot_quota function=hook_mail params=<tmpusr@cyberteer.com> retval=CONT msg=""
    2018-04-23 11:20:55     [core] [DEBUG] - running mail hook in karma plugin
    2018-04-23 11:20:55     [karma] [DEBUG] - applied relaying:6
    2018-04-23 11:20:55     [core] [DEBUG] - hook=mail plugin=karma function=hook_mail params=<tmpusr@cyberteer.com> retval=CONT msg=""
    2018-04-23 11:20:55     [core] [NOTICE] - sender <tmpusr@cyberteer.com> code=CONT msg=""
    2018-04-23 11:20:55     250 sender <tmpusr@cyberteer.com> OK
    2018-04-23 11:20:55     RCPT TO:<sco.macd@gmail.com>
    2018-04-23 11:20:55     [core] [DEBUG] - running rcpt hooks
    2018-04-23 11:20:55     [core] [DEBUG] - running rcpt hook in srs plugin
    2018-04-23 11:20:55     [core] [DEBUG] - hook=rcpt plugin=srs function=hook_rcpt params=<sco.macd@gmail.com> retval=CONT msg=""
    2018-04-23 11:20:55     [core] [DEBUG] - running rcpt hook in rcpt_database plugin
    2018-04-23 11:20:55     [rcpt_database] [DEBUG] - remote delivery domain <gmail.com> (relaying)
    2018-04-23 11:20:55     [core] [INFO] - hook=rcpt plugin=rcpt_database function=database_rcpt params=<sco.macd@gmail.com> retval=OK msg=""
    2018-04-23 11:20:55     [core] [DEBUG] - running rcpt_ok hooks
    2018-04-23 11:20:55     [core] [DEBUG] - running rcpt_ok hook in known-senders plugin
    2018-04-23 11:20:55     [core] [DEBUG] - hook=rcpt_ok plugin=known-senders function=check_recipient params="" retval=CONT msg=""
    2018-04-23 11:20:55     [core] [DEBUG] - running rcpt_ok hook in karma plugin
    2018-04-23 11:20:55     [core] [DEBUG] - hook=rcpt_ok plugin=karma function=hook_rcpt_ok params="" retval=CONT msg=""
    2018-04-23 11:20:55     [core] [NOTICE] - recipient <sco.macd@gmail.com> code=OK msg="" sender=tmpusr@cyberteer.com
    2018-04-23 11:20:55     250 recipient <sco.macd@gmail.com> OK
    2018-04-23 11:20:55     DATA
    2018-04-23 11:20:55     [core] [DEBUG] - running data hooks
    2018-04-23 11:20:55     [core] [DEBUG] - running data hook in early_talker plugin
    2018-04-23 11:20:55     [core] [DEBUG] - hook=data plugin=early_talker function=early_talker params="" retval=CONT msg=""
    2018-04-23 11:20:55     [core] [DEBUG] - running data hook in data.uribl plugin
    2018-04-23 11:20:55     [core] [DEBUG] - hook=data plugin=data.uribl function=hook_data params="" retval=CONT msg=""
    2018-04-23 11:20:55     [core] [DEBUG] - running data hook in bounce plugin
    2018-04-23 11:20:55     [core] [DEBUG] - hook=data plugin=bounce function=single_recipient params="" retval=CONT msg=""
    2018-04-23 11:20:55     [core] [DEBUG] - running data hook in bounce plugin
    2018-04-23 11:20:55     [core] [DEBUG] - hook=data plugin=bounce function=bad_rcpt params="" retval=CONT msg=""
    2018-04-23 11:20:55     [core] [DEBUG] - running data hook in bounce plugin
    2018-04-23 11:20:55     [core] [DEBUG] - hook=data plugin=bounce function=bounce_spf_enable params="" retval=CONT msg=""
    2018-04-23 11:20:55     [core] [DEBUG] - running data hook in attachment plugin
    2018-04-23 11:20:55     [core] [DEBUG] - hook=data plugin=attachment function=hook_data params="" retval=CONT msg=""
    2018-04-23 11:20:55     [core] [DEBUG] - running data hook in clamd plugin
    2018-04-23 11:20:55     [core] [DEBUG] - hook=data plugin=clamd function=hook_data params="" retval=CONT msg=""
    2018-04-23 11:20:55     [core] [DEBUG] - running data hook in karma plugin
    2018-04-23 11:20:55     [core] [DEBUG] - hook=data plugin=karma function=hook_data params="" retval=CONT msg=""
    2018-04-23 11:20:55     354 go ahead, make my day
    2018-04-23 11:20:56     [core] [DEBUG] - running data_post hooks
    2018-04-23 11:20:56     [core] [DEBUG] - running data_post hook in srs plugin
    2018-04-23 11:20:56     [srs] [DEBUG] - Remote host (142.140.83.191), skipping
    2018-04-23 11:20:56     [core] [DEBUG] - hook=data_post plugin=srs function=hook_data_post params="" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - running data_post hook in asn plugin
    2018-04-23 11:20:56     [core] [DEBUG] - hook=data_post plugin=asn function=add_header_asn params="" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - running data_post hook in p0f plugin
    2018-04-23 11:20:56     [p0f] [DEBUG] - header disabled in ini
    2018-04-23 11:20:56     [core] [DEBUG] - hook=data_post plugin=p0f function=hook_data_post params="" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - running data_post hook in data.uribl plugin
    2018-04-23 11:20:56     [data.uribl] [DEBUG] - (from) found 1 items for lookup
    2018-04-23 11:20:56     [data.uribl] [DEBUG] - (from) checking: cyberteer.com
    2018-04-23 11:20:56     [data.uribl] [DEBUG] - cyberteer.com.dbl.spamhaus.org. => (Error: queryA ENOTFOUND cyberteer.com.dbl.spamhaus.org.)
    2018-04-23 11:20:56     [data.uribl] [DEBUG] - (msgid) found 1 items for lookup
    2018-04-23 11:20:56     [data.uribl] [DEBUG] - (msgid) checking: cyberteer.com
    2018-04-23 11:20:56     [data.uribl] [DEBUG] - cyberteer.com.dbl.spamhaus.org. => (Error: queryA ENOTFOUND cyberteer.com.dbl.spamhaus.org.)
    2018-04-23 11:20:56     [data.uribl] [DEBUG] - (body) found 1 items for lookup
    2018-04-23 11:20:56     [data.uribl] [DEBUG] - (body) checking: cyberteer.com
    2018-04-23 11:20:56     [data.uribl] [DEBUG] - cyberteer.com.dbl.spamhaus.org. => (Error: queryA ENOTFOUND cyberteer.com.dbl.spamhaus.org.)
    2018-04-23 11:20:56     [data.uribl] [DEBUG] - cyberteer.com.multi.surbl.org. => (Error: queryA ENOTFOUND cyberteer.com.multi.surbl.org.)
    2018-04-23 11:20:56     [data.uribl] [DEBUG] - cyberteer.com.multi.uribl.com. => (Error: queryA ENOTFOUND cyberteer.com.multi.uribl.com.)
    2018-04-23 11:20:56     [core] [DEBUG] - hook=data_post plugin=data.uribl function=hook_data_post params="" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - running data_post hook in data.headers plugin
    2018-04-23 11:20:56     [core] [DEBUG] - hook=data_post plugin=data.headers function=duplicate_singular params="" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - running data_post hook in data.headers plugin
    2018-04-23 11:20:56     [core] [DEBUG] - hook=data_post plugin=data.headers function=missing_required params="" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - running data_post hook in data.headers plugin
    2018-04-23 11:20:56     [data.headers] [DEBUG] - message date: Mon, 23 Apr 2018 11:20:39 -0400
    2018-04-23 11:20:56     [core] [DEBUG] - hook=data_post plugin=data.headers function=invalid_date params="" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - running data_post hook in data.headers plugin
    2018-04-23 11:20:56     [core] [DEBUG] - hook=data_post plugin=data.headers function=invalid_return_path params="" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - running data_post hook in data.headers plugin
    2018-04-23 11:20:56     [core] [DEBUG] - hook=data_post plugin=data.headers function=user_agent params="" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - running data_post hook in data.headers plugin
    2018-04-23 11:20:56     [core] [DEBUG] - hook=data_post plugin=data.headers function=direct_to_mx params="" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - running data_post hook in data.headers plugin
    2018-04-23 11:20:56     [core] [DEBUG] - hook=data_post plugin=data.headers function=from_match params="" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - running data_post hook in data.headers plugin
    2018-04-23 11:20:56     [core] [DEBUG] - hook=data_post plugin=data.headers function=delivered_to params="" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - running data_post hook in data.headers plugin
    2018-04-23 11:20:56     [core] [DEBUG] - hook=data_post plugin=data.headers function=mailing_list params="" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - running data_post hook in known-senders plugin
    2018-04-23 11:20:56     [core] [DEBUG] - hook=data_post plugin=known-senders function=is_dkim_authenticated params="" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - running data_post hook in bounce plugin
    2018-04-23 11:20:56     [core] [DEBUG] - hook=data_post plugin=bounce function=empty_return_path params="" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - running data_post hook in bounce plugin
    2018-04-23 11:20:56     [core] [DEBUG] - hook=data_post plugin=bounce function=bounce_spf params="" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - running data_post hook in bounce plugin
    2018-04-23 11:20:56     [core] [DEBUG] - hook=data_post plugin=bounce function=non_local_msgid params="" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - running data_post hook in dovecot_quota plugin
    2018-04-23 11:20:56     [dovecot_quota] [DEBUG] - domain 'gmail.com' is remote
    2018-04-23 11:20:56     [core] [DEBUG] - hook=data_post plugin=dovecot_quota function=hook_data_post params="" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - running data_post hook in rspamd plugin
    2018-04-23 11:20:56     [core] [DEBUG] - hook=data_post plugin=rspamd function=hook_data_post params="" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - running data_post hook in attachment plugin
    2018-04-23 11:20:56     [core] [DEBUG] - hook=data_post plugin=attachment function=wait_for_attachment_hooks params="" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - running data_post hook in attachment plugin
    2018-04-23 11:20:56     [attachment] [DEBUG] - found content type: text/plain
    2018-04-23 11:20:56     [core] [DEBUG] - hook=data_post plugin=attachment function=check_attachments params="" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - running data_post hook in clamd plugin
    2018-04-23 11:20:56     [clamd] [DEBUG] - trying host: /var/run/clamav/clamd.ctl
    2018-04-23 11:20:56     [clamd] [DEBUG] - connected to host undefined:undefined
    2018-04-23 11:20:56     [clamd] [PROTOCOL] - C:stream: OK
    2018-04-23 11:20:56     [clamd] [INFO] - pass:clean
    2018-04-23 11:20:56     [core] [DEBUG] - hook=data_post plugin=clamd function=hook_data_post params="" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - running data_post hook in karma plugin
    2018-04-23 11:20:56     [karma] [DEBUG] - adding header: score: 4, awards: 086,130,133,162,150,182, pass:relaying
    2018-04-23 11:20:56     [core] [DEBUG] - hook=data_post plugin=karma function=hook_data_post params="" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - running data_post hook in queue/quarantine-decide plugin
    2018-04-23 11:20:56     [core] [INFO] - hook=data_post plugin=queue/quarantine-decide function=hook_data_post params="" retval=OK msg=""
    2018-04-23 11:20:56     [core] [NOTICE] - message mid=<20180423112039.e3cfc2d802af98ea7b239d34@cyberteer.com> size=405 rcpts=1/0/0 delay=0.457 code=OK msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - running queue_outbound hooks
    2018-04-23 11:20:56     [core] [DEBUG] - running queue_outbound hook in dkim_sign plugin
    2018-04-23 11:20:56     [core] [CRIT] - Plugin queue/quarantine-decide failed: TypeError: Cannot read property 'score' of undefined
    2018-04-23 11:20:56     [core] [ERROR] - queue/quarantine-decide plugin ran callback multiple times - ignoring subsequent calls
    2018-04-23 11:20:56     [core] [ERROR] - Error
    2018-04-23 11:20:56     [dkim_sign] [DEBUG] - undefined
    2018-04-23 11:20:56     [dkim_sign] [ERROR] - skipped: missing dkim private key
    2018-04-23 11:20:56     [core] [DEBUG] - hook=queue_outbound plugin=dkim_sign function=hook_queue_outbound params="" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - running queue_outbound hook in dkim_sign plugin
    2018-04-23 11:20:56     [dkim_sign] [DEBUG] - undefined
    2018-04-23 11:20:56     [dkim_sign] [ERROR] - skipped: missing dkim private key
    2018-04-23 11:20:56     [core] [DEBUG] - hook=queue_outbound plugin=dkim_sign function=hook_queue_outbound params="" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - running queue_outbound hook in queue/quarantine plugin
    2018-04-23 11:20:56     [core] [DEBUG] - hook=queue_outbound plugin=queue/quarantine function=quarantine params="" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [NOTICE] - queue code=CONT msg=" (A183D908-33EB-4D75-A215-4C4D2F355331.1)"
    2018-04-23 11:20:56     [core] [DEBUG] - running queue_ok hooks
    2018-04-23 11:20:56     [core] [DEBUG] - running queue_ok hook in limits plugin
    2018-04-23 11:20:56     [core] [DEBUG] - hook=queue_ok plugin=limits function=hook_queue_ok params="Message Queued (A183D908-33EB-4D75-A215-4C4D2F355331.1)" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - running queue_ok hook in stats plugin
    2018-04-23 11:20:56     [core] [DEBUG] - hook=queue_ok plugin=stats function=hook_queue_ok params="Message Queued (A183D908-33EB-4D75-A215-4C4D2F355331.1)" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - running queue_ok hook in known-senders plugin
    2018-04-23 11:20:56     [known-senders] [INFO] - saved cyberteer.com : gmail.com : 1
    2018-04-23 11:20:56     [core] [DEBUG] - hook=queue_ok plugin=known-senders function=update_sender params="Message Queued (A183D908-33EB-4D75-A215-4C4D2F355331.1)" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - running queue_ok hook in queue/quarantine-decide plugin
    2018-04-23 11:20:56     [core] [DEBUG] - hook=queue_ok plugin=queue/quarantine-decide function=hook_queue_ok params="Message Queued (A183D908-33EB-4D75-A215-4C4D2F355331.1)" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - running queue_ok hook in watch plugin
    2018-04-23 11:20:56     [core] [DEBUG] - hook=queue_ok plugin=watch function=queue_ok params="Message Queued (A183D908-33EB-4D75-A215-4C4D2F355331.1)" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [NOTICE] - queue code=OK msg="Message Queued (A183D908-33EB-4D75-A215-4C4D2F355331.1)"
    2018-04-23 11:20:56     250 Message Queued (A183D908-33EB-4D75-A215-4C4D2F355331.1)
    2018-04-23 11:20:56     [core] [DEBUG] - running reset_transaction hooks
    2018-04-23 11:20:56     [core] [DEBUG] - running reset_transaction hook in stats plugin
    2018-04-23 11:20:56     [core] [DEBUG] - hook=reset_transaction plugin=stats function=hook_reset_transaction params="" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - running reset_transaction hook in log plugin
    2018-04-23 11:20:56     [core] [DEBUG] - hook=reset_transaction plugin=log function=hook_reset_transaction params="" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - running reset_transaction hook in karma plugin
    2018-04-23 11:20:56     [karma] [INFO] - score: 4, awards: 086,130,133,162,150,182, pass:relaying
    2018-04-23 11:20:56     [core] [DEBUG] - hook=reset_transaction plugin=karma function=hook_reset_transaction params="" retval=CONT msg=""
    2018-04-23 11:20:56     QUIT
    2018-04-23 11:20:56     [core] [DEBUG] - running quit hooks
    2018-04-23 11:20:56     221 mx01.cyberteer.com closing connection. Have a jolly good day.
    2018-04-23 11:20:56     [core] [DEBUG] - client has disconnected
    2018-04-23 11:20:56     [core] [DEBUG] - running disconnect hooks
    2018-04-23 11:20:56     [core] [DEBUG] - client has disconnected
    2018-04-23 11:20:56     [core] [DEBUG] - running disconnect hook in stats plugin
    2018-04-23 11:20:56     [core] [DEBUG] - client has disconnected
    2018-04-23 11:20:56     [core] [DEBUG] - hook=disconnect plugin=stats function=hook_disconnect params="" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - client has disconnected
    2018-04-23 11:20:56     [core] [DEBUG] - running disconnect hook in guard/block_bad_connections plugin
    2018-04-23 11:20:56     [core] [DEBUG] - client has disconnected
    2018-04-23 11:20:56     [core] [DEBUG] - hook=disconnect plugin=guard/block_bad_connections function=hook_disconnect params="" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - client has disconnected
    2018-04-23 11:20:56     [core] [DEBUG] - running disconnect hook in log plugin
    2018-04-23 11:20:56     [core] [DEBUG] - client has disconnected
    2018-04-23 11:20:56     [core] [DEBUG] - hook=disconnect plugin=log function=hook_disconnect params="" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - client has disconnected
    2018-04-23 11:20:56     [core] [DEBUG] - running disconnect hook in tls plugin
    2018-04-23 11:20:56     [core] [DEBUG] - client has disconnected
    2018-04-23 11:20:56     [core] [DEBUG] - hook=disconnect plugin=tls function=hook_disconnect params="" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [DEBUG] - client has disconnected
    2018-04-23 11:20:56     [core] [DEBUG] - running disconnect hook in karma plugin
    2018-04-23 11:20:56     [karma] [INFO] - score: 4, awards: 086,130,133,162,150,182, pass:relaying
    2018-04-23 11:20:56     [core] [DEBUG] - client has disconnected
    2018-04-23 11:20:56     [core] [DEBUG] - hook=disconnect plugin=karma function=hook_disconnect params="" retval=CONT msg=""
    2018-04-23 11:20:56     [core] [NOTICE] - disconnect ip=142.140.83.191 rdns=NXDOMAIN helo=ON34C02159586VX relay=Y early=N esmtp=Y tls=Y pipe=N errors=0 txns=1 rcpts=1/0/0 msgs=1/0/0 bytes=405 lr="" time=19.129
    2018-04-23 11:20:56     [karma] [DEBUG] - unsubsubscribed from result-A183D908-33EB-4D75-A215-4C4D2F355331*
     Delivery of A183D908-33EB-4D75-A215-4C4D2F355331.1
    2018-04-23 11:20:56     [outbound] [DEBUG] - running send_email hooks
    2018-04-23 11:20:56     [outbound] [DEBUG] - Sending mail: 1524496856575_1524496856575_0_882_etga5Z_1_8d84972aeade
    2018-04-23 11:20:56     [outbound] [DEBUG] - running get_mx hooks
    2018-04-23 11:20:56     [outbound] [DEBUG] - running get_mx hook in relay plugin
    2018-04-23 11:20:56     [outbound] [DEBUG] - hook=get_mx plugin=relay function=force_routing params=gmail.com retval=CONT msg=""
    2018-04-23 11:20:56     [outbound] [DEBUG] - running get_mx hook in rcpt_database plugin
    2018-04-23 11:20:56     [outbound] [DEBUG] - hook=get_mx plugin=rcpt_database function=hook_get_mx params=gmail.com retval=CONT msg=""
    2018-04-23 11:20:56     [outbound] [DEBUG] - running get_mx hook in queue/lmtp plugin
    2018-04-23 11:20:56     [outbound] [DEBUG] - hook=get_mx plugin=queue/lmtp function=hook_get_mx params=gmail.com retval=CONT msg=""
    2018-04-23 11:20:56     [outbound] [INFO] - Looking up A records for: gmail-smtp-in.l.google.com
    2018-04-23 11:20:56     [outbound] [INFO] - Attempting to deliver to: 74.125.202.26:25 (0) (0)
    2018-04-23 11:20:56     220 mx.google.com ESMTP m44-v6si6789835iti.64 - gsmtp\r\n
    2018-04-23 11:20:56     EHLO mx01.cyberteer.com
    2018-04-23 11:20:56     250-mx.google.com at your service, [138.197.161.42]\r\n
    2018-04-23 11:20:56     250-SIZE 157286400\r\n
    2018-04-23 11:20:56     250-8BITMIME\r\n
    2018-04-23 11:20:56     250-STARTTLS\r\n
    2018-04-23 11:20:56     250-ENHANCEDSTATUSCODES\r\n
    2018-04-23 11:20:56     250-PIPELINING\r\n
    2018-04-23 11:20:56     250-CHUNKING\r\n
    2018-04-23 11:20:56     250 SMTPUTF8\r\n
    2018-04-23 11:20:56     STARTTLS
    2018-04-23 11:20:56     220 2.0.0 Ready to start TLS\r\n
    2018-04-23 11:20:56     [outbound] [INFO] - secured verified=true cipher=ECDHE-RSA-AES128-GCM-SHA256 version=TLSv1/SSLv3 cn=mx.google.com organization="Google Inc" issuer="Google Trust Services" expires="Jun 20 13:25:00 2018 GMT" fingerprint=E6:93:AF:2C:53:75:70:CF:D7:17:97:35:AD:8F:3A:72:8A:BD:B9:C0
    2018-04-23 11:20:56     EHLO mx01.cyberteer.com
    2018-04-23 11:20:56     250-mx.google.com at your service, [138.197.161.42]\r\n
    2018-04-23 11:20:56     250-SIZE 157286400\r\n
    2018-04-23 11:20:56     250-8BITMIME\r\n
    2018-04-23 11:20:56     250-ENHANCEDSTATUSCODES\r\n
    2018-04-23 11:20:56     250-PIPELINING\r\n
    2018-04-23 11:20:56     250-CHUNKING\r\n
    2018-04-23 11:20:56     250 SMTPUTF8\r\n
    2018-04-23 11:20:56     MAIL FROM:<tmpusr@cyberteer.com>
    2018-04-23 11:20:56     250 2.1.0 OK m44-v6si6789835iti.64 - gsmtp\r\n
    2018-04-23 11:20:56     RCPT TO:<sco.macd@gmail.com>
    2018-04-23 11:20:57     250 2.1.5 OK m44-v6si6789835iti.64 - gsmtp\r\n
    2018-04-23 11:20:57     DATA
    2018-04-23 11:20:57     354 Go ahead m44-v6si6789835iti.64 - gsmtp\r\n
    2018-04-23 11:20:57     .
    2018-04-23 11:20:57     250 2.0.0 OK 1524496857 m44-v6si6789835iti.64 - gsmtp\r\n
    2018-04-23 11:20:57     [outbound] [NOTICE] - delivered file=1524496856575_1524496856575_0_882_etga5Z_1_8d84972aeade domain=gmail.com host=gmail-smtp-in.l.google.com ip=74.125.202.26 port=25 mode=SMTP tls=Y auth=N response="OK 1524496857 m44-v6si6789835iti.64 - gsmtp" delay=0.694 fails=0 rcpts=1/0/0
    2018-04-23 11:20:57     [outbound] [DEBUG] - running delivered hooks
    2018-04-23 11:20:57     [outbound] [DEBUG] - running delivered hook in stats plugin
    2018-04-23 11:20:57     RSET
    2018-04-23 11:20:57     [outbound] [DEBUG] - hook=delivered plugin=stats function=hook_delivered params=gmail-smtp-in.l.google.com retval=CONT msg=""
    2018-04-23 11:20:57     250 2.1.5 Flushed m44-v6si6789835iti.64 - gsmtp\r\n
    
  3. Roshan Jonah reporter

    Same thing. I have tried emailing remote hosts (Gmail) and it doesn't use the smart host configured. It uses local IP to deliver emails.

    I am also getting a 500 Internal Server Error trying to retrieve logs through administration panel.

  4. Scott MacDonald

    I've tested Poste.io FREE 2.0.1 (latest image) and the Default SMTP Route is now working perfectly. When will the corresponding PRO image update be made available??

  5. Log in to comment