[Mimedefang] MIMEDefang crashing regularly

[Aaron Paetznick]

MIMEDefang has been crashing on me fairly regularly and it's driving me 
bonkers.  Basically it goes "to error state" and doesn't recover.  I 
have a script watching the logfile, and when there is a problem MD gets 
automatically restarted, but when this happens it won't start up again 
until I manually remove the /var/lock/subsys/mimedefang file.  Here's a 
clip from /var/log/maillog from when this last happened.  I would 
consider increasing the children, except my MX_MAXIMUM is already set to 
80.  I just now increased the MX_REQUESTS to 500, but I'm not confident 
that that will help too much.  Any ideas?  This is MD v2.41, SA v2.63 + 
the usual suspects on RHEL 3.0 with perl v5.8.3.  Thanks.


--Aaron



Log clip:

Mar 17 05:59:56 elrond sendmail[9880]: i2HBxsic009880: Milter: data, discard
Mar 17 05:59:56 elrond sendmail[9880]: i2HBxsic009880: discarded
Mar 17 05:59:56 elrond mimedefang.pl[5421]: filter: i2HBxtnl009903: 
discard=1
Mar 17 05:59:56 elrond mimedefang[24034]: i2HBxtnl009903: Discarding 
because filter instructed us to
Mar 17 05:59:56 elrond sendmail[9903]: i2HBxtnl009903: Milter: data, discard
Mar 17 05:59:56 elrond sendmail[9903]: i2HBxtnl009903: discarded
Mar 17 05:59:56 elrond mimedefang-multiplexor: Slave 77 (pid 28149) 
taking too long to exit; sending SIGTERM
Mar 17 05:59:56 elrond sendmail[8316]: i2HBwQqf008316: 
<brianl at itctel.com>... User unknown
Mar 17 05:59:56 elrond mimedefang[24034]: Error from multiplexor: error: 
No free slaves
Mar 17 05:59:56 elrond last message repeated 2 times
Mar 17 05:59:56 elrond mimedefang[24034]: mfconnect: No free slaves
Mar 17 05:59:56 elrond mimedefang[24034]: i2HBjB4Z027427: smfi_chgheader 
returned MI_FAILURE
Mar 17 05:59:56 elrond mimedefang[24034]: i2HBjB4Z027427: Discarding 
because filter instructed us to
Mar 17 05:59:56 elrond mimedefang[24034]: i2HBjFKj027731: smfi_chgheader 
returned MI_FAILURE
Mar 17 05:59:56 elrond mimedefang[24034]: i2HBjFKj027731: Discarding 
because filter instructed us to
Mar 17 05:59:56 elrond mimedefang[24034]: mfconnect: No free slaves
Mar 17 05:59:56 elrond mimedefang[24034]: i2HBjBPR027478: smfi_chgheader 
returned MI_FAILURE
Mar 17 05:59:56 elrond mimedefang[24034]: Error from multiplexor: error: 
No free slaves
Mar 17 05:59:56 elrond mimedefang[24034]: i2HBjFhK027792: smfi_chgheader 
returned MI_FAILURE
Mar 17 05:59:56 elrond mimedefang[24034]: i2HBjAW0027383: smfi_chgheader 
returned MI_FAILURE
Mar 17 05:59:56 elrond mimedefang[24034]: i2HBjAW0027383: Discarding 
because filter instructed us to
Mar 17 05:59:56 elrond mimedefang-multiplexor: Reap: Killed slave 77 
(pid 28149) exited due to SIGTERM/SIGKILL as expected.
Mar 17 05:59:56 elrond mimedefang[24034]: mfconnect: No free slaves
Mar 17 05:59:56 elrond mimedefang-multiplexor: Slave 77 resource usage: 
req=500, scans=99, user=14.070, sys=2.830, nswap=0, majflt=44338, 
minflt=351313, maxrss=0, bi=0, bo=0
Mar 17 05:59:56 elrond mimedefang[24034]: i2HBjEbQ027679: smfi_chgheader 
returned MI_FAILURE
Mar 17 05:59:56 elrond mimedefang[24034]: i2HBjDha027617: smfi_chgheader 
returned MI_FAILURE
Mar 17 05:59:56 elrond mimedefang[24034]: i2HBjDha027617: Discarding 
because filter instructed us to
Mar 17 05:59:56 elrond mimedefang[24034]: i2HBjCfY027513: smfi_chgheader 
returned MI_FAILURE
Mar 17 05:59:56 elrond mimedefang[24034]: i2HBjESv027698: smfi_chgheader 
returned MI_FAILURE
Mar 17 05:59:56 elrond mimedefang[24034]: i2HBjCfY027513: smfi_addheader 
returned MI_FAILURE
Mar 17 05:59:56 elrond mimedefang[24034]: i2HBjDKR027586: smfi_chgheader 
returned MI_FAILURE
Mar 17 05:59:56 elrond mimedefang[24034]: i2HBjCEK027496: smfi_chgheader 
returned MI_FAILURE
Mar 17 05:59:56 elrond mimedefang[24034]: i2HBjDKR027586: smfi_addheader 
returned MI_FAILURE
Mar 17 05:59:56 elrond mimedefang[24034]: i2HBjBPR027478: smfi_addheader 
returned MI_FAILURE
Mar 17 05:59:56 elrond mimedefang[24034]: i2HBjCEK027496: smfi_addheader 
returned MI_FAILURE
Mar 17 05:59:56 elrond mimedefang[24034]: i2HBjAlu027403: smfi_chgheader 
returned MI_FAILURE
Mar 17 05:59:56 elrond mimedefang[24034]: i2HBjFhK027792: smfi_addheader 
returned MI_FAILURE
Mar 17 05:59:56 elrond mimedefang[24034]: i2HBjEbQ027679: smfi_addheader 
returned MI_FAILURE
Mar 17 05:59:56 elrond mimedefang[24034]: i2HBjEKu027625: smfi_chgheader 
returned MI_FAILURE
Mar 17 05:59:56 elrond mimedefang[24034]: i2HBjESv027698: smfi_addheader 
returned MI_FAILURE
Mar 17 05:59:56 elrond mimedefang[24034]: i2HBjFou027733: smfi_chgheader 
returned MI_FAILURE
Mar 17 05:59:56 elrond mimedefang[24034]: i2HBjDKh027595: smfi_chgheader 
returned MI_FAILURE
Mar 17 05:59:56 elrond mimedefang[24034]: i2HBjAlu027403: smfi_addheader 
returned MI_FAILURE
Mar 17 05:59:56 elrond sendmail[9858]: i2HBxqtt009858: 
milter_read(mimedefang): cmd read returned 0, expecting 5
Mar 17 05:59:56 elrond sendmail[9858]: i2HBxqtt009858: Milter 
(mimedefang): to error state
Mar 17 05:59:56 elrond sendmail[9858]: i2HBxqtt009858: Milter: data, 
reject=451 4.7.1 Please try again later
Mar 17 05:59:56 elrond sendmail[9917]: i2HBxtnh009917: 
milter_read(mimedefang): cmd read returned 0, expecting 5
Mar 17 05:59:56 elrond sendmail[9917]: i2HBxtnh009917: Milter 
(mimedefang): to error state
Mar 17 05:59:56 elrond sendmail[9858]: i2HBxqtt009858: 
to=<bdoerr at itctel.com>, delay=00:00:01, pri=31649, stat=Please try again 
later
Mar 17 05:59:56 elrond sendmail[9917]: i2HBxtnh009917: Milter: data, 
reject=451 4.7.1 Please try again later
Mar 17 05:59:56 elrond sendmail[9917]: i2HBxtnh009917: 
to=<TJCCAL at ITCTEL.COM>, delay=00:00:00, pri=34798, stat=Please try again 
later
Mar 17 05:59:56 elrond sendmail[9903]: i2HBxtnm009903: Milter 
(mimedefang): write(Q) returned -1, expected 5: Broken pipe
Mar 17 05:59:56 elrond sendmail[9903]: i2HBxtnm009903: Milter 
(mimedefang): to error state
Mar 17 05:59:56 elrond sendmail[9926]: i2HBxu7s009926: Milter 
(mimedefang): write(D) returned -1, expected 5: Broken pipe
Mar 17 05:59:56 elrond sendmail[9926]: i2HBxu7s009926: Milter 
(mimedefang): to error state