[Mimedefang] Help Request: MimeDefang breaks Sendmail

Dirk the Daring dirk at psicorps.org
Fri Jun 4 00:21:12 EDT 2004


On Thu, 3 Jun 2004, David F. Skoll wrote:

> >    Should I re-build MIMEDefang (perform a "make clean" and then re-run
> > configure)?
> 
> Yes; then it should work.

   OK, I did that, and along the way fixed a problem where the
md-quarantine directory was incorrectly speced. I had high hopes that the
problem was fixed, but this is what happened:

# pwd
/etc/init.d
# ./mimedefang start

[syslog]
Jun  4 00:09:25 myhost mimedefang-multiplexor[22381]: [ID 268819 mail.info] started; minSlaves=2, maxSlaves=10, maxRequests=200, maxIdleTime=120, busyTimeout=300, clientTimeout=10
Jun  4 00:09:25 myhost mimedefang-multiplexor[22381]: [ID 778059 mail.info] Starting slave 0 (pid 22384) (1 running): Bringing slaves up to minSlaves (2)
Jun  4 00:09:25 myhost mimedefang-multiplexor[22381]: [ID 731849 mail.info] stats 1086322165.986 StartSlave slave=0 nslaves=1 nbusy=0 reason="Bringing slaves up to minSlaves (2)"
Jun  4 00:09:27 myhost mimedefang[22394]: [ID 936676 mail.info] Multiplexor alive - entering main loop
Jun  4 00:09:34 myhost mimedefang-multiplexor[22381]: [ID 980602 mail.info] Slave 0 stderr: unix dgram connect: Socket operation on non-socket at /opt/mimedefang/bin/mimedefang.pl line 465
Jun  4 00:09:34 myhost mimedefang-multiplexor[22381]: [ID 980602 mail.info] Slave 0 stderr: no connection to syslog available at /opt/mimedefang/bin/mimedefang.pl line 465
Jun  4 00:09:35 myhost mimedefang-multiplexor[22381]: [ID 260045 mail.error] Reap: Idle slave 0 (pid 22384) exited normally with status 255 (SLAVE DIED UNEXPECTEDLY)
Jun  4 00:09:35 myhost mimedefang-multiplexor[22381]: [ID 638987 mail.info] Slave 0 resource usage: req=0, scans=0, user=7.460, sys=0.940, nswap=0, majflt=0, minflt=0, maxrss=0, bi=0, bo=0
Jun  4 00:09:35 myhost mimedefang-multiplexor[22381]: [ID 731849 mail.info] stats 1086322175.064 ReapSlave slave=0 nslaves=0 nbusy=0
Jun  4 00:09:36 myhost mimedefang-multiplexor[22381]: [ID 778059 mail.info] Starting slave 0 (pid 22395) (1 running): Bringing slaves up to minSlaves (2)
Jun  4 00:09:36 myhost mimedefang-multiplexor[22381]: [ID 731849 mail.info] stats 1086322176.045 StartSlave slave=0 nslaves=1 nbusy=0 reason="Bringing slaves up to minSlaves (2)"
Jun  4 00:09:44 myhost mimedefang-multiplexor[22381]: [ID 980602 mail.info] Slave 0 stderr: unix dgram connect: Socket operation on non-socket at /opt/mimedefang/bin/mimedefang.pl line 465
Jun  4 00:09:44 myhost mimedefang-multiplexor[22381]: [ID 980602 mail.info] Slave 0 stderr: no connection to syslog available at /opt/mimedefang/bin/mimedefang.pl line 465
Jun  4 00:09:44 myhost mimedefang-multiplexor[22381]: [ID 260045 mail.error] Reap: Idle slave 0 (pid 22395) exited normally with status 255 (SLAVE DIED UNEXPECTEDLY)
[SNIP]

# ./sendmail start

[syslog]
Jun  4 00:10:57 myhost sm-mta[22408]: [ID 702911 mail.info] starting daemon (8.12.11): SMTP+queueing at 01:00:00
Jun  4 00:10:58 myhost sm-msp-queue[22411]: [ID 702911 mail.info] starting daemon (8.12.11): queueing at 00:30:00
[SNIP]

Using PINE, I now tried to send an E-Mail out to an external address

[syslog]
Jun  4 00:11:14 myhost mimedefang[22394]: [ID 893429 mail.debug] Entering mfconnect (line 363)
Jun  4 00:11:14 myhost mimedefang[22394]: [ID 893429 mail.debug] Entering mfclose (line 1472)
Jun  4 00:11:14 myhost mimedefang[22394]: [ID 893429 mail.debug] Entering cleanup (line 1522)
Jun  4 00:11:14 myhost mimedefang[22394]: [ID 521097 mail.debug] Exiting cleanup (line 1524) ret=SMFIS_CONTINUE
Jun  4 00:11:14 myhost mimedefang[22394]: [ID 521097 mail.debug] Exiting mfclose (line 1488) ret=SMFIS_CONTINUE
Jun  4 00:11:14 myhost mimedefang[22394]: [ID 521097 mail.debug] Exiting mfconnect (line 502) ret=SMFIS_CONTINUE
Jun  4 00:11:14 myhost mimedefang[22394]: [ID 893429 mail.debug] Entering envfrom (line 556)
Jun  4 00:11:14 myhost mimedefang[22394]: [ID 521097 mail.debug] Exiting envfrom (line 763) ret=SMFIS_CONTINUE
Jun  4 00:11:14 myhost mimedefang[22394]: [ID 893429 mail.debug] Entering rcptto (line 785)
Jun  4 00:11:14 myhost mimedefang[22394]: [ID 521097 mail.debug] Exiting rcptto (line 864) ret=SMFIS_CONTINUE or SMFIS_ACCEPT
Jun  4 00:11:15 myhost sm-mta[22414]: [ID 801593 mail.info] i544BDtg022414: from=<me at myhost.com>, size=251, class=0, nrcpts=1, msgid=<Pine.GSO.4.58.0406040011060.22404 at myhost.domain.com>, proto=ESMTP, daemon=MTA, relay=myhost.domain.com [xxx.yyy.zzz.aaa]
Jun  4 00:11:15 myhost mimedefang[22394]: [ID 893429 mail.debug] Entering header (line 886)
Jun  4 00:11:15 myhost mimedefang[22394]: [ID 521097 mail.debug] Exiting header (line 977) ret=SMFIS_CONTINUE
Jun  4 00:11:15 myhost mimedefang[22394]: [ID 893429 mail.debug] Entering header (line 886)
Jun  4 00:11:15 myhost mimedefang[22394]: [ID 521097 mail.debug] Exiting header (line 977) ret=SMFIS_CONTINUE
Jun  4 00:11:15 myhost mimedefang[22394]: [ID 893429 mail.debug] Entering header (line 886)
Jun  4 00:11:15 myhost mimedefang[22394]: [ID 521097 mail.debug] Exiting header (line 977) ret=SMFIS_CONTINUE
Jun  4 00:11:15 myhost mimedefang[22394]: [ID 893429 mail.debug] Entering header (line 886)
Jun  4 00:11:15 myhost mimedefang[22394]: [ID 521097 mail.debug] Exiting header (line 977) ret=SMFIS_CONTINUE
Jun  4 00:11:15 myhost mimedefang[22394]: [ID 893429 mail.debug] Entering header (line 886)
Jun  4 00:11:15 myhost mimedefang[22394]: [ID 521097 mail.debug] Exiting header (line 977) ret=SMFIS_CONTINUE
Jun  4 00:11:15 myhost mimedefang[22394]: [ID 893429 mail.debug] Entering header (line 886)
Jun  4 00:11:15 myhost mimedefang[22394]: [ID 521097 mail.debug] Exiting header (line 977) ret=SMFIS_CONTINUE
Jun  4 00:11:15 myhost mimedefang[22394]: [ID 893429 mail.debug] Entering header (line 886)
Jun  4 00:11:15 myhost mimedefang[22394]: [ID 521097 mail.debug] Exiting header (line 977) ret=SMFIS_CONTINUE
Jun  4 00:11:15 myhost mimedefang[22394]: [ID 893429 mail.debug] Entering eoh (line 995)
Jun  4 00:11:15 myhost mimedefang[22394]: [ID 521097 mail.debug] Exiting eoh (line 1022) ret=SMFIS_CONTINUE
Jun  4 00:11:15 myhost mimedefang[22394]: [ID 893429 mail.debug] Entering body (line 1045)
Jun  4 00:11:15 myhost mimedefang[22394]: [ID 521097 mail.debug] Exiting body (line 1111) ret=SMFIS_CONTINUE
Jun  4 00:11:15 myhost mimedefang[22394]: [ID 893429 mail.debug] Entering eom (line 1147)
Jun  4 00:11:15 myhost mimedefang-multiplexor[22381]: [ID 731849 mail.info] stats 1086322275.115 StartFilter slave=0 nslaves=1 nbusy=1
Jun  4 00:11:15 myhost mimedefang-multiplexor[22381]: [ID 980602 mail.info] Slave 0 stderr: unix dgram connect: Socket operation on non-socket at /opt/mimedefang/bin/mimedefang.pl line 465
Jun  4 00:11:15 myhost mimedefang-multiplexor[22381]: [ID 980602 mail.info] Slave 0 stderr: no connection to syslog available at /opt/mimedefang/bin/mimedefang.pl line 465
Jun  4 00:11:15 myhost mimedefang-multiplexor[22381]: [ID 389462 mail.error] Slave 0 died prematurely -- check your filter rules
Jun  4 00:11:15 myhost mimedefang-multiplexor[22381]: [ID 731849 mail.info] stats 1086322275.771 EndFilter slave=0 nslaves=1 nbusy=0 numRequests=1
Jun  4 00:11:15 myhost mimedefang-multiplexor[22381]: [ID 260045 mail.error] Reap: Idle slave 0 (pid 22413) exited normally with status 255 (SLAVE DIED UNEXPECTEDLY)
Jun  4 00:11:15 myhost mimedefang-multiplexor[22381]: [ID 638987 mail.info] Slave 0 resource usage: req=1, scans=1, user=7.200, sys=1.020, nswap=0, majflt=0, minflt=0, maxrss=0, bi=0, bo=0
Jun  4 00:11:15 myhost mimedefang-multiplexor[22381]: [ID 731849 mail.info] stats 1086322275.777 ReapSlave slave=0 nslaves=0 nbusy=0
Jun  4 00:11:15 myhost mimedefang[22394]: [ID 847421 mail.error] Error from multiplexor: ERR No response from slave
Jun  4 00:11:15 myhost mimedefang[22394]: [ID 893429 mail.debug] Entering cleanup (line 1522)
Jun  4 00:11:15 myhost mimedefang[22394]: [ID 598230 mail.warning] i544BDtg022414: Filter failed.  Message kept in /var/spool/mimedefang/mdefang-i544BDtg022414
Jun  4 00:11:15 myhost mimedefang[22394]: [ID 402246 mail.info] Exiting cleanup (line 1566) ret=SMFIS_CONTINUE
Jun  4 00:11:15 myhost mimedefang[22394]: [ID 521097 mail.debug] Exiting eom (line 1193) ret=SMFIS_TEMPFAIL
Jun  4 00:11:15 myhost sm-mta[22414]: [ID 801593 mail.info] i544BDtg022414: Milter: data, reject=451 4.7.1 Please try again later
Jun  4 00:11:16 myhost sm-mta[22414]: [ID 801593 mail.info] i544BDtg022414: to=<me at myhost.com>, delay=00:00:02, pri=30251, stat=Please try again later
Jun  4 00:11:16 myhost mimedefang[22394]: [ID 893429 mail.debug] Entering mfclose (line 1472)
Jun  4 00:11:16 myhost mimedefang[22394]: [ID 893429 mail.debug] Entering cleanup (line 1522)
Jun  4 00:11:16 myhost mimedefang[22394]: [ID 402246 mail.info] Exiting cleanup (line 1566) ret=SMFIS_CONTINUE
Jun  4 00:11:16 myhost mimedefang[22394]: [ID 521097 mail.debug] Exiting mfclose (line 1488) ret=SMFIS_CONTINUE
Jun  4 00:11:16 myhost mimedefang-multiplexor[22381]: [ID 778059 mail.info] Starting slave 0 (pid 22415) (1 running): Bringing slaves up to minSlaves (2)
Jun  4 00:11:16 myhost mimedefang-multiplexor[22381]: [ID 731849 mail.info] stats 1086322276.685 StartSlave slave=0 nslaves=1 nbusy=0 reason="Bringing slaves up to minSlaves (2)"
Jun  4 00:11:25 myhost mimedefang-multiplexor[22381]: [ID 980602 mail.info] Slave 0 stderr: unix dgram connect: Socket operation on non-socket at /opt/mimedefang/bin/mimedefang.pl line 465
Jun  4 00:11:25 myhost mimedefang-multiplexor[22381]: [ID 980602 mail.info] Slave 0 stderr: no connection to syslog available at /opt/mimedefang/bin/mimedefang.pl line 465
Jun  4 00:11:25 myhost mimedefang-multiplexor[22381]: [ID 260045 mail.error] Reap: Idle slave 0 (pid 22415) exited normally with status 255 (SLAVE DIED UNEXPECTEDLY)
Jun  4 00:11:25 myhost mimedefang-multiplexor[22381]: [ID 638987 mail.info] Slave 0 resource usage: req=0, scans=0, user=7.320, sys=0.940, nswap=0, majflt=0, minflt=0, maxrss=0, bi=0, bo=0
Jun  4 00:11:25 myhost mimedefang-multiplexor[22381]: [ID 731849 mail.info] stats 1086322285.724 ReapSlave slave=0 nslaves=0 nbusy=0
Jun  4 00:11:25 myhost mimedefang-multiplexor[22381]: [ID 628557 mail.info] Slave status: Stopped=10 Idle=0 Busy=0 Killed=0 Queued=0 Msgs=1 Activations=12
Jun  4 00:11:26 myhost mimedefang-multiplexor[22381]: [ID 778059 mail.info] Starting slave 0 (pid 22422) (1 running): Bringing slaves up to minSlaves (2)
Jun  4 00:11:26 myhost mimedefang-multiplexor[22381]: [ID 731849 mail.info] stats 1086322286.745 StartSlave slave=0 nslaves=1 nbusy=0 reason="Bringing slaves up to minSlaves (2)"

# ./sendmail stop
# ./mimedefang stop

[syslog]
Jun  4 00:11:32 myhost mimedefang-multiplexor[22381]: [ID 685023 mail.info] Received SIGTERM: Killing slaves and terminating
Jun  4 00:11:34 myhost mimedefang[22394]: [ID 649295 mail.info] MIMEDefang-2.43: mi_stop=1

Dirk



More information about the MIMEDefang mailing list