Error : said: 451 4.4.0 detected undelivered mail to <address>

bougatoyta

Member
Jun 8, 2021
71
7
13
35
Hi,

I have some trouble with specific email from a marketing sending.

Here's the output of tracking center

Code:
Jan 12 16:12:55 myHostname postfix/qmgr[907477]: C3B9A3E75: from=<sender>, size=28757, nrcpt=1 (queue active)
Jan 12 16:12:57 myHostname postfix/lmtp[1079237]: C3B9A3E75: to=<recipient>, relay=127.0.0.1[127.0.0.1]:10024, delay=25972, delays=25970/0/0.04/1.3, dsn=4.4.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.4.0 detected undelivered mail to <recipient> (in reply to end of DATA command))
Jan 12 16:24:51 myHostname postfix/qmgr[907477]: C3B9A3E75: from=<sender>, size=28757, nrcpt=1 (queue active)
Jan 12 16:25:04 myHostname postfix/lmtp[1079645]: C3B9A3E75: to=<recipient>, relay=127.0.0.1[127.0.0.1]:10024, delay=26699, delays=26686/3.8/0.43/8.2, dsn=4.4.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.4.0 detected undelivered mail to <recipient> (in reply to end of DATA command))
Jan 12 16:36:35 myHostname postfix/qmgr[907477]: C3B9A3E75: from=<sender>, size=28757, nrcpt=1 (queue active)
Jan 12 16:36:44 myHostname postfix/lmtp[1081923]: C3B9A3E75: to=<recipient>, relay=127.0.0.1[127.0.0.1]:10024, delay=27399, delays=27390/4/0.39/4.8, dsn=4.4.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.4.0 detected undelivered mail to <recipient> (in reply to end of DATA command))

I never had this issue before, does anyone know what can be wrong ?

Last version of PMG with all packages up-to-date.
 
I have some trouble with specific email from a marketing sending.

Here's the output of tracking center
please post more logs - especially lines from pmg-smtp-filter and the other postfix services
 
So here's a log output for a specific mail (see below for smtp-filter)

Code:
Jan 12 10:00:10 myHostname postfix/smtpd[1042521]: 18AE93F85: client=mta-domain.com[mta-IP]
Jan 12 10:00:10 myHostname postfix/cleanup[1043701]: 18AE93F85: message-id=<1422208.514456842.1255761118063110328.1673514008835.@news.espace-emeraude.com>
Jan 12 10:00:10 myHostname postfix/qmgr[907477]: 18AE93F85: from=<sender>, size=8507, nrcpt=1 (queue active)
Jan 12 10:00:13 myHostname postfix/lmtp[1041448]: 18AE93F85: to=<recipient>, relay=127.0.0.1[127.0.0.1]:10024, delay=3.9, delays=0.03/2/0.33/1.5, dsn=4.4.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.4.0 detected undelivered mail to <recipient> (in reply to end of DATA command))
Jan 12 10:07:55 myHostname postfix/qmgr[907477]: 18AE93F85: from=<sender>, size=8507, nrcpt=1 (queue active)
Jan 12 10:08:00 myHostname postfix/lmtp[1039968]: 18AE93F85: to=<recipient>, relay=127.0.0.1[127.0.0.1]:10024, delay=470, delays=465/3.1/0.56/1.6, dsn=4.4.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.4.0 detected undelivered mail to <recipient> (in reply to end of DATA command))
Jan 12 10:17:55 myHostname postfix/qmgr[907477]: 18AE93F85: from=<sender>, size=8507, nrcpt=1 (queue active)
Jan 12 10:18:01 myHostname postfix/lmtp[1046035]: 18AE93F85: to=<recipient>, relay=127.0.0.1[127.0.0.1]:10024, delay=1071, delays=1065/3.6/0.38/2.1, dsn=4.4.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.4.0 detected undelivered mail to <recipient> (in reply to end of DATA command))
Jan 12 10:37:55 myHostname postfix/qmgr[907477]: 18AE93F85: from=<sender>, size=8507, nrcpt=1 (queue active)
Jan 12 10:38:01 myHostname postfix/lmtp[1047228]: 18AE93F85: to=<recipient>, relay=127.0.0.1[127.0.0.1]:10024, delay=2271, delays=2265/3.4/0.49/1.9, dsn=4.4.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.4.0 detected undelivered mail to <recipient> (in reply to end of DATA command))
Jan 12 11:17:55 myHostname postfix/qmgr[907477]: 18AE93F85: from=<sender>, size=8507, nrcpt=1 (queue active)
Jan 12 11:18:01 myHostname postfix/lmtp[1052402]: 18AE93F85: to=<recipient>, relay=127.0.0.1[127.0.0.1]:10024, delay=4671, delays=4666/3/0.6/2.2, dsn=4.4.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.4.0 detected undelivered mail to <recipient> (in reply to end of DATA command))
Jan 12 12:27:55 myHostname postfix/qmgr[907477]: 18AE93F85: from=<sender>, size=8507, nrcpt=1 (queue active)
Jan 12 12:28:01 myHostname postfix/lmtp[1059697]: 18AE93F85: to=<recipient>, relay=127.0.0.1[127.0.0.1]:10024, delay=8871, delays=8866/2.9/1.1/1.6, dsn=4.4.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.4.0 detected undelivered mail to <recipient> (in reply to end of DATA command))
Jan 12 13:37:55 myHostname postfix/qmgr[907477]: 18AE93F85: from=<sender>, size=8507, nrcpt=1 (queue active)
Jan 12 13:38:00 myHostname postfix/lmtp[1065314]: 18AE93F85: to=<recipient>, relay=127.0.0.1[127.0.0.1]:10024, delay=13070, delays=13065/2.9/0.66/1.5, dsn=4.4.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.4.0 detected undelivered mail to <recipient> (in reply to end of DATA command))
Jan 12 14:47:55 myHostname postfix/qmgr[907477]: 18AE93F85: from=<sender>, size=8507, nrcpt=1 (queue active)
Jan 12 14:48:00 myHostname postfix/lmtp[1072134]: 18AE93F85: to=<recipient>, relay=127.0.0.1[127.0.0.1]:10024, delay=17271, delays=17266/3.5/0.45/1.1, dsn=4.4.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.4.0 detected undelivered mail to <recipient> (in reply to end of DATA command))
Jan 12 14:48:00 myHostname postfix/bounce[1071351]: 18AE93F85: sender delay notification: E2BB43F16
Jan 12 15:57:56 myHostname postfix/qmgr[907477]: 18AE93F85: from=<sender>, size=8507, nrcpt=1 (queue active)
Jan 12 15:58:01 myHostname postfix/lmtp[1079358]: 18AE93F85: to=<recipient>, relay=127.0.0.1[127.0.0.1]:10024, delay=21472, delays=21466/3.6/0.17/2, dsn=4.4.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.4.0 detected undelivered mail to <recipient> (in reply to end of DATA command))
Jan 12 16:25:01 myHostname postfix/qmgr[907477]: 18AE93F85: from=<sender>, size=8507, nrcpt=1 (queue active)
Jan 12 16:25:15 myHostname postfix/lmtp[1082159]: 18AE93F85: to=<recipient>, relay=127.0.0.1[127.0.0.1]:10024, delay=23106, delays=23092/11/0.05/2.6, dsn=4.4.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.4.0 detected undelivered mail to <recipient> (in reply to end of DATA command))
Jan 12 16:36:35 myHostname postfix/qmgr[907477]: 18AE93F85: from=<sender>, size=8507, nrcpt=1 (queue active)
Jan 12 16:36:55 myHostname postfix/lmtp[1082159]: 18AE93F85: to=<recipient>, relay=127.0.0.1[127.0.0.1]:10024, delay=23805, delays=23786/18/0.04/1.5, dsn=4.4.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.4.0 detected undelivered mail to <recipient> (in reply to end of DATA command))
Jan 12 17:45:26 myHostname postfix/qmgr[1176]: 18AE93F85: from=<sender>, size=8507, nrcpt=1 (queue active)
Jan 12 17:45:45 myHostname postfix/lmtp[1400]: 18AE93F85: to=<recipient>, relay=127.0.0.1[127.0.0.1]:10024, delay=27935, delays=27916/17/0.04/2.2, dsn=4.4.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.4.0 detected undelivered mail to <recipient> (in reply to end of DATA command))
Jan 12 18:55:28 myHostname postfix/qmgr[1176]: 18AE93F85: from=<sender>, size=8507, nrcpt=1 (queue active)
Jan 12 18:55:45 myHostname postfix/lmtp[12846]: 18AE93F85: to=<recipient>, relay=127.0.0.1[127.0.0.1]:10024, delay=32136, delays=32118/15/0.04/2, dsn=4.4.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.4.0 detected undelivered mail to <recipient> (in reply to end of DATA command))
Jan 12 20:05:26 myHostname postfix/qmgr[1176]: 18AE93F85: from=<sender>, size=8507, nrcpt=1 (queue active)
Jan 12 20:05:46 myHostname postfix/lmtp[18466]: 18AE93F85: to=<recipient>, relay=127.0.0.1[127.0.0.1]:10024, delay=36336, delays=36316/18/0.04/2.2, dsn=4.4.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.4.0 detected undelivered mail to <recipient> (in reply to end of DATA command))
Jan 12 21:15:25 myHostname postfix/qmgr[1176]: 18AE93F85: from=<sender>, size=8507, nrcpt=1 (queue active)
Jan 12 21:15:46 myHostname postfix/lmtp[20577]: 18AE93F85: to=<recipient>, relay=127.0.0.1[127.0.0.1]:10024, delay=40537, delays=40516/17/0.04/3.9, dsn=4.4.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.4.0 detected undelivered mail to <recipient> (in reply to end of DATA command))
Jan 12 21:43:45 myHostname postfix/qmgr[1176]: 18AE93F85: from=<sender>, size=8507, nrcpt=1 (queue active)
Jan 12 21:43:50 myHostname postfix/lmtp[27442]: 18AE93F85: to=<recipient>, relay=127.0.0.1[127.0.0.1]:10024, delay=42221, delays=42215/3.4/0.4/1.9, dsn=4.4.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.4.0 detected undelivered mail to <recipient> (in reply to end of DATA command))

Here's an extract for smtp-filter

Code:
Jan 12 21:10:26 myHostname pmg-smtp-filter[23408]: 1C0C63C069319FE3B: reinject mail to <recipient> (rule: default-accept) failed
Jan 12 21:10:26 myHostname pmg-smtp-filter[23402]: 1BF863C0693198FCF: reinject mail to <recipient> (rule: default-accept) failed
Jan 12 21:15:33 myHostname pmg-smtp-filter[24129]: 176463C06A61877E1: reinject mail to <recipient> (rule: default-accept) failed
Jan 12 21:15:33 myHostname pmg-smtp-filter[24045]: 179463C06A638D463: reinject mail to <recipient> (rule: default-accept) failed
Jan 12 21:15:36 myHostname pmg-smtp-filter[24211]: 179663C06A63C3063: reinject mail to <recipient> (rule: default-accept) failed
Jan 12 21:15:42 myHostname pmg-smtp-filter[24211]: 188A63C06A6C0C5A0: reinject mail to <recipient> (rule: default-accept) failed
Jan 12 21:15:45 myHostname pmg-smtp-filter[24005]: 199463C06A6EDB681: reinject mail to <recipient> (rule: default-accept) failed
Jan 12 21:15:45 myHostname pmg-smtp-filter[24045]: 18BC63C06A6DB934B: reinject mail to <recipient> (rule: default-accept) failed
Jan 12 21:15:46 myHostname pmg-smtp-filter[24247]: 19D863C06A6F01A4E: reinject mail to <recipient> (rule: default-accept) failed
Jan 12 21:43:47 myHostname pmg-smtp-filter[27107]: 16DB63C0710229A54: reinject mail to <recipient> (rule: default-accept) failed
Jan 12 21:43:48 myHostname pmg-smtp-filter[27295]: 16FB63C071023B39C: reinject mail to <recipient> (rule: default-accept) failed
Jan 12 21:43:48 myHostname pmg-smtp-filter[27360]: 171463C07102C113D: reinject mail to <recipient> (rule: default-accept) failed
Jan 12 21:43:50 myHostname pmg-smtp-filter[27462]: 176463C07104E5348: reinject mail to <recipient> (rule: default-accept) failed
Jan 12 21:43:52 myHostname pmg-smtp-filter[27400]: 178863C0710626BA9: reinject mail to <recipient> (rule: default-accept) failed
Jan 12 21:43:53 myHostname pmg-smtp-filter[27543]: 179663C07106D2A5B: reinject mail to <recipient> (rule: default-accept) failed
Jan 12 21:43:57 myHostname pmg-smtp-filter[27514]: 17C663C07108EEE80: reinject mail to <recipient> (rule: default-accept) failed
Jan 12 21:44:01 myHostname pmg-smtp-filter[27524]: 185D63C0710D8F357: reinject mail to <recipient> (rule: default-accept) failed
Jan 12 21:44:02 myHostname pmg-smtp-filter[27307]: 184A63C0710D579A6: reinject mail to <recipient> (rule: default-accept) failed

I don't understand why the accept rule fails...
 
I get same error for a specific sender, I added ip, email, domain everything to whitelist but same same...

*******

Jan 13 10:21:22 pmg postfix/smtpd[253401]: connect from kackar.isbank.com.tr[213.161.144.82]
Jan 13 10:21:22 pmg postfix/smtpd[253401]: 9ADCE843F4: client=kackar.isbank.com.tr[213.161.144.82]
Jan 13 10:21:22 pmg postfix/cleanup[258359]: 9ADCE843F4: message-id=<188156245.2496158.1673594496275@email-smtp-sender-l-d467d8fcd-xkbzs>
Jan 13 10:21:23 pmg postfix/qmgr[208065]: 9ADCE843F4: from=<bilgilendirme@ileti.isbank.com.tr>, size=204266, nrcpt=1 (queue active)
Jan 13 10:21:23 pmg postfix/smtpd[253401]: disconnect from kackar.isbank.com.tr[213.161.144.82] ehlo=2 starttls=1 mail=1 rcpt=1 data=1 quit=1 commands=7
Jan 13 10:21:25 pmg postfix/lmtp[250694]: 9ADCE843F4: to=<XXXXXXXXXXXXX.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=3.2, delays=1.5/0/0.05/1.7, dsn=4.4.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.4.0 detected undelivered mail to <XXXXXXXXX.com> (in reply to end of DATA command))
Jan 13 10:28:31 pmg postfix/qmgr[208065]: 9ADCE843F4: from=<bilgilendirme@ileti.isbank.com.tr>, size=204266, nrcpt=1 (queue active)
Jan 13 10:28:32 pmg postfix/lmtp[252909]: 9ADCE843F4: to=<XXXXXXXXXXXXX.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=430, delays=429/0/0.05/1.3, dsn=4.4.0, status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.4.0 detected undelivered mail to <XXXXXXXXXXXXX.com> (in reply to end of DATA command))
 
Thanks - but the logs seem missing the line where the reason is logged why the reinject fails - as this should be logged as well

* do you have enough free diskspace on the PMG?
* could you please share the complete journal of that time frame? (journalctl --since '2023-01-12 20:00' --until '2023-01-12 23:00')
 
Hi,

I belive the problem is linked to SMTPUTF8, my zimbra does not support it so it is disabled in PMG :

Code:
janv. 15 03:16:00 myHostname pmg-smtp-filter[318492]: smtp error - got: 555 5.5.4 Unsupported option: SMTPUTF8
janv. 15 03:16:00 myHostname postfix/smtpd[318592]: lost connection after MAIL from localhost[127.0.0.1]
janv. 15 03:16:00 myHostname postfix/smtpd[318592]: disconnect from localhost[127.0.0.1] ehlo=1 xforward=1 mail=0/1 commands=2/3
janv. 15 03:16:00 myHostname pmg-smtp-filter[318492]: smtp from: ERROR at /usr/share/perl5/PMG/Utils.pm line 279.
janv. 15 03:16:00 myHostname pmg-smtp-filter[318492]: 37F863C361DD77C4C: reinject mail to <recipient> (rule: default-accept) failed

I have properly disabled smtputf8 in /etc/pmg/templates/main.cf.in and restarted the server.
 
I have properly disabled smtputf8 in /etc/pmg/templates/main.cf.in and restarted the server.
Did you disable this recently - or as a result of the failed delivery

Also - would it be possible to share such a mail that cannot be sent to zimbra as .eml?
 
Did you disable this recently - or as a result of the failed delivery

Also - would it be possible to share such a mail that cannot be sent to zimbra as .eml?
Disabled as a result of failed delivery (and I forgot to disable it at first but I usually always do it since zimbra does not support it)

This seems close to this issue : https://forum.proxmox.com/threads/pmg-api-7-2-3-can-turn-on-smtputf8-breaking-mail-flow.120886/

I will try to retreive the mail as .eml
 
  • Like
Reactions: Stoiko Ivanov
Thanks for confirming this - I'll try to get to fixing this this week!
 
  • Like
Reactions: Sinancay