[Mimedefang] Tons o' problems with multiplexor

Mon Mar 18 21:12:42 EST 2002

I wish I could be more specific.  I really do.  But when you see the
snippets from the log files below, you'll see what I mean.

Environment:
BSDi 4.1 Unix
Sendmail 8.11.6
MIMEDefang 2.6
drwx------  41 root  wheel  1536 Mar 19 01:48 /var/spool/MIMEDefang

Commands being run:
/usr/local/bin/mimedefang-multiplexor -x 32 -m 2 -b 30 -i 300 -l -t
/var/log/md-stats -u -s /var/run/mdefang-mx.sock

/usr/local/bin/mimedefang -m /var/run/mdefang-mx.sock -p
/var/run/mimedefang.sock

/usr/sbin/sendmail -bd -q30m

I put the max number of daemons to 32 because this is a pretty busy server.
While I was testing it out (for about 4 minutes) it got up to 9 going at
once.  But I digress.

I'm seeing the following kinds of messages in /var/log/maillog.

- Everything starts out fine:

Mar 19 01:37:09 clio mimedefang-multiplexor: started; minSlaves=2,
maxSlaves=32, maxRequests=100, maxIdleTime=300, busyTimeout=30,
clientTimeout=10
Mar 19 01:37:09 clio mimedefang-multiplexor: Starting slave 0 (pid 3487) (1
running): Starting initial slaves
Mar 19 01:37:09 clio mimedefang-multiplexor: Starting slave 1 (pid 3488) (2
running): Starting initial slaves
Mar 19 01:38:48 clio mimedefang-multiplexor: Starting slave 2 (pid 4482) (3
running): About to check relay
Mar 19 01:38:48 clio mimedefang-multiplexor: Starting slave 3 (pid 4484) (4
running): About to check relay
Mar 19 01:38:48 clio mimedefang-multiplexor: Starting slave 4 (pid 4486) (5
running): About to perform scan
Mar 19 01:38:48 clio mimedefang-multiplexor: Starting slave 5 (pid 4487) (6
running): About to perform scan
Mar 19 01:38:49 clio mimedefang-multiplexor: Starting slave 6 (pid 4491) (7
running): About to perform scan

- Then things start to head south:  (I sorted these by PID, which is why
it's not in chronological order)

Mar 19 01:38:56 clio mimedefang[4542]: Error from multiplexor: 
Mar 19 01:38:57 clio mimedefang[4542]: Could not create
/var/spool/MIMEDefang/mdefang-3C9696AF-0: File exists

Mar 19 01:38:57 clio mimedefang[3810]: Error from multiplexor: 
Mar 19 01:38:58 clio mimedefang[3810]: Could not create
/var/spool/MIMEDefang/mdefang-3C9696AF-0: No such file or directory
Mar 19 01:38:58 clio mimedefang[3810]: Could not create
/var/spool/MIMEDefang/mdefang-3C9696AE-1: File exists

Mar 19 01:38:58 clio mimedefang[4546]: Could not create
/var/spool/MIMEDefang/mdefang-3C9696AE-0: File exists
Mar 19 01:38:58 clio mimedefang[4546]: Failure in cleanup line 1242: Bad
file descriptor

Mar 19 01:38:58 clio mimedefang[4549]: Failure in cleanup line 1275: No
child processes
Mar 19 01:38:58 clio mimedefang[4549]: Could not create
/var/spool/MIMEDefang/mdefang-3C9696AF-0: No such file or directory
Mar 19 01:38:58 clio mimedefang[4549]: Failure in cleanup line 1242: Bad
file descriptor

Mar 19 01:38:58 clio mimedefang[4554]: Failure in cleanup line 1275: No
child processes
Mar 19 01:38:58 clio mimedefang[4554]: MIMEDefang-2.6: mi_rd_cmd: select
returned -1: Bad file descriptor
Mar 19 01:38:58 clio mimedefang[4554]: MIMEDefang-2.6: mi_rd_cmd: select
returned -1: Bad file descriptor
Mar 19 01:38:58 clio mimedefang[4554]: MIMEDefang-2.6: mi_rd_cmd: select
returned -1: Bad file descriptor
Mar 19 01:38:58 clio mimedefang[4554]: Failure in cleanup line 1242: Bad
file descriptor
Mar 19 01:38:58 clio mimedefang[4554]: Error from multiplexor: 
Mar 19 01:38:58 clio mimedefang[4554]: MIMEDefang-2.6: mi_rd_cmd: select
returned -1: Bad file descriptor

- Here's the error output from just one process (PID 3810):

Mar 19 01:38:57 clio mimedefang[3810]: Error from multiplexor: 
Mar 19 01:38:58 clio mimedefang[3810]: Could not create
/var/spool/MIMEDefang/mdefang-C9696AF-0: No such file or directory
Mar 19 01:38:58 clio mimedefang[3810]: Could not create
/var/spool/MIMEDefang/mdefang-3C9696AE-1: File exists
Mar 19 01:39:01 clio mimedefang[3810]: Error from multiplexor: error: Cannot
chdir(/var/spool/MIMEDefang/mdefang-3C9696AC-2): No such file or directory
Mar 19 01:39:04 clio mimedefang[3810]: Error from multiplexor: error: Cannot
chdir(/var/spool/MIMEDefang/mdefang-3C9696A6-2): No such file or directory
Mar 19 01:39:09 clio mimedefang[3810]: Could not create
/var/spool/MIMEDefang/mdefang-3C9696BA-3: File exists
Mar 19 01:39:09 clio mimedefang[3810]: Error from multiplexor: 
Mar 19 01:39:09 clio mimedefang[3810]: mfconnect: No free slaves
Mar 19 01:39:09 clio mimedefang[3810]: Error from multiplexor: 
Mar 19 01:39:09 clio mimedefang[3810]: Error from multiplexor: 
Mar 19 01:39:10 clio mimedefang[3810]: Error from multiplexor: error: Cannot
chdir(/var/spool/MIMEDefang/mdefang-3C9696B4-4): No such file or directory
Mar 19 01:39:11 clio mimedefang[3810]: Error from multiplexor: error: Cannot
chdir(/var/spool/MIMEDefang/mdefang-3C9696BB-1): No such file or directory
Mar 19 01:39:11 clio mimedefang[3810]: Unable to obtain private data from
milter context
Mar 19 01:39:18 clio mimedefang[3810]: Error from multiplexor: error: Cannot
chdir(/var/spool/MIMEDefang/mdefang-3C9696C5-1): No such file or directory
Mar 19 01:39:18 clio mimedefang[3810]: Error from multiplexor: error: Cannot
chdir(/var/spool/MIMEDefang/mdefang-3C9696C5-0): No such file or directory
Mar 19 01:39:28 clio mimedefang[3810]: Error from multiplexor: 
Mar 19 01:39:28 clio mimedefang[3810]: Could not create
/var/spool/MIMEDefang/mdefang-3C9696CE-3: File exists
Mar 19 01:39:28 clio mimedefang[3810]: Could not create
/var/spool/MIMEDefang/mdefang-3C9696CE-2: File exists
Mar 19 01:39:30 clio mimedefang[3810]: Could not create
/var/spool/MIMEDefang/mdefang-3C9696D1-0: No such file or directory
Mar 19 01:39:30 clio mimedefang[3810]: mfconnect: No free slaves
Mar 19 01:39:30 clio mimedefang[3810]: Error from multiplexor: error: Cannot
chdir(/var/spool/MIMEDefang/mdefang-3C9696CB-1): No such file or directory
Mar 19 01:39:30 clio mimedefang[3810]: Error from multiplexor: error:
<No-Message-ID>: couldn't open INPUTMSG: No such file or directory
Mar 19 01:39:42 clio mimedefang[3810]: Error from multiplexor: error: Cannot
chdir(/var/spool/MIMEDefang/mdefang-3C9696DC-0): No such file or directory
Mar 19 01:39:42 clio mimedefang[3810]: Error from multiplexor: error: Cannot
chdir(/var/spool/MIMEDefang/mdefang-3C9696DB-0): No such file or directory
Mar 19 01:39:47 clio mimedefang[3810]: Error from multiplexor: error:
<No-Message-ID>: couldn't open INPUTMSG: No such file or directory
Mar 19 01:40:02 clio mimedefang[3810]: Error from multiplexor: 
Mar 19 01:40:03 clio mimedefang[3810]: Error from multiplexor: 
Mar 19 01:40:03 clio mimedefang[3810]: Error from multiplexor: 
Mar 19 01:40:03 clio mimedefang[3810]: Error from multiplexor: 
Mar 19 01:40:03 clio mimedefang[3810]: Error from multiplexor: 
Mar 19 01:40:03 clio mimedefang[3810]: Error from multiplexor: 
Mar 19 01:40:03 clio mimedefang[3810]: Error from multiplexor: 
Mar 19 01:40:05 clio mimedefang[3810]: Error from multiplexor: 
Mar 19 01:40:05 clio mimedefang[3810]: Could not create
/var/spool/MIMEDefang/mdefang-3C9696EE-1: File exists
Mar 19 01:40:05 clio mimedefang[3810]: Could not create
/var/spool/MIMEDefang/mdefang-3C9696EE-2: File exists
Mar 19 01:40:05 clio mimedefang[3810]: Could not create
/var/spool/MIMEDefang/mdefang-3C9696EE-3: File exists
Mar 19 01:40:09 clio mimedefang[3810]: Could not create
/var/spool/MIMEDefang/mdefang-3C9696EE-0: No such file or directory
Mar 19 01:40:25 clio mimedefang[3810]: Could not create
/var/spool/MIMEDefang/mdefang-3C969701-0: File exists
Mar 19 01:40:26 clio mimedefang[3810]: Could not create
/var/spool/MIMEDefang/mdefang-3C969700-4: File exists
Mar 19 01:40:33 clio mimedefang[3810]: Error from multiplexor: error:
g2J1eIr05341: Cannot mkdir(Work): File exists
Mar 19 01:40:33 clio mimedefang[3810]: Could not create
/var/spool/MIMEDefang/mdefang-3C96970C-1: File exists
Mar 19 01:40:33 clio mimedefang[3810]: Error from multiplexor: error:
g2J1eJr05349: Cannot mkdir(Work): File exists
Mar 19 01:40:36 clio mimedefang[3810]: Error from multiplexor: error:
g2J1eLr05361: Cannot mkdir(Work): File exists
Mar 19 01:40:36 clio mimedefang[3810]: Error from multiplexor: error:
g2J1eNr05377: Cannot mkdir(Work): File exists
Mar 19 01:40:39 clio mimedefang[3810]: Error from multiplexor: error: Cannot
chdir(/var/spool/MIMEDefang/mdefang-3C969707-1): No such file or directory
Mar 19 01:40:41 clio mimedefang[3810]: Error from multiplexor: error: Cannot
chdir(/var/spool/MIMEDefang/mdefang-3C969708-2): No such file or directory
Mar 19 01:44:37 clio mimedefang[3810]: Error from multiplexor: 
Mar 19 01:44:39 clio mimedefang[3810]: Error from multiplexor: error:
<No-Message-ID>: couldn't open INPUTMSG: No such file or directory
Mar 19 01:44:39 clio mimedefang[3810]: Error from multiplexor: error: Cannot
chdir(/var/spool/MIMEDefang/mdefang-3C969802-3): No such file or directory
Mar 19 01:44:40 clio mimedefang[3810]: Error from multiplexor: error: Cannot
chdir(/var/spool/MIMEDefang/mdefang-3C969804-0): No such file or directory
Mar 19 01:44:46 clio mimedefang[3810]: Could not create
/var/spool/MIMEDefang/mdefang-3C96980D-1: File exists
Mar 19 01:44:48 clio mimedefang[3810]: Error from multiplexor: error:
<No-Message-ID>: couldn't open INPUTMSG: No such file or directory
Mar 19 01:44:53 clio mimedefang[3810]: Could not create
/var/spool/MIMEDefang/mdefang-3C969814-0: File exists
Mar 19 01:44:55 clio mimedefang[3810]: Error from multiplexor: error: Cannot
chdir(/var/spool/MIMEDefang/mdefang-3C969815-1): No such file or directory
Mar 19 01:44:56 clio mimedefang[3810]: Discarding because filter instructed
us to
Mar 19 01:44:57 clio mimedefang[3810]: Error from multiplexor: error: Cannot
chdir(/var/spool/MIMEDefang/mdefang-3C969817-1): No such file or directory
Mar 19 01:45:01 clio mimedefang[3810]: Error from multiplexor: error: Cannot
chdir(/var/spool/MIMEDefang/mdefang-3C969815-0): No such file or directory
Mar 19 01:45:02 clio mimedefang[3810]: Error from multiplexor: 
Mar 19 01:45:05 clio mimedefang[3810]: Error from multiplexor: error: Cannot
chdir(/var/spool/MIMEDefang/mdefang-3C96981C-1): No such file or directory
Mar 19 01:45:09 clio mimedefang[3810]: Could not create
/var/spool/MIMEDefang/mdefang-3C969825-0: File exists
Mar 19 01:45:17 clio mimedefang[3810]: Could not create
/var/spool/MIMEDefang/mdefang-3C96982C-0: File exists
Mar 19 01:45:19 clio mimedefang[3810]: Error from multiplexor: error:
<No-Message-ID>: couldn't open INPUTMSG: No such file or directory
Mar 19 01:45:29 clio mimedefang[3810]: Error from multiplexor: error: Cannot
chdir(/var/spool/MIMEDefang/mdefang-3C969827-1): No such file or directory
Mar 19 01:48:38 clio mimedefang[3810]: Error from multiplexor: error: Cannot
chdir(/var/spool/MIMEDefang/mdefang-3C96981E-2): No such file or directory

- Of course when I started to get:

Mar 19 01:45:01 clio sendmail[6175]: g2J1j0v06175: Please try again later

... is when I decided to back out of using MIMEDefang again.

These errors are just weird looking to me.  I don't expect "No such file or
directory" errors to be intermingled with "File exists" errors, for
instance.  And there are some "No free slaves" errors tossed in there, when
I *know* the system never had more than 9 slaves, and it was configured for
32.

I hope at least that my questions are getting less and less newbie-like!

--
Doug McCarthy
Sendmail Product Engineer               And this is artificial moonlight...
Internet Connectivity Engineering       ...and artificial sky.
doug.firewall.mccarthy at intel.com