Redirect emails doesn't work anymore on domain bin addresses
Issue #562
resolved
Hi,
since the last update I am no longer able to redirect emails from a domain bin address to multiple recipients. Instead new emails just stay in the account inbox now.
This is the sieve filter of the domain bin address:
# poste.io redirect filter
if true
{
redirect "mail1@example.com";
redirect "mail2@example.com";
}
I tried to remove it and recreate the filter again by using the GUI – but unfortunately this doesn't helped me out. My Poste.io instance is: 2.0.23 PRO
Thank you
Comments (11)
-
repo owner
-
repo owner
I can't reproduce the issue. Very helpful would be delivery logfile(s). There should be one with remote delivery and another two delivering to mail1@ and mail2...
-
Are there only 2 emails you are redirecting to? or more? It may be the sieve redirect limit if more?
I am also sure there is a sieve error log too, but can't remember where
-
I have activated only the domain bin functionality under the domain and "forwarded" all mails to a central account (of course only if the recipient account doesn't exist).
After that I edited the account setting from all@example.com to redirect all incoming mails to mail1@example.com and mail2@example.com, like so:
For some reasons, as you can see, the mail box is not redirecting mails to the target accounts (which are btw. under the same domain). Instead, mails to hello@example.com just land into the all@example.com account and stay there.
This is what I see at the delivery logs. Unfortunately older logs are removed so I can't show you the logs from when it was working fine. Normally after receiving to a domain bin address, I was able to see in the logs the redirection to mail1@example.com and so on…
Maybe the information will help that it did not happen until we used the vacation mode on the target addresses. Now, after completely removing the vacation rule from both mail addresses, it's still not working as beforee.
This is the complete raw log of this mail (anonymized. hopefully).
Connection 2019-01-04T15:18:29.830Z [NOTICE] [core] connect ip=209.85.221.45 port=44238 local_ip=:: local_port=25 2019-01-04T15:18:29.831Z [DEBUG] [core] running connect_init hooks 2019-01-04T15:18:29.831Z [DEBUG] [core] running connect_init hook in guard plugin 2019-01-04T15:18:29.832Z [DEBUG] [core] hook=connect_init plugin=guard function=hook_connect_init params="" retval=CONT msg="" 2019-01-04T15:18:29.832Z [DEBUG] [core] running connect_init hook in karma plugin 2019-01-04T15:18:29.834Z [DEBUG] [karma] psubscribed to result-88E585D3-430D-4299-8A82-500C73E586E8* 2019-01-04T15:18:29.834Z [DEBUG] [core] hook=connect_init plugin=karma function=results_init params="" retval=CONT msg="" 2019-01-04T15:18:29.834Z [DEBUG] [core] running connect_init hook in karma plugin 2019-01-04T15:18:29.835Z [INFO] [karma] score: 0, good: 22, bad: 0, connections: 22, history: 22, pass:all_good 2019-01-04T15:18:29.835Z [DEBUG] [core] hook=connect_init plugin=karma function=ip_history_from_redis params="" retval=CONT msg="" 2019-01-04T15:18:29.835Z [DEBUG] [core] running connect_init hook in early_talker plugin 2019-01-04T15:18:32.835Z [DEBUG] [core] hook=connect_init plugin=early_talker function=early_talker params="" retval=CONT msg="" 2019-01-04T15:18:32.836Z [DEBUG] [core] running connect_init hook in fcrdns plugin 2019-01-04T15:18:32.836Z [DEBUG] [core] hook=connect_init plugin=fcrdns function=initialize_fcrdns params="" retval=CONT msg="" 2019-01-04T15:18:32.836Z [DEBUG] [core] running connect_init hook in relay plugin 2019-01-04T15:18:32.836Z [DEBUG] [relay] checking 209.85.221.45 in relay_acl_allow 2019-01-04T15:18:32.836Z [DEBUG] [relay] checking if 209.85.221.45 is in 127.0.0.1/32 2019-01-04T15:18:32.836Z [DEBUG] [relay] checking if 209.85.221.45 is in 10.42.115.21/32 2019-01-04T15:18:32.836Z [DEBUG] [core] hook=connect_init plugin=relay function=acl params="" retval=CONT msg="" 2019-01-04T15:18:32.836Z [DEBUG] [core] running connect_init_respond 2019-01-04T15:18:32.836Z [DEBUG] [core] running lookup_rdns hooks 2019-01-04T15:18:32.836Z [DEBUG] [core] running lookup_rdns hook in p0f plugin 2019-01-04T15:18:32.837Z [INFO] [p0f] os="Linux 2.2.x-3.x" link_type="Google" distance=18 total_conn=2 2019-01-04T15:18:32.837Z [DEBUG] [core] hook=lookup_rdns plugin=p0f function=query_p0f params="" retval=CONT msg="" 2019-01-04T15:18:32.837Z [DEBUG] [core] running lookup_rdns hook in fcrdns plugin 2019-01-04T15:18:32.838Z [DEBUG] [fcrdns] rdns lookup: 209.85.221.45 2019-01-04T15:18:32.839Z [DEBUG] [fcrdns] domain: mail-wr1-f45.google.com 2019-01-04T15:18:32.856Z [DEBUG] [fcrdns] mail-wr1-f45.google.com => 209.85.221.45 2019-01-04T15:18:32.857Z [INFO] [fcrdns] ip=209.85.221.45 rdns="mail-wr1-f45.google.com" rdns_len=1 fcrdns="mail-wr1-f45.google.com" fcrdns_len=1 other_ips_len=0 invalid_tlds=0 generic_rdns=true 2019-01-04T15:18:32.857Z [DEBUG] [core] hook=lookup_rdns plugin=fcrdns function=do_dns_lookups params="" retval=CONT msg="" 2019-01-04T15:18:32.857Z [DEBUG] [core] running lookup_rdns hook in data.uribl plugin 2019-01-04T15:18:32.859Z [DEBUG] [data.uribl] (rdns) found 1 items for lookup 2019-01-04T15:18:32.859Z [DEBUG] [data.uribl] (rdns) checking: mail-wr1-f45.google.com 2019-01-04T15:18:32.874Z [DEBUG] [data.uribl] mail-wr1-f45.google.com.dbl.spamhaus.org. => (Error: queryA ENOTFOUND mail-wr1-f45.google.com.dbl.spamhaus.org.) 2019-01-04T15:18:32.874Z [DEBUG] [core] hook=lookup_rdns plugin=data.uribl function=hook_lookup_rdns params="" retval=CONT msg="" 2019-01-04T15:18:32.874Z [DEBUG] [core] running lookup_rdns hook in asn plugin 2019-01-04T15:18:32.874Z [DEBUG] [asn] zone: origin.asn.cymru.com 2019-01-04T15:18:32.980Z [INFO] [asn] asn: 15169, net: 209.85.128.0/17 2019-01-04T15:18:32.980Z [DEBUG] [core] hook=lookup_rdns plugin=asn function=lookup_via_dns params="" retval=CONT msg="" 2019-01-04T15:18:32.981Z [DEBUG] [core] running connect hooks 2019-01-04T15:18:32.981Z [DEBUG] [core] running connect hook in limits plugin 2019-01-04T15:18:32.982Z [DEBUG] [core] hook=connect plugin=limits function=hook_connect params="" retval=CONT msg="" 2019-01-04T15:18:32.982Z [DEBUG] [core] running connect hook in guard plugin 2019-01-04T15:18:32.982Z [DEBUG] [core] hook=connect plugin=guard function=hook_connect params="" retval=CONT msg="" 2019-01-04T15:18:32.982Z [DEBUG] [core] running connect hook in karma plugin 2019-01-04T15:18:32.982Z [DEBUG] [core] hook=connect plugin=karma function=hook_connect params="" retval=CONT msg="" 2019-01-04T15:18:32.982Z [DEBUG] [core] running connect hook in asn plugin 2019-01-04T15:18:32.982Z [DEBUG] [core] hook=connect plugin=asn function=lookup_via_maxmind params="" retval=CONT msg="" 2019-01-04T15:18:32.982Z [DEBUG] [core] running connect hook in geoip plugin 2019-01-04T15:18:32.982Z [INFO] [geoip] NA, US 2019-01-04T15:18:32.983Z [DEBUG] [core] hook=connect plugin=geoip function=lookup_maxmind params="" retval=CONT msg="" 2019-01-04T15:18:32.983Z [DEBUG] [core] running connect hook in dnsbl plugin 2019-01-04T15:18:32.984Z [INFO] [karma] score: 4, good: 22, bad: 0, connections: 22, history: 22, awards: 006,089, asn_score: 2344, asn_connections: 2533, asn_good: 2369, asn_bad: 25, pass:all_good, asn:history 2019-01-04T15:18:33.145Z [DEBUG] [core] hook=connect plugin=dnsbl function=connect_first params="" retval=CONT msg="" 2019-01-04T15:18:33.145Z [DEBUG] [core] running connect hook in relay plugin 2019-01-04T15:18:33.145Z [DEBUG] [core] hook=connect plugin=relay function=pass_relaying params="" retval=CONT msg="" 2019-01-04T15:18:33.145Z [PROTOCOL] [core] S: 220 email.example.one ESMTP Haraka ready 2019-01-04T15:18:33.166Z [PROTOCOL] [core] C: EHLO mail-wr1-f45.google.com state=1 2019-01-04T15:18:33.166Z [DEBUG] [core] running ehlo hooks 2019-01-04T15:18:33.166Z [DEBUG] [core] running ehlo hook in hello_block plugin 2019-01-04T15:18:33.166Z [DEBUG] [core] hook=ehlo plugin=hello_block function=check_ymlf params=mail-wr1-f45.google.com retval=CONT msg="" 2019-01-04T15:18:33.166Z [DEBUG] [core] running ehlo hook in karma plugin 2019-01-04T15:18:33.166Z [DEBUG] [core] hook=ehlo plugin=karma function=hook_ehlo params=mail-wr1-f45.google.com retval=CONT msg="" 2019-01-04T15:18:33.166Z [DEBUG] [core] running ehlo hook in helo.checks plugin 2019-01-04T15:18:33.166Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=proto_mismatch_esmtp params=mail-wr1-f45.google.com retval=CONT msg="" 2019-01-04T15:18:33.166Z [DEBUG] [core] running ehlo hook in helo.checks plugin 2019-01-04T15:18:33.167Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=init params=mail-wr1-f45.google.com retval=CONT msg="" 2019-01-04T15:18:33.167Z [DEBUG] [core] running ehlo hook in helo.checks plugin 2019-01-04T15:18:33.167Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=match_re params=mail-wr1-f45.google.com retval=CONT msg="" 2019-01-04T15:18:33.167Z [DEBUG] [core] running ehlo hook in helo.checks plugin 2019-01-04T15:18:33.167Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=bare_ip params=mail-wr1-f45.google.com retval=CONT msg="" 2019-01-04T15:18:33.167Z [DEBUG] [core] running ehlo hook in helo.checks plugin 2019-01-04T15:18:33.170Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=dynamic params=mail-wr1-f45.google.com retval=CONT msg="" 2019-01-04T15:18:33.170Z [DEBUG] [core] running ehlo hook in helo.checks plugin 2019-01-04T15:18:33.170Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=big_company params=mail-wr1-f45.google.com retval=CONT msg="" 2019-01-04T15:18:33.170Z [DEBUG] [core] running ehlo hook in helo.checks plugin 2019-01-04T15:18:33.171Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=literal_mismatch params=mail-wr1-f45.google.com retval=CONT msg="" 2019-01-04T15:18:33.171Z [DEBUG] [core] running ehlo hook in helo.checks plugin 2019-01-04T15:18:33.171Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=valid_hostname params=mail-wr1-f45.google.com retval=CONT msg="" 2019-01-04T15:18:33.171Z [DEBUG] [core] running ehlo hook in helo.checks plugin 2019-01-04T15:18:33.171Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=rdns_match params=mail-wr1-f45.google.com retval=CONT msg="" 2019-01-04T15:18:33.171Z [DEBUG] [core] running ehlo hook in helo.checks plugin 2019-01-04T15:18:33.172Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=forward_dns params=mail-wr1-f45.google.com retval=CONT msg="" 2019-01-04T15:18:33.172Z [DEBUG] [core] running ehlo hook in helo.checks plugin 2019-01-04T15:18:33.172Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=host_mismatch params=mail-wr1-f45.google.com retval=CONT msg="" 2019-01-04T15:18:33.172Z [DEBUG] [core] running ehlo hook in helo.checks plugin 2019-01-04T15:18:33.172Z [INFO] [helo.checks] helo_host: mail-wr1-f45.google.com, ips: 209.85.221.45, pass:match_re, bare_ip, dynamic, big_co(not), literal_mismatch, valid_hostname, rdns_match, forward_dns, host_mismatch 2019-01-04T15:18:33.172Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=emit_log params=mail-wr1-f45.google.com retval=CONT msg="" 2019-01-04T15:18:33.173Z [DEBUG] [core] running ehlo hook in spf plugin 2019-01-04T15:18:33.188Z [INFO] [spf] identity=helo ip=209.85.221.45 domain="mail-wr1-f45.google.com" mfrom=<postmaster@mail-wr1-f45.google.com> result=None 2019-01-04T15:18:33.189Z [INFO] [spf] scope: helo, result: None, domain: mail-wr1-f45.google.com 2019-01-04T15:18:33.189Z [DEBUG] [core] hook=ehlo plugin=spf function=hook_ehlo params=mail-wr1-f45.google.com retval=CONT msg="" 2019-01-04T15:18:33.189Z [DEBUG] [core] running ehlo hook in data.uribl plugin 2019-01-04T15:18:33.189Z [DEBUG] [data.uribl] (helo) found 1 items for lookup 2019-01-04T15:18:33.189Z [DEBUG] [data.uribl] (helo) checking: mail-wr1-f45.google.com 2019-01-04T15:18:33.190Z [DEBUG] [data.uribl] mail-wr1-f45.google.com.dbl.spamhaus.org. => (Error: queryA ENOTFOUND mail-wr1-f45.google.com.dbl.spamhaus.org.) 2019-01-04T15:18:33.190Z [DEBUG] [core] hook=ehlo plugin=data.uribl function=hook_ehlo params=mail-wr1-f45.google.com retval=CONT msg="" 2019-01-04T15:18:33.190Z [DEBUG] [core] running capabilities hooks 2019-01-04T15:18:33.190Z [DEBUG] [core] running capabilities hook in tls plugin 2019-01-04T15:18:33.190Z [DEBUG] [core] hook=capabilities plugin=tls function=advertise_starttls params="" retval=CONT msg="" 2019-01-04T15:18:33.191Z [DEBUG] [core] running capabilities hook in auth/poste plugin 2019-01-04T15:18:33.191Z [DEBUG] [core] hook=capabilities plugin=auth/poste function=hook_capabilities params="" retval=CONT msg="" 2019-01-04T15:18:33.191Z [PROTOCOL] [core] S: 250-email.example.one Hello mail-wr1-f45.google.com [209.85.221.45]Haraka is at your service. 2019-01-04T15:18:33.191Z [PROTOCOL] [core] S: 250-PIPELINING 2019-01-04T15:18:33.191Z [PROTOCOL] [core] S: 250-8BITMIME 2019-01-04T15:18:33.191Z [PROTOCOL] [core] S: 250-SMTPUTF8 2019-01-04T15:18:33.191Z [PROTOCOL] [core] S: 250-SIZE 524288000 2019-01-04T15:18:33.191Z [PROTOCOL] [core] S: 250 STARTTLS 2019-01-04T15:18:33.211Z [PROTOCOL] [core] C: STARTTLS state=1 2019-01-04T15:18:33.211Z [DEBUG] [core] running unrecognized_command hooks 2019-01-04T15:18:33.211Z [DEBUG] [core] running unrecognized_command hook in karma plugin 2019-01-04T15:18:33.212Z [DEBUG] [core] hook=unrecognized_command plugin=karma function=hook_unrecognized_command params=STARTTLS retval=CONT msg="" 2019-01-04T15:18:33.212Z [DEBUG] [core] running unrecognized_command hook in tls plugin 2019-01-04T15:18:33.212Z [PROTOCOL] [core] S: 220 Go ahead. 2019-01-04T15:18:33.269Z [INFO] [tls] secured: cipher=ECDHE-RSA-AES128-GCM-SHA256 version=TLSv1/SSLv3 verified=false cn="smtp.gmail.com" organization="Google LLC" issuer="Google Trust Services" expires="Mar 27 00:00:00 2019 GMT" fingerprint=AD:31:F1:EC:C2:E8:62:9C:ED:C2:D6:29:16:1D:03:DD:A3:4F:76:63 2019-01-04T15:18:33.269Z [INFO] [core] hook=unrecognized_command plugin=tls function=upgrade_connection params=STARTTLS retval=OK msg="" 2019-01-04T15:18:33.290Z [PROTOCOL] [core] C: EHLO mail-wr1-f45.google.com state=1 2019-01-04T15:18:33.292Z [DEBUG] [core] running ehlo hooks 2019-01-04T15:18:33.292Z [DEBUG] [core] running ehlo hook in hello_block plugin 2019-01-04T15:18:33.292Z [DEBUG] [core] hook=ehlo plugin=hello_block function=check_ymlf params=mail-wr1-f45.google.com retval=CONT msg="" 2019-01-04T15:18:33.292Z [DEBUG] [core] running ehlo hook in karma plugin 2019-01-04T15:18:33.292Z [DEBUG] [core] hook=ehlo plugin=karma function=hook_ehlo params=mail-wr1-f45.google.com retval=CONT msg="" 2019-01-04T15:18:33.292Z [DEBUG] [core] running ehlo hook in helo.checks plugin 2019-01-04T15:18:33.292Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=proto_mismatch_esmtp params=mail-wr1-f45.google.com retval=CONT msg="" 2019-01-04T15:18:33.292Z [DEBUG] [core] running ehlo hook in helo.checks plugin 2019-01-04T15:18:33.292Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=init params=mail-wr1-f45.google.com retval=CONT msg="" 2019-01-04T15:18:33.292Z [DEBUG] [core] running ehlo hook in helo.checks plugin 2019-01-04T15:18:33.292Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=match_re params=mail-wr1-f45.google.com retval=CONT msg="" 2019-01-04T15:18:33.292Z [DEBUG] [core] running ehlo hook in helo.checks plugin 2019-01-04T15:18:33.292Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=bare_ip params=mail-wr1-f45.google.com retval=CONT msg="" 2019-01-04T15:18:33.292Z [DEBUG] [core] running ehlo hook in helo.checks plugin 2019-01-04T15:18:33.292Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=dynamic params=mail-wr1-f45.google.com retval=CONT msg="" 2019-01-04T15:18:33.292Z [DEBUG] [core] running ehlo hook in helo.checks plugin 2019-01-04T15:18:33.292Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=big_company params=mail-wr1-f45.google.com retval=CONT msg="" 2019-01-04T15:18:33.292Z [DEBUG] [core] running ehlo hook in helo.checks plugin 2019-01-04T15:18:33.293Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=literal_mismatch params=mail-wr1-f45.google.com retval=CONT msg="" 2019-01-04T15:18:33.293Z [DEBUG] [core] running ehlo hook in helo.checks plugin 2019-01-04T15:18:33.293Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=valid_hostname params=mail-wr1-f45.google.com retval=CONT msg="" 2019-01-04T15:18:33.293Z [DEBUG] [core] running ehlo hook in helo.checks plugin 2019-01-04T15:18:33.293Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=rdns_match params=mail-wr1-f45.google.com retval=CONT msg="" 2019-01-04T15:18:33.293Z [DEBUG] [core] running ehlo hook in helo.checks plugin 2019-01-04T15:18:33.293Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=forward_dns params=mail-wr1-f45.google.com retval=CONT msg="" 2019-01-04T15:18:33.293Z [DEBUG] [core] running ehlo hook in helo.checks plugin 2019-01-04T15:18:33.293Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=host_mismatch params=mail-wr1-f45.google.com retval=CONT msg="" 2019-01-04T15:18:33.293Z [DEBUG] [core] running ehlo hook in helo.checks plugin 2019-01-04T15:18:33.293Z [INFO] [helo.checks] helo_host: mail-wr1-f45.google.com, ips: 209.85.221.45, multi: true, pass:match_re, bare_ip, dynamic, big_co(not), literal_mismatch, valid_hostname, rdns_match, forward_dns, host_mismatch, host_mismatch 2019-01-04T15:18:33.293Z [DEBUG] [core] hook=ehlo plugin=helo.checks function=emit_log params=mail-wr1-f45.google.com retval=CONT msg="" 2019-01-04T15:18:33.293Z [DEBUG] [core] running ehlo hook in spf plugin 2019-01-04T15:18:33.294Z [INFO] [spf] identity=helo ip=209.85.221.45 domain="mail-wr1-f45.google.com" mfrom=<postmaster@mail-wr1-f45.google.com> result=None 2019-01-04T15:18:33.294Z [INFO] [spf] scope: helo, result: None, domain: mail-wr1-f45.google.com 2019-01-04T15:18:33.294Z [DEBUG] [core] hook=ehlo plugin=spf function=hook_ehlo params=mail-wr1-f45.google.com retval=CONT msg="" 2019-01-04T15:18:33.294Z [DEBUG] [core] running ehlo hook in data.uribl plugin 2019-01-04T15:18:33.294Z [DEBUG] [data.uribl] (helo) found 1 items for lookup 2019-01-04T15:18:33.294Z [DEBUG] [data.uribl] (helo) checking: mail-wr1-f45.google.com 2019-01-04T15:18:33.295Z [DEBUG] [data.uribl] mail-wr1-f45.google.com.dbl.spamhaus.org. => (Error: queryA ENOTFOUND mail-wr1-f45.google.com.dbl.spamhaus.org.) 2019-01-04T15:18:33.295Z [DEBUG] [core] hook=ehlo plugin=data.uribl function=hook_ehlo params=mail-wr1-f45.google.com retval=CONT msg="" 2019-01-04T15:18:33.295Z [DEBUG] [core] running capabilities hooks 2019-01-04T15:18:33.295Z [DEBUG] [core] running capabilities hook in tls plugin 2019-01-04T15:18:33.295Z [DEBUG] [core] hook=capabilities plugin=tls function=advertise_starttls params="" retval=CONT msg="" 2019-01-04T15:18:33.295Z [DEBUG] [core] running capabilities hook in auth/poste plugin 2019-01-04T15:18:33.295Z [DEBUG] [core] hook=capabilities plugin=auth/poste function=hook_capabilities params="" retval=CONT msg="" 2019-01-04T15:18:33.295Z [PROTOCOL] [core] S: 250-email.example.one Hello mail-wr1-f45.google.com [209.85.221.45]Haraka is at your service. 2019-01-04T15:18:33.295Z [PROTOCOL] [core] S: 250-PIPELINING 2019-01-04T15:18:33.295Z [PROTOCOL] [core] S: 250-8BITMIME 2019-01-04T15:18:33.295Z [PROTOCOL] [core] S: 250-SMTPUTF8 2019-01-04T15:18:33.295Z [PROTOCOL] [core] S: 250-SIZE 524288000 2019-01-04T15:18:33.295Z [PROTOCOL] [core] S: 250 AUTH PLAIN LOGIN 2019-01-04T15:18:33.316Z [PROTOCOL] [core] C: MAIL FROM:<sender.from@gmail.com> SIZE=2061 state=1 2019-01-04T15:18:33.357Z [TX] [core] #88E585D3-430D-4299-8A82-500C73E586E8.1 Transaction 2019-01-04T15:18:33.317Z [DEBUG] [core] running mail hooks 2019-01-04T15:18:33.317Z [DEBUG] [core] running mail hook in limits plugin 2019-01-04T15:18:33.317Z [DEBUG] [core] hook=mail plugin=limits function=hook_mail params=<sender.from@gmail.com> retval=CONT msg="" 2019-01-04T15:18:33.317Z [DEBUG] [core] running mail hook in karma plugin 2019-01-04T15:18:33.317Z [DEBUG] [core] hook=mail plugin=karma function=hook_mail params=<sender.from@gmail.com> retval=CONT msg="" 2019-01-04T15:18:33.317Z [DEBUG] [core] running mail hook in mail_from.is_resolvable plugin 2019-01-04T15:18:33.318Z [DEBUG] [core] pipeline: RCPT TO:<hello@example.one>\r 2019-01-04T15:18:33.318Z [DEBUG] [core] pipeline: RCPT TO:<hello@example.one>\r 2019-01-04T15:18:33.319Z [DEBUG] [mail_from.is_resolvable] gmail.com: MX 20 alt2.gmail-smtp-in.l.google.com => 74.125.24.27,2404:6800:4003:c03::1a 2019-01-04T15:18:33.320Z [DEBUG] [mail_from.is_resolvable] gmail.com: MX 30 alt3.gmail-smtp-in.l.google.com => 108.177.125.26,2404:6800:4008:c01::1a 2019-01-04T15:18:33.320Z [DEBUG] [mail_from.is_resolvable] gmail.com: MX 10 alt1.gmail-smtp-in.l.google.com => 108.177.14.27,2a00:1450:4010:c0f::1b 2019-01-04T15:18:33.320Z [DEBUG] [mail_from.is_resolvable] gmail.com: MX 5 gmail-smtp-in.l.google.com => 2a00:1450:400c:c00::1b,108.177.15.27 2019-01-04T15:18:33.320Z [DEBUG] [mail_from.is_resolvable] gmail.com: MX 40 alt4.gmail-smtp-in.l.google.com => 2607:f8b0:400e:c09::1b,74.125.195.27 2019-01-04T15:18:33.320Z [DEBUG] [mail_from.is_resolvable] gmail.com: 74.125.24.27,2404:6800:4003:c03::1a,108.177.125.26,2404:6800:4008:c01::1a,108.177.14.27,2a00:1450:4010:c0f::1b,2a00:1450:400c:c00::1b,108.177.15.27,2607:f8b0:400e:c09::1b,74.125.195.27 2019-01-04T15:18:33.320Z [DEBUG] [core] hook=mail plugin=mail_from.is_resolvable function=hook_mail params=<sender.from@gmail.com> retval=CONT msg="" 2019-01-04T15:18:33.320Z [DEBUG] [core] running mail hook in spf plugin 2019-01-04T15:18:33.326Z [INFO] [spf] identity=mfrom ip=209.85.221.45 domain="gmail.com" mfrom=<sender.from@gmail.com> result=Pass 2019-01-04T15:18:33.326Z [INFO] [spf] scope: mfrom, result: Pass, domain: gmail.com 2019-01-04T15:18:33.327Z [DEBUG] [core] hook=mail plugin=spf function=hook_mail params=<sender.from@gmail.com> retval=CONT msg="" 2019-01-04T15:18:33.327Z [DEBUG] [core] running mail hook in data.uribl plugin 2019-01-04T15:18:33.327Z [DEBUG] [data.uribl] (envfrom) found 1 items for lookup 2019-01-04T15:18:33.327Z [DEBUG] [data.uribl] (envfrom) checking: gmail.com 2019-01-04T15:18:33.354Z [DEBUG] [data.uribl] gmail.com.dbl.spamhaus.org. => (Error: queryA ENOTFOUND gmail.com.dbl.spamhaus.org.) 2019-01-04T15:18:33.355Z [DEBUG] [core] hook=mail plugin=data.uribl function=hook_mail params=<sender.from@gmail.com> retval=CONT msg="" 2019-01-04T15:18:33.355Z [DEBUG] [core] running mail hook in known-senders plugin 2019-01-04T15:18:33.355Z [DEBUG] [known-senders] [ 'mail-wr1-f45.google.com' ] 2019-01-04T15:18:33.355Z [INFO] [known-senders] sender: gmail.com, auth: spf 2019-01-04T15:18:33.355Z [DEBUG] [known-senders] +spf: gmail.com 2019-01-04T15:18:33.355Z [DEBUG] [core] hook=mail plugin=known-senders function=is_authenticated params=<sender.from@gmail.com> retval=CONT msg="" 2019-01-04T15:18:33.355Z [DEBUG] [core] running mail hook in bounce plugin 2019-01-04T15:18:33.355Z [DEBUG] [core] hook=mail plugin=bounce function=reject_all params=<sender.from@gmail.com> retval=CONT msg="" 2019-01-04T15:18:33.355Z [DEBUG] [core] running mail hook in log plugin 2019-01-04T15:18:33.355Z [PROTOCOL] [core] C: MAIL FROM:<sender.from@gmail.com> SIZE=2061 state=1 2019-01-04T15:18:33.357Z [DEBUG] [core] hook=mail plugin=log function=hook_mail params=<sender.from@gmail.com> retval=CONT msg="" 2019-01-04T15:18:33.357Z [DEBUG] [core] running mail hook in dovecot_quota plugin 2019-01-04T15:18:33.357Z [DEBUG] [core] hook=mail plugin=dovecot_quota function=hook_mail params=<sender.from@gmail.com> retval=CONT msg="" 2019-01-04T15:18:33.357Z [NOTICE] [core] sender <sender.from@gmail.com> code=CONT msg="" 2019-01-04T15:18:33.358Z [PROTOCOL] [core] S: 250 sender <sender.from@gmail.com> OK 2019-01-04T15:18:33.358Z [PROTOCOL] [core] C: RCPT TO:<hello@example.one> state=1 2019-01-04T15:18:33.358Z [DEBUG] [core] running rcpt hooks 2019-01-04T15:18:33.358Z [DEBUG] [core] running rcpt hook in srs plugin 2019-01-04T15:18:33.358Z [DEBUG] [core] hook=rcpt plugin=srs function=hook_rcpt params=<hello@example.one> retval=CONT msg="" 2019-01-04T15:18:33.358Z [DEBUG] [core] running rcpt hook in karma plugin 2019-01-04T15:18:33.358Z [DEBUG] [core] hook=rcpt plugin=karma function=hook_rcpt params=<hello@example.one> retval=CONT msg="" 2019-01-04T15:18:33.358Z [DEBUG] [core] running rcpt hook in rcpt_database plugin 2019-01-04T15:18:33.359Z [DEBUG] [core] pipeline: DATA\r 2019-01-04T15:18:33.360Z [INFO] [rcpt_database] rewriting to domain bin from hello@example.one to all@example.one 2019-01-04T15:18:33.360Z [INFO] [core] hook=rcpt plugin=rcpt_database function=database_rcpt params=<hello@example.one> retval=OK msg="" 2019-01-04T15:18:33.360Z [DEBUG] [core] running rcpt_ok hooks 2019-01-04T15:18:33.360Z [DEBUG] [core] running rcpt_ok hook in karma plugin 2019-01-04T15:18:33.360Z [DEBUG] [core] hook=rcpt_ok plugin=karma function=hook_rcpt_ok params="" retval=CONT msg="" 2019-01-04T15:18:33.360Z [DEBUG] [core] running rcpt_ok hook in known-senders plugin 2019-01-04T15:18:33.361Z [DEBUG] [known-senders] example.one : gmail.com : 1247 2019-01-04T15:18:33.361Z [DEBUG] [core] hook=rcpt_ok plugin=known-senders function=check_recipient params="" retval=CONT msg="" 2019-01-04T15:18:33.361Z [NOTICE] [core] recipient <all@example.one> code=OK msg="" sender=sender.from@gmail.com 2019-01-04T15:18:33.361Z [PROTOCOL] [core] S: 250 recipient <all@example.one> OK 2019-01-04T15:18:33.362Z [PROTOCOL] [core] C: DATA state=1 2019-01-04T15:18:33.362Z [DEBUG] [core] running data hooks 2019-01-04T15:18:33.362Z [DEBUG] [core] running data hook in karma plugin 2019-01-04T15:18:33.362Z [DEBUG] [core] hook=data plugin=karma function=hook_data params="" retval=CONT msg="" 2019-01-04T15:18:33.362Z [DEBUG] [core] running data hook in early_talker plugin 2019-01-04T15:18:36.363Z [DEBUG] [core] hook=data plugin=early_talker function=early_talker params="" retval=CONT msg="" 2019-01-04T15:18:36.363Z [DEBUG] [core] running data hook in fcrdns plugin 2019-01-04T15:18:36.363Z [DEBUG] [core] hook=data plugin=fcrdns function=add_message_headers params="" retval=CONT msg="" 2019-01-04T15:18:36.363Z [DEBUG] [core] running data hook in data.uribl plugin 2019-01-04T15:18:36.363Z [DEBUG] [core] hook=data plugin=data.uribl function=hook_data params="" retval=CONT msg="" 2019-01-04T15:18:36.363Z [DEBUG] [core] running data hook in bounce plugin 2019-01-04T15:18:36.363Z [DEBUG] [core] hook=data plugin=bounce function=single_recipient params="" retval=CONT msg="" 2019-01-04T15:18:36.364Z [DEBUG] [core] running data hook in bounce plugin 2019-01-04T15:18:36.364Z [DEBUG] [core] hook=data plugin=bounce function=bad_rcpt params="" retval=CONT msg="" 2019-01-04T15:18:36.364Z [DEBUG] [core] running data hook in bounce plugin 2019-01-04T15:18:36.364Z [DEBUG] [core] hook=data plugin=bounce function=bounce_spf_enable params="" retval=CONT msg="" 2019-01-04T15:18:36.364Z [DEBUG] [core] running data hook in attachment plugin 2019-01-04T15:18:36.364Z [DEBUG] [core] hook=data plugin=attachment function=hook_data params="" retval=CONT msg="" 2019-01-04T15:18:36.364Z [DEBUG] [core] running data hook in clamd plugin 2019-01-04T15:18:36.364Z [DEBUG] [core] hook=data plugin=clamd function=hook_data params="" retval=CONT msg="" 2019-01-04T15:18:36.364Z [DEBUG] [core] running data hook in inspection plugin 2019-01-04T15:18:36.364Z [DEBUG] [core] hook=data plugin=inspection function=hook_data params="" retval=CONT msg="" 2019-01-04T15:18:36.364Z [PROTOCOL] [core] S: 354 go ahead, make my day 2019-01-04T15:18:36.386Z [DEBUG] [core] running data_post hooks 2019-01-04T15:18:36.386Z [DEBUG] [core] running data_post hook in srs plugin 2019-01-04T15:18:36.386Z [DEBUG] [srs] Remote host (209.85.221.45), skipping 2019-01-04T15:18:36.386Z [DEBUG] [core] hook=data_post plugin=srs function=hook_data_post params="" retval=CONT msg="" 2019-01-04T15:18:36.387Z [DEBUG] [core] running data_post hook in karma plugin 2019-01-04T15:18:36.387Z [DEBUG] [karma] adding header: score: 9, good: 22, bad: 0, connections: 22, history: 22, awards: 006,089,131,280, asn_score: 2344, asn_connections: 2533, asn_good: 2369, asn_bad: 25, pass:all_good, asn:history, fail:cmd:(STARTTLS,), rcpt_to 2019-01-04T15:18:36.387Z [DEBUG] [core] hook=data_post plugin=karma function=hook_data_post params="" retval=CONT msg="" 2019-01-04T15:18:36.387Z [DEBUG] [core] running data_post hook in asn plugin 2019-01-04T15:18:36.387Z [DEBUG] [core] hook=data_post plugin=asn function=add_header_asn params="" retval=CONT msg="" 2019-01-04T15:18:36.387Z [DEBUG] [core] running data_post hook in p0f plugin 2019-01-04T15:18:36.387Z [DEBUG] [p0f] adding header 2019-01-04T15:18:36.387Z [DEBUG] [core] hook=data_post plugin=p0f function=add_p0f_header params="" retval=CONT msg="" 2019-01-04T15:18:36.387Z [DEBUG] [core] running data_post hook in geoip plugin 2019-01-04T15:18:36.387Z [INFO] [geoip] received=209.85.221.45 country=US 2019-01-04T15:18:36.387Z [DEBUG] [core] hook=data_post plugin=geoip function=add_headers params="" retval=CONT msg="" 2019-01-04T15:18:36.387Z [DEBUG] [core] running data_post hook in dkim_verify plugin 2019-01-04T15:18:36.388Z [DEBUG] [dkim_verify] [] 2019-01-04T15:18:36.388Z [DEBUG] [core] hook=data_post plugin=dkim_verify function=hook_data_post params="" retval=CONT msg="" 2019-01-04T15:18:36.388Z [DEBUG] [core] running data_post hook in data.uribl plugin 2019-01-04T15:18:36.389Z [DEBUG] [data.uribl] (from) found 1 items for lookup 2019-01-04T15:18:36.389Z [DEBUG] [data.uribl] (from) checking: me.com 2019-01-04T15:18:36.425Z [DEBUG] [data.uribl] me.com.dbl.spamhaus.org. => (Error: queryA ENOTFOUND me.com.dbl.spamhaus.org.) 2019-01-04T15:18:36.425Z [DEBUG] [data.uribl] (msgid) found 1 items for lookup 2019-01-04T15:18:36.425Z [DEBUG] [data.uribl] (msgid) checking: mail.gmail.com 2019-01-04T15:18:36.459Z [DEBUG] [data.uribl] mail.gmail.com.dbl.spamhaus.org. => (Error: queryA ENOTFOUND mail.gmail.com.dbl.spamhaus.org.) 2019-01-04T15:18:36.459Z [DEBUG] [data.uribl] (body) no items found for lookup 2019-01-04T15:18:36.459Z [DEBUG] [core] hook=data_post plugin=data.uribl function=hook_data_post params="" retval=CONT msg="" 2019-01-04T15:18:36.459Z [DEBUG] [core] running data_post hook in data.headers plugin 2019-01-04T15:18:36.459Z [DEBUG] [core] hook=data_post plugin=data.headers function=duplicate_singular params="" retval=CONT msg="" 2019-01-04T15:18:36.459Z [DEBUG] [core] running data_post hook in data.headers plugin 2019-01-04T15:18:36.459Z [DEBUG] [core] hook=data_post plugin=data.headers function=missing_required params="" retval=CONT msg="" 2019-01-04T15:18:36.459Z [DEBUG] [core] running data_post hook in data.headers plugin 2019-01-04T15:18:36.459Z [DEBUG] [data.headers] message date: Fri, 4 Jan 2019 16:18:04 +0100 2019-01-04T15:18:36.460Z [DEBUG] [core] hook=data_post plugin=data.headers function=invalid_date params="" retval=CONT msg="" 2019-01-04T15:18:36.460Z [DEBUG] [core] running data_post hook in data.headers plugin 2019-01-04T15:18:36.460Z [DEBUG] [core] hook=data_post plugin=data.headers function=invalid_return_path params="" retval=CONT msg="" 2019-01-04T15:18:36.460Z [DEBUG] [core] running data_post hook in data.headers plugin 2019-01-04T15:18:36.460Z [DEBUG] [core] hook=data_post plugin=data.headers function=user_agent params="" retval=CONT msg="" 2019-01-04T15:18:36.460Z [DEBUG] [core] running data_post hook in data.headers plugin 2019-01-04T15:18:36.460Z [DEBUG] [core] hook=data_post plugin=data.headers function=direct_to_mx params="" retval=CONT msg="" 2019-01-04T15:18:36.460Z [DEBUG] [core] running data_post hook in data.headers plugin 2019-01-04T15:18:36.461Z [INFO] [data.headers] pass:duplicate, missing, invalid_date, Return-Path, direct-to-mx(2), fail:UA, from_match(gmail.com / me.com) 2019-01-04T15:18:36.461Z [DEBUG] [core] hook=data_post plugin=data.headers function=from_match params="" retval=CONT msg="" 2019-01-04T15:18:36.461Z [DEBUG] [core] running data_post hook in data.headers plugin 2019-01-04T15:18:36.461Z [DEBUG] [core] hook=data_post plugin=data.headers function=delivered_to params="" retval=CONT msg="" 2019-01-04T15:18:36.461Z [DEBUG] [core] running data_post hook in data.headers plugin 2019-01-04T15:18:36.462Z [DEBUG] [core] hook=data_post plugin=data.headers function=mailing_list params="" retval=CONT msg="" 2019-01-04T15:18:36.462Z [DEBUG] [core] running data_post hook in known-senders plugin 2019-01-04T15:18:36.462Z [INFO] [known-senders] is_dkim_authenticated: no dkim_verify results 2019-01-04T15:18:36.462Z [DEBUG] [core] hook=data_post plugin=known-senders function=is_dkim_authenticated params="" retval=CONT msg="" 2019-01-04T15:18:36.462Z [DEBUG] [core] running data_post hook in bounce plugin 2019-01-04T15:18:36.462Z [DEBUG] [core] hook=data_post plugin=bounce function=empty_return_path params="" retval=CONT msg="" 2019-01-04T15:18:36.462Z [DEBUG] [core] running data_post hook in bounce plugin 2019-01-04T15:18:36.462Z [DEBUG] [core] hook=data_post plugin=bounce function=bounce_spf params="" retval=CONT msg="" 2019-01-04T15:18:36.462Z [DEBUG] [core] running data_post hook in bounce plugin 2019-01-04T15:18:36.462Z [DEBUG] [core] hook=data_post plugin=bounce function=non_local_msgid params="" retval=CONT msg="" 2019-01-04T15:18:36.462Z [DEBUG] [core] running data_post hook in dovecot_quota plugin 2019-01-04T15:18:36.464Z [DEBUG] [dovecot_quota] sent: request=smtpd_access_policy\nsender=sender.from@gmail.com\nrecipient=all@example.one\nsize=2064\n\n 2019-01-04T15:18:36.474Z [DEBUG] [dovecot_quota] received: action=OK 2019-01-04T15:18:36.476Z [DEBUG] [core] hook=data_post plugin=dovecot_quota function=hook_data_post params="" retval=CONT msg="" 2019-01-04T15:18:36.476Z [DEBUG] [core] running data_post hook in rspamd plugin 2019-01-04T15:18:36.993Z [INFO] [rspamd] action: no action, is_skipped: false, required_score: 15, score: -4.344592, time: 0.516 2019-01-04T15:18:36.993Z [DEBUG] [core] hook=data_post plugin=rspamd function=hook_data_post params="" retval=CONT msg="" 2019-01-04T15:18:36.993Z [DEBUG] [core] running data_post hook in attachment plugin 2019-01-04T15:18:36.993Z [DEBUG] [core] hook=data_post plugin=attachment function=wait_for_attachment_hooks params="" retval=CONT msg="" 2019-01-04T15:18:36.993Z [DEBUG] [core] running data_post hook in attachment plugin 2019-01-04T15:18:36.994Z [DEBUG] [attachment] found content type: multipart/alternative 2019-01-04T15:18:36.994Z [DEBUG] [attachment] found content type: text/plain 2019-01-04T15:18:36.994Z [DEBUG] [attachment] found content type: text/html 2019-01-04T15:18:36.994Z [DEBUG] [core] hook=data_post plugin=attachment function=check_attachments params="" retval=CONT msg="" 2019-01-04T15:18:36.994Z [DEBUG] [core] running data_post hook in clamd plugin 2019-01-04T15:18:36.994Z [DEBUG] [clamd] trying host: /var/run/clamav/clamd.ctl 2019-01-04T15:18:36.996Z [DEBUG] [clamd] connected to host undefined:undefined 2019-01-04T15:18:37.020Z [PROTOCOL] [clamd] C:stream: OK 2019-01-04T15:18:37.020Z [INFO] [clamd] pass:clean 2019-01-04T15:18:37.021Z [DEBUG] [core] hook=data_post plugin=clamd function=hook_data_post params="" retval=CONT msg="" 2019-01-04T15:18:37.021Z [DEBUG] [core] running data_post hook in strict_from plugin 2019-01-04T15:18:37.021Z [DEBUG] [strict_from] skiping non-authenticated user 2019-01-04T15:18:37.021Z [DEBUG] [core] hook=data_post plugin=strict_from function=from_check params="" retval=CONT msg="" 2019-01-04T15:18:37.021Z [DEBUG] [core] running data_post hook in inspection plugin 2019-01-04T15:18:37.021Z [INFO] [inspection] Subject: TEST 2019-01-04T15:18:37.021Z [DEBUG] [core] hook=data_post plugin=inspection function=hook_data_post params="" retval=CONT msg="" 2019-01-04T15:18:37.021Z [DEBUG] [core] running data_post hook in queue/quarantine-decide plugin 2019-01-04T15:18:37.021Z [INFO] [core] hook=data_post plugin=queue/quarantine-decide function=hook_data_post params="" retval=OK msg="" 2019-01-04T15:18:37.021Z [NOTICE] [core] message mid=<CABJeWfNOYeHOeTLY9fmLCk_QD2yGvw-eN58hki8721-kq6s2tA@mail.gmail.com> size=2064 rcpts=1/0/0 delay=0.635 code=OK msg="" 2019-01-04T15:18:37.021Z [DEBUG] [core] running queue hooks 2019-01-04T15:18:37.021Z [DEBUG] [core] running queue hook in karma plugin 2019-01-04T15:18:37.021Z [DEBUG] [core] hook=queue plugin=karma function=hook_queue params="" retval=CONT msg="" 2019-01-04T15:18:37.021Z [DEBUG] [core] running queue hook in queue/dmarc-report-save plugin 2019-01-04T15:18:37.022Z [DEBUG] [core] hook=queue plugin=queue/dmarc-report-save function=hook_queue params="" retval=CONT msg="" 2019-01-04T15:18:37.022Z [DEBUG] [core] running queue hook in queue/quarantine plugin 2019-01-04T15:18:37.022Z [DEBUG] [core] hook=queue plugin=queue/quarantine function=quarantine params="" retval=CONT msg="" 2019-01-04T15:18:37.022Z [DEBUG] [core] running queue hook in queue/lmtp plugin 2019-01-04T15:18:37.041Z [INFO] [core] hook=queue plugin=queue/lmtp function=hook_queue params="" retval=OK msg="Message Queued (88E585D3-430D-4299-8A82-500C73E586E8.1)" 2019-01-04T15:18:37.041Z [DEBUG] [core] running queue_ok hooks 2019-01-04T15:18:37.041Z [DEBUG] [core] running queue_ok hook in limits plugin 2019-01-04T15:18:37.042Z [DEBUG] [core] hook=queue_ok plugin=limits function=hook_queue_ok params="Message Queued (88E585D3-430D-4299-8A82-500C73E586E8.1) (88E585D3-430D-4299-8A82-500C73E586E8.1)" retval=CONT msg="" 2019-01-04T15:18:37.042Z [DEBUG] [core] running queue_ok hook in stats plugin 2019-01-04T15:18:37.047Z [DEBUG] [core] hook=queue_ok plugin=stats function=hook_queue_ok params="Message Queued (88E585D3-430D-4299-8A82-500C73E586E8.1) (88E585D3-430D-4299-8A82-500C73E586E8.1)" retval=CONT msg="" 2019-01-04T15:18:37.047Z [DEBUG] [core] running queue_ok hook in block_bad_connections plugin 2019-01-04T15:18:37.047Z [DEBUG] [core] hook=queue_ok plugin=block_bad_connections function=hook_queue_ok params="Message Queued (88E585D3-430D-4299-8A82-500C73E586E8.1) (88E585D3-430D-4299-8A82-500C73E586E8.1)" retval=CONT msg="" 2019-01-04T15:18:37.047Z [DEBUG] [core] running queue_ok hook in known-senders plugin 2019-01-04T15:18:37.047Z [DEBUG] [core] hook=queue_ok plugin=known-senders function=update_sender params="Message Queued (88E585D3-430D-4299-8A82-500C73E586E8.1) (88E585D3-430D-4299-8A82-500C73E586E8.1)" retval=CONT msg="" 2019-01-04T15:18:37.047Z [DEBUG] [core] running queue_ok hook in queue/quarantine-decide plugin 2019-01-04T15:18:37.047Z [DEBUG] [core] hook=queue_ok plugin=queue/quarantine-decide function=hook_queue_ok params="Message Queued (88E585D3-430D-4299-8A82-500C73E586E8.1) (88E585D3-430D-4299-8A82-500C73E586E8.1)" retval=CONT msg="" 2019-01-04T15:18:37.047Z [DEBUG] [core] running queue_ok hook in watch plugin 2019-01-04T15:18:37.047Z [DEBUG] [core] hook=queue_ok plugin=watch function=queue_ok params="Message Queued (88E585D3-430D-4299-8A82-500C73E586E8.1) (88E585D3-430D-4299-8A82-500C73E586E8.1)" retval=CONT msg="" 2019-01-04T15:18:37.047Z [NOTICE] [core] queue code=OK msg="Message Queued (88E585D3-430D-4299-8A82-500C73E586E8.1) (88E585D3-430D-4299-8A82-500C73E586E8.1)" 2019-01-04T15:18:37.047Z [PROTOCOL] [core] S: 250 Message Queued (88E585D3-430D-4299-8A82-500C73E586E8.1) (88E585D3-430D-4299-8A82-500C73E586E8.1) 2019-01-04T15:18:37.048Z [DEBUG] [core] running reset_transaction hooks 2019-01-04T15:18:37.048Z [DEBUG] [core] running reset_transaction hook in stats plugin 2019-01-04T15:18:37.048Z [DEBUG] [core] hook=reset_transaction plugin=stats function=hook_reset_transaction params="" retval=CONT msg="" 2019-01-04T15:18:37.048Z [DEBUG] [core] running reset_transaction hook in karma plugin 2019-01-04T15:18:37.049Z [INFO] [karma] score: 8, good: 22, bad: 0, connections: 22, history: 22, awards: 006,089,131,280,183,181,232, asn_score: 2344, asn_connections: 2533, asn_good: 2369, asn_bad: 25, pass:all_good, asn:history, fail:cmd:(STARTTLS,), rcpt_to 2019-01-04T15:18:37.049Z [DEBUG] [core] hook=reset_transaction plugin=karma function=hook_reset_transaction params="" retval=CONT msg="" 2019-01-04T15:18:37.049Z [DEBUG] [core] running reset_transaction hook in log plugin 2019-01-04T15:18:37.055Z [DEBUG] [core] hook=reset_transaction plugin=log function=hook_reset_transaction params="" retval=CONT msg="" 2019-01-04T15:18:37.068Z [PROTOCOL] [core] C: QUIT state=1 2019-01-04T15:18:37.068Z [DEBUG] [core] running quit hooks 2019-01-04T15:18:37.068Z [PROTOCOL] [core] S: 221 email.example.one closing connection. Have a jolly good day. 2019-01-04T15:18:37.068Z [DEBUG] [core] client has disconnected 2019-01-04T15:18:37.068Z [DEBUG] [core] running disconnect hooks 2019-01-04T15:18:37.069Z [DEBUG] [core] client has disconnected 2019-01-04T15:18:37.070Z [DEBUG] [core] running disconnect hook in stats plugin 2019-01-04T15:18:37.071Z [DEBUG] [core] client has disconnected 2019-01-04T15:18:37.071Z [INFO] [core] hook=disconnect plugin=stats function=hook_disconnect params="" retval=OK msg="" 2019-01-04T15:18:37.071Z [DEBUG] [core] client has disconnected 2019-01-04T15:18:37.071Z [DEBUG] [core] running disconnect hook in block_bad_connections plugin 2019-01-04T15:18:37.071Z [DEBUG] [core] client has disconnected 2019-01-04T15:18:37.071Z [INFO] [core] hook=disconnect plugin=block_bad_connections function=hook_disconnect params="" retval=OK msg="" 2019-01-04T15:18:37.071Z [DEBUG] [core] client has disconnected 2019-01-04T15:18:37.071Z [DEBUG] [core] running disconnect hook in karma plugin 2019-01-04T15:18:37.071Z [INFO] [karma] score: 8, good: 22, bad: 0, connections: 22, history: 22, awards: 006,089,131,280,183,181,232, asn_score: 2344, asn_connections: 2533, asn_good: 2369, asn_bad: 25, pass:all_good, asn:history, fail:cmd:(STARTTLS,), rcpt_to 2019-01-04T15:18:37.071Z [DEBUG] [core] client has disconnected 2019-01-04T15:18:37.071Z [DEBUG] [core] hook=disconnect plugin=karma function=hook_disconnect params="" retval=CONT msg="" 2019-01-04T15:18:37.071Z [DEBUG] [core] client has disconnected 2019-01-04T15:18:37.071Z [DEBUG] [core] running disconnect hook in log plugin 2019-01-04T15:18:37.071Z [DEBUG] [core] client has disconnected 2019-01-04T15:18:37.071Z [INFO] [core] hook=disconnect plugin=log function=hook_disconnect params="" retval=OK msg="" 2019-01-04T15:18:37.071Z [DEBUG] [core] client has disconnected 2019-01-04T15:18:37.071Z [DEBUG] [core] running disconnect hook in tls plugin 2019-01-04T15:18:37.071Z [DEBUG] [core] client has disconnected 2019-01-04T15:18:37.071Z [DEBUG] [core] hook=disconnect plugin=tls function=hook_disconnect params="" retval=CONT msg="" 2019-01-04T15:18:37.071Z [NOTICE] [core] disconnect ip=209.85.221.45 rdns=mail-wr1-f45.google.com helo=mail-wr1-f45.google.com relay=N early=N esmtp=Y tls=Y pipe=Y errors=0 txns=1 rcpts=1/0/0 msgs=1/0/0 bytes=2064 lr="" time=7.242 2019-01-04T15:18:37.072Z [DEBUG] [karma] unsubsubscribed from result-88E585D3-430D-4299-8A82-500C73E586E8* Delivery 2019-01-04T15:18:37.043Z [1] [DEBUG] [outbound] running send_email hooks 2019-01-04T15:18:37.043Z [1] [DEBUG] [outbound] Sending mail: 1546615117026_1546615117026_0_1239_bZ4zWv_1412_email.example.hosting 2019-01-04T15:18:37.043Z [1] [DEBUG] [outbound] running get_mx hooks 2019-01-04T15:18:37.043Z [1] [DEBUG] [outbound] running get_mx hook in relay plugin 2019-01-04T15:18:37.043Z [1] [DEBUG] [outbound] hook=get_mx plugin=relay function=force_routing params=example.one retval=CONT msg="" 2019-01-04T15:18:37.043Z [1] [DEBUG] [outbound] running get_mx hook in rcpt_database plugin 2019-01-04T15:18:37.045Z [1] [INFO] [outbound] hook=get_mx plugin=rcpt_database function=hook_get_mx params=example.one retval=OK msg="[object Object]" 2019-01-04T15:18:37.045Z [1] [DEBUG] [outbound] Got an MX from Plugin: example.one => 0 [object Object] 2019-01-04T15:18:37.045Z [1] [INFO] [outbound] Attempting to deliver to: 127.0.0.1:24 using LMTP (0) (0) 2019-01-04T15:18:37.053Z [1] [PROTOCOL] [outbound] S: 220 email.example.one Dovecot ready.\r\n 2019-01-04T15:18:37.053Z [1] [PROTOCOL] [outbound] C: LHLO email.example.one 2019-01-04T15:18:37.053Z [1] [PROTOCOL] [outbound] S: 250-email.example.one\r\n 2019-01-04T15:18:37.053Z [1] [PROTOCOL] [outbound] S: 250-8BITMIME\r\n 2019-01-04T15:18:37.053Z [1] [PROTOCOL] [outbound] S: 250-ENHANCEDSTATUSCODES\r\n 2019-01-04T15:18:37.053Z [1] [PROTOCOL] [outbound] S: 250 PIPELINING\r\n 2019-01-04T15:18:37.053Z [1] [PROTOCOL] [outbound] C: MAIL FROM:<sender.from@gmail.com> 2019-01-04T15:18:37.053Z [1] [PROTOCOL] [outbound] S: 250 2.1.0 OK\r\n 2019-01-04T15:18:37.054Z [1] [PROTOCOL] [outbound] C: RCPT TO:<all@example.one> 2019-01-04T15:18:37.063Z [1] [PROTOCOL] [outbound] S: 250 2.1.5 OK\r\n 2019-01-04T15:18:37.063Z [1] [PROTOCOL] [outbound] C: DATA 2019-01-04T15:18:37.064Z [1] [PROTOCOL] [outbound] S: 354 OK\r\n 2019-01-04T15:18:37.064Z [1] [PROTOCOL] [outbound] C: . 2019-01-04T15:18:37.093Z [1] [PROTOCOL] [outbound] S: 250 2.0.0 <all@example.one> kFAwA015L1yzhAAAPY/GGA Saved\r\n 2019-01-04T15:18:37.093Z [1] [NOTICE] [outbound] delivered file=1546615117026_1546615117026_0_1239_bZ4zWv_1412_email.example.hosting domain=example.one host=127.0.0.1 ip=127.0.0.1 port=24 mode=LMTP tls=N auth=N response="<all@example.one> kFAwA015L1yzhAAAPY/GGA Saved" delay=0.067 fails=0 rcpts=1/0/0 2019-01-04T15:18:37.093Z [1] [DEBUG] [outbound] running delivered hooks 2019-01-04T15:18:37.093Z [1] [DEBUG] [outbound] running delivered hook in stats plugin 2019-01-04T15:18:37.094Z [1] [PROTOCOL] [outbound] C: QUIT 2019-01-04T15:18:37.095Z [1] [DEBUG] [outbound] hook=delivered plugin=stats function=hook_delivered params=127.0.0.1 retval=CONT msg="" -
Hi, I have the same problem. I even have the problem if I want to redirect or copy emails to only one email address. Also I have the problem, that clients have the same problem and mails are not delivered to the right place.
When checking the delivery log I only can find the logs for the initial email (bin@example.com) but the redirected mail (mail1@example.com) is not inside the delivery log. So it seems like the redirection is not working properly…
I also checked if this problem exists only for domain bin emails, but unfortunately it also does not work with a normal email address I want to redirect or an alias (Create redirect). So mail1@example.com will not be redirected to mail1@gmail.com, ….
If necessary I could send you a delivery log via email… But as I can see it, the mail is delivered to this email which should redirect and than the progress stops.
Quick help is needed, as the clients are getting furios :S
-
how many redirect under all@ ?
-
In my case there are two redirects, so two different mails.
-
Okay I found the bug. Setting up "0" to "Maximum number of actions (0 = unlimited)" and "Maximum number of redirect actions (0 = unlimited)" doesn't mean unlimited unfortunately.
After setting this value to a value > 0 everything works fine! :)
-
Great you found it!
0 for unlimited does not work - even when I edited config before the sieve max actions update it still didn't work then.
-
repo owner
Argh. The maximum number of redirect actions that can be performed during a single script execution. The meaning of 0 differs based on your version. For versions v0.3.0 and beyond this means that redirect is prohibited. For older versions, however, this means that the number of redirects is unlimited, so be careful.
-
repo owner
- changed status to resolved
fix
#562remove "0 = unlimited" notice from label→ <<cset 192b83acd163>>
- Log in to comment
So you have domain bin (example-bin.com) directed to something like bin@example.com where is redirection to mail1@example.com, mail2@example.com?