----------------------------------------
> Date: Fri, 20 Feb 2009 12:36:07 -0600
> From: njo...@megan.vbhcs.org
> To: jackey...@hotmail.com; postfix-users@postfix.org
> Subject: Re: Your Email
>
> Daniel C wrote:
>> Hi,
>>
>> Sorry for the informations missing. Here they are. This is running on a
>> vserver, which are behind a iptables firewall, and the local IPs are in the
>> range 10.0.32.0/24.
>>
>>
>>
>
> I've annotated your logs...
>
> Feb 19 22:23:15 homer postfix/smtpd[3711]: connect from
> xxxxxxxxxx[xxx.xxx.xxx.xxx]
> Feb 19 22:23:16 homer postfix/smtpd[3711]: 1B292370557:
> client=xxxxxxxxxx[xxx.xxx.xxx.xxx], sasl_method=PLAIN,
> sasl_username=jac...@mydomain.com
> Feb 19 22:23:16 homer postfix/cleanup[3893]: 1B292370557:
> message-id=
> Feb 19 22:23:16 homer postfix/qmgr[3176]: 1B292370557: from=,
> size=796, nrcpt=1 (queue active)
> Feb 19 22:23:16 homer postfix/smtpd[3711]: disconnect from
> xxxxxxxxxx[xxx.xxx.xxx.xxx]
>
> message A arrives via SMTP AUTH
> One recipient.
>
> Feb 19 22:23:16 homer postfix/pickup[3175]: 4774B3706C6:
> uid=207 from=
> Feb 19 22:23:16 homer postfix/cleanup[3893]: 4774B3706C6:
> message-id=
> Feb 19 22:23:16 homer postfix/qmgr[3176]: 4774B3706C6: from=,
> size=686, nrcpt=1 (queue active)
>
> message B arrives via sendmail(1)
> one recipient.
>
> Feb 19 22:23:19 homer postfix/smtpd[3910]: connect from
> mail.mydomain.com[10.0.32.13]
> Feb 19 22:23:19 homer postfix/smtpd[3910]: 81995370679:
> client=homer.mydomain.com[10.0.32.13]
> Feb 19 22:23:19 homer postfix/cleanup[3893]: 81995370679:
> message-id=
> Feb 19 22:23:19 homer postfix/qmgr[3176]: 81995370679: from=,
> size=1269, nrcpt=1 (queue active)
> Feb 19 22:23:19 homer postfix/smtpd[3910]: disconnect from
> homer.mydomain.com[10.0.32.13]
>
> message AF arrives from content filter
> still one recipient.
>
> Feb 19 22:23:19 homer postfix/qmgr[3176]: 1B292370557: removed
> Feb 19 22:23:19 homer postfix/lmtp[3894]: 1B292370557: to=,
> relay=10.0.32.13[10.0.32.13]:10024, delay=3.5,
> delays=0.1/0.01/0/3.4, dsn=2.0.0, status=sent (250 2.0.0 Ok,
> id=21166-10, from MTA([10.0.32.13]:10025): 250 2.0.0 Ok:
> queued as 81995370679)
>
> message A sent to content filter, new ID is 81995370679 (AF)
>
> Feb 19 22:23:19 homer postfix/smtpd[3910]: connect from
> homer.mydomain.com[10.0.32.13]
> Feb 19 22:23:19 homer postfix/smtpd[3910]: 9ACC2370557:
> client=homer.mydomain.com[10.0.32.13]
> Feb 19 22:23:19 homer postfix/cleanup[3893]: 9ACC2370557:
> message-id=
> Feb 19 22:23:19 homer postfix/qmgr[3176]: 9ACC2370557: from=,
> size=1159, nrcpt=1 (queue active)
> Feb 19 22:23:19 homer postfix/smtpd[3910]: disconnect from
> homer.mydomain.com[10.0.32.13]
>
> message BF arrives from content filter
> still one recipient
>
> Feb 19 22:23:19 homer postfix/lmtp[3900]: 4774B3706C6: to=,
> relay=10.0.32.13[10.0.32.13]:10024, delay=3.4,
> delays=0.05/0.01/0/3.3, dsn=2.0.0, status=sent (250 2.0.0 Ok,
> id=21929-10, from MTA([10.0.32.13]:10025): 250 2.0.0 Ok:
> queued as 9ACC2370557)
> Feb 19 22:23:19 homer postfix/qmgr[3176]: 4774B3706C6: removed
>
> message B sent to content filter, new ID 9ACC2370557 (BF)
>
> Feb 19 22:23:20 homer postfix/smtp[3913]: 81995370679: to=,
> relay=mx4.hotmail.com[65.54.244.104]:25, delay=0.6,
> delays=0.01/0.01/0.25/0.34, dsn=2.0.0, status=sent (250
> Queued mail for delivery)
> Feb 19 22:23:20 homer postfix/qmgr[3176]: 81995370679: removed
>
> message AF delivered to destination
>
> Feb 19 22:23:20 homer postfix/smtp[3914]: 9ACC2370557: to=,
> relay=mx3.hotmail.com[65.54.244.72]:25, delay=0.56,
> delays=0.01/0.01/0.25/0.31, dsn=2.0.0, status=sent (250
> Queued mail for delivery)
> Feb 19 22:23:20 homer postfix/qmgr[3176]: 9ACC2370557: removed
>
> mesasge BF delivered to destination
>
> ==================
>
> Looks from here as if the message was submitted twice. The
> problem you report is apparently not a postfix problem, nor
> related to your content_filter.
>
> BTW (and not related to your reported problem), check the RBLs
> you are using in your smtpd_recipient_restrictions; several of
> them are dead.
>
>
> -- Noel Jones
Thanks for the informations. I looked at all my RBLs and modified them.
For the duplicate message, from what I saw too, it looked like the same message
was sent twice. Could it be from the authentication? Could you just take a look
to see if I'm missing something there?
SASLAUTD is started with the "-a pam -r" options.
--------------------------------------------------------
In my /etc/sasl2/smtp.conf
--------------------------------------------------------
mech_list: PLAIN LOGIN
pwcheck_method: saslauthd
--------------------------------------------------------
In my /etc/pam.d/smtp
--------------------------------------------------------
auth required /lib64/security/pam_mysql.so host=hostname db=database
user=username passwd=password table=mailbox usercolumn=username
passwdcolumn=password where=active=1 crypt=1
account required /lib64/security/pam_mysql.so host=hostname db=database
user=username passwd=password table=mailbox usercolumn=username
passwdcolumn=password where=active=1 crypt=1
Daniel
