Postfix/mysql failure with amavisd enabled

Jason Dixon · *nix forums beginner Joined: 30 Mar 2005 Posts: 7

I'm setting up a new server with Postfix-2.0.16 and MySQL virtual
accounts. Everything works as expected until I enable amavisd-new and
reload postfix. Sending a test email, it appears to be failing to
connect to the MySQL database. What's strange is that it connects fine
to the database when receiving mail and the content_filter is disabled.
Here is the capture:

Feb 26 18:08:55 mail postfix/smtpd[22222]: connect from
mail.somehost.com[127.0.0.1]
Feb 26 18:09:01 mail postfix/smtpd[22222]: 92D2863E00:
client=mail.somehost.com[127.0.0.1]
Feb 26 18:09:17 mail postfix/cleanup[22226]: 92D2863E00:
message-id=<20050226230901.92D2863E00@mail.somehost.com>
Feb 26 18:09:17 mail postfix/nqmgr[22208]: 92D2863E00:
from=<root@localhost.somehost.com>, size=391, nrcpt=1 (queue active)
Feb 26 18:09:17 mail amavis[21593]: (21593-03) ESMTP::10024
/var/lib/amavis/tmp/amavis-20050226T180348-21593:
<root@localhost.somehost.com> -> <jason@dixongroup.net> Received:
SIZE=391 from mail.somehost.com ([127.0.0.1]) by localhost
(mail.somehost.com [127.0.0.1]) (amavisd-new, port 10024) with ESMTP id
21593-03 for <jason@dixongroup.net>; Sat, 26 Feb 2005 18:09:17 -0500
(EST)
Feb 26 18:09:17 mail amavis[21593]: (21593-03) Checking:
<root@localhost.somehost.com> -> <jason@dixongroup.net>
Feb 26 18:09:17 mail amavis[21593]: (21593-03) p001 1 Content-Type:
text/plain, size: 6 B, name: Feb 26 18:09:17 mail amavis[21593]:
(21593-03) FWD via SMTP: [127.0.0.1]:10025
<root@localhost.somehost.com> -> <jason@dixongroup.net>
Feb 26 18:09:17 mail postfix/smtpd[22233]: connect from
unknown[127.0.0.1]
Feb 26 18:09:17 mail postfix/smtpd[22233]: AD79863E01:
client=unknown[127.0.0.1
Feb 26 18:09:17 mail postfix/smtpd[22233]: warning: connect to mysql
server localhost: Can't connect to local MySQL server through socket
'/var/lib/mysql/mysql.sock' (2)
Feb 26 18:09:17 mail postfix/smtpd[22233]: AD79863E01: reject: RCPT
from unknown[127.0.0.1]: 451 <jason@dixongroup.net>: Temporary lookup
failure; from=<root@localhost.somehost.com> to=<jason@dixongroup.net>
proto=ESMTP helo=<localhost>
Feb 26 18:09:18 mail amavis[21593]: (21593-03) mail_via_smtp: DATA
skipped, no valid recips, 1
Feb 26 18:09:18 mail postfix/cleanup[22234]: B1AE063E02:
message-id=<20050226230918.B1AE063E02@mail.somehost.com>
Feb 26 18:09:18 mail postfix/nqmgr[22208]: B1AE063E02:
from=<double-bounce@mail.somehost.com>, size=853, nrcpt=1 (queue
active)
Feb 26 18:09:18 mail postfix/smtpd[22233]: disconnect from
unknown[127.0.0.1]
Feb 26 18:09:18 mail amavis[21593]: (21593-03) mail_via_smtp: 451
4.1.0 <jason@dixongroup.net>: Temporary lookup failure
Feb 26 18:09:18 mail amavis[21593]: (21593-03) Blocked CLEAN,
[127.0.0.1] <root@localhost.somehost.com> -> <jason@dixongroup.net>,
Message-ID: <20050226230901.92D2863E00@mail.somehost.com>, Hits: 1.419,
1262 msFeb 26 18:09:18 mail amavis[21593]: (21593-03) TIMING [total
1264 ms] - SMTP EHLO: 4 (0%), SMTP pre-MAIL: 1 (0%), SMTP
pre-DATA-flush: 3 (0%), SMTP DATA: 30 (2%), body_hash: 0 (0%),
mime_decode: 9 (1%), get-file-type1: 18 (1%), decompose_part: 1 (0%),
parts_decode: 0 (0%), AV-scan-1: 4 (0%), spam-wb-list: 3 (0%), SA msg
read: 1 (0%), SA parse: 1 (0%), SA check: 117 (9%), update_cache: 1
(0%), fwd-connect: 13 (1%), fwd-mail-from: 1 (0%), fwd-rcpt-to: 1010
(80%), fwd-rundown: 27 (2%), main_log_entry: 19 (2%), update_snmp: 0
(0%), unlink-1-files: 1 (0%), rundown: 0 (0%)
Feb 26 18:09:18 mail postfix/smtp[22230]: 92D2863E00:
to=<jason@dixongroup.net>, relay=127.0.0.1[127.0.0.1], delay=17,
status=deferred (host 127.0.0.1[127.0.0.1] said: 451 4.1.0
<jason@dixongroup.net>: Temporary lookup failure (in reply to end of
DATA command))
Feb 26 18:09:18 mail postfix/virtual[22236]: B1AE063E02:
to=<postmaster@somehost.com>, orig_to=<postmaster>, relay=virtual,
delay=0, status=bounced (unknown user: "postmaster@somehost.com")
Feb 26 18:09:18 mail postfix/bounce[22238]: warning: B1AE063E02:
undeliverable postmaster notification discarded
Feb 26 18:09:18 mail postfix/smtpd[22222]: disconnect from
mail.somehost.com[127.0.0.1]

Can anyone clue me in to what I might be overlooking? I'll post the
master.cf or main.cf files if that will help.

Thanks,

--
Jason Dixon
DixonGroup Consulting
http://www.dixongroup.net

Google