I am having a problem with multi-recipient mail.
The message is delivered to the first recipient, however, dbmail
bounces the second recipient as "user unknown".
A sample log entry:
Feb 28 15:36:18 fw postfix/smtpd[15481]: connect from echo.1fast.us
[72.244.45.37]
Feb 28 15:36:19 fw postfix/policy-spf[15485]: : testing: stripped
[EMAIL PROTECTED], stripped [EMAIL PROTECTED]
Feb 28 15:36:19 fw postfix/policy-spf[15485]: : SPF pass:
smtp_comment=Please see http://spf.pobox.com/why.html?sender=mark%
40downtownhelpdesk.com&ip=72.244.45.37&receiver=fw.cindrich.com:
72.244.45.32/29 contains 72.244.45.37,
header_comment=fw.cindrich.com: domain of [EMAIL PROTECTED]
designates 72.244.45.37 as permitted sender
Feb 28 15:36:19 fw postfix/policy-spf[15485]: decided action=DUNNO
Feb 28 15:36:19 fw postfix/smtpd[15481]: NOQUEUE: client=echo.1fast.us
[72.244.45.37]
Feb 28 15:36:19 fw policyd: rcpt=2172, whitelist=pass,
host=72.244.45.37 (echo.1fast.us), [EMAIL PROTECTED],
[EMAIL PROTECTED], size=758
Feb 28 15:36:19 fw postfix/policy-spf[15485]: : testing: stripped
[EMAIL PROTECTED], stripped [EMAIL PROTECTED]
Feb 28 15:36:19 fw postfix/policy-spf[15485]: : SPF pass:
smtp_comment=Please see http://spf.pobox.com/why.html?sender=mark%
40downtownhelpdesk.com&ip=72.244.45.37&receiver=fw.cindrich.com:
72.244.45.32/29 contains 72.244.45.37,
header_comment=fw.cindrich.com: domain of [EMAIL PROTECTED]
designates 72.244.45.37 as permitted sender
Feb 28 15:36:19 fw postfix/policy-spf[15485]: decided action=DUNNO
Feb 28 15:36:19 fw amavis[15287]: (15287-03) ESMTP::10023 /var/amavis/
tmp/amavis-20060228T150339-15287: <[EMAIL PROTECTED]> ->
<[EMAIL PROTECTED]>,<[EMAIL PROTECTED]> Received: SIZE=758 from
postoffice.cindrich.com ([127.0.0.1]) by localhost
(postoffice.cindrich.com [127.0.0.1]) (amavisd-new, port 10023) with
ESMTP id 15287-03; Tue, 28 Feb 2006 15:36:19 -0500 (EST)
Feb 28 15:36:19 fw amavis[15287]: (15287-03) Actual message size 928
B greater than the declared 758 B
Feb 28 15:36:19 fw amavis[15287]: (15287-03) Checking: TUFDFkt16QOg
[72.244.45.37] <[EMAIL PROTECTED]> ->
<[EMAIL PROTECTED]>,<[EMAIL PROTECTED]>
Feb 28 15:36:19 fw amavis[15287]: (15287-03) p001 1 Content-Type:
text/plain, size: 42 B, name:
Feb 28 15:36:20 fw postfix/smtpd[15487]: connect from
localhost.cindrich.com[127.0.0.1]
Feb 28 15:36:20 fw postfix/smtpd[15487]: 1D80ED5098:
client=localhost.cindrich.com[127.0.0.1]
Feb 28 15:36:20 fw postfix/cleanup[15488]: 1D80ED5098: message-
id=<[EMAIL PROTECTED]>
Feb 28 15:36:20 fw postfix/qmgr[15476]: 1D80ED5098:
from=<[EMAIL PROTECTED]>, size=1358, nrcpt=2 (queue active)
Feb 28 15:36:20 fw postfix/smtpd[15487]: disconnect from
localhost.cindrich.com[127.0.0.1]
Feb 28 15:36:20 fw amavis[15287]: (15287-03) FWD via SMTP:
<[EMAIL PROTECTED]> -> <[EMAIL PROTECTED]>,
<[EMAIL PROTECTED]>, 250 2.6.0 Ok, id=15287-03, from MTA([127.0.0.1]:
587): 250 2.0.0 Ok: queued as 1D80ED5098
Feb 28 15:36:20 fw amavis[15287]: (15287-03) Passed CLEAN,
[72.244.45.37] [72.244.45.37] <[EMAIL PROTECTED]> ->
<[EMAIL PROTECTED]>,<[EMAIL PROTECTED]>, Message-ID: <07103736-
[EMAIL PROTECTED]>, mail_id:
TUFDFkt16QOg, Hits: -0.001, 630 ms
Feb 28 15:36:20 fw postfix/smtpd[15481]: disconnect from echo.1fast.us
[72.244.45.37]
Feb 28 15:36:20 fw dbmail/smtp[15493]: sort.c, sort_and_deliver:
message id=25136, size=1328 is inserted
Feb 28 15:36:20 fw dbmail/smtp[15495]: dbmysql.c,db_query: query
[UPDATE dbmail_users SET curmail_size = curmail_size + '1328' WHERE
user_idnr = '6'] failed
Feb 28 15:36:20 fw dbmail/smtp[15495]: dbmysql.c,db_query:
mysql_real_query failed: Deadlock found when trying to get lock; Try
restarting transaction
Feb 28 15:36:20 fw dbmail/smtp[15495]: db.c,db_add_quotum_used: error
adding [1328] to quotum of user [6]
Feb 28 15:36:20 fw dbmail/smtp[15495]: db.c,db_copymsg: error setting
the new quotum used value for user [6]
Feb 28 15:36:20 fw dbmail/smtp[15495]: sort.c, sort_and_deliver:
error copying message to user [6]
Feb 28 15:36:20 fw postfix/pipe[15491]: 1D80ED5098:
to=<[EMAIL PROTECTED]>, relay=dbmail, delay=0.4,
delays=0.15/0.08/0/0.17, dsn=2.0.0, status=sent (delivered via dbmail
service)
Feb 28 15:36:20 fw dbmail/smtp[15495]: pipe.c, insert_messages:
temporary failure sort_and_deliver for useridnr [6]
Feb 28 15:36:20 fw postfix/pipe[15494]: 1D80ED5098:
to=<[EMAIL PROTECTED]>, relay=dbmail, delay=0.42,
delays=0.15/0.12/0/0.15, dsn=5.1.1, status=bounced (user unknown)
Interestingly, user id number 6 is NOT "AMY" or "PHIL" @ CINDRICH.COM.
Any ideas? Any information anyone may require will be provided.
