Amavis setup was working just fine for about a week after switching from amavis-milter to Petr's amavisd-milter, then earlier today all of a sudden I noticed no mail was coming in, look in the logs and I see this...
Sep 6 00:53:10 stage2 amavisd-milter[10209]: j867qwKD010376: mlfi_eom: could not read from amavisd socket /var/amavis/amavisd.sock: Cannot allocate memory Stopped and restarted amavisd and amavisd-milter, processed 2 or 3 messages and died again. Restarted again and sendmail and spamassassin as well. Again processed a couple messages and died. Restarted the server completely... was up for a whopping 2 minutes before erroring out again. Both amavis and the milter both seem to be "happy" on their own going through their processing cycles for each mail the comes in, and dying at the same spot each time... so it's not like one or the other is dying completely. Not sure whether this is a milter issue or an amavis issue and even less sure why its an "all of a sudden issue", so I'm crossposting to both lists... anyone have any ideas? I'm going to try feeding the machine an extra stick of memory in the morning, admittedly it is a bit shy on RAM... however it has been shy on ram for months, and it has plenty of (unused) swap, so given things have been working fine for a week I highly doubt that it is lack of RAM. Below is a log clip for one email with debugging turned way up for amavis and the milter, apologies in advance for how horribly this is going to linewrap :-) Sep 6 01:22:58 stage2 amavisd-milter[11003]: j868Mw9h011335: MAIL FROM: <[EMAIL PROTECTED]> Sep 6 01:22:59 stage2 amavisd-milter[11003]: j868Mw9h011335: create work directory /var/amavis/tmp/afj868Mw9h011335 Sep 6 01:22:59 stage2 amavisd-milter[11003]: j868Mw9h011335: create message file /var/amavis/tmp/afj868Mw9h011335/email.txt Sep 6 01:22:59 stage2 amavisd-milter[11003]: j868Mw9h011335: RCPT TO: <[EMAIL PROTECTED]> Sep 6 01:22:59 stage2 sendmail[11335]: j868Mw9h011335: from=<[EMAIL PROTECTED]>, size=2156, class=0, nrcpts=1, msgid=<[EMAIL PROTECTED]>, bodytype=8BITMIME, proto=ESMTP, daemon=smail.iul.net, relay=columbia.iul.net [139.142.140.130] Sep 6 01:22:59 stage2 sendmail[11335]: j868Mw9h011335: Milter add: header: Received-SPF: pass (mail.starmania.net: 139.142.140.130 is authenticated by a trusted mechanism) Sep 6 01:22:59 stage2 amavisd-milter[11003]: j868Mw9h011335: HEADER: Received: from 58.10.215.184 (fi.ba.215.184.revip2.asianet.co.th [58.10.215.184] (may be forged)) by columbia.iul.net (8.12.11/8.12.11) with SMTP id j863aDlY003761 for <[EMAIL PROTECTED]>; Mon, 5 Sep 2005 21:36:21 -0600 Sep 6 01:22:59 stage2 amavisd-milter[11003]: j868Mw9h011335: HEADER: Message-ID: <[EMAIL PROTECTED]> Sep 6 01:22:59 stage2 amavisd-milter[11003]: j868Mw9h011335: HEADER: From: =?windows-1251?B?bHVvbmdAYXJ0ZXNtYXJjaWFsZXMuY29tIA==?= <[EMAIL PROTECTED]> Sep 6 01:22:59 stage2 amavisd-milter[11003]: j868Mw9h011335: HEADER: To: [EMAIL PROTECTED] Sep 6 01:22:59 stage2 amavisd-milter[11003]: j868Mw9h011335: HEADER: Subject: =?windows-1251?B?0+3o6uDr/O3g/yD25e3gIC0g7vIgMjM1IPMu5S4g5+AgMSDq4i7sLg==?= Sep 6 01:22:59 stage2 amavisd-milter[11003]: j868Mw9h011335: HEADER: Date: Tue, 06 Sep 2005 03:22:24 +0000 Sep 6 01:22:59 stage2 amavisd-milter[11003]: j868Mw9h011335: HEADER: MIME-Version: 1.0 Sep 6 01:22:59 stage2 amavisd-milter[11003]: j868Mw9h011335: HEADER: Content-Type: multipart/related; type="multipart/alternative"; boundary="----=_NextPart_000_0000_00FB3848.4F4936B9" Sep 6 01:22:59 stage2 amavisd-milter[11003]: j868Mw9h011335: HEADER: X-Priority: 3 Sep 6 01:22:59 stage2 amavisd-milter[11003]: j868Mw9h011335: HEADER: X-MSMail-Priority: Normal Sep 6 01:22:59 stage2 amavisd-milter[11003]: j868Mw9h011335: HEADER: X-Mailer: Microsoft Outlook Express V6.00.2900.2180 Sep 6 01:22:59 stage2 amavisd-milter[11003]: j868Mw9h011335: HEADER: X-MimeOLE: Produced By Microsoft MimeOLE V6.00.2900.2180 Sep 6 01:22:59 stage2 amavisd-milter[11003]: j868Mw9h011335: HEADER: Received-SPF: pass (mail.starmania.net: 139.142.140.130 is authenticated by a trusted mechanism) Sep 6 01:22:59 stage2 amavisd-milter[11003]: j868Mw9h011335: END OF HEADERS Sep 6 01:23:00 stage2 amavisd-milter[11003]: j868Mw9h011335: body chunk: 1423 Sep 6 01:23:00 stage2 amavisd-milter[11003]: j868Mw9h011335: CONTENT CHECK Sep 6 01:23:00 stage2 amavisd-milter[11003]: j868Mw9h011335: close message file /var/amavis/tmp/afj868Mw9h011335/email.txt Sep 6 01:23:00 stage2 amavisd-milter[11003]: j868Mw9h011335: AMAVISD REQUEST Sep 6 01:23:00 stage2 amavisd-milter[11003]: j868Mw9h011335: request=AM.PDP Sep 6 01:23:00 stage2 amavisd-milter[11003]: j868Mw9h011335: queue_id=j868Mw9h011335 Sep 6 01:23:00 stage2 amavisd-milter[11003]: j868Mw9h011335: sender=<[EMAIL PROTECTED]> Sep 6 01:23:00 stage2 amavisd-milter[11003]: j868Mw9h011335: recipient=<[EMAIL PROTECTED]> Sep 6 01:23:00 stage2 amavisd-milter[11003]: j868Mw9h011335: tempdir=/var/amavis/tmp/afj868Mw9h011335 Sep 6 01:23:00 stage2 amavisd-milter[11003]: j868Mw9h011335: tempdir_removed_by=server Sep 6 01:23:00 stage2 amavis[11038]: policy protocol: tempdir=/var/amavis/tmp/afj868Mw9h011335 Sep 6 01:23:00 stage2 amavisd-milter[11003]: j868Mw9h011335: mail_file=/var/amavis/tmp/afj868Mw9h011335/email.txt Sep 6 01:23:00 stage2 amavisd-milter[11003]: j868Mw9h011335: delivery_care_of=client Sep 6 01:23:00 stage2 amavisd-milter[11003]: j868Mw9h011335: client_address=139.142.140.130 Sep 6 01:23:00 stage2 amavisd-milter[11003]: j868Mw9h011335: client_name=columbia.iul.net Sep 6 01:23:00 stage2 amavisd-milter[11003]: j868Mw9h011335: helo_name=columbia.iul.net Sep 6 01:23:00 stage2 amavisd-milter[11003]: j868Mw9h011335: AMAVISD RESPONSE Sep 6 01:23:24 stage2 amavis[11038]: (11038) Passed SPAM, LOCAL [139.142.140.130] [58.10.215.184] <[EMAIL PROTECTED]> -> <[EMAIL PROTECTED]>, Queue-ID: j868Mw9h011335, Message-ID: <[EMAIL PROTECTED]>, mail_id: KMcNuLt8u4hD, Hits: 9.643, 24202 ms Sep 6 01:23:25 stage2 amavisd-milter[11003]: j868Mw9h011335: setreply=250 2.5.0 Ok, id=11038, continue delivery Sep 6 01:23:32 stage2 amavisd-milter[11003]: j868Mw9h011335: addheader=X-Virus-Scanned amavisd-new at starmania.net Sep 6 01:23:32 stage2 amavisd-milter[11003]: j868Mw9h011335: addheader=X-Spam-Status Yes, hits=9.643 tagged_above=-999 required=5 tests=[AWL=-1.705, BAYES_99=3.5, FORGED_OUTLOOK_TAGS=0.074, HTML_40_50=0.035, HTML_FONT_BIG=0.142, HTML_MESSAGE=0.001, RAZOR2_CF_RANGE_51_100=0.056, RAZOR2_CHECK=2, RCVD_IN_BL_SPAMCOP_NET=1.216, RCVD_IN_XBL=3.076, RCVD_NUMERIC_HELO=1.248] Sep 6 01:23:32 stage2 amavisd-milter[11003]: j868Mw9h011335: addheader=X-Spam-Level ********* Sep 6 01:23:32 stage2 amavisd-milter[11003]: j868Mw9h011335: addheader=X-Spam-Flag YES Sep 6 01:23:32 stage2 sendmail[11335]: j868Mw9h011335: Milter add: header: X-Virus-Scanned: amavisd-new at starmania.net Sep 6 01:23:32 stage2 sendmail[11335]: j868Mw9h011335: Milter add: header: X-Spam-Status: Yes, hits=9.643 tagged_above=-999 required=5 tests=[AWL=-1.705,\n BAYES_99=3.5, FORGED_OUTLOOK_TAGS=0.074, HTML_40_50=0.035,\n HTML_FONT_BIG=0.142, HTML_MESSAGE=0.001, RAZOR2_CF_RANGE_51_100=0.056,\n RAZOR2_CHECK=2, RCVD_IN_BL_SPAMCOP_NET=1.216, RCVD_IN_XBL=3.076,\n RCVD_NUMERIC_HELO=1.248] Sep 6 01:23:32 stage2 sendmail[11335]: j868Mw9h011335: Milter add: header: X-Spam-Level: ********* Sep 6 01:23:32 stage2 sendmail[11335]: j868Mw9h011335: Milter add: header: X-Spam-Flag: YES Sep 6 01:23:32 stage2 amavisd-milter[11003]: j868Mw9h011335: mlfi_eom: could not read from amavisd socket /var/amavis/amavisd.sock: Cannot allocate memory Sep 6 01:23:32 stage2 amavisd-milter[11003]: j868Mw9h011335: set reply 451 4.6.0 Content scanner malfunction Sep 6 01:23:32 stage2 sendmail[11335]: j868Mw9h011335: Milter: data, reject=451 4.6.0 Content scanner malfunction Sep 6 01:23:32 stage2 sendmail[11335]: j868Mw9h011335: to=<[EMAIL PROTECTED]>, delay=00:00:33, pri=32156, stat=Content scanner malfunction Sep 6 01:23:32 stage2 amavisd-milter[11003]: j868Mw9h011335: CLEANUP Sep 6 01:23:33 stage2 amavisd-milter[11003]: j868Mw9h011335: unlink message file /var/amavis/tmp/afj868Mw9h011335/email.txt Sep 6 01:23:33 stage2 amavisd-milter[11003]: j868Mw9h011335: remove work directory /var/amavis/tmp/afj868Mw9h011335 Cheers, >>>>> Mike <<<<< ------------------------------------------------------- SF.Net email is Sponsored by the Better Software Conference & EXPO September 19-22, 2005 * San Francisco, CA * Development Lifecycle Practices Agile & Plan-Driven Development * Managing Projects & Teams * Testing & QA Security * Process Improvement & Measurement * http://www.sqe.com/bsce5sf _______________________________________________ AMaViS-user mailing list AMaViS-user@lists.sourceforge.net https://lists.sourceforge.net/lists/listinfo/amavis-user AMaViS-FAQ:http://www.amavis.org/amavis-faq.php3 AMaViS-HowTos:http://www.amavis.org/howto/
