Re: OpenDKIM isn't signing nor throwing any errors in log

From: Antony <sunnydelight_at_umich.edu>
Date: Sat, 10 Mar 2012 08:38:37 -0600

Murray,

There are two things I tested since my last reply.

I can confirm that yes, Postfix, somehow disregards the setting in its
main.cf and remains unaware of OpenDKIM
The test is as simple as setting smtpd_milters & non_smtpd_milters to a
port that is not listening, i.e. inet:127.0.0.1:1234
After restarting the postfix and trying to send out another email. The
mail.log shows no sign of communication between Postfix & OpenDKIM;
hence, no errors of what so ever.

The second thing I did was to verify that the DNS domain key record
matches up with my private key. The command below shows no error when I
supplied the right private key (I also did a negative test by supplying
wrong private key)
sudo opendkim-testkey -d coudora.com -s main -k
/etc/opendkim/keys/coudora.com/main

Thus, it all comes back to Postfix. I know this is not a Postfix support
group, but if anyone has experience on why Postfix is not reading the
milter settings, I will appreciate it.

Thanks
Antony

On 3/10/12 12:05 AM, Antony wrote:
> Murray,
>
> Thanks for the reply. I can confirm that I restarted Postfix in two
> different ways
> 1. sudo postfix reload
> but just be sure
> 2. sudo /etc/init.d/postfix restart
>
> How do I investigate and figure out why postfix isn't talking to
> opendkim?
>
> If I do telnet localhost 8891, it connects just fine, and proves that
> opendkim is listening. So why wouldn't Postfix just take notice?
>
> Thanks,
> Antony
>
> On 3/9/12 9:41 PM, Murray S. Kucherawy wrote:
>> On Fri, 9 Mar 2012, Antony wrote:
>>> Mar 9 23:35:48 ip-10-64-6-122 opendkim[32270]: OpenDKIM Filter v2.0.2
>>> starting (args: -x /etc/opendkim.conf -u opendkim -P
>>> /var/run/opendkim/opendkim.pid)
>>> Mar 9 23:36:11 ip-10-64-6-122 postfix/pickup[32184]: E5377982ED:
>>> uid=1002
>>> from=<info>
>>> Mar 9 23:36:11 ip-10-64-6-122 postfix/cleanup[32246]: E5377982ED:
>>> message-id=<20120309233611.E5377982ED_at_coudora.com>
>>> Mar 9 23:36:11 ip-10-64-6-122 postfix/qmgr[32185]: E5377982ED:
>>> from=<info_at_coudora.com>, size=332, nrcpt=1 (queue active)
>>> Mar 9 23:36:12 ip-10-64-6-122 postfix/pipe[32248]: E5377982ED:
>>> to=<sunnydelight_at_umich.edu>, relay=aws-email, delay=0.51,
>>> delays=0.03/0/0/0.48, dsn=2.0.0, status=sent (delivered via aws-email
>>> service)
>>> Mar 9 23:36:12 ip-10-64-6-122 postfix/qmgr[32185]: E5377982ED: removed
>>>
>>> And that's it. The only reference I see is that OpenDKIM started and
>>> listens
>>> on port 8891, but there is no sign of OpenDKIM ever picking up a
>>> message and
>>> doing anything with it before handing it over to aws-email for
>>> delivery.
>>
>> That typically means postfix isn't talking to it. Has postfix been
>> restarted since you added the milter configuration lines?
>>
>> -MSK
Received on Sat Mar 10 2012 - 14:38:51 PST