> Michael Fox skrev den 2017-09-21 19:52:
> > I have a problem that seems to have started when I upgraded from Ubuntu
> > 14.04/Postfix 2.11.0 to Ubuntu 16.04/Postfix 3.1.0. It involves the
> > From:
> > and Return Path: addresses seen by recipients of mail sent from a
> > virtual
> > domain on that machine.
>
> you should not care of return-path at all, and if you try to make them
> equal with from you have a hard time with that job, no logs no problem,
> but thanks for postconf -n and postconf -Mf anyway
Thanks Benny.
I don't really care about Return-path and I'm not trying to make them equal.
Again, what I reported is that recipients on Gmail, Yahoo, Rackspace and
others see the correct value (user@virtualdomain) in both headers (Return
Path: and From: ), whether I send from the new Postfix 3.1.0 machine or the
older Postfix 2.11.0 machine. However, recipients at this one (large) email
provider see the wrong value (user@gatewayhostname) in both headers when
sending from Postfix 3.1.0 and the right value (user@virtualdomain) when
sending from Postfix 2.11.0.
> need more help show logs of a real problem
Here are two sets of logs. The first is from the Postfix 3.1.0 machine
which results in the recipient seeing the wrong From: address. The second
is from the Postfix 2.11.0 machine which results in the recipient seeing the
correct address. In both cases, I included submission of the message
through delivery to the destination.
I'm not very skilled at interpreting the logs, but I've looked at them line
by line and I don't see where the destination server would ever get "From:
n6...@w6xsc-gw.scc-ares-races.org". I'm hoping that someone here with more
knowledge than me can see where I went wrong. I'm stumped.
Thanks,
Michael
>From Postfix 3.1.0 - recipient sees From: n6...@w6xsc-gw.scc-ares-races.org,
should be From: n6...@email6.scc-ares-races.org
Sep 21 18:45:41 w6xsc-gw postfix/submission/smtpd[26419]: connect from
n6mef-gw.n6mef.org[173.167.109.217]
Sep 21 18:45:41 w6xsc-gw postfix/submission/smtpd[26419]: Anonymous TLS
connection established from n6mef-gw.n6mef.org[173.167.109.217]: TLSv1.2
with cipher ECDHE-RSA-AES128-GCM-SHA256 (128/128 bits)
Sep 21 18:45:41 w6xsc-gw dovecot: auth:
passwd-file(n6...@email6.scc-ares-races.org,173.167.109.217): unknown user
Sep 21 18:45:41 w6xsc-gw postfix/submission/smtpd[26419]: 6A9E31F70E:
client=n6mef-gw.n6mef.org[173.167.109.217], sasl_method=CRAM-MD5,
sasl_username=n6...@email6.scc-ares-races.org
Sep 21 18:45:41 w6xsc-gw postfix/pre-cleanup/cleanup[26426]: 6A9E31F70E:
message-id=<4c1afef8-9ab0-738c-a20a-212e7141a...@email6.scc-ares-races.org>
Sep 21 18:45:41 w6xsc-gw opendkim[1408]: 6A9E31F70E: DKIM-Signature field
added (s=mail61709, d=email6.scc-ares-races.org)
Sep 21 18:45:41 w6xsc-gw postfix/qmgr[26352]: 6A9E31F70E:
from=<n6...@email6.scc-ares-races.org>, size=673, nrcpt=1 (queue active)
Sep 21 18:45:41 w6xsc-gw amavis[2735]: (02735-07) LMTP [127.0.0.1]:10024
/var/lib/amavis/tmp/amavis-20170921T061543-02735-H33h2gd8:
<n6...@email6.scc-ares-races.org> -> <n6...@prismatic.com> SIZE=673
BODY=8BITMIME Received: from w6xsc-gw.scc-ares-races.org ([127.0.0.1]) by
localhost (w6xsc-gw.scc-ares-races.org [127.0.0.1]) (amavisd-new, port
10024) with LMTP for <n6...@prismatic.com>; Thu, 21 Sep 2017 18:45:41 -0700
(PDT)
Sep 21 18:45:41 w6xsc-gw postfix/submission/smtpd[26419]: disconnect from
n6mef-gw.n6mef.org[173.167.109.217] ehlo=2 starttls=1 auth=1 mail=1 rcpt=1
data=1 quit=1 commands=8
Sep 21 18:45:41 w6xsc-gw amavis[2735]: (02735-07) dkim: VALID
Author+Sender+MailFrom signature by d=email6.scc-ares-races.org, From:
<n6...@email6.scc-ares-races.org>, a=rsa-sha256, c=simple/simple,
s=mail61709, i=@email6.scc-ares-races.org
Sep 21 18:45:41 w6xsc-gw amavis[2735]: (02735-07) Checking: EmxshYSM9dtH
[173.167.109.217] <n6...@email6.scc-ares-races.org> -> <n6...@prismatic.com>
Sep 21 18:45:41 w6xsc-gw amavis[2735]: (02735-07) Open relay? Nonlocal
recips but not originating: n6...@prismatic.com
Sep 21 18:45:41 w6xsc-gw amavis[2735]: (02735-07) p001 1 Content-Type:
text/plain, size: 10 B, name:
Sep 21 18:45:42 w6xsc-gw postfix/amavisreturn/smtpd[26431]: connect from
localhost.localdomain[127.0.0.1]
Sep 21 18:45:42 w6xsc-gw postfix/amavisreturn/smtpd[26431]: 380AA1F824:
client=localhost.localdomain[127.0.0.1]
Sep 21 18:45:42 w6xsc-gw postfix/cleanup[26432]: 380AA1F824:
message-id=<4c1afef8-9ab0-738c-a20a-212e7141a...@email6.scc-ares-races.org>
Sep 21 18:45:42 w6xsc-gw postfix/amavisreturn/smtpd[26431]: disconnect from
localhost.localdomain[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1
commands=5
Sep 21 18:45:42 w6xsc-gw postfix/qmgr[26352]: 380AA1F824:
from=<n6...@email6.scc-ares-races.org>, size=1552, nrcpt=1 (queue active)
Sep 21 18:45:42 w6xsc-gw amavis[2735]: (02735-07) EmxshYSM9dtH FWD from
<n6...@email6.scc-ares-races.org> -> <n6...@prismatic.com>, BODY=7BIT 250
2.0.0 from MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as 380AA1F824
Sep 21 18:45:42 w6xsc-gw amavis[2735]: (02735-07) Passed CLEAN
{RelayedOpenRelay}, [173.167.109.217]:12252 [173.167.109.217]
<n6...@email6.scc-ares-races.org> -> <n6...@prismatic.com>, Queue-ID:
6A9E31F70E, Message-ID:
<4c1afef8-9ab0-738c-a20a-212e7141a...@email6.scc-ares-races.org>, mail_id:
EmxshYSM9dtH, Hits: -4.1, size: 1017, queued_as: 380AA1F824,
dkim_sd=mail61709:email6.scc-ares-races.org, 719 ms
Sep 21 18:45:42 w6xsc-gw amavis[2735]: (02735-07) TIMING-SA total 573 ms -
parse: 1.92 (0.3%), extract_message_metadata: 4.0 (0.7%),
get_uri_detail_list: 0.35 (0.1%), tests_pri_-1000: 11 (1.9%),
tests_pri_-950: 2.5 (0.4%), tests_pri_-900: 2.1 (0.4%), tests_pri_-400: 1.70
(0.3%), tests_pri_0: 497 (86.7%), check_spf: 0.48 (0.1%), check_razor2: 385
(67.2%), check_pyzor: 74 (12.9%), tests_pri_500: 6 (1.0%), learn: 33 (5.7%),
b_learn: 30 (5.2%), b_count_change: 12 (2.0%), get_report: 0.65 (0.1%)
Sep 21 18:45:42 w6xsc-gw postfix/amavisfeed/lmtp[26427]: 6A9E31F70E:
to=<n6...@prismatic.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=0.83,
delays=0.1/0.01/0/0.72, 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 380AA1F824)
Sep 21 18:45:42 w6xsc-gw amavis[2735]: (02735-07) size: 1017, TIMING [total
726 ms] - SMTP greeting: 2.1 (0%)0, SMTP LHLO: 0.9 (0%)0, SMTP pre-MAIL: 1.0
(0%)1, SMTP pre-DATA-flush: 3.0 (0%)1, SMTP DATA: 36 (5%)6, check_init: 0.5
(0%)6, digest_hdr: 2.5 (0%)6, digest_body_dkim: 7 (1%)7, collect_info: 3.0
(0%)8, mime_decode: 9 (1%)9, get-file-type1: 19 (3%)11, parts_decode: 0.2
(0%)11, check_header: 0.6 (0%)12, AV-scan-1: 7 (1%)12, spam-wb-list: 1.0
(0%)13, SA msg read: 0.4 (0%)13, SA parse: 2.4 (0%)13, SA check: 568
(78%)91, decide_mail_destiny: 6 (1%)92, notif-quar: 0.3 (0%)92, fwd-connect:
19 (3%)95, fwd-mail-pip: 11 (2%)96, fwd-rcpt-pip: 0.3 (0%)96,
fwd-data-chkpnt: 0.1 (0%)96, write-header: 0.6 (0%)96, fwd-data-contents:
0.1 (0%)96, fwd-end-chkpnt: 10 (1%)98, prepare-dsn: 1.1 (0%)98, report: 2.2
(0%)98, main_log_entry: 8 (1%)99, update_snmp: 3.1 (0%)100, SMTP
pre-response: 0.4 (0%)100, SMTP response: 0.3 (0%)100, unlink-1-files: 0.3
(0%)100, rundown: 0.9 (0%)100
Sep 21 18:45:42 w6xsc-gw postfix/qmgr[26352]: 6A9E31F70E: removed
Sep 21 18:45:43 w6xsc-gw postfix/smtp[26433]: 380AA1F824:
to=<n6...@prismatic.com>, relay=mailin-02.mx.sonic.net[69.12.210.173]:25,
delay=1.5, delays=0.02/0.01/1.1/0.35, dsn=2.0.0, status=sent (250 2.0.0
v8M1jgLS017129 Message accepted for delivery)
Sep 21 18:45:43 w6xsc-gw postfix/qmgr[26352]: 380AA1F824: removed
>From Postfix 2.11.0 - the same recipient as above sees correct From:
address: n6...@email.n6mef.org
Sep 21 18:43:22 n6mef-gw postfix/submission/smtpd[9519]: connect from
unknown[192.168.7.147]
Sep 21 18:43:22 n6mef-gw postfix/submission/smtpd[9519]: Anonymous TLS
connection established from unknown[192.168.7.147]: TLSv1.2 with cipher
ECDHE-RSA-AES128-GCM-SHA256 (128/128 bits)
Sep 21 18:43:22 n6mef-gw dovecot: auth:
passwd-file(n6...@email.n6mef.org,192.168.7.147): unknown user
Sep 21 18:43:22 n6mef-gw postfix/submission/smtpd[9519]: D30ED1F8:
client=unknown[192.168.7.147], sasl_method=CRAM-MD5,
sasl_username=n6...@email.n6mef.org
Sep 21 18:43:22 n6mef-gw postfix/pre-cleanup/cleanup[9524]: D30ED1F8:
message-id=<0f06acee-f9a5-8a07-2803-8bea58c39...@email.n6mef.org>
Sep 21 18:43:22 n6mef-gw opendkim[3104]: D30ED1F8: DKIM-Signature field
added (s=mail01700, d=email.n6mef.org)
Sep 21 18:43:22 n6mef-gw mimedefang.pl[9411]: D30ED1F8: filter_begin: Enter
Sep 21 18:43:22 n6mef-gw mimedefang.pl[9411]: D30ED1F8: filter_begin: Exit
Sep 21 18:43:22 n6mef-gw mimedefang.pl[9411]: D30ED1F8: filter_end: Enter
Sep 21 18:43:22 n6mef-gw mimedefang.pl[9411]: D30ED1F8:
MDLOG,D30ED1F8,mail_in,,,<n6...@email.n6mef.org>,<n6...@prismatic.com>,test
good
Sep 21 18:43:22 n6mef-gw mimedefang.pl[9411]: D30ED1F8: filter_end: Exit
Sep 21 18:43:23 n6mef-gw postfix/qmgr[9455]: D30ED1F8:
from=<n6...@email.n6mef.org>, size=639, nrcpt=1 (queue active)
Sep 21 18:43:23 n6mef-gw amavis[2514]: (02514-06) LMTP::10024
/var/lib/amavis/tmp/amavis-20170920T174708-02514-cBtySv0X:
<n6...@email.n6mef.org> -> <n6...@prismatic.com> SIZE=639 BODY=8BITMIME
Received: from n6mef-gw.n6mef.org ([127.0.0.1]) by localhost
(n6mef-gw.n6mef.org [127.0.0.1]) (amavisd-new, port 10024) with LMTP for
<n6...@prismatic.com>; Thu, 21 Sep 2017 18:43:23 -0700 (PDT)
Sep 21 18:43:23 n6mef-gw amavis[2514]: (02514-06) dkim: VALID
Author+Sender+MailFrom signature by d=email.n6mef.org, From:
<n6...@email.n6mef.org>, a=rsa-sha256, c=simple/simple, s=mail01700,
i=@email.n6mef.org
Sep 21 18:43:23 n6mef-gw amavis[2514]: (02514-06) Checking: 6xdyL5iwLkkP
[192.168.7.147] <n6...@email.n6mef.org> -> <n6...@prismatic.com>
Sep 21 18:43:23 n6mef-gw amavis[2514]: (02514-06) Open relay? Nonlocal
recips but not originating: n6...@prismatic.com
Sep 21 18:43:23 n6mef-gw amavis[2514]: (02514-06) p001 1 Content-Type:
text/plain, size: 11 B, name:
Sep 21 18:43:23 n6mef-gw postfix/submission/smtpd[9519]: disconnect from
unknown[192.168.7.147]
Sep 21 18:43:25 n6mef-gw postfix/amavisreturn/smtpd[9529]: connect from
localhost.localdomain[127.0.0.1]
Sep 21 18:43:25 n6mef-gw postfix/amavisreturn/smtpd[9529]: 84C081EE:
client=localhost.localdomain[127.0.0.1]
Sep 21 18:43:25 n6mef-gw postfix/cleanup[9530]: 84C081EE:
message-id=<0f06acee-f9a5-8a07-2803-8bea58c39...@email.n6mef.org>
Sep 21 18:43:25 n6mef-gw postfix/qmgr[9455]: 84C081EE:
from=<n6...@email.n6mef.org>, size=1514, nrcpt=1 (queue active)
Sep 21 18:43:25 n6mef-gw postfix/amavisreturn/smtpd[9529]: disconnect from
localhost.localdomain[127.0.0.1]
Sep 21 18:43:25 n6mef-gw amavis[2514]: (02514-06) FWD from
<n6...@email.n6mef.org> -> <n6...@prismatic.com>,BODY=7BIT 250 2.0.0 from
MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as 84C081EE
Sep 21 18:43:25 n6mef-gw amavis[2514]: (02514-06) Passed CLEAN
{RelayedOpenRelay}, [192.168.7.147]:62053 [192.168.7.147]
<n6...@email.n6mef.org> -> <n6...@prismatic.com>, Queue-ID: D30ED1F8,
Message-ID: <0f06acee-f9a5-8a07-2803-8bea58c39...@email.n6mef.org>, mail_id:
6xdyL5iwLkkP, Hits: -2.1, size: 1017, queued_as: 84C081EE,
dkim_sd=mail01700:email.n6mef.org, 2551 ms
Sep 21 18:43:25 n6mef-gw amavis[2514]: (02514-06) TIMING-SA total 2460 ms -
parse: 0.52 (0.0%), extract_message_metadata: 1.02 (0.0%),
get_uri_detail_list: 0.11 (0.0%), tests_pri_-1000: 1.47 (0.1%),
tests_pri_-950: 0.58 (0.0%), tests_pri_-900: 0.41 (0.0%), tests_pri_-400:
0.38 (0.0%), tests_pri_0: 2395 (97.3%), check_spf: 0.15 (0.0%),
check_razor2: 2116 (86.0%), check_pyzor: 268 (10.9%), tests_pri_500: 2.4
(0.1%), learn: 54 (2.2%), b_learn: 53 (2.2%), b_count_change: 2.6 (0.1%),
get_report: 0.21 (0.0%)
Sep 21 18:43:25 n6mef-gw postfix/amavisfeed/lmtp[9525]: D30ED1F8:
to=<n6...@prismatic.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=2.7,
delays=0.16/0/0/2.6, 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 84C081EE)
Sep 21 18:43:25 n6mef-gw amavis[2514]: (02514-06) size: 1017, TIMING [total
2552 ms] - SMTP greeting: 1 (0%)0, SMTP LHLO: 0 (0%)0, SMTP pre-MAIL: 0
(0%)0, SMTP pre-DATA-flush: 1 (0%)0, SMTP DATA: 36 (1%)2, check_init: 0
(0%)2, digest_hdr: 1 (0%)2, digest_body_dkim: 2 (0%)2, mime_decode: 3 (0%)2,
get-file-type1: 5 (0%)2, parts_decode: 0 (0%)2, check_header: 0 (0%)2,
AV-scan-1: 2 (0%)2, spam-wb-list: 0 (0%)2, SA parse: 1 (0%)2, SA check: 2459
(96%)98, decide_mail_destiny: 2 (0%)98, notif-quar: 0 (0%)98, fwd-connect: 6
(0%)99, fwd-mail-pip: 3 (0%)99, fwd-rcpt-pip: 0 (0%)99, fwd-data-chkpnt: 0
(0%)99, write-header: 0 (0%)99, fwd-data-contents: 0 (0%)99, fwd-end-chkpnt:
25 (1%)100, prepare-dsn: 0 (0%)100, main_log_entry: 3 (0%)100, update_snmp:
1 (0%)100, SMTP pre-response: 0 (0%)100, SMTP response: 0 (0%)100,
unlink-1-files: 0 (0%)100, rundown: 0 (0%)100
Sep 21 18:43:25 n6mef-gw postfix/qmgr[9455]: D30ED1F8: removed
Sep 21 18:43:26 n6mef-gw postfix/smtp[9531]: 84C081EE:
to=<n6...@prismatic.com>, relay=mailin-01.mx.sonic.net[69.12.210.174]:25,
delay=1.4, delays=0.03/0/1.1/0.28, dsn=2.0.0, status=sent (250 2.0.0
v8M1hPc2018675 Message accepted for delivery)
Sep 21 18:43:26 n6mef-gw postfix/qmgr[9455]: 84C081EE: removed
