[Mimedefang] Help Request: MimeDefang breaks Sendmail

Dirk the Daring dirk at psicorps.org
Wed Jun 2 22:34:17 EDT 2004 

   Hello, I'm new to this list and to MimeDefang. After seeing other
mailing lists I'm on successfully use MimeDefang, I'm trying to get it
running for my mailing lists.

   I'm having a problem and I hope someone on this list can help me. In
short, when I insert MimeDefang into my system, sendmail is suddenly
unable to send anything. I've looked in the archives, and found some
similar issues in December of last year, but nothing that seems to solve
my issue.

   Allow me to detail the problem... (I'll be happy to provide any
additional information)

The System: Sun SparcStation 20 SM51 CPU 384 MB RAM Solaris v2.8 with MU
#7 and latest Recommended Patches

uname -a output: SunOS myhost 5.8 Generic_117000-03 sun4m sparc SUNW,SPARCstation-20

The software:
Sendmail v8.12.11 - Installed and functioning (can send and receive)
Perl v5.8.4 (Installed and functioning - Majordomo v1.94.5 likes it just fine)

[Attempting to install]
MimeDefang v2.43
Digest::SHA1 v2.10
IO::stringy v2.109
Mail::Tools v1.62
MIME::Tools v5.411aP2 
UNIX::Syslog v0.100 (note: the problem occurred before and after
		UNIX::Syslog was installed)

The problem:

MimeDefang appears to start up just fine:

# /etc/init.d/mimedefang start
Starting mimedefang-multiplexor:	[OK]
Starting mimedefang:			[OK]
#

Similarly, sendmail starts fine:
# /etc/init.d/sendmail start
#

However, the following appears in /var/log/syslog (and also on the console):

Jun  2 18:32:28 myhost mimedefang-multiplexor[18267]: [ID 268819
mail.info] started; minSlaves=2, maxSlaves=10, maxRequests=200,
maxIdleTime=120, busyTimeout=300, clientTimeout=10

Jun  2 18:32:28 myhost mimedefang-multiplexor[18267]: [ID 778059
mail.info] Starting slave 0 (pid 18268) (1 running): Bringing slaves up to
minSlaves (2)

Jun  2 18:32:28 myhost mimedefang-multiplexor[18267]: [ID 731849
mail.info] stats 1086215548.446 StartSlave slave=0 nslaves=1 nbusy=0
reason="Bringing slaves up to minSlaves (2)"

Jun  2 18:32:30 myhost mimedefang[18280]: [ID 936676 mail.info]
Multiplexor alive - entering main loop

Jun  2 18:32:37 myhost mimedefang-multiplexor[18267]: [ID 980602
mail.info] Slave 0 stderr: unix dgram connect: Socket operation on
non-socket at /opt/mimedefang/bin/mimedefang.pl line 465

Jun  2 18:32:37 myhost mimedefang-multiplexor[18267]: [ID 980602
mail.info] Slave 0 stderr: no connection to syslog available at
/opt/mimedefang/bin/mimedefang.pl line 465

Jun  2 18:32:38 myhost mimedefang-multiplexor[18267]: [ID 260045
mail.error] Reap: Idle slave 0 (pid 18268) exited normally with status 255
(SLAVE DIED UNEXPECTEDLY)

Jun  2 18:32:38 myhost mimedefang-multiplexor[18267]: [ID 638987
mail.info] Slave 0 resource usage: req=0, scans=0, user=7.330, sys=0.960,
nswap=0, majflt=0, minflt=0, maxrss=0, bi=0, bo=0

Jun  2 18:32:38 myhost mimedefang-multiplexor[18267]: [ID 731849
mail.info] stats 1086215558.032 ReapSlave slave=0 nslaves=0 nbusy=0

Jun  2 18:32:38 myhost mimedefang-multiplexor[18267]: [ID 778059
mail.info] Starting slave 0 (pid 18281) (1 running): Bringing slaves up to
minSlaves (2)

Jun  2 18:32:38 myhost mimedefang-multiplexor[18267]: [ID 731849
mail.info] stats 1086215558.506 StartSlave slave=0 nslaves=1 nbusy=0
reason="Bringing slaves up to minSlaves (2)"

Jun  2 18:32:46 myhost sm-mta[18284]: [ID 702911 mail.info] starting
daemon (8.12.11): SMTP+queueing at 01:00:00

Jun  2 18:32:47 myhost sm-msp-queue[18287]: [ID 702911 mail.info] starting
daemon(8.12.11): queueing at 00:30:00

Jun  2 18:32:48 myhost mimedefang-multiplexor[18267]: [ID 980602
mail.info] Slave 0 stderr: unix dgram connect: Socket operation on
non-socket at /opt/mimedefang/bin/mimedefang.pl line 465

Jun  2 18:32:48 myhost mimedefang-multiplexor[18267]: [ID 980602
mail.info] Slave 0 stderr: no connection to syslog available at
/opt/mimedefang/bin/mimedefang.pl line 465

Jun  2 18:32:48 myhost mimedefang-multiplexor[18267]: [ID 260045
mail.error] Reap: Idle slave 0 (pid 18281) exited normally with status 255
(SLAVE DIED UNEXPECTEDLY)

Jun  2 18:32:48 myhost mimedefang-multiplexor[18267]: [ID 638987
mail.info] Slave 0 resource usage: req=0, scans=0, user=7.210, sys=1.010,
nswap=0, majflt=0, minflt=0, maxrss=0, bi=0, bo=0

Jun  2 18:32:48 myhost mimedefang-multiplexor[18267]: [ID 731849
mail.info] stats 1086215568.321 ReapSlave slave=0 nslaves=0 nbusy=0

Jun  2 18:32:48 myhost mimedefang-multiplexor[18267]: [ID 778059
mail.info] Starting slave 0 (pid 18289) (1 running): Bringing slaves up to
minSlaves (2)

Jun  2 18:32:48 myhost mimedefang-multiplexor[18267]: [ID 731849
mail.info] stats 1086215568.603 StartSlave slave=0 nslaves=1 nbusy=0
reason="Bringing slaves up to minSlaves (2)"

Jun  2 18:32:57 myhost mimedefang-multiplexor[18267]: [ID 980602
mail.info] Slave 0 stderr: unix dgram connect: Socket operation on
non-socket at /opt/mimedefang/bin/mimedefang.pl line 465

Jun  2 18:32:57 myhost mimedefang-multiplexor[18267]: [ID 980602
mail.info] Slave 0 stderr: no connection to syslog available at
/opt/mimedefang/bin/mimedefang.pl line 465

Jun  2 18:32:57 myhost mimedefang-multiplexor[18267]: [ID 260045
mail.error] Reap: Idle slave 0 (pid 18289) exited normally with status 255
(SLAVE DIED UNEXPECTEDLY)

Jun  2 18:32:57 myhost mimedefang-multiplexor[18267]: [ID 638987
mail.info] Slave 0 resource usage: req=0, scans=0, user=7.270, sys=0.960,
nswap=0, majflt=0, minflt=0, maxrss=0, bi=0, bo=0

Jun  2 18:32:57 myhost mimedefang-multiplexor[18267]: [ID 731849
mail.info] stats 1086215577.591 ReapSlave slave=0 nslaves=0 nbusy=0

Jun  2 18:32:58 myhost mimedefang-multiplexor[18267]: [ID 778059
mail.info] Starting slave 0 (pid 18296) (1 running): Bringing slaves up to
minSlaves (2)

Jun  2 18:32:58 myhost mimedefang-multiplexor[18267]: [ID 731849
mail.info] stats 1086215578.644 StartSlave slave=0 nslaves=1 nbusy=0
reason="Bringing slaves up to minSlaves (2)"

Jun  2 18:32:59 myhost mimedefang-multiplexor[18267]: [ID 685023
mail.info] Received SIGTERM: Killing slaves and terminating

Jun  2 18:33:00 myhost mimedefang[18280]: [ID 649295 mail.info]
MIMEDefang-2.43: mi_stop=1

I restarted and tried to send some E-Mail from inside Pine, and I got
"Mail not sent: Sending Error: 471 4.7.1 Please try again later"

The relevant portion of /var/log/syslog looks like this:

Jun  2 18:39:43 myhost mimedefang[18349]: [ID 893429 mail.debug] Entering
mfconnect (line 363)

Jun  2 18:39:43 myhost mimedefang[18349]: [ID 893429 mail.debug] Entering
mfclose(line 1472)

Jun  2 18:39:43 myhost mimedefang[18349]: [ID 893429 mail.debug] Entering
cleanup(line 1522)

Jun  2 18:39:43 myhost mimedefang[18349]: [ID 521097 mail.debug] Exiting
cleanup (line 1524) ret=SMFIS_CONTINUE

Jun  2 18:39:43 myhost mimedefang[18349]: [ID 521097 mail.debug] Exiting
mfclose (line 1488) ret=SMFIS_CONTINUE

Jun  2 18:39:43 myhost mimedefang[18349]: [ID 521097 mail.debug] Exiting
mfconnect (line 502) ret=SMFIS_CONTINUE

Jun  2 18:39:43 myhost mimedefang[18349]: [ID 893429 mail.debug] Entering
envfrom(line 556)

Jun  2 18:39:43 myhost mimedefang[18349]: [ID 521097 mail.debug] Exiting
envfrom (line 763) ret=SMFIS_CONTINUE

Jun  2 18:39:43 myhost mimedefang[18349]: [ID 893429 mail.debug] Entering
rcptto (line 785)

Jun  2 18:39:43 myhost mimedefang[18349]: [ID 521097 mail.debug] Exiting
rcptto (line 864) ret=SMFIS_CONTINUE or SMFIS_ACCEPT

Jun  2 18:39:44 myhost sm-mta[18359]: [ID 801593 mail.info]
i52Mdhu0018359: from=<me at myhost.com>, size=256, class=0, nrcpts=1,
msgid=<Pine.GSO.4.58.0406021839280.18309 at ahost.myhost.com>, proto=ESMTP,
daemon=MTA, relay=ahost.myhost.com [xxx.yyy.zzz.aaa]

Jun  2 18:39:44 myhost mimedefang[18349]: [ID 893429 mail.debug] Entering
header (line 886)    

Jun  2 18:39:44 myhost mimedefang[18349]: [ID 521097 mail.debug] Exiting
header (line 977) ret=SMFIS_CONTINUE

Jun  2 18:39:44 myhost mimedefang[18349]: [ID 893429 mail.debug] Entering
header (line 886)

Jun  2 18:39:44 myhost mimedefang[18349]: [ID 521097 mail.debug] Exiting
header (line 977) ret=SMFIS_CONTINUE

Jun  2 18:39:44 myhost mimedefang[18349]: [ID 893429 mail.debug] Entering
header (line 886)

Jun  2 18:39:44 myhost mimedefang[18349]: [ID 521097 mail.debug] Exiting
header (line 977) ret=SMFIS_CONTINUE

Jun  2 18:39:44 myhost mimedefang[18349]: [ID 893429 mail.debug] Entering
header (line 886)

Jun  2 18:39:44 myhost mimedefang[18349]: [ID 521097 mail.debug] Exiting
header (line 977) ret=SMFIS_CONTINUE

Jun  2 18:39:44 myhost mimedefang[18349]: [ID 893429 mail.debug] Entering
header (line 886)

Jun  2 18:39:44 myhost mimedefang[18349]: [ID 521097 mail.debug] Exiting
header (line 977) ret=SMFIS_CONTINUE

Jun  2 18:39:44 myhost mimedefang[18349]: [ID 893429 mail.debug] Entering
header (line 886)

Jun  2 18:39:44 myhost mimedefang[18349]: [ID 521097 mail.debug] Exiting
header (line 977) ret=SMFIS_CONTINUE

Jun  2 18:39:44 myhost mimedefang[18349]: [ID 893429 mail.debug] Entering
header (line 886)    

Jun  2 18:39:44 myhost mimedefang[18349]: [ID 521097 mail.debug] Exiting
header (line 977) ret=SMFIS_CONTINUE

Jun  2 18:39:44 myhost mimedefang[18349]: [ID 893429 mail.debug] Entering
eoh (line 995)

Jun  2 18:39:44 myhost mimedefang[18349]: [ID 521097 mail.debug] Exiting
eoh (line 1022) ret=SMFIS_CONTINUE

Jun  2 18:39:44 myhost mimedefang[18349]: [ID 893429 mail.debug] Entering
body (line 1045)

Jun  2 18:39:44 myhost mimedefang[18349]: [ID 521097 mail.debug] Exiting
body (line 1111) ret=SMFIS_CONTINUE

Jun  2 18:39:44 myhost mimedefang[18349]: [ID 893429 mail.debug] Entering
eom (line 1147)

Jun  2 18:39:44 myhost mimedefang-multiplexor[18336]: [ID 731849
mail.info] stats 1086215984.175 StartFilter slave=0 nslaves=1 nbusy=1

Jun  2 18:39:49 myhost mimedefang-multiplexor[18336]: [ID 980602
mail.info] Slave 0 stderr: unix dgram connect: Socket operation on
non-socket at /opt/mimedefang/bin/mimedefang.pl line 465

Jun  2 18:39:49 myhost mimedefang-multiplexor[18336]: [ID 980602
mail.info] Slave 0 stderr: no connection to syslog available at
/opt/mimedefang/bin/mimedefang.pl line 465

Jun  2 18:39:49 myhost mimedefang-multiplexor[18336]: [ID 389462
mail.error] Slave 0 died prematurely -- check your filter rules

Jun  2 18:39:49 myhost mimedefang-multiplexor[18336]: [ID 731849
mail.info] stats 1086215989.491 EndFilter slave=0 nslaves=1 nbusy=0
numRequests=1

Jun  2 18:39:49 myhost mimedefang-multiplexor[18336]: [ID 260045
mail.error] Reap: Idle slave 0 (pid 18358) exited normally with status 255
(SLAVE DIED UNEXPECTEDLY)

Jun  2 18:39:49 myhost mimedefang-multiplexor[18336]: [ID 638987
mail.info] Slave 0 resource usage: req=1, scans=1, user=7.170, sys=1.010,
nswap=0, majflt=0, minflt=0, maxrss=0, bi=0, bo=0

Jun  2 18:39:49 myhost mimedefang-multiplexor[18336]: [ID 731849
mail.info] stats 1086215989.498 ReapSlave slave=0 nslaves=0 nbusy=0  

Jun  2 18:39:49 myhost mimedefang[18349]: [ID 847421 mail.error] Error
from multiplexor: ERR No response from slave

Jun  2 18:39:49 myhost mimedefang[18349]: [ID 893429 mail.debug] Entering
cleanup (line 1522)

Jun  2 18:39:49 myhost mimedefang[18349]: [ID 598230 mail.warning]
i52Mdhu0018359: Filter failed.  Message kept in
/var/spool/mimedefang/mdefang-i52Mdhu0018359

Jun  2 18:39:49 myhost mimedefang[18349]: [ID 402246 mail.info] Exiting
cleanup (line 1566) ret=SMFIS_CONTINUE

Jun  2 18:39:49 myhost mimedefang[18349]: [ID 521097 mail.debug] Exiting
eom (line 1193) ret=SMFIS_TEMPFAIL

Jun  2 18:39:49 myhost sm-mta[18359]: [ID 801593 mail.info]
i52Mdhu0018359: Milter: data, reject=451 4.7.1 Please try again later

Jun  2 18:39:49 myhost sm-mta[18359]: [ID 801593 mail.info]
i52Mdhu0018359: to=<me at myhost.com>, delay=00:00:06, pri=30256, stat=Please
try again later

Jun  2 18:39:49 myhost mimedefang[18349]: [ID 893429 mail.debug] Entering
mfclose(line 1472)

Jun  2 18:39:49 myhost mimedefang[18349]: [ID 893429 mail.debug] Entering
cleanup(line 1522)

Jun  2 18:39:49 myhost mimedefang[18349]: [ID 402246 mail.info] Exiting
cleanup (line 1566) ret=SMFIS_CONTINUE

Jun  2 18:39:49 myhost mimedefang[18349]: [ID 521097 mail.debug] Exiting
mfclose (line 1488) ret=SMFIS_CONTINUE      

The Question:

What is going wrong and how do I fix it?

More information about the MIMEDefang mailing list