search

haraka / Haraka

A fast, highly extensible, and event driven SMTP server
https://haraka.github.io
MIT License
5.02k stars 662 forks source link

'From' header is missing. #1912

Closed davejcowen closed 7 years ago

davejcowen commented 7 years ago

I'm at a loss and would greatly appreciate any help. Full logs are below and I keep getting 'From' header missing even though it starts at the send mail with the 'From' data.

loaded TLD files: 1=1528 2=6067 3=2322 loaded 8066 Public Suffixes loglevel: LOGPROTOCOL Starting up Haraka version 2.8.13 [INFO] [-] [core] Loading plugins [INFO] [-] [core] Loading plugin: access [DEBUG] [-] [core] no timeout in access.timeout [DEBUG] [-] [core] no timeout in plugin_timeout [DEBUG] [-] [core] plugin access timeout is: 30s [INFO] [-] [access] skipping helo.checks.regexps [DEBUG] [-] [core] registered hook connect to access.rdns_access priority 0 [DEBUG] [-] [core] registered hook mail to access.mail_from_access priority 0 [DEBUG] [-] [core] registered hook rcpt to access.rcpt_to_access priority 0 [DEBUG] [-] [core] registered hook connect to access.any priority 0 [DEBUG] [-] [core] registered hook helo to access.any priority 0 [DEBUG] [-] [core] registered hook ehlo to access.any priority 0 [DEBUG] [-] [core] registered hook mail to access.any priority 0 [DEBUG] [-] [core] registered hook rcpt to access.any priority 0 [DEBUG] [-] [core] registered hook data_post to access.data_any priority 0 [INFO] [-] [core] Loading plugin: dnsbl [DEBUG] [-] [core] no timeout in dnsbl.timeout [DEBUG] [-] [core] no timeout in plugin_timeout [DEBUG] [-] [core] plugin dnsbl timeout is: 30s [DEBUG] [-] [core] no timeout in dns_list_base.timeout [DEBUG] [-] [core] no timeout in plugin_timeout [DEBUG] [-] [core] plugin dns_list_base timeout is: 30s [DEBUG] [-] [core] Returning boolean true for main.reject=true [DEBUG] [-] [dnsbl] looking up: 1.0.0.127.zen.spamhaus.org. [DEBUG] [-] [dnsbl] will re-test list zones every 30 minutes [DEBUG] [-] [core] registered hook connect to dnsbl.connect_first priority 0 [INFO] [-] [core] Loading plugin: helo.checks [DEBUG] [-] [core] no timeout in helo.checks.timeout [DEBUG] [-] [core] no timeout in plugin_timeout [DEBUG] [-] [core] plugin helo.checks timeout is: 30s [DEBUG] [-] [core] Returning boolean true for check.match_re=true [DEBUG] [-] [core] Returning boolean true for check.bare_ip=true [DEBUG] [-] [core] Returning boolean true for check.dynamic=true [DEBUG] [-] [core] Returning boolean true for check.big_company=true [DEBUG] [-] [core] Returning boolean true for check.valid_hostname=true [DEBUG] [-] [core] Returning boolean true for check.forward_dns=true [DEBUG] [-] [core] Returning boolean true for check.rdns_match=true [DEBUG] [-] [core] Returning boolean true for check.mismatch=true [DEBUG] [-] [core] Returning boolean true for check.proto_mismatch=true [DEBUG] [-] [core] Returning boolean true for reject.mismatch=true [DEBUG] [-] [core] Returning boolean false for reject.proto_mismatch=false [DEBUG] [-] [core] Returning boolean false for reject.rdns_match=false [DEBUG] [-] [core] Returning boolean false for reject.dynamic=false [DEBUG] [-] [core] Returning boolean false for reject.bare_ip=false [DEBUG] [-] [core] Returning boolean false for reject.literal_mismatch=false [DEBUG] [-] [core] Returning boolean false for reject.valid_hostname=false [DEBUG] [-] [core] Returning boolean false for reject.forward_dns=false [DEBUG] [-] [core] Returning boolean true for reject.big_company=true [DEBUG] [-] [core] Returning boolean true for skip.private_ip=true [DEBUG] [-] [core] Returning boolean true for skip.relaying=true [DEBUG] [-] [core] registered hook helo to helo.checks.proto_mismatch_smtp priority 0 [DEBUG] [-] [core] registered hook ehlo to helo.checks.proto_mismatch_esmtp priority 0 [DEBUG] [-] [core] registered hook helo to helo.checks.init priority 0 [DEBUG] [-] [core] registered hook ehlo to helo.checks.init priority 0 [DEBUG] [-] [core] registered hook helo to helo.checks.match_re priority 0 [DEBUG] [-] [core] registered hook ehlo to helo.checks.match_re priority 0 [DEBUG] [-] [core] registered hook helo to helo.checks.bare_ip priority 0 [DEBUG] [-] [core] registered hook ehlo to helo.checks.bare_ip priority 0 [DEBUG] [-] [core] registered hook helo to helo.checks.dynamic priority 0 [DEBUG] [-] [core] registered hook ehlo to helo.checks.dynamic priority 0 [DEBUG] [-] [core] registered hook helo to helo.checks.big_company priority 0 [DEBUG] [-] [core] registered hook ehlo to helo.checks.big_company priority 0 [DEBUG] [-] [core] registered hook helo to helo.checks.literal_mismatch priority 0 [DEBUG] [-] [core] registered hook ehlo to helo.checks.literal_mismatch priority 0 [DEBUG] [-] [core] registered hook helo to helo.checks.valid_hostname priority 0 [DEBUG] [-] [core] registered hook ehlo to helo.checks.valid_hostname priority 0 [DEBUG] [-] [core] registered hook helo to helo.checks.rdns_match priority 0 [DEBUG] [-] [core] registered hook ehlo to helo.checks.rdns_match priority 0 [DEBUG] [-] [core] registered hook helo to helo.checks.forward_dns priority 0 [DEBUG] [-] [core] registered hook ehlo to helo.checks.forward_dns priority 0 [INFO] [-] [core] Loading plugin: auth/flat_file [DEBUG] [-] [core] no timeout in auth/flat_file.timeout [DEBUG] [-] [core] no timeout in plugin_timeout [DEBUG] [-] [core] plugin auth/flat_file timeout is: 30s [DEBUG] [-] [core] no timeout in auth/auth_base.timeout [DEBUG] [-] [core] no timeout in plugin_timeout [DEBUG] [-] [core] plugin auth/auth_base timeout is: 30s [DEBUG] [-] [core] registered hook capabilities to auth/flat_file.hook_capabilities priority 0 [DEBUG] [-] [core] registered hook unrecognized_command to auth/flat_file.hook_unrecognized_command priority 0 [INFO] [-] [core] Loading plugin: mail_from.is_resolvable [DEBUG] [-] [core] no timeout in mail_from.is_resolvable.timeout [DEBUG] [-] [core] no timeout in plugin_timeout [DEBUG] [-] [core] plugin mail_from.is_resolvable timeout is: 30s [DEBUG] [-] [core] Returning boolean false for main.allow_mx_ip=0 [DEBUG] [-] [core] Returning boolean true for main.reject_no_mx=1 [DEBUG] [-] [core] registered hook mail to mail_from.is_resolvable.hook_mail priority 0 [INFO] [-] [core] Loading plugin: rcpt_to.in_host_list [DEBUG] [-] [core] no timeout in rcpt_to.in_host_list.timeout [DEBUG] [-] [core] no timeout in plugin_timeout [DEBUG] [-] [core] plugin rcpt_to.in_host_list timeout is: 30s [DEBUG] [-] [core] no timeout in rcpt_to.host_list_base.timeout [DEBUG] [-] [core] no timeout in plugin_timeout [DEBUG] [-] [core] plugin rcpt_to.host_list_base timeout is: 30s [DEBUG] [-] [core] registered hook rcpt to rcpt_to.in_host_list.hook_rcpt priority 0 [DEBUG] [-] [core] registered hook mail to rcpt_to.in_host_list.hook_mail priority 0 [INFO] [-] [core] Loading plugin: data.headers [DEBUG] [-] [core] no timeout in data.headers.timeout [DEBUG] [-] [core] no timeout in plugin_timeout [DEBUG] [-] [core] plugin data.headers timeout is: 30s [DEBUG] [-] [core] registered hook data_post to data.headers.duplicate_singular priority 0 [DEBUG] [-] [core] registered hook data_post to data.headers.missing_required priority 0 [DEBUG] [-] [core] registered hook data_post to data.headers.invalid_date priority 0 [DEBUG] [-] [core] registered hook data_post to data.headers.invalid_return_path priority 0 [DEBUG] [-] [core] registered hook data_post to data.headers.user_agent priority 0 [DEBUG] [-] [core] registered hook data_post to data.headers.direct_to_mx priority 0 [DEBUG] [-] [core] registered hook data_post to data.headers.from_match priority 0 [DEBUG] [-] [core] registered hook data_post to data.headers.delivered_to priority 0 [DEBUG] [-] [core] registered hook data_post to data.headers.mailing_list priority 0 [INFO] [-] [core] Loading plugin: max_unrecognized_commands [DEBUG] [-] [core] no timeout in max_unrecognized_commands.timeout [DEBUG] [-] [core] no timeout in plugin_timeout [DEBUG] [-] [core] plugin max_unrecognized_commands timeout is: 30s [DEBUG] [-] [core] registered hook connect to max_unrecognized_commands.hook_connect priority 0 [DEBUG] [-] [core] registered hook unrecognized_command to max_unrecognized_commands.hook_unrecognized_command priority 0 [NOTICE] [-] [core] Listening on :::2525 [DEBUG] [-] [server] running init_master hooks [DEBUG] [-] [dnsbl] looking up: 2.0.0.127.zen.spamhaus.org. [NOTICE] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] connect ip=127.0.0.1 port=45008 local_ip=:: local_port=2525 [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running connect_init hooks [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running connect_init_respond [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running lookup_rdns hooks [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running connect hooks [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running connect hook in access plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [access] checking 127.0.0.1 against connect.rdns_access.whitelist [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [access] checking 127.0.0.1 against connect.rdns_access.whitelist_regex [DEBUG] [-] [access] empty file: connect.rdns_access.whitelist_regex [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [access] checking localhost.localdomain against connect.rdns_access.whitelist [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [access] checking localhost.localdomain against connect.rdns_access.whitelist_regex [DEBUG] [-] [access] empty file: connect.rdns_access.whitelist_regex [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [access] checking 127.0.0.1 against connect.rdns_access.blacklist_regex [DEBUG] [-] [access] empty file: connect.rdns_access.blacklist_regex [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [access] checking localhost.localdomain against connect.rdns_access.blacklist_regex [DEBUG] [-] [access] empty file: connect.rdns_access.blacklist_regex [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=connect plugin=access function=rdns_access params="" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running connect hook in access plugin [ERROR] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [access] no org domain from localhost.localdomain [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=connect plugin=access function=any params="" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running connect hook in dnsbl plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [dnsbl] skip private: 127.0.0.1 [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=connect plugin=dnsbl function=connect_first params="" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running connect hook in max_unrecognized_commands plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=connect plugin=max_unrecognized_commands function=hook_connect params="" retval=CONT msg="" [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] S: 220 localhost.localdomain ESMTP Haraka 2.8.13 ready [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] C: EHLO localhost.localdomain state=1 [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running ehlo hooks [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running ehlo hook in access plugin [ERROR] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [access] no org domain from localhost.localdomain [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=ehlo plugin=access function=any params="localhost.localdomain" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running ehlo hook in helo.checks plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=ehlo plugin=helo.checks function=proto_mismatch_esmtp params="localhost.localdomain" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running ehlo hook in helo.checks plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=ehlo plugin=helo.checks function=init params="localhost.localdomain" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running ehlo hook in helo.checks plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=ehlo plugin=helo.checks function=match_re params="localhost.localdomain" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running ehlo hook in helo.checks plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=ehlo plugin=helo.checks function=bare_ip params="localhost.localdomain" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running ehlo hook in helo.checks plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=ehlo plugin=helo.checks function=dynamic params="localhost.localdomain" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running ehlo hook in helo.checks plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=ehlo plugin=helo.checks function=big_company params="localhost.localdomain" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running ehlo hook in helo.checks plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=ehlo plugin=helo.checks function=literal_mismatch params="localhost.localdomain" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running ehlo hook in helo.checks plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=ehlo plugin=helo.checks function=valid_hostname params="localhost.localdomain" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running ehlo hook in helo.checks plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=ehlo plugin=helo.checks function=rdns_match params="localhost.localdomain" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running ehlo hook in helo.checks plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=ehlo plugin=helo.checks function=forward_dns params="localhost.localdomain" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running capabilities hooks [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running capabilities hook in auth/flat_file plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=capabilities plugin=auth/flat_file function=hook_capabilities params="" retval=CONT msg="" [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] S: 250-localhost.localdomain Hello localhost.localdomain [127.0.0.1], Haraka is at your service. [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] S: 250-PIPELINING [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] S: 250-8BITMIME [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] S: 250-SIZE 0 [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] S: 250 AUTH LOGIN CRAM-MD5 [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] C: AUTH LOGIN state=1 [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running unrecognized_command hooks [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running unrecognized_command hook in auth/flat_file plugin [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] S: 334 VXNlcm5hbWU6 [INFO] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=unrecognized_command plugin=auth/flat_file function=hook_unrecognized_command params="AUTH" retval=OK msg="" [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] C: YWRtaW4= state=1 [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running unrecognized_command hooks [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running unrecognized_command hook in auth/flat_file plugin [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] S: 334 UGFzc3dvcmQ6 [INFO] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=unrecognized_command plugin=auth/flat_file function=hook_unrecognized_command params="YWRtaW4=" retval=OK msg="" [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] C: MUhvbmV5d2VsbA== state=1 [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running unrecognized_command hooks [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running unrecognized_command hook in auth/flat_file plugin [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] S: 235 Authentication successful [INFO] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=unrecognized_command plugin=auth/flat_file function=hook_unrecognized_command params="MUhvbmV5d2VsbA==" retval=OK msg="" [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] C: MAIL FROM:admin@myrealflyer.com state=1 [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running mail hooks [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running mail hook in access plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [access] checking admin@myrealflyer.com against mail_from.access.whitelist [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [access] checking admin@myrealflyer.com against mail_from.access.whitelist_regex [DEBUG] [-] [access] empty file: mail_from.access.whitelist_regex [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [access] checking admin@myrealflyer.com against mail_from.access.blacklist_regex [DEBUG] [-] [access] empty file: mail_from.access.blacklist_regex [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=mail plugin=access function=mail_from_access params="admin@myrealflyer.com" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running mail hook in access plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=mail plugin=access function=any params="admin@myrealflyer.com" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running mail hook in mail_from.is_resolvable plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [mail_from.is_resolvable] myrealflyer.com: MX 1 aspmx.l.google.com => 173.194.196.27,2607:f8b0:4001:c16::1a [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [mail_from.is_resolvable] myrealflyer.com: MX 5 alt1.aspmx.l.google.com => 173.194.66.26,2607:f8b0:400d:c01::1a [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [mail_from.is_resolvable] myrealflyer.com: MX 5 alt2.aspx.l.google.com => queryA ENOTFOUND alt2.aspx.l.google.com [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [mail_from.is_resolvable] myrealflyer.com: MX 10 aspmx2.googlemail.com => 173.194.66.27,2607:f8b0:400d:c01::1a [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [mail_from.is_resolvable] myrealflyer.com: 173.194.196.27,2607:f8b0:4001:c16::1a,173.194.66.26,2607:f8b0:400d:c01::1a,173.194.66.27 [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=mail plugin=mail_from.is_resolvable function=hook_mail params="admin@myrealflyer.com" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running mail hook in rcpt_to.in_host_list plugin [DEBUG] [-] [rcpt_to.in_host_list] checking myrealflyer.com in config/host_list [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=mail plugin=rcpt_to.in_host_list function=hook_mail params="admin@myrealflyer.com" retval=CONT msg="" [NOTICE] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] sender admin@myrealflyer.com code=CONT msg="" [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] S: 250 sender admin@myrealflyer.com OK [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] C: RCPT TO:dave@kdcowen.com state=1 [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running rcpt hooks [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running rcpt hook in access plugin [DEBUG] [-] [access] empty file: rcpt_to.access.whitelist_regex [DEBUG] [-] [access] empty file: rcpt_to.access.blacklist_regex [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=rcpt plugin=access function=rcpt_to_access params="dave@kdcowen.com" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running rcpt hook in access plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=rcpt plugin=access function=any params="dave@kdcowen.com" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running rcpt hook in rcpt_to.in_host_list plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [rcpt_to.in_host_list] Checking if dave@kdcowen.com host is in host_list [DEBUG] [-] [rcpt_to.in_host_list] checking kdcowen.com in config/host_list [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=rcpt plugin=rcpt_to.in_host_list function=hook_rcpt params="dave@kdcowen.com" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running rcpt_ok hooks [NOTICE] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] recipient dave@kdcowen.com code=OK msg="" sender="admin@myrealflyer.com" [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] S: 250 recipient dave@kdcowen.com OK [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] C: DATA state=1 [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running data hooks [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] S: 354 go ahead, make my day [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running data_post hooks [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running data_post hook in access plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=data_post plugin=access function=data_any params="" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running data_post hook in data.headers plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=data_post plugin=data.headers function=duplicate_singular params="" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running data_post hook in data.headers plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=data_post plugin=data.headers function=missing_required params="" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running data_post hook in data.headers plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [data.headers] message date: Tue, 09 May 2017 21:23:45 +0000 [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=data_post plugin=data.headers function=invalid_date params="" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running data_post hook in data.headers plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=data_post plugin=data.headers function=invalid_return_path params="" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running data_post hook in data.headers plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=data_post plugin=data.headers function=user_agent params="" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running data_post hook in data.headers plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=data_post plugin=data.headers function=direct_to_mx params="" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running data_post hook in data.headers plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=data_post plugin=data.headers function=from_match params="" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running data_post hook in data.headers plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=data_post plugin=data.headers function=delivered_to params="" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running data_post hook in data.headers plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=data_post plugin=data.headers function=mailing_list params="" retval=CONT msg="" [NOTICE] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] message mid="5d8d71dc-b10a-8a67-52df-2c2531ac1985@myrealflyer.com" size=390 rcpts=1/0/0 delay=0.006 code=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running queue_outbound hooks [NOTICE] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] queue code=CONT msg=" (A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1)" [INFO] [-] [core] [outbound] Sending email as a transaction [DEBUG] [-] [core] running pre_send_trans_email hooks [INFO] [-] [core] [outbound] Processing domain: kdcowen.com [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running queue_ok hooks [NOTICE] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] queue code=OK msg="Message Queued" [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] S: 250 Message Queued [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running reset_transaction hooks [INFO] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] client [127.0.0.1] half closed connection [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running disconnect hooks [NOTICE] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] disconnect ip=127.0.0.1 rdns="localhost.localdomain" helo="localhost.localdomain" relay=Y early=N esmtp=Y tls=N pipe=N errors=0 txns=1 rcpts=1/0/0 msgs=1/0/0 bytes=390 lr="" time=0.138 [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1.1] [outbound] running send_email hooks [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1.1] [outbound] Sending mail: 1494365025298_0_6257_8962.localhost.localdomain [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1.1] [outbound] running get_mx hooks [INFO] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1.1] [outbound] Looking up A records for: mx00.1and1.com [INFO] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1.1] [outbound] Attempting to deliver to: 74.208.5.3:25 (0) (0) [INFO] [-] [core] [outbound] [outbound::25:74.208.5.3:undefined:50] dispense() clients=1 available=0 [DEBUG] [-] [core] [outbound] [outbound::25:74.208.5.3:undefined:50] createResource() - creating obj - count=1 min=0 max=10 [DEBUG] [-] [core] [outbound] host=74.208.5.3 port=25 pool_timeout=50 created [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1.1] [outbound] S: 554-perfora.net (mxeueus003) Nemesis ESMTP Service not available\r\n [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1.1] [outbound] S: 554-No SMTP service\r\n [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1.1] [outbound] S: 554 invalid DNS PTR resource record, IP=50.21.179.201\r\n [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1.1] [outbound] C: RSET [DEBUG] [-] [core] [outbound] release_client: 74.208.5.3:25 to undefined [INFO] [-] [core] [outbound] [outbound::25:74.208.5.3:undefined:50] dispense() clients=0 available=1 [INFO] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1.1] [outbound] bouncing mail: 554 perfora.net (mxeueus003) Nemesis ESMTP Service not available No SMTP service invalid DNS PTR resource record, IP=50.21.179.201 [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1.1] [outbound] running bounce hooks [WARN] [-] [core] [outbound] Socket [outbound::25:74.208.5.3:undefined:50] in pool got FIN [DEBUG] [-] [core] [outbound] destroying pool entry for 74.208.5.3:25 [INFO] [-] [core] [outbound] Sending email via params [INFO] [-] [core] [outbound] Created transaction: 5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1 [INFO] [-] [core] [outbound] Adding missing Message-Id header [INFO] [-] [core] [outbound] Adding missing Date header [DEBUG] [-] [core] running pre_send_trans_email hooks [INFO] [-] [core] [outbound] Processing domain: myrealflyer.com [DEBUG] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] running send_email hooks [DEBUG] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] Sending mail: 1494365025354_0_6257_8963.localhost.localdomain [DEBUG] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] running get_mx hooks [INFO] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] Looking up A records for: aspmx.l.google.com [INFO] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] Attempting to deliver to: 173.194.196.27:25 (0) (0) [INFO] [-] [core] [outbound] [outbound::25:173.194.196.27:undefined:50] dispense() clients=1 available=0 [DEBUG] [-] [core] [outbound] [outbound::25:173.194.196.27:undefined:50] createResource() - creating obj - count=1 min=0 max=10 [DEBUG] [-] [core] [outbound] host=173.194.196.27 port=25 pool_timeout=50 created [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 220 mx.google.com ESMTP p139si1250267itp.22 - gsmtp\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] C: EHLO localhost.localdomain [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250-mx.google.com at your service, [50.21.179.201]\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250-SIZE 157286400\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250-8BITMIME\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250-STARTTLS\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250-ENHANCEDSTATUSCODES\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250-PIPELINING\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250-CHUNKING\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250 SMTPUTF8\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] C: STARTTLS [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 220 2.0.0 Ready to start TLS\r\n [DEBUG] [-] [core] client TLS upgrade in progress, awaiting secured. [DEBUG] [-] [core] client TLS secured. [INFO] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] secured: cipher=ECDHE-RSA-AES128-GCM-SHA256 version=TLSv1/SSLv3 verified=true cn="mx.google.com" organization="Google Inc" issuer="Google Inc" expires="Jul 20 08:30:00 2017 GMT" fingerprint=AD:2A:D0:08:7D:14:52:2E:4A:16:AD:E5:8A:E4:A4:E7:BD:00:ED:2E [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] C: EHLO localhost.localdomain [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250-mx.google.com at your service, [50.21.179.201]\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250-SIZE 157286400\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250-8BITMIME\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250-ENHANCEDSTATUSCODES\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250-PIPELINING\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250-CHUNKING\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250 SMTPUTF8\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] C: MAIL FROM:<> [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250 2.1.0 OK p139si1250267itp.22 - gsmtp\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] C: RCPT TO:admin@myrealflyer.com [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250 2.1.5 OK p139si1250267itp.22 - gsmtp\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] C: DATA [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 354 Go ahead p139si1250267itp.22 - gsmtp\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] C: . [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 550-5.7.1 [50.21.179.201 11] Our system has detected that this message is\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 550-5.7.1 not RFC 5322 compliant:\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 550-5.7.1 'From' header is missing.\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 550-5.7.1 To reduce the amount of spam sent to Gmail, this message has been\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 550-5.7.1 blocked. Please visit\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 550-5.7.1 https://support.google.com/mail/?p=RfcMessageNonCompliant\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 550 5.7.1 and review RFC 5322 specifications for more information. p139si1250267itp.22 - gsmtp\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] C: RSET [DEBUG] [-] [core] [outbound] release_client: 173.194.196.27:25 to undefined [INFO] [-] [core] [outbound] [outbound::25:173.194.196.27:undefined:50] dispense() clients=0 available=1 [INFO] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] bouncing mail: 550 5.7.1 [50.21.179.201 11] Our system has detected that this message is not RFC 5322 compliant: 'From' header is missing. To reduce the amount of spam sent to Gmail, this message has been blocked. Please visit https://support.google.com/mail/?p=RfcMessageNonCompliant and review RFC 5322 specifications for more information. p139si1250267itp.22 - gsmtp [DEBUG] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] running bounce hooks [ERROR] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] Double bounce: Mail was already a bounce [WARN] [-] [core] [outbound] Socket [outbound::25:173.194.196.27:undefined:50] in pool got FIN [DEBUG] [-] [core] [outbound] destroying pool entry for 173.194.196.27:25 [DEBUG] [-] [core] [outbound] [outbound::25:74.208.5.3:undefined:50] removeIdle() all objects removed [DEBUG] [-] [core] [outbound] [outbound::25:173.194.196.27:undefined:50] removeIdle() all objects removed

Dexus commented 7 years ago

Hi, you have not configured your haraka right! Please check the "me" config and set the right hostname there of your system.

Von meinem iPhone gesendet

Am 09.05.2017 um 23:31 schrieb davejcowen notifications@github.com:

I'm at a loss and would greatly appreciate any help. Full logs are below and I keep getting 'From' header missing even though it starts at the send mail with the 'From' data.

loaded TLD files: 1=1528 2=6067 3=2322 loaded 8066 Public Suffixes loglevel: LOGPROTOCOL Starting up Haraka version 2.8.13 [INFO] [-] [core] Loading plugins [INFO] [-] [core] Loading plugin: access [DEBUG] [-] [core] no timeout in access.timeout [DEBUG] [-] [core] no timeout in plugin_timeout [DEBUG] [-] [core] plugin access timeout is: 30s [INFO] [-] [access] skipping helo.checks.regexps [DEBUG] [-] [core] registered hook connect to access.rdns_access priority 0 [DEBUG] [-] [core] registered hook mail to access.mail_from_access priority 0 [DEBUG] [-] [core] registered hook rcpt to access.rcpt_to_access priority 0 [DEBUG] [-] [core] registered hook connect to access.any priority 0 [DEBUG] [-] [core] registered hook helo to access.any priority 0 [DEBUG] [-] [core] registered hook ehlo to access.any priority 0 [DEBUG] [-] [core] registered hook mail to access.any priority 0 [DEBUG] [-] [core] registered hook rcpt to access.any priority 0 [DEBUG] [-] [core] registered hook data_post to access.data_any priority 0 [INFO] [-] [core] Loading plugin: dnsbl [DEBUG] [-] [core] no timeout in dnsbl.timeout [DEBUG] [-] [core] no timeout in plugin_timeout [DEBUG] [-] [core] plugin dnsbl timeout is: 30s [DEBUG] [-] [core] no timeout in dns_list_base.timeout [DEBUG] [-] [core] no timeout in plugin_timeout [DEBUG] [-] [core] plugin dns_list_base timeout is: 30s [DEBUG] [-] [core] Returning boolean true for main.reject=true [DEBUG] [-] [dnsbl] looking up: 1.0.0.127.zen.spamhaus.org. [DEBUG] [-] [dnsbl] will re-test list zones every 30 minutes [DEBUG] [-] [core] registered hook connect to dnsbl.connect_first priority 0 [INFO] [-] [core] Loading plugin: helo.checks [DEBUG] [-] [core] no timeout in helo.checks.timeout [DEBUG] [-] [core] no timeout in plugin_timeout [DEBUG] [-] [core] plugin helo.checks timeout is: 30s [DEBUG] [-] [core] Returning boolean true for check.match_re=true [DEBUG] [-] [core] Returning boolean true for check.bare_ip=true [DEBUG] [-] [core] Returning boolean true for check.dynamic=true [DEBUG] [-] [core] Returning boolean true for check.big_company=true [DEBUG] [-] [core] Returning boolean true for check.valid_hostname=true [DEBUG] [-] [core] Returning boolean true for check.forward_dns=true [DEBUG] [-] [core] Returning boolean true for check.rdns_match=true [DEBUG] [-] [core] Returning boolean true for check.mismatch=true [DEBUG] [-] [core] Returning boolean true for check.proto_mismatch=true [DEBUG] [-] [core] Returning boolean true for reject.mismatch=true [DEBUG] [-] [core] Returning boolean false for reject.proto_mismatch=false [DEBUG] [-] [core] Returning boolean false for reject.rdns_match=false [DEBUG] [-] [core] Returning boolean false for reject.dynamic=false [DEBUG] [-] [core] Returning boolean false for reject.bare_ip=false [DEBUG] [-] [core] Returning boolean false for reject.literal_mismatch=false [DEBUG] [-] [core] Returning boolean false for reject.valid_hostname=false [DEBUG] [-] [core] Returning boolean false for reject.forward_dns=false [DEBUG] [-] [core] Returning boolean true for reject.big_company=true [DEBUG] [-] [core] Returning boolean true for skip.private_ip=true [DEBUG] [-] [core] Returning boolean true for skip.relaying=true [DEBUG] [-] [core] registered hook helo to helo.checks.proto_mismatch_smtp priority 0 [DEBUG] [-] [core] registered hook ehlo to helo.checks.proto_mismatch_esmtp priority 0 [DEBUG] [-] [core] registered hook helo to helo.checks.init priority 0 [DEBUG] [-] [core] registered hook ehlo to helo.checks.init priority 0 [DEBUG] [-] [core] registered hook helo to helo.checks.match_re priority 0 [DEBUG] [-] [core] registered hook ehlo to helo.checks.match_re priority 0 [DEBUG] [-] [core] registered hook helo to helo.checks.bare_ip priority 0 [DEBUG] [-] [core] registered hook ehlo to helo.checks.bare_ip priority 0 [DEBUG] [-] [core] registered hook helo to helo.checks.dynamic priority 0 [DEBUG] [-] [core] registered hook ehlo to helo.checks.dynamic priority 0 [DEBUG] [-] [core] registered hook helo to helo.checks.big_company priority 0 [DEBUG] [-] [core] registered hook ehlo to helo.checks.big_company priority 0 [DEBUG] [-] [core] registered hook helo to helo.checks.literal_mismatch priority 0 [DEBUG] [-] [core] registered hook ehlo to helo.checks.literal_mismatch priority 0 [DEBUG] [-] [core] registered hook helo to helo.checks.valid_hostname priority 0 [DEBUG] [-] [core] registered hook ehlo to helo.checks.valid_hostname priority 0 [DEBUG] [-] [core] registered hook helo to helo.checks.rdns_match priority 0 [DEBUG] [-] [core] registered hook ehlo to helo.checks.rdns_match priority 0 [DEBUG] [-] [core] registered hook helo to helo.checks.forward_dns priority 0 [DEBUG] [-] [core] registered hook ehlo to helo.checks.forward_dns priority 0 [INFO] [-] [core] Loading plugin: auth/flat_file [DEBUG] [-] [core] no timeout in auth/flat_file.timeout [DEBUG] [-] [core] no timeout in plugin_timeout [DEBUG] [-] [core] plugin auth/flat_file timeout is: 30s [DEBUG] [-] [core] no timeout in auth/auth_base.timeout [DEBUG] [-] [core] no timeout in plugin_timeout [DEBUG] [-] [core] plugin auth/auth_base timeout is: 30s [DEBUG] [-] [core] registered hook capabilities to auth/flat_file.hook_capabilities priority 0 [DEBUG] [-] [core] registered hook unrecognized_command to auth/flat_file.hook_unrecognized_command priority 0 [INFO] [-] [core] Loading plugin: mail_from.is_resolvable [DEBUG] [-] [core] no timeout in mail_from.is_resolvable.timeout [DEBUG] [-] [core] no timeout in plugin_timeout [DEBUG] [-] [core] plugin mail_from.is_resolvable timeout is: 30s [DEBUG] [-] [core] Returning boolean false for main.allow_mx_ip=0 [DEBUG] [-] [core] Returning boolean true for main.reject_no_mx=1 [DEBUG] [-] [core] registered hook mail to mail_from.is_resolvable.hook_mail priority 0 [INFO] [-] [core] Loading plugin: rcpt_to.in_host_list [DEBUG] [-] [core] no timeout in rcpt_to.in_host_list.timeout [DEBUG] [-] [core] no timeout in plugin_timeout [DEBUG] [-] [core] plugin rcpt_to.in_host_list timeout is: 30s [DEBUG] [-] [core] no timeout in rcpt_to.host_list_base.timeout [DEBUG] [-] [core] no timeout in plugin_timeout [DEBUG] [-] [core] plugin rcpt_to.host_list_base timeout is: 30s [DEBUG] [-] [core] registered hook rcpt to rcpt_to.in_host_list.hook_rcpt priority 0 [DEBUG] [-] [core] registered hook mail to rcpt_to.in_host_list.hook_mail priority 0 [INFO] [-] [core] Loading plugin: data.headers [DEBUG] [-] [core] no timeout in data.headers.timeout [DEBUG] [-] [core] no timeout in plugin_timeout [DEBUG] [-] [core] plugin data.headers timeout is: 30s [DEBUG] [-] [core] registered hook data_post to data.headers.duplicate_singular priority 0 [DEBUG] [-] [core] registered hook data_post to data.headers.missing_required priority 0 [DEBUG] [-] [core] registered hook data_post to data.headers.invalid_date priority 0 [DEBUG] [-] [core] registered hook data_post to data.headers.invalid_return_path priority 0 [DEBUG] [-] [core] registered hook data_post to data.headers.user_agent priority 0 [DEBUG] [-] [core] registered hook data_post to data.headers.direct_to_mx priority 0 [DEBUG] [-] [core] registered hook data_post to data.headers.from_match priority 0 [DEBUG] [-] [core] registered hook data_post to data.headers.delivered_to priority 0 [DEBUG] [-] [core] registered hook data_post to data.headers.mailing_list priority 0 [INFO] [-] [core] Loading plugin: max_unrecognized_commands [DEBUG] [-] [core] no timeout in max_unrecognized_commands.timeout [DEBUG] [-] [core] no timeout in plugin_timeout [DEBUG] [-] [core] plugin max_unrecognized_commands timeout is: 30s [DEBUG] [-] [core] registered hook connect to max_unrecognized_commands.hook_connect priority 0 [DEBUG] [-] [core] registered hook unrecognized_command to max_unrecognized_commands.hook_unrecognized_command priority 0 [NOTICE] [-] [core] Listening on :::2525 [DEBUG] [-] [server] running init_master hooks [DEBUG] [-] [dnsbl] looking up: 2.0.0.127.zen.spamhaus.org. [NOTICE] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] connect ip=127.0.0.1 port=45008 local_ip=:: local_port=2525 [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running connect_init hooks [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running connect_init_respond [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running lookup_rdns hooks [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running connect hooks [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running connect hook in access plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [access] checking 127.0.0.1 against connect.rdns_access.whitelist [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [access] checking 127.0.0.1 against connect.rdns_access.whitelist_regex [DEBUG] [-] [access] empty file: connect.rdns_access.whitelist_regex [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [access] checking localhost.localdomain against connect.rdns_access.whitelist [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [access] checking localhost.localdomain against connect.rdns_access.whitelist_regex [DEBUG] [-] [access] empty file: connect.rdns_access.whitelist_regex [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [access] checking 127.0.0.1 against connect.rdns_access.blacklist_regex [DEBUG] [-] [access] empty file: connect.rdns_access.blacklist_regex [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [access] checking localhost.localdomain against connect.rdns_access.blacklist_regex [DEBUG] [-] [access] empty file: connect.rdns_access.blacklist_regex [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=connect plugin=access function=rdns_access params="" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running connect hook in access plugin [ERROR] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [access] no org domain from localhost.localdomain [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=connect plugin=access function=any params="" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running connect hook in dnsbl plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [dnsbl] skip private: 127.0.0.1 [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=connect plugin=dnsbl function=connect_first params="" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running connect hook in max_unrecognized_commands plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=connect plugin=max_unrecognized_commands function=hook_connect params="" retval=CONT msg="" [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] S: 220 localhost.localdomain ESMTP Haraka 2.8.13 ready [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] C: EHLO localhost.localdomain state=1 [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running ehlo hooks [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running ehlo hook in access plugin [ERROR] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [access] no org domain from localhost.localdomain [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=ehlo plugin=access function=any params="localhost.localdomain" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running ehlo hook in helo.checks plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=ehlo plugin=helo.checks function=proto_mismatch_esmtp params="localhost.localdomain" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running ehlo hook in helo.checks plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=ehlo plugin=helo.checks function=init params="localhost.localdomain" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running ehlo hook in helo.checks plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=ehlo plugin=helo.checks function=match_re params="localhost.localdomain" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running ehlo hook in helo.checks plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=ehlo plugin=helo.checks function=bare_ip params="localhost.localdomain" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running ehlo hook in helo.checks plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=ehlo plugin=helo.checks function=dynamic params="localhost.localdomain" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running ehlo hook in helo.checks plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=ehlo plugin=helo.checks function=big_company params="localhost.localdomain" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running ehlo hook in helo.checks plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=ehlo plugin=helo.checks function=literal_mismatch params="localhost.localdomain" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running ehlo hook in helo.checks plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=ehlo plugin=helo.checks function=valid_hostname params="localhost.localdomain" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running ehlo hook in helo.checks plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=ehlo plugin=helo.checks function=rdns_match params="localhost.localdomain" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running ehlo hook in helo.checks plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=ehlo plugin=helo.checks function=forward_dns params="localhost.localdomain" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running capabilities hooks [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running capabilities hook in auth/flat_file plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=capabilities plugin=auth/flat_file function=hook_capabilities params="" retval=CONT msg="" [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] S: 250-localhost.localdomain Hello localhost.localdomain [127.0.0.1], Haraka is at your service. [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] S: 250-PIPELINING [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] S: 250-8BITMIME [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] S: 250-SIZE 0 [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] S: 250 AUTH LOGIN CRAM-MD5 [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] C: AUTH LOGIN state=1 [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running unrecognized_command hooks [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running unrecognized_command hook in auth/flat_file plugin [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] S: 334 VXNlcm5hbWU6 [INFO] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=unrecognized_command plugin=auth/flat_file function=hook_unrecognized_command params="AUTH" retval=OK msg="" [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] C: YWRtaW4= state=1 [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running unrecognized_command hooks [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running unrecognized_command hook in auth/flat_file plugin [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] S: 334 UGFzc3dvcmQ6 [INFO] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=unrecognized_command plugin=auth/flat_file function=hook_unrecognized_command params="YWRtaW4=" retval=OK msg="" [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] C: MUhvbmV5d2VsbA== state=1 [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running unrecognized_command hooks [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] running unrecognized_command hook in auth/flat_file plugin [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] S: 235 Authentication successful [INFO] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] hook=unrecognized_command plugin=auth/flat_file function=hook_unrecognized_command params="MUhvbmV5d2VsbA==" retval=OK msg="" [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04] [core] C: MAIL FROM:admin@myrealflyer.com state=1 [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running mail hooks [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running mail hook in access plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [access] checking admin@myrealflyer.com against mail_from.access.whitelist [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [access] checking admin@myrealflyer.com against mail_from.access.whitelist_regex [DEBUG] [-] [access] empty file: mail_from.access.whitelist_regex [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [access] checking admin@myrealflyer.com against mail_from.access.blacklist_regex [DEBUG] [-] [access] empty file: mail_from.access.blacklist_regex [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=mail plugin=access function=mail_from_access params="admin@myrealflyer.com" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running mail hook in access plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=mail plugin=access function=any params="admin@myrealflyer.com" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running mail hook in mail_from.is_resolvable plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [mail_from.is_resolvable] myrealflyer.com: MX 1 aspmx.l.google.com => 173.194.196.27,2607:f8b0:4001:c16::1a [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [mail_from.is_resolvable] myrealflyer.com: MX 5 alt1.aspmx.l.google.com => 173.194.66.26,2607:f8b0:400d:c01::1a [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [mail_from.is_resolvable] myrealflyer.com: MX 5 alt2.aspx.l.google.com => queryA ENOTFOUND alt2.aspx.l.google.com [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [mail_from.is_resolvable] myrealflyer.com: MX 10 aspmx2.googlemail.com => 173.194.66.27,2607:f8b0:400d:c01::1a [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [mail_from.is_resolvable] myrealflyer.com: 173.194.196.27,2607:f8b0:4001:c16::1a,173.194.66.26,2607:f8b0:400d:c01::1a,173.194.66.27 [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=mail plugin=mail_from.is_resolvable function=hook_mail params="admin@myrealflyer.com" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running mail hook in rcpt_to.in_host_list plugin [DEBUG] [-] [rcpt_to.in_host_list] checking myrealflyer.com in config/host_list [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=mail plugin=rcpt_to.in_host_list function=hook_mail params="admin@myrealflyer.com" retval=CONT msg="" [NOTICE] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] sender admin@myrealflyer.com code=CONT msg="" [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] S: 250 sender admin@myrealflyer.com OK [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] C: RCPT TO:dave@kdcowen.com state=1 [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running rcpt hooks [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running rcpt hook in access plugin [DEBUG] [-] [access] empty file: rcpt_to.access.whitelist_regex [DEBUG] [-] [access] empty file: rcpt_to.access.blacklist_regex [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=rcpt plugin=access function=rcpt_to_access params="dave@kdcowen.com" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running rcpt hook in access plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=rcpt plugin=access function=any params="dave@kdcowen.com" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running rcpt hook in rcpt_to.in_host_list plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [rcpt_to.in_host_list] Checking if dave@kdcowen.com host is in host_list [DEBUG] [-] [rcpt_to.in_host_list] checking kdcowen.com in config/host_list [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=rcpt plugin=rcpt_to.in_host_list function=hook_rcpt params="dave@kdcowen.com" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running rcpt_ok hooks [NOTICE] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] recipient dave@kdcowen.com code=OK msg="" sender="admin@myrealflyer.com" [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] S: 250 recipient dave@kdcowen.com OK [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] C: DATA state=1 [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running data hooks [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] S: 354 go ahead, make my day [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running data_post hooks [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running data_post hook in access plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=data_post plugin=access function=data_any params="" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running data_post hook in data.headers plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=data_post plugin=data.headers function=duplicate_singular params="" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running data_post hook in data.headers plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=data_post plugin=data.headers function=missing_required params="" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running data_post hook in data.headers plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [data.headers] message date: Tue, 09 May 2017 21:23:45 +0000 [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=data_post plugin=data.headers function=invalid_date params="" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running data_post hook in data.headers plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=data_post plugin=data.headers function=invalid_return_path params="" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running data_post hook in data.headers plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=data_post plugin=data.headers function=user_agent params="" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running data_post hook in data.headers plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=data_post plugin=data.headers function=direct_to_mx params="" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running data_post hook in data.headers plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=data_post plugin=data.headers function=from_match params="" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running data_post hook in data.headers plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=data_post plugin=data.headers function=delivered_to params="" retval=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running data_post hook in data.headers plugin [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] hook=data_post plugin=data.headers function=mailing_list params="" retval=CONT msg="" [NOTICE] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] message mid="5d8d71dc-b10a-8a67-52df-2c2531ac1985@myrealflyer.com" size=390 rcpts=1/0/0 delay=0.006 code=CONT msg="" [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running queue_outbound hooks [NOTICE] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] queue code=CONT msg=" (A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1)" [INFO] [-] [core] [outbound] Sending email as a transaction [DEBUG] [-] [core] running pre_send_trans_email hooks [INFO] [-] [core] [outbound] Processing domain: kdcowen.com [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running queue_ok hooks [NOTICE] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] queue code=OK msg="Message Queued" [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] S: 250 Message Queued [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running reset_transaction hooks [INFO] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] client [127.0.0.1] half closed connection [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] running disconnect hooks [NOTICE] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1] [core] disconnect ip=127.0.0.1 rdns="localhost.localdomain" helo="localhost.localdomain" relay=Y early=N esmtp=Y tls=N pipe=N errors=0 txns=1 rcpts=1/0/0 msgs=1/0/0 bytes=390 lr="" time=0.138 [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1.1] [outbound] running send_email hooks [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1.1] [outbound] Sending mail: 1494365025298_0_6257_8962.localhost.localdomain [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1.1] [outbound] running get_mx hooks [INFO] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1.1] [outbound] Looking up A records for: mx00.1and1.com [INFO] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1.1] [outbound] Attempting to deliver to: 74.208.5.3:25 (0) (0) [INFO] [-] [core] [outbound] [outbound::25:74.208.5.3:undefined:50] dispense() clients=1 available=0 [DEBUG] [-] [core] [outbound] [outbound::25:74.208.5.3:undefined:50] createResource() - creating obj - count=1 min=0 max=10 [DEBUG] [-] [core] [outbound] host=74.208.5.3 port=25 pool_timeout=50 created [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1.1] [outbound] S: 554-perfora.net (mxeueus003) Nemesis ESMTP Service not available\r\n [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1.1] [outbound] S: 554-No SMTP service\r\n [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1.1] [outbound] S: 554 invalid DNS PTR resource record, IP=50.21.179.201\r\n [PROTOCOL] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1.1] [outbound] C: RSET [DEBUG] [-] [core] [outbound] release_client: 74.208.5.3:25 to undefined [INFO] [-] [core] [outbound] [outbound::25:74.208.5.3:undefined:50] dispense() clients=0 available=1 [INFO] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1.1] [outbound] bouncing mail: 554 perfora.net (mxeueus003) Nemesis ESMTP Service not available No SMTP service invalid DNS PTR resource record, IP=50.21.179.201 [DEBUG] [A94A3B0F-D2AA-4B0A-B007-7EA689641F04.1.1] [outbound] running bounce hooks [WARN] [-] [core] [outbound] Socket [outbound::25:74.208.5.3:undefined:50] in pool got FIN [DEBUG] [-] [core] [outbound] destroying pool entry for 74.208.5.3:25 [INFO] [-] [core] [outbound] Sending email via params [INFO] [-] [core] [outbound] Created transaction: 5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1 [INFO] [-] [core] [outbound] Adding missing Message-Id header [INFO] [-] [core] [outbound] Adding missing Date header [DEBUG] [-] [core] running pre_send_trans_email hooks [INFO] [-] [core] [outbound] Processing domain: myrealflyer.com [DEBUG] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] running send_email hooks [DEBUG] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] Sending mail: 1494365025354_0_6257_8963.localhost.localdomain [DEBUG] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] running get_mx hooks [INFO] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] Looking up A records for: aspmx.l.google.com [INFO] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] Attempting to deliver to: 173.194.196.27:25 (0) (0) [INFO] [-] [core] [outbound] [outbound::25:173.194.196.27:undefined:50] dispense() clients=1 available=0 [DEBUG] [-] [core] [outbound] [outbound::25:173.194.196.27:undefined:50] createResource() - creating obj - count=1 min=0 max=10 [DEBUG] [-] [core] [outbound] host=173.194.196.27 port=25 pool_timeout=50 created [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 220 mx.google.com ESMTP p139si1250267itp.22 - gsmtp\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] C: EHLO localhost.localdomain [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250-mx.google.com at your service, [50.21.179.201]\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250-SIZE 157286400\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250-8BITMIME\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250-STARTTLS\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250-ENHANCEDSTATUSCODES\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250-PIPELINING\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250-CHUNKING\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250 SMTPUTF8\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] C: STARTTLS [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 220 2.0.0 Ready to start TLS\r\n [DEBUG] [-] [core] client TLS upgrade in progress, awaiting secured. [DEBUG] [-] [core] client TLS secured. [INFO] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] secured: cipher=ECDHE-RSA-AES128-GCM-SHA256 version=TLSv1/SSLv3 verified=true cn="mx.google.com" organization="Google Inc" issuer="Google Inc" expires="Jul 20 08:30:00 2017 GMT" fingerprint=AD:2A:D0:08:7D:14:52:2E:4A:16:AD:E5:8A:E4:A4:E7:BD:00:ED:2E [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] C: EHLO localhost.localdomain [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250-mx.google.com at your service, [50.21.179.201]\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250-SIZE 157286400\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250-8BITMIME\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250-ENHANCEDSTATUSCODES\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250-PIPELINING\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250-CHUNKING\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250 SMTPUTF8\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] C: MAIL FROM:<> [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250 2.1.0 OK p139si1250267itp.22 - gsmtp\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] C: RCPT TO:admin@myrealflyer.com [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 250 2.1.5 OK p139si1250267itp.22 - gsmtp\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] C: DATA [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 354 Go ahead p139si1250267itp.22 - gsmtp\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] C: . [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 550-5.7.1 [50.21.179.201 11] Our system has detected that this message is\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 550-5.7.1 not RFC 5322 compliant:\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 550-5.7.1 'From' header is missing.\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 550-5.7.1 To reduce the amount of spam sent to Gmail, this message has been\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 550-5.7.1 blocked. Please visit\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 550-5.7.1 https://support.google.com/mail/?p=RfcMessageNonCompliant\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] S: 550 5.7.1 and review RFC 5322 specifications for more information. p139si1250267itp.22 - gsmtp\r\n [PROTOCOL] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] C: RSET [DEBUG] [-] [core] [outbound] release_client: 173.194.196.27:25 to undefined [INFO] [-] [core] [outbound] [outbound::25:173.194.196.27:undefined:50] dispense() clients=0 available=1 [INFO] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] bouncing mail: 550 5.7.1 [50.21.179.201 11] Our system has detected that this message is not RFC 5322 compliant: 'From' header is missing. To reduce the amount of spam sent to Gmail, this message has been blocked. Please visit https://support.google.com/mail/?p=RfcMessageNonCompliant and review RFC 5322 specifications for more information. p139si1250267itp.22 - gsmtp [DEBUG] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] running bounce hooks [ERROR] [5DEFCEC4-1E87-43C4-89DB-9E116E2C9AB1.1] [outbound] Double bounce: Mail was already a bounce [WARN] [-] [core] [outbound] Socket [outbound::25:173.194.196.27:undefined:50] in pool got FIN [DEBUG] [-] [core] [outbound] destroying pool entry for 173.194.196.27:25 [DEBUG] [-] [core] [outbound] [outbound::25:74.208.5.3:undefined:50] removeIdle() all objects removed [DEBUG] [-] [core] [outbound] [outbound::25:173.194.196.27:undefined:50] removeIdle() all objects removed

— You are receiving this because you are subscribed to this thread. Reply to this email directly, view it on GitHub, or mute the thread.

davejcowen commented 7 years ago

I added the "me" config file with my domain name and I'm still having the issue. I pulled my application out of the picture and use the below swaks command and I get the same error.

./swaks -f admin@myrealflyer.com -t dave@kdcowen.com -s localhost -p 2525 -au -ap

At one point during the setup of Haraka I did get one succesful message sent but I've been unable to determine what configuration I've changed since that one time. Any other help is greatly appreciated.

Dexus commented 7 years ago

I dont know what you have configured bit google get your bounce mail and there is the error...

Are you sending from your own mail-server and address? Or is it spam for an other domain?

Please show me your plugin config and your me config. And did you have custom plugins enabled?

Von meinem iPhone gesendet

Am 10.05.2017 um 21:42 schrieb davejcowen notifications@github.com:

I added the "me" config file with my domain name and I'm still having the issue. I pulled my application out of the picture and use the below swaks command and I get the same error.

./swaks -f admin@myrealflyer.com -t dave@kdcowen.com -s localhost -p 2525 -au -ap

At one point during the setup of Haraka I did get one succesful message sent but I've been unable to determine what configuration I've changed since that one time. Any other help is greatly appreciated.

— You are receiving this because you commented. Reply to this email directly, view it on GitHub, or mute the thread.

davejcowen commented 7 years ago

I'm setting up my own mail-server on a dedicated Centos system. I'll be only sending emails to my customers so it's not spam from another domain. the domain is therealflyer.com from 1and1 and I have the VM with 1and1 also. I have no custom plugins.

Plugins:

This file lists plugins that Haraka will run

#

Plugin ordering often matters, run 'haraka -o -c /path/to/haraka/config'

to see the order plugins (and their hooks) will run in.

#

To see a list of all plugins, run 'haraka -l'

#

To see the help docs for a particular plugin, run 'haraka -h plugin.name'

process_title

Log to syslog (see 'haraka -h syslog')

syslog

CONNECT

toobusy

relay

control which IPs, rDNS hostnames, HELO hostnames, MAIL FROM addresses, and

RCPT TO address you accept mail from. See 'haraka -h access'.

access

connect.p0f

geoip

asn

connect.fcrdns

block mails from known bad hosts (see config/dnsbl.zones for the DNS zones queried)

dnsbl

HELO

early_talker

see config/helo.checks.ini for configuration

helo.checks

see 'haraka -h tls' for config instructions before enabling!

tls

#

AUTH plugins require TLS before AUTH is advertised, see

https://github.com/haraka/Haraka/wiki/Require-SSL-TLS

auth/flat_file

auth/auth_proxy

auth/auth_ldap

MAIL FROM

Only accept mail where the MAIL FROM domain is resolvable to an MX record

mail_from.is_resolvable

spf

RCPT TO

At least one rcpt_to plugin is REQUIRED for inbound email. The simplest

plugin is in_host_list, see 'haraka -h rcpt_to.in_host_list' to configure.

rcpt_to.in_host_list

rcpt_to.qmail_deliverable

rcpt_to.ldap

rcpt_to.routes

DATA

bounce

Check mail headers are valid

data.headers

data.uribl

attachment

clamd

spamassassin

dkim_sign

karma

limit

QUEUE

queues: discard qmail-queue quarantine smtp_forward smtp_proxy

Queue mail via smtp - see config/smtp_forward.ini for where your mail goes

queue/smtp_forward

Disconnect client if they spew bad SMTP commands at us

max_unrecognized_commands

watch

me: therealflyer.com

davejcowen commented 7 years ago

Sorry, cut and paste messed up the font for some reason.

davejcowen commented 7 years ago

Ok, I'm getting different results now. I removed the npm version of Haraka and cloned it from Git. built it and running it from node with all the same configuration options I had prior. Looks like everything send successfully except I haven't seemed to receive the email yet. Below is the log to see if it help understand why npm version doesn't work but git clone does.

[root@localhost Haraka]# node haraka.js WARNING: Not running installed Haraka - command line arguments ignored loaded TLD files: 1=1528 2=6067 3=2322 loaded 8066 Public Suffixes loglevel: LOGPROTOCOL Starting up Haraka version 2.8.13 [INFO] [-] [core] Loading plugins [INFO] [-] [core] Loading plugin: access [DEBUG] [-] [core] no timeout in access.timeout [DEBUG] [-] [core] no timeout in plugin_timeout [DEBUG] [-] [core] plugin access timeout is: 30s [INFO] [-] [access] skipping helo.checks.regexps [DEBUG] [-] [core] registered hook connect to access.rdns_access priority 0 [DEBUG] [-] [core] registered hook mail to access.mail_from_access priority 0 [DEBUG] [-] [core] registered hook rcpt to access.rcpt_to_access priority 0 [DEBUG] [-] [core] registered hook connect to access.any priority 0 [DEBUG] [-] [core] registered hook helo to access.any priority 0 [DEBUG] [-] [core] registered hook ehlo to access.any priority 0 [DEBUG] [-] [core] registered hook mail to access.any priority 0 [DEBUG] [-] [core] registered hook rcpt to access.any priority 0 [DEBUG] [-] [core] registered hook data_post to access.data_any priority 0 [INFO] [-] [core] Loading plugin: dnsbl [DEBUG] [-] [core] no timeout in dnsbl.timeout [DEBUG] [-] [core] no timeout in plugin_timeout [DEBUG] [-] [core] plugin dnsbl timeout is: 30s [DEBUG] [-] [core] no timeout in dns_list_base.timeout [DEBUG] [-] [core] no timeout in plugin_timeout [DEBUG] [-] [core] plugin dns_list_base timeout is: 30s [DEBUG] [-] [core] Returning boolean true for main.reject=true [DEBUG] [-] [dnsbl] looking up: 1.0.0.127.zen.spamhaus.org. [DEBUG] [-] [dnsbl] will re-test list zones every 30 minutes [DEBUG] [-] [core] registered hook connect to dnsbl.connect_first priority 0 [INFO] [-] [core] Loading plugin: helo.checks [DEBUG] [-] [core] no timeout in helo.checks.timeout [DEBUG] [-] [core] no timeout in plugin_timeout [DEBUG] [-] [core] plugin helo.checks timeout is: 30s [DEBUG] [-] [core] registered hook helo to helo.checks.proto_mismatch_smtp priority 0 [DEBUG] [-] [core] registered hook ehlo to helo.checks.proto_mismatch_esmtp priority 0 [DEBUG] [-] [core] registered hook helo to helo.checks.init priority 0 [DEBUG] [-] [core] registered hook ehlo to helo.checks.init priority 0 [DEBUG] [-] [core] registered hook helo to helo.checks.match_re priority 0 [DEBUG] [-] [core] registered hook ehlo to helo.checks.match_re priority 0 [DEBUG] [-] [core] registered hook helo to helo.checks.bare_ip priority 0 [DEBUG] [-] [core] registered hook ehlo to helo.checks.bare_ip priority 0 [DEBUG] [-] [core] registered hook helo to helo.checks.dynamic priority 0 [DEBUG] [-] [core] registered hook ehlo to helo.checks.dynamic priority 0 [DEBUG] [-] [core] registered hook helo to helo.checks.big_company priority 0 [DEBUG] [-] [core] registered hook ehlo to helo.checks.big_company priority 0 [DEBUG] [-] [core] registered hook helo to helo.checks.literal_mismatch priority 0 [DEBUG] [-] [core] registered hook ehlo to helo.checks.literal_mismatch priority 0 [DEBUG] [-] [core] registered hook helo to helo.checks.valid_hostname priority 0 [DEBUG] [-] [core] registered hook ehlo to helo.checks.valid_hostname priority 0 [DEBUG] [-] [core] registered hook helo to helo.checks.rdns_match priority 0 [DEBUG] [-] [core] registered hook ehlo to helo.checks.rdns_match priority 0 [DEBUG] [-] [core] registered hook helo to helo.checks.forward_dns priority 0 [DEBUG] [-] [core] registered hook ehlo to helo.checks.forward_dns priority 0 [DEBUG] [-] [core] registered hook helo to helo.checks.host_mismatch priority 0 [DEBUG] [-] [core] registered hook ehlo to helo.checks.host_mismatch priority 0 [DEBUG] [-] [core] registered hook helo to helo.checks.emit_log priority 0 [DEBUG] [-] [core] registered hook ehlo to helo.checks.emit_log priority 0 [INFO] [-] [core] Loading plugin: auth/flat_file [DEBUG] [-] [core] no timeout in auth/flat_file.timeout [DEBUG] [-] [core] no timeout in plugin_timeout [DEBUG] [-] [core] plugin auth/flat_file timeout is: 30s [DEBUG] [-] [core] no timeout in auth/auth_base.timeout [DEBUG] [-] [core] no timeout in plugin_timeout [DEBUG] [-] [core] plugin auth/auth_base timeout is: 30s [DEBUG] [-] [core] registered hook capabilities to auth/flat_file.hook_capabilities priority 0 [DEBUG] [-] [core] registered hook unrecognized_command to auth/flat_file.hook_unrecognized_command priority 0 [INFO] [-] [core] Loading plugin: mail_from.is_resolvable [DEBUG] [-] [core] no timeout in mail_from.is_resolvable.timeout [DEBUG] [-] [core] no timeout in plugin_timeout [DEBUG] [-] [core] plugin mail_from.is_resolvable timeout is: 30s [DEBUG] [-] [core] Returning boolean false for main.allow_mx_ip=0 [DEBUG] [-] [core] Returning boolean true for main.reject_no_mx=1 [DEBUG] [-] [core] registered hook mail to mail_from.is_resolvable.hook_mail priority 0 [INFO] [-] [core] Loading plugin: rcpt_to.in_host_list [DEBUG] [-] [core] no timeout in rcpt_to.in_host_list.timeout [DEBUG] [-] [core] no timeout in plugin_timeout [DEBUG] [-] [core] plugin rcpt_to.in_host_list timeout is: 30s [DEBUG] [-] [core] no timeout in rcpt_to.host_list_base.timeout [DEBUG] [-] [core] no timeout in plugin_timeout [DEBUG] [-] [core] plugin rcpt_to.host_list_base timeout is: 30s [DEBUG] [-] [core] registered hook rcpt to rcpt_to.in_host_list.hook_rcpt priority 0 [DEBUG] [-] [core] registered hook mail to rcpt_to.in_host_list.hook_mail priority 0 [INFO] [-] [core] Loading plugin: data.headers [DEBUG] [-] [core] no timeout in data.headers.timeout [DEBUG] [-] [core] no timeout in plugin_timeout [DEBUG] [-] [core] plugin data.headers timeout is: 30s [DEBUG] [-] [core] registered hook data_post to data.headers.duplicate_singular priority 0 [DEBUG] [-] [core] registered hook data_post to data.headers.missing_required priority 0 [DEBUG] [-] [core] registered hook data_post to data.headers.invalid_date priority 0 [DEBUG] [-] [core] registered hook data_post to data.headers.invalid_return_path priority 0 [DEBUG] [-] [core] registered hook data_post to data.headers.user_agent priority 0 [DEBUG] [-] [core] registered hook data_post to data.headers.direct_to_mx priority 0 [DEBUG] [-] [core] registered hook data_post to data.headers.from_match priority 0 [DEBUG] [-] [core] registered hook data_post to data.headers.delivered_to priority 0 [DEBUG] [-] [core] registered hook data_post to data.headers.mailing_list priority 0 [INFO] [-] [core] Loading plugin: max_unrecognized_commands [DEBUG] [-] [core] no timeout in max_unrecognized_commands.timeout [DEBUG] [-] [core] no timeout in plugin_timeout [DEBUG] [-] [core] plugin max_unrecognized_commands timeout is: 30s [DEBUG] [-] [core] registered hook connect to max_unrecognized_commands.hook_connect priority 0 [DEBUG] [-] [core] registered hook unrecognized_command to max_unrecognized_commands.hook_unrecognized_command priority 0 [NOTICE] [-] [core] Listening on :::2525 [DEBUG] [-] [server] running init_master hooks [DEBUG] [-] [dnsbl] looking up: 2.0.0.127.zen.spamhaus.org. [NOTICE] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] connect ip=127.0.0.1 port=47620 local_ip=:: local_port=2525 [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] running connect_init hooks [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] running connect_init_respond [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] running lookup_rdns hooks [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] running connect hooks [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] running connect hook in access plugin [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [access] checking 127.0.0.1 against connect.rdns_access.whitelist [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [access] checking 127.0.0.1 against connect.rdns_access.whitelist_regex [DEBUG] [-] [access] empty file: connect.rdns_access.whitelist_regex [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [access] checking localhost.localdomain against connect.rdns_access.whitelist [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [access] checking localhost.localdomain against connect.rdns_access.whitelist_regex [DEBUG] [-] [access] empty file: connect.rdns_access.whitelist_regex [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [access] checking 127.0.0.1 against connect.rdns_access.blacklist_regex [DEBUG] [-] [access] empty file: connect.rdns_access.blacklist_regex [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [access] checking localhost.localdomain against connect.rdns_access.blacklist_regex [DEBUG] [-] [access] empty file: connect.rdns_access.blacklist_regex [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] hook=connect plugin=access function=rdns_access params="" retval=CONT msg="" [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] running connect hook in access plugin [ERROR] [8945C5A8-659D-494D-8986-92CD72D72DDA] [access] no org domain from localhost.localdomain [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] hook=connect plugin=access function=any params="" retval=CONT msg="" [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] running connect hook in dnsbl plugin [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [dnsbl] skip private: 127.0.0.1 [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] hook=connect plugin=dnsbl function=connect_first params="" retval=CONT msg="" [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] running connect hook in max_unrecognized_commands plugin [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] hook=connect plugin=max_unrecognized_commands function=hook_connect params="" retval=CONT msg="" [PROTOCOL] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] S: 220 therealflyer.com ESMTP Haraka 2.8.13 ready [PROTOCOL] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] C: EHLO localhost state=1 [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] running ehlo hooks [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] running ehlo hook in access plugin [INFO] [8945C5A8-659D-494D-8986-92CD72D72DDA] [access] msg:unlisted(conn), fail:invalid domain: localhost [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] hook=ehlo plugin=access function=any params="localhost" retval=CONT msg="" [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] running ehlo hook in helo.checks plugin [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] hook=ehlo plugin=helo.checks function=proto_mismatch_esmtp params="localhost" retval=CONT msg="" [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] running ehlo hook in helo.checks plugin [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] hook=ehlo plugin=helo.checks function=init params="localhost" retval=CONT msg="" [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] running ehlo hook in helo.checks plugin [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] hook=ehlo plugin=helo.checks function=match_re params="localhost" retval=CONT msg="" [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] running ehlo hook in helo.checks plugin [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] hook=ehlo plugin=helo.checks function=bare_ip params="localhost" retval=CONT msg="" [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] running ehlo hook in helo.checks plugin [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] hook=ehlo plugin=helo.checks function=dynamic params="localhost" retval=CONT msg="" [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] running ehlo hook in helo.checks plugin [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] hook=ehlo plugin=helo.checks function=big_company params="localhost" retval=CONT msg="" [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] running ehlo hook in helo.checks plugin [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] hook=ehlo plugin=helo.checks function=literal_mismatch params="localhost" retval=CONT msg="" [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] running ehlo hook in helo.checks plugin [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] hook=ehlo plugin=helo.checks function=valid_hostname params="localhost" retval=CONT msg="" [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] running ehlo hook in helo.checks plugin [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] hook=ehlo plugin=helo.checks function=rdns_match params="localhost" retval=CONT msg="" [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] running ehlo hook in helo.checks plugin [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] hook=ehlo plugin=helo.checks function=forward_dns params="localhost" retval=CONT msg="" [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] running ehlo hook in helo.checks plugin [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] hook=ehlo plugin=helo.checks function=host_mismatch params="localhost" retval=CONT msg="" [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] running ehlo hook in helo.checks plugin [INFO] [8945C5A8-659D-494D-8986-92CD72D72DDA] [helo.checks] multi: true, skip:proto_mismatch(private), host_mismatch(private) [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] hook=ehlo plugin=helo.checks function=emit_log params="localhost" retval=CONT msg="" [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] running capabilities hooks [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] running capabilities hook in auth/flat_file plugin [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] hook=capabilities plugin=auth/flat_file function=hook_capabilities params="" retval=CONT msg="" [PROTOCOL] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] S: 250-therealflyer.com Hello localhost.localdomain [127.0.0.1], Haraka is at your service. [PROTOCOL] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] S: 250-PIPELINING [PROTOCOL] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] S: 250-8BITMIME [PROTOCOL] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] S: 250-SIZE 26214400 [PROTOCOL] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] S: 250 AUTH LOGIN CRAM-MD5 [PROTOCOL] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] C: AUTH LOGIN state=1 [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] running unrecognized_command hooks [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] running unrecognized_command hook in auth/flat_file plugin [PROTOCOL] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] S: 334 VXNlcm5hbWU6 [INFO] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] hook=unrecognized_command plugin=auth/flat_file function=hook_unrecognized_command params="AUTH" retval=OK msg="" [PROTOCOL] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] C: YWRtaW4= state=1 [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] running unrecognized_command hooks [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] running unrecognized_command hook in auth/flat_file plugin [PROTOCOL] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] S: 334 UGFzc3dvcmQ6 [INFO] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] hook=unrecognized_command plugin=auth/flat_file function=hook_unrecognized_command params="YWRtaW4=" retval=OK msg="" [PROTOCOL] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] C: MUhvbmV5d2VsbA== state=1 [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] running unrecognized_command hooks [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] running unrecognized_command hook in auth/flat_file plugin [PROTOCOL] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] S: 235 Authentication successful [INFO] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] hook=unrecognized_command plugin=auth/flat_file function=hook_unrecognized_command params="MUhvbmV5d2VsbA==" retval=OK msg="" [PROTOCOL] [8945C5A8-659D-494D-8986-92CD72D72DDA] [core] C: MAIL FROM:admin@myrealflyer.com state=1 [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] running mail hooks [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] running mail hook in access plugin [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [access] checking admin@myrealflyer.com against mail_from.access.whitelist [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [access] checking admin@myrealflyer.com against mail_from.access.whitelist_regex [DEBUG] [-] [access] empty file: mail_from.access.whitelist_regex [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [access] checking admin@myrealflyer.com against mail_from.access.blacklist_regex [DEBUG] [-] [access] empty file: mail_from.access.blacklist_regex [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] hook=mail plugin=access function=mail_from_access params="admin@myrealflyer.com" retval=CONT msg="" [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] running mail hook in access plugin [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] hook=mail plugin=access function=any params="admin@myrealflyer.com" retval=CONT msg="" [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] running mail hook in mail_from.is_resolvable plugin [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [mail_from.is_resolvable] myrealflyer.com: MX 1 aspmx.l.google.com => 74.125.129.27,2607:f8b0:4001:c16::1b [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [mail_from.is_resolvable] myrealflyer.com: MX 10 aspmx2.googlemail.com => 173.194.66.27,2607:f8b0:400d:c01::1b [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [mail_from.is_resolvable] myrealflyer.com: MX 5 alt1.aspmx.l.google.com => 173.194.66.27,2607:f8b0:400d:c01::1b [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [mail_from.is_resolvable] myrealflyer.com: MX 5 alt2.aspx.l.google.com => queryA ENOTFOUND alt2.aspx.l.google.com [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [mail_from.is_resolvable] myrealflyer.com: 74.125.129.27,2607:f8b0:4001:c16::1b,173.194.66.27,2607:f8b0:400d:c01::1b [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] hook=mail plugin=mail_from.is_resolvable function=hook_mail params="admin@myrealflyer.com" retval=CONT msg="" [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] running mail hook in rcpt_to.in_host_list plugin [DEBUG] [-] [rcpt_to.in_host_list] checking myrealflyer.com in config/host_list [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] hook=mail plugin=rcpt_to.in_host_list function=hook_mail params="admin@myrealflyer.com" retval=CONT msg="" [NOTICE] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] sender admin@myrealflyer.com code=CONT msg="" [PROTOCOL] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] S: 250 sender admin@myrealflyer.com OK [PROTOCOL] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] C: RCPT TO:dave@kdcowen.com state=1 [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] running rcpt hooks [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] running rcpt hook in access plugin [DEBUG] [-] [access] empty file: rcpt_to.access.whitelist_regex [DEBUG] [-] [access] empty file: rcpt_to.access.blacklist_regex [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] hook=rcpt plugin=access function=rcpt_to_access params="dave@kdcowen.com" retval=CONT msg="" [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] running rcpt hook in access plugin [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] hook=rcpt plugin=access function=any params="dave@kdcowen.com" retval=CONT msg="" [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] running rcpt hook in rcpt_to.in_host_list plugin [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [rcpt_to.in_host_list] Checking if dave@kdcowen.com host is in host_list [DEBUG] [-] [rcpt_to.in_host_list] checking kdcowen.com in config/host_list [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] hook=rcpt plugin=rcpt_to.in_host_list function=hook_rcpt params="dave@kdcowen.com" retval=CONT msg="" [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] running rcpt_ok hooks [NOTICE] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] recipient dave@kdcowen.com code=OK msg="" sender="admin@myrealflyer.com" [PROTOCOL] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] S: 250 recipient dave@kdcowen.com OK [PROTOCOL] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] C: DATA state=1 [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] running data hooks [PROTOCOL] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] S: 354 go ahead, make my day [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] running data_post hooks [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] running data_post hook in access plugin [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] hook=data_post plugin=access function=data_any params="" retval=CONT msg="" [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] running data_post hook in data.headers plugin [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] hook=data_post plugin=data.headers function=duplicate_singular params="" retval=CONT msg="" [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] running data_post hook in data.headers plugin [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] hook=data_post plugin=data.headers function=missing_required params="" retval=CONT msg="" [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] running data_post hook in data.headers plugin [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [data.headers] message date: Wed, 10 May 2017 21:37:13 +0000 [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] hook=data_post plugin=data.headers function=invalid_date params="" retval=CONT msg="" [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] running data_post hook in data.headers plugin [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] hook=data_post plugin=data.headers function=invalid_return_path params="" retval=CONT msg="" [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] running data_post hook in data.headers plugin [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] hook=data_post plugin=data.headers function=user_agent params="" retval=CONT msg="" [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] running data_post hook in data.headers plugin [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] hook=data_post plugin=data.headers function=direct_to_mx params="" retval=CONT msg="" [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] running data_post hook in data.headers plugin [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] hook=data_post plugin=data.headers function=from_match params="" retval=CONT msg="" [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] running data_post hook in data.headers plugin [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] hook=data_post plugin=data.headers function=delivered_to params="" retval=CONT msg="" [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] running data_post hook in data.headers plugin [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] hook=data_post plugin=data.headers function=mailing_list params="" retval=CONT msg="" [NOTICE] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] message mid="20170510213713.008090@localhost" size=273 rcpts=1/0/0 delay=0.01 code=CONT msg="" [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] running queue_outbound hooks [NOTICE] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] queue code=CONT msg=" (8945C5A8-659D-494D-8986-92CD72D72DDA.1)" [INFO] [-] [core] [outbound] Sending email as a transaction [DEBUG] [-] [core] running pre_send_trans_email hooks [INFO] [-] [core] [outbound] Processing domain: kdcowen.com [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] running queue_ok hooks [NOTICE] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] queue code=OK msg="Message Queued" [PROTOCOL] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] S: 250 Message Queued [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] running reset_transaction hooks [PROTOCOL] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] C: QUIT state=1 [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] running quit hooks [PROTOCOL] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] S: 221 therealflyer.com closing connection. Have a jolly good day. [DEBUG] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] running disconnect hooks [NOTICE] [8945C5A8-659D-494D-8986-92CD72D72DDA.1] [core] disconnect ip=127.0.0.1 rdns="localhost.localdomain" helo="localhost" relay=Y early=N esmtp=Y tls=N pipe=N errors=0 txns=1 rcpts=1/0/0 msgs=1/0/0 bytes=273 lr="" time=0.157 [DEBUG] [-] [outbound] running send_email hooks [DEBUG] [-] [outbound] Sending mail: 1494452234145_1494452234145_0_8022_F7aZmD_1_localhost.localdomain [DEBUG] [-] [outbound] running get_mx hooks [INFO] [-] [outbound] Looking up A records for: mx00.1and1.com [INFO] [-] [outbound] Attempting to deliver to: 74.208.5.3:25 (0) (0) [INFO] [-] [core] [outbound] [outbound::25:74.208.5.3:undefined:undefined] dispense() clients=1 available=0 [DEBUG] [-] [core] [outbound] [outbound::25:74.208.5.3:undefined:undefined] createResource() - creating obj - count=1 min=0 max=10 [DEBUG] [-] [core] [outbound] host=74.208.5.3 port=25 pool_timeout=undefined created [PROTOCOL] [-] [outbound] S: 554-perfora.net (mxeueus004) Nemesis ESMTP Service not available\r\n [PROTOCOL] [-] [outbound] S: 554-No SMTP service\r\n [PROTOCOL] [-] [outbound] S: 554 invalid DNS PTR resource record, IP=50.21.179.201\r\n [PROTOCOL] [-] [outbound] C: RSET [DEBUG] [-] [core] [outbound] release_client: 74.208.5.3:25 to undefined [INFO] [-] [core] [outbound] [outbound::25:74.208.5.3:undefined:undefined] dispense() clients=0 available=1 [INFO] [-] [outbound] bouncing mail: 554 perfora.net (mxeueus004) Nemesis ESMTP Service not available No SMTP service invalid DNS PTR resource record, IP=50.21.179.201 [DEBUG] [-] [outbound] running bounce hooks [WARN] [-] [core] [outbound] Socket [outbound::25:74.208.5.3:undefined:undefined] in pool got FIN [DEBUG] [-] [core] [outbound] destroying pool entry for 74.208.5.3:25 [INFO] [-] [core] [outbound] Sending email via params [INFO] [-] [core] [outbound] Created transaction: 4CD83109-2ECB-45DE-A050-4D54A8865E2A [DEBUG] [-] [core] running pre_send_trans_email hooks [INFO] [-] [core] [outbound] Processing domain: myrealflyer.com [DEBUG] [-] [outbound] running send_email hooks [DEBUG] [-] [outbound] Sending mail: 1494452234193_1494452234193_0_8022_AKt55d_2_localhost.localdomain [DEBUG] [-] [outbound] running get_mx hooks [INFO] [-] [outbound] Looking up A records for: aspmx.l.google.com [INFO] [-] [outbound] Attempting to deliver to: 74.125.129.27:25 (0) (0) [INFO] [-] [core] [outbound] [outbound::25:74.125.129.27:undefined:undefined] dispense() clients=1 available=0 [DEBUG] [-] [core] [outbound] [outbound::25:74.125.129.27:undefined:undefined] createResource() - creating obj - count=1 min=0 max=10 [DEBUG] [-] [core] [outbound] host=74.125.129.27 port=25 pool_timeout=undefined created [PROTOCOL] [-] [outbound] S: 220 mx.google.com ESMTP k34si161847ioi.202 - gsmtp\r\n [PROTOCOL] [-] [outbound] C: EHLO therealflyer.com [PROTOCOL] [-] [outbound] S: 250-mx.google.com at your service, [50.21.179.201]\r\n [PROTOCOL] [-] [outbound] S: 250-SIZE 157286400\r\n [PROTOCOL] [-] [outbound] S: 250-8BITMIME\r\n [PROTOCOL] [-] [outbound] S: 250-STARTTLS\r\n [PROTOCOL] [-] [outbound] S: 250-ENHANCEDSTATUSCODES\r\n [PROTOCOL] [-] [outbound] S: 250-PIPELINING\r\n [PROTOCOL] [-] [outbound] S: 250-CHUNKING\r\n [PROTOCOL] [-] [outbound] S: 250 SMTPUTF8\r\n [PROTOCOL] [-] [outbound] C: MAIL FROM:<> [PROTOCOL] [-] [outbound] S: 250 2.1.0 OK k34si161847ioi.202 - gsmtp\r\n [PROTOCOL] [-] [outbound] C: RCPT TO:admin@myrealflyer.com [PROTOCOL] [-] [outbound] S: 250 2.1.5 OK k34si161847ioi.202 - gsmtp\r\n [PROTOCOL] [-] [outbound] C: DATA [PROTOCOL] [-] [outbound] S: 354 Go ahead k34si161847ioi.202 - gsmtp\r\n [PROTOCOL] [-] [outbound] C: . [PROTOCOL] [-] [outbound] S: 250 2.0.0 OK 1494452234 k34si161847ioi.202 - gsmtp\r\n [NOTICE] [-] [outbound] delivered file=1494452234193_1494452234193_0_8022_AKt55d_2_localhost.localdomain domain="myrealflyer.com" host="aspmx.l.google.com" ip=74.125.129.27 port=25 mode=SMTP tls=N auth=N response="OK 1494452234 k34si161847ioi.202 - gsmtp" delay=0.357 fails=0 rcpts=1/0/0 [DEBUG] [-] [outbound] running delivered hooks [DEBUG] [-] [core] [outbound] release_client: 74.125.129.27:25 to undefined [INFO] [-] [core] [outbound] [outbound::25:74.125.129.27:undefined:undefined] dispense() clients=0 available=1 [PROTOCOL] [-] [outbound] C: RSET [DEBUG] [-] [core] [outbound] [outbound::25:74.208.5.3:undefined:undefined] removeIdle() all objects removed

davejcowen commented 7 years ago

one note that I realized may be part of the issue is I'm sending an email to my clients (all) on behalf of another client(B) so I wanted to have the from be the (B) client instead of no-reply if possible. I'm currently doing this on my other windows system using Email Architects EASendMail .net module. I'm trying Haraka because I rewrote app in mean

davejcowen commented 7 years ago

Closing: removed npm version and cloned git with same configuration got it working. no all To: domains are receiving and all are going to spam but I think all of this is tweaking the configuration and not an issue. Anyone have guidelines for proper configuration to reduce spam?