>There are no "Received:" headers here, are you sure you've posted *all* the
headers?  All messages received by Postfix get a "Received" header
prepended.  Where are they?  However, we do see that the message went
through >Amavis, so ypu should have logs for that... and ideally for how it
got into Amavis as well.

There is a Received header in the NDR email headers, look for it in the
paste above the one you looked at.

>It appears to be a reply to a message from icloud, sent on Jan 6th, so
delayed by 6 days.  But that delay could well be a result of the master.cf
breakage.
Correct. The delay is because the email has been bouncing since the message
size was too big for the recipient's server. 

>What's in your logs for "2f18001d6e499$48eeff40$daccfdc0$@xxx"
>on "Wed, 6 Jan 2021 18:03:24 -0800"?  (With "grep" and the like escape the
'$' characters your just search for '.' instead.

Here is where I see the first send of this message (based on the message
id):

-----------------------------------------------------------------
Jan  7 02:03:25 mail postfix/submission/smtpd[23109]: connect from
c-11-22-33-44.hsd1.ca.comcast.net[11.22.33.44]
Jan  7 02:03:26 mail postfix/submission/smtpd[23109]: 09E898DFA2:
client=c-11-22-33-44.hsd1.ca.comcast.net[11.22.33.44], sasl_method=LOGIN,
sasl_username=yyy@xxx
Jan  7 02:03:26 mail postfix/cleanup[23314]: 09E898DFA2:
message-id=<2f18001d6e499$48eeff40$daccfdc0$@xxx>
Jan  7 02:04:17 mail postfix/qmgr[1576]: 09E898DFA2: from=<yyy@xxx>,
size=35378641, nrcpt=1 (queue active)
Jan  7 02:04:19 mail postfix/submission/smtpd[23109]: disconnect from
c-11-22-33-44.hsd1.ca.comcast.net[11.22.33.44]
Jan  7 02:04:56 mail postfix/smtp[23322]: 09E898DFA2: to=<x...@icloud.com>,
relay=127.0.0.1[127.0.0.1]:10024, delay=91, delays=52/0.03/0.01/39,
dsn=2.0.0, status=sent (250 2.0.0 from MTA(smtp:[127.0.0.1]:10025): 250
2.0.0 Ok: queued as 9AE9C281)
Jan  7 02:04:56 mail postfix/qmgr[1576]: 09E898DFA2: removed

Jan  7 02:04:32 mail postfix/smtpd[23324]: connect from mail.xxx[127.0.0.1]
Jan  7 02:04:32 mail postfix/smtpd[23324]: 9AE9C281:
client=mail.xxx[127.0.0.1], orig_queue_id=09E898DFA2,
orig_client=c-11-22-33-44.hsd1.ca.comcast.net[11.22.33.44]
Jan  7 02:04:32 mail postfix/cleanup[23229]: 9AE9C281:
message-id=<2f18001d6e499$48eeff40$daccfdc0$@xxx>
Jan  7 02:04:56 mail postfix/qmgr[1576]: 9AE9C281: from=<yyy@xxx>,
size=35378688, nrcpt=1 (queue active)
Jan  7 02:04:56 mail postfix/smtpd[23324]: disconnect from
mail.xxx[127.0.0.1]
Jan  7 02:04:58 mail postfix/smtp[23331]: 9AE9C281: to=<x...@icloud.com>,
relay=127.0.0.1[127.0.0.1]:10026, delay=26, delays=24/0.13/0/1.6, dsn=2.0.0,
status=sent (250 OK Message /var/spool/pykolab/wallace/tmpLo9c_K queued)
Jan  7 02:04:58 mail postfix/qmgr[1576]: 9AE9C281: removed

Jan  7 02:05:05 mail postfix/smtpd[23332]: connect from mail.xxx[127.0.0.1]
Jan  7 02:05:05 mail postfix/smtpd[23332]: EE2D1281:
client=mail.xxx[127.0.0.1]
Jan  7 02:05:07 mail postfix/cleanup[23229]: EE2D1281:
message-id=<2f18001d6e499$48eeff40$daccfdc0$@xxx>
Jan  7 02:05:30 mail postfix/qmgr[1576]: EE2D1281: from=<yyy@xxx>,
size=35379321, nrcpt=1 (queue active)
Jan  7 02:05:30 mail postfix/smtpd[23332]: disconnect from
mail.xxx[127.0.0.1]
Jan  7 02:05:31 mail postfix/smtp[23334]: EE2D1281: to=<x...@icloud.com>,
relay=mx02.mail.icloud.com[17.57.152.14]:25, delay=25,
delays=25/0.03/0.45/0, dsn=5.3.4, status=bounced (message size 35379321
exceeds size limit 28311552 of server mx02.mail.icloud.com[17.57.152.14])
Jan  7 02:05:31 mail postfix/qmgr[1576]: EE2D1281: removed

Jan  7 02:05:33 mail postfix/smtpd[23332]: connect from mail.xxx[127.0.0.1]
Jan  7 02:05:33 mail postfix/smtpd[23332]: 102A0281:
client=mail.xxx[127.0.0.1]
Jan  7 02:05:34 mail postfix/cleanup[23229]: 102A0281:
message-id=<2f18001d6e499$48eeff40$daccfdc0$@xxx>
Jan  7 02:05:57 mail postfix/qmgr[1576]: 102A0281: from=<yyy@xxx>,
size=35379321, nrcpt=1 (queue active)
Jan  7 02:05:57 mail postfix/smtpd[23332]: disconnect from
mail.xxx[127.0.0.1]
Jan  7 02:05:57 mail postfix/smtp[23334]: 102A0281: to=<x...@icloud.com>,
relay=mx02.mail.icloud.com[17.56.9.19]:25, delay=25, delays=24/0/0.28/0,
dsn=5.3.4, status=bounced (message size 35379321 exceeds size limit 28311552
of server mx02.mail.icloud.com[17.56.9.19])
Jan  7 02:05:57 mail postfix/qmgr[1576]: 102A0281: removed

Jan  7 02:06:00 mail postfix/smtpd[23332]: connect from mail.xxx[127.0.0.1]
Jan  7 02:06:00 mail postfix/smtpd[23332]: 0CEF9281:
client=mail.xxx[127.0.0.1]
Jan  7 02:06:01 mail postfix/cleanup[23229]: 0CEF9281:
message-id=<2f18001d6e499$48eeff40$daccfdc0$@xxx>
Jan  7 02:06:25 mail postfix/smtpd[23332]: disconnect from
mail.xxx[127.0.0.1]
Jan  7 02:06:25 mail postfix/qmgr[1576]: 0CEF9281: from=<yyy@xxx>,
size=35379321, nrcpt=1 (queue active)
Jan  7 02:06:25 mail postfix/smtp[23334]: 0CEF9281: to=<x...@icloud.com>,
relay=mx01.mail.icloud.com[17.57.154.23]:25, delay=26,
delays=25/0.01/0.33/0, dsn=5.3.4, status=bounced (message size 35379321
exceeds size limit 28311552 of server mx01.mail.icloud.com[17.57.154.23])
Jan  7 02:06:25 mail postfix/qmgr[1576]: 0CEF9281: removed

Jan  7 02:06:27 mail postfix/smtpd[23332]: connect from mail.xxx[127.0.0.1]
Jan  7 02:06:27 mail postfix/smtpd[23332]: 152C88DFA2:
client=mail.xxx[127.0.0.1]
Jan  7 02:06:28 mail postfix/cleanup[23229]: 152C88DFA2:
message-id=<2f18001d6e499$48eeff40$daccfdc0$@xxx>
Jan  7 02:06:51 mail postfix/qmgr[1576]: 152C88DFA2: from=<yyy@xxx>,
size=35379321, nrcpt=1 (queue active)
Jan  7 02:06:51 mail postfix/smtpd[23332]: disconnect from
mail.xxx[127.0.0.1]
Jan  7 02:06:53 mail postfix/smtp[23347]: 152C88DFA2: to=<x...@icloud.com>,
relay=mx02.mail.icloud.com[17.57.152.14]:25, delay=26, delays=25/0.04/1.1/0,
dsn=5.3.4, status=bounced (message size 35379321 exceeds size limit 28311552
of server mx02.mail.icloud.com[17.57.152.14])
Jan  7 02:06:53 mail postfix/qmgr[1576]: 152C88DFA2: removed

Jan  7 02:06:54 mail postfix/smtpd[23339]: connect from mail.xxx[127.0.0.1]
Jan  7 02:06:54 mail postfix/smtpd[23339]: 1F2C78DFA2:
client=mail.xxx[127.0.0.1]
Jan  7 02:06:55 mail postfix/cleanup[23341]: 1F2C78DFA2:
message-id=<2f18001d6e499$48eeff40$daccfdc0$@xxx>
Jan  7 02:07:19 mail postfix/qmgr[1576]: 1F2C78DFA2: from=<yyy@xxx>,
size=35379321, nrcpt=1 (queue active)
Jan  7 02:07:19 mail postfix/smtpd[23339]: disconnect from
mail.xxx[127.0.0.1]
Jan  7 02:07:19 mail postfix/smtp[23334]: 1F2C78DFA2: to=<x...@icloud.com>,
relay=mx01.mail.icloud.com[17.57.152.9]:25, delay=26, delays=26/0/0.33/0,
dsn=5.3.4, status=bounced (message size 35379321 exceeds size limit 28311552
of server mx01.mail.icloud.com[17.57.152.9])
Jan  7 02:07:19 mail postfix/qmgr[1576]: 1F2C78DFA2: removed

Jan  7 02:21:15 mail postfix/smtpd[23447]: connect from mail.xxx[127.0.0.1]
Jan  7 02:21:15 mail postfix/smtpd[23447]: D13F48DFA4:
client=mail.xxx[127.0.0.1]
Jan  7 02:21:17 mail postfix/cleanup[23483]: D13F48DFA4:
message-id=<2f18001d6e499$48eeff40$daccfdc0$@xxx>
Jan  7 02:21:39 mail postfix/qmgr[1576]: D13F48DFA4: from=<yyy@xxx>,
size=35379321, nrcpt=1 (queue active)
Jan  7 02:21:39 mail postfix/smtpd[23447]: disconnect from
mail.xxx[127.0.0.1]
Jan  7 02:21:40 mail postfix/smtp[23527]: D13F48DFA4: to=<x...@icloud.com>,
relay=mx01.mail.icloud.com[17.42.251.10]:25, delay=25,
delays=24/0.04/0.96/0, dsn=5.3.4, status=bounced (message size 35379321
exceeds size limit 28311552 of server mx01.mail.icloud.com[17.42.251.10])
Jan  7 02:21:41 mail postfix/qmgr[1576]: D13F48DFA4: removed

Jan  7 02:21:42 mail postfix/smtpd[23484]: connect from mail.xxx[127.0.0.1]
Jan  7 02:21:42 mail postfix/smtpd[23484]: 7221C8DFA2:
client=mail.xxx[127.0.0.1]
Jan  7 02:21:44 mail postfix/cleanup[23446]: 7221C8DFA2:
message-id=<2f18001d6e499$48eeff40$daccfdc0$@xxx>
Jan  7 02:22:07 mail postfix/qmgr[1576]: 7221C8DFA2: from=<yyy@xxx>,
size=35379321, nrcpt=1 (queue active)
Jan  7 02:22:07 mail postfix/smtpd[23484]: disconnect from
mail.xxx[127.0.0.1]
Jan  7 02:22:07 mail postfix/smtp[23527]: 7221C8DFA2: to=<x...@icloud.com>,
relay=mx01.mail.icloud.com[17.56.9.17]:25, delay=25, delays=25/0/0.23/0,
dsn=5.3.4, status=bounced (message size 35379321 exceeds size limit 28311552
of server mx01.mail.icloud.com[17.56.9.17])
Jan  7 02:22:07 mail postfix/qmgr[1576]: 7221C8DFA2: removed

Jan  7 02:22:09 mail postfix/smtpd[23443]: connect from mail.xxx[127.0.0.1]
Jan  7 02:22:09 mail postfix/smtpd[23443]: A693B8DFA2:
client=mail.xxx[127.0.0.1]
Jan  7 02:22:11 mail postfix/cleanup[23449]: A693B8DFA2:
message-id=<2f18001d6e499$48eeff40$daccfdc0$@xxx>
Jan  7 02:22:34 mail postfix/qmgr[1576]: A693B8DFA2: from=<yyy@xxx>,
size=35379321, nrcpt=1 (queue active)
Jan  7 02:22:34 mail postfix/smtpd[23443]: disconnect from
mail.xxx[127.0.0.1]
Jan  7 02:22:34 mail postfix/smtp[23527]: A693B8DFA2: to=<x...@icloud.com>,
relay=mx02.mail.icloud.com[17.57.154.7]:25, delay=25, delays=25/0.01/0.23/0,
dsn=5.3.4, status=bounced (message size 35379321 exceeds size limit 28311552
of server mx02.mail.icloud.com[17.57.154.7])
Jan  7 02:22:34 mail postfix/qmgr[1576]: A693B8DFA2: removed

Jan  7 02:22:36 mail postfix/smtpd[23447]: connect from mail.xxx[127.0.0.1]
Jan  7 02:22:36 mail postfix/smtpd[23447]: BA44A8DFA2:
client=mail.xxx[127.0.0.1]
Jan  7 02:22:38 mail postfix/cleanup[23483]: BA44A8DFA2:
message-id=<2f18001d6e499$48eeff40$daccfdc0$@xxx>
Jan  7 02:23:01 mail postfix/qmgr[1576]: BA44A8DFA2: from=<yyy@xxx>,
size=35379321, nrcpt=1 (queue active)
Jan  7 02:23:01 mail postfix/smtpd[23447]: disconnect from
mail.xxx[127.0.0.1]
Jan  7 02:23:01 mail postfix/smtp[23527]: BA44A8DFA2: to=<x...@icloud.com>,
relay=mx01.mail.icloud.com[17.57.154.6]:25, delay=25, delays=25/0/0.27/0,
dsn=5.3.4, status=bounced (message size 35379321 exceeds size limit 28311552
of server mx01.mail.icloud.com[17.57.154.6])
Jan  7 02:23:01 mail postfix/qmgr[1576]: BA44A8DFA2: removed
----------------------------------------------------------------------------
--------------


>You seem to be double-signing messages, before and after Amavis perhaps?
Once is likely enough.

Once this is resolved, perhaps that needs to be looked into. Why do you
think the same message id keeps getting to postfix?

Reply via email to