Is my inbound authentication working or not??

From: Stephen eire <ibrokeit2010_at_hotmail.co.uk>
Date: Sat, 10 Jul 2010 18:23:12 +0100

I've been doing more testing and I'm seeing what I think are conflicting results?

I've sent an email from gmail to a test inbox here.

In the email header I see:

Authentication-Results: foo.umrk.org; dkim=pass

        (1024-bit key; insecure key) header.i=_at_gmail.com; dkim-adsp=pass

Which seems to suggest that the incoming mail authenticated ok?

In mail.log I'm seeing opendkim report "not authenticated" and spamassassin report T_DKIM_INVALID.

Here's the log details for the email in question.

Jul 10 18:03:19 cobalt opendkim[3282]: OpenDKIM Filter v2.0.2 starting (args: -x /etc/opendkim.conf -u opendkim -P /var/run/opendkim/opendkim.pid -p inet:20209_at_localhost)
Jul 10 18:03:33 cobalt postfix/smtpd[3288]: connect from mail-fx0-f50.google.com[209.85.161.50]
Jul 10 18:03:34 cobalt postfix/smtpd[3288]: ABC653FC3B: client=mail-fx0-f50.google.com[209.85.161.50]
Jul 10 18:03:34 cobalt postfix/cleanup[3292]: ABC653FC3B: message-id=<AANLkTinOmOWDwCbOUCKa6-sTiKYzfYrJRkZih2X-cYgh_at_mail.gmail.com>
Jul 10 18:03:34 cobalt opendkim[3282]: ABC653FC3B mail-fx0-f50.google.com [209.85.161.50] not internal
Jul 10 18:03:34 cobalt opendkim[3282]: ABC653FC3B not authenticated
Jul 10 18:03:35 cobalt postfix/qmgr[1987]: ABC653FC3B: from=<imaginary.user_at_gmail.com>, size=1663, nrcpt=1 (queue active)
Jul 10 18:03:35 cobalt spamd[781]: spamd: connection from localhost [127.0.0.1] at port 38373
Jul 10 18:03:35 cobalt spamd[781]: spamd: setuid to spamd succeeded
Jul 10 18:03:35 cobalt spamd[781]: spamd: processing message <AANLkTinOmOWDwCbOUCKa6-sTiKYzfYrJRkZih2X-cYgh_at_mail.gmail.com> for spamd:1002
Jul 10 18:03:36 cobalt spamd[781]: spamd: clean message (0.7/5.0) for spamd:1002 in 1.2 seconds, 1709 bytes.
Jul 10 18:03:36 cobalt spamd[781]: spamd: result: . 0 - FREEMAIL_FROM,LOCALPART_IN_SUBJECT,T_DKIM_INVALID scantime=1.2,size=1709,user=spamd,uid=1002,required_score=5.0,rhost=localhost,raddr=127.0.0.1,rport=38373,mid=<AANLkTinOmOWDwCbOUCKa6-sTiKYzfYrJRkZih2X-cYgh_at_mail.gmail.com>,autolearn=no
Jul 10 18:03:36 cobalt postfix/pipe[3294]: ABC653FC3B: to=<test_at_i-systems.co.uk>, relay=spamassassin, delay=2, delays=0.74/0.02/0/1.3, dsn=2.0.0, status=sent (delivered via spamassassin service)
Jul 10 18:03:36 cobalt postfix/qmgr[1987]: ABC653FC3B: removed
Jul 10 18:03:36 cobalt postfix/pickup[3237]: 9F1AA41C4C: uid=1002 from=<imaginary.user_at_gmail.com>
Jul 10 18:03:36 cobalt postfix/cleanup[3292]: 9F1AA41C4C: message-id=<AANLkTinOmOWDwCbOUCKa6-sTiKYzfYrJRkZih2X-cYgh_at_mail.gmail.com>
Jul 10 18:03:36 cobalt opendkim[3282]: 9F1AA41C4C no signing table match for `imaginary.user_at_gmail.com'
Jul 10 18:03:36 cobalt postfix/qmgr[1987]: 9F1AA41C4C: from=<imaginary.user_at_gmail.com>, size=2113, nrcpt=1 (queue active)
Jul 10 18:03:36 cobalt clamsmtpd: 10000B: accepted connection from: 127.0.0.1
Jul 10 18:03:36 cobalt spamd[755]: prefork: child states: II
Jul 10 18:03:36 cobalt postfix/smtpd[3302]: connect from localhost[127.0.0.1]
Jul 10 18:03:36 cobalt postfix/smtpd[3302]: D18C33FC3B: client=localhost[127.0.0.1]
Jul 10 18:03:36 cobalt postfix/cleanup[3292]: D18C33FC3B: message-id=<AANLkTinOmOWDwCbOUCKa6-sTiKYzfYrJRkZih2X-cYgh_at_mail.gmail.com>
Jul 10 18:03:36 cobalt opendkim[3282]: D18C33FC3B no signing table match for `imaginary.user_at_gmail.com'
Jul 10 18:03:37 cobalt postfix/qmgr[1987]: D18C33FC3B: from=<imaginary.user_at_gmail.com>, size=2327, nrcpt=1 (queue active)
Jul 10 18:03:37 cobalt clamsmtpd: 10000B: from=imaginary.user_at_gmail.com, to=test_at_i-systems.co.uk, status=CLEAN
Jul 10 18:03:37 cobalt postfix/smtp[3300]: 9F1AA41C4C: to=<test_at_i-systems.co.uk>, relay=127.0.0.1[127.0.0.1]:10025, delay=0.42, delays=0.17/0.03/0.06/0.16, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as D18C33FC3B)
Jul 10 18:03:37 cobalt postfix/smtpd[3302]: disconnect from localhost[127.0.0.1]
Jul 10 18:03:37 cobalt postfix/qmgr[1987]: 9F1AA41C4C: removed
Jul 10 18:03:37 cobalt postfix/pipe[3304]: D18C33FC3B: to=<test_at_i-systems.co.uk>, relay=zarafa, delay=0.45, delays=0.15/0.02/0/0.27, dsn=2.0.0, status=sent (delivered via zarafa service)
Jul 10 18:03:37 cobalt postfix/qmgr[1987]: D18C33FC3B: removed
Jul 10 18:04:05 cobalt postfix/smtpd[3288]: disconnect from mail-fx0-f50.google.com[209.85.161.50]

Any thoughts?

Stephen

                                               
_________________________________________________________________
http://clk.atdmt.com/UKM/go/197222280/direct/01/
Do you have a story that started on Hotmail? Tell us now
Received on Sat Jul 10 2010 - 17:23:27 PST

This archive was generated by hypermail 2.3.0 : Mon Oct 29 2012 - 23:19:47 PST