Hi,
today I gave a test run to the quarantine cleanup script during working
hours, and noticed amavisd-new behaviour. Because of DB timeout the
message was left in Postfix' incoming queue.
Setup: 2.3.3 with Mysql 4.1.17 quarantine and Bayes store. Dual Postfix.
No RAM-to-disk swapping occurred.
Here's the log excerpt from one of the messages that got deferred:
Dec 28 15:40:44 srv-asgw03 amavis[23922]: (23922-01-2)
ESMTP:[127.0.0.1]:10024 /var/amavis/tmp/amavis-20051228T154043-23922:
<[EMAIL PROTECTED]> -> <[EMAIL PROTECTED]> Received: SIZE=2281 from
srv-asgw03 ([127.0.0.1]) by localhost (srv-asgw03 [127.0.0.1])
(amavisd-new, port 10024) with ESMTP id 23922-01-2 for
<[EMAIL PROTECTED]>; Wed, 28 Dec 2005 15:40:44 +0100 (CET)
Dec 28 15:40:44 srv-asgw03 amavis[23922]: (23922-01-2) Checking:
RwE12bnYGrrT [60.63.215.214] <[EMAIL PROTECTED]> -> <[EMAIL PROTECTED]>
Dec 28 15:40:45 srv-asgw03 amavis[23922]: (23922-01-2) cached
af681bcbe3a6fba70c9ba9af802f2d58 from <[EMAIL PROTECTED]> (1,1)
Dec 28 15:40:45 srv-asgw03 amavis[23922]: (23922-01-2) p003 1
Content-Type: multipart/alternative
Dec 28 15:40:45 srv-asgw03 amavis[23922]: (23922-01-2) p001 1/1
Content-Type: text/plain, size: 148 B, name:
Dec 28 15:40:45 srv-asgw03 amavis[23922]: (23922-01-2) p002 1/2
Content-Type: text/html, size: 919 B, name:
Dec 28 15:40:45 srv-asgw03 amavis[23922]: (23922-01-2) SEND via SQL:
<[EMAIL PROTECTED]> -> <[EMAIL PROTECTED]>, mail_id RwE12bnYGrrT
Dec 28 15:41:36 srv-asgw03 amavis[23922]: (23922-01-2) writing mail text
to SQL failed: Error closing, flush: sql inserting text failed, sql
execute: sts=1205, DBD::mysql::st execute failed: Lock wait timeout
exceeded; try restarting transaction at (eval 37) line 127, <GEN5> line
336. at (eval 37) line 131, <GEN5> line 336. at (eval 39) line 175,
<GEN5> line 336. at (eval 39) line 67, <GEN5> line 336.
Dec 28 15:41:36 srv-asgw03 amavis[23922]: (23922-01-2) mail_via_sql:
rollback done
Dec 28 15:41:36 srv-asgw03 amavis[23922]: (23922-01-2) TROUBLE in
check_mail: spam quar+notif FAILED: temporarily unable to quarantine:
451 4.5.0 Storing to sql db as mail_id RwE12bnYGrrT failed: writing mail
text to SQL failed: Error closing, flush: sql inserting text failed, sql
execute: sts=1205, DBD::mysql::st execute failed: Lock wait timeout
exceeded; try restarting transaction at (eval 37) line 127, <GEN5> line
336. at (eval 37) line 131, <GEN5> line 336. at (eval 39) line 175,
<GEN5> line 336. at (eval 39) line 67, <GEN5> line 336. at (eval 39)
line 284, <GEN5> line 336., id=23922-01-2 at /usr/local/sbin/amavisd
line 8183, <GEN5> line 336.
Dec 28 15:41:36 srv-asgw03 amavis[23922]: (23922-01-2) PRESERVING
EVIDENCE in /var/amavis/tmp/amavis-20051228T154043-23922
Dec 28 15:41:36 srv-asgw03 amavis[23922]: (23922-01-2) TIMING [total
51834 ms] - lookup_ldap: 7 (0%)0, SMTP pre-DATA-flush: 1 (0%)0, SMTP
DATA: 32 (0%)0, body_digest: 1 (0%)0, sql-enter: 18 (0%)0, mime_decode:
27 (0%)0, get-file-type2: 15 (0%)0, decompose_part: 1 (0%)0,
parts_decode: 0 (0%)0, spam-wb-list: 5 (0%)0, update_cache: 20 (0%)0,
write-header: 5 (0%)0, fwd-sql: 51700 (100%)100, rundown: 3 (0%)100
Dec 28 15:41:36 srv-asgw03 postfix/smtp[23889]: DAE675840FA:
to=<[EMAIL PROTECTED]>, relay=127.0.0.1[127.0.0.1], delay=217,
status=deferred (host 127.0.0.1[127.0.0.1] said: 451-4.5.0 Error in
processing, id=23922-01-2, spam quar+notif FAILED: temporarily unable to
quarantine: 451 4.5.0 Storing to sql db as mail_id RwE12bnYGrrT failed:
writing mail text to SQL failed: Error closing, flush: sql inserting
text failed, sql execute: sts=1205, DBD::mysql::st execute failed: Lock
wait timeout exceeded; try restarting transaction at (eval 37) line 127,
<GEN5> line 336. at (eval 37) line 131, <GEN5> line 336. at (eval 39)
line 175, <GEN5> line 336. at (eval 39) line 67, <GEN5> line 3 451 4.5.0
36. at (eval 39) line 284, <GEN5> line 336., id=23922-01-2 at
/usr/local/sbin/amavisd line 8183, <GEN5> line 336. (in reply to end of
DATA command))
Was the message sent twice? Any cure?
Paolo
-------------------------------------------------------
This SF.net email is sponsored by: Splunk Inc. Do you grep through log files
for problems? Stop! Download the new AJAX search engine that makes
searching your log files as easy as surfing the web. DOWNLOAD SPLUNK!
http://ads.osdn.com/?ad_id=7637&alloc_id=16865&op=click
_______________________________________________
AMaViS-user mailing list
[email protected]
https://lists.sourceforge.net/lists/listinfo/amavis-user
AMaViS-FAQ:http://www.amavis.org/amavis-faq.php3
AMaViS-HowTos:http://www.amavis.org/howto/
