2016-02-02 by mauricio.teixeira@...
Hello,
I'm new to this list, so I'm sorry if this has been discussed/answered before.
I have recently started testing milter-greylist-4.5.16, and I modified my postfix as suggested in this post. However the log output is not consistent, so it will be very hard to debug greylisting issues when moving this server to production. What I mean is that only one or two entries in the log get the queue ID, while it's missing for everything else, so it would be hard to co-relate facts if I would be receiving many messages in parallel at the exact same time (see the log output below).
So the are two possible ways of fixing it:
a) Adding the queue ID to every single logged message; or
b) Adding the process ID to each one of them.
The ideal, obviously, would be doing both.
Any thoughts?
Feb 2 16:29:58 mx01 postfix/smtpd[9818]: connect from cli01[10.10.16.1]
Feb 2 16:29:58 mx01 milter-greylist: smfi_getsymval failed for {if_addr}, consider setting localaddr option
Feb 2 16:29:58 mx01 milter-greylist: smfi_getsymval failed for {daemon_port}, using default smtp port
Feb 2 16:29:58 mx01 milter-greylist: p0f_lookup: 10.10.16.1[54750] -> 0.0.0.0[6400]
Feb 2 16:29:58 mx01 milter-greylist: SPF return temperror (test code 6, result 0)
Feb 2 16:29:58 mx01 milter-greylist: SPF lookup performed in 0.006320s
Feb 2 16:29:58 mx01 postfix/smtpd[9818]: 7F85CC005910: client=cli01[10.10.16.1]
Feb 2 16:29:58 mx01 milter-greylist: 7F85CC005910: addr = cli01[10.10.16.1], from = <user1@example.com>, rcpt = <user2@example.com>
Feb 2 16:29:58 mx01 milter-greylist: Mail from=<user1@example.com>, rcpt=<user2@example.com>, addr=cli01[10.10.16.1] is matched by entry racl 108 continue from_re /.*/ [addheader "X-Greylist: inspected by %V for IP:'%i' DOMAIN:'%d' HELO:'%h' FROM:'%f' RCPT:'%r'"]
Feb 2 16:29:58 mx01 milter-greylist: DNSRBL lookup 1.16.10.10.sh-sbl.example.com performed in 0.002837s
Feb 2 16:29:58 mx01 milter-greylist: DNSRBL lookup 1.16.10.10.sh-xbl.example.com performed in 0.002941s
Feb 2 16:29:58 mx01 milter-greylist: DNSRBL lookup 1.16.10.10.sh-xbl.example.com performed in 0.001323s
Feb 2 16:29:58 mx01 milter-greylist: Mail from=<user1@example.com>, rcpt=<user2@example.com>, addr=cli01[10.10.16.1] is matched by entry racl 118 greylist [maxpeek -1] default
Feb 2 16:29:58 mx01 milter-greylist: (local): 10.10.16.1 from <user1@example.com> to <user2@example.com>: greylisted entry timed out
Feb 2 16:29:58 mx01 milter-greylist: created: 10.10.16.1 from <user1@example.com> to <user2@example.com> delayed for 00:05:00
Feb 2 16:29:58 mx01 milter-greylist: 7F85CC005910: addr cli01[10.10.16.1] from <user1@example.com> to <user2@example.com> delayed for 00:05:00 (ACL 118)
Feb 2 16:29:58 mx01 milter-greylist: sync_sender running
Feb 2 16:29:58 mx01 postfix/smtpd[9818]: 7F85CC005910: milter-reject: RCPT from cli01[10.10.16.1]: 451 4.7.1 Greylisting in action, please come back in 00:05:00; from=<user1@example.com> to=<user2@example.com> proto=ESMTP helo=<cli01>
Feb 2 16:29:58 mx01 postfix/smtpd[9818]: disconnect from cli01[10.10.16.1]