[Mimedefang] milter timing out.
-ray
ray at ops.selu.edu
Tue Feb 3 18:58:00 EST 2004
Did more troubleshooting today. I think this is a multiplexor problem. I
started logging the # of sendmail and MD processes. At some point it
appears the multiplexor decides to stop spawning new slaves, and sendmail
processes start backing up. Log created with:
echo "`date`: Sendmail `ps aux | grep sendmail: -c` | mimedefang `ps -efl | grep fang | grep pid -c`" >> /log2/proclog
sleep 5
Tue Feb 3 17:21:09 CST 2004: Sendmail 45 | mimedefang 24
Tue Feb 3 17:21:14 CST 2004: Sendmail 43 | mimedefang 26
Tue Feb 3 17:21:20 CST 2004: Sendmail 49 | mimedefang 31
Tue Feb 3 17:21:25 CST 2004: Sendmail 49 | mimedefang 31
Tue Feb 3 17:21:30 CST 2004: Sendmail 46 | mimedefang 28
Tue Feb 3 17:21:35 CST 2004: Sendmail 54 | mimedefang 36
Tue Feb 3 17:21:41 CST 2004: Sendmail 53 | mimedefang 36
Tue Feb 3 17:21:46 CST 2004: Sendmail 45 | mimedefang 27
Tue Feb 3 17:21:51 CST 2004: Sendmail 43 | mimedefang 1
Tue Feb 3 17:21:56 CST 2004: Sendmail 44 | mimedefang 1
Tue Feb 3 17:22:01 CST 2004: Sendmail 44 | mimedefang 1
Tue Feb 3 17:22:07 CST 2004: Sendmail 45 | mimedefang 1
Tue Feb 3 17:22:12 CST 2004: Sendmail 49 | mimedefang 1
Tue Feb 3 17:22:17 CST 2004: Sendmail 51 | mimedefang 1
Tue Feb 3 17:22:22 CST 2004: Sendmail 57 | mimedefang 1
Tue Feb 3 17:22:27 CST 2004: Sendmail 62 | mimedefang 1
Tue Feb 3 17:22:33 CST 2004: Sendmail 68 | mimedefang 1
Tue Feb 3 17:22:38 CST 2004: Sendmail 74 | mimedefang 1
Tue Feb 3 17:22:43 CST 2004: Sendmail 78 | mimedefang 1
Tue Feb 3 17:22:48 CST 2004: Sendmail 88 | mimedefang 1
Tue Feb 3 17:22:53 CST 2004: Sendmail 97 | mimedefang 1
Tue Feb 3 17:22:59 CST 2004: Sendmail 102 | mimedefang 1
Tue Feb 3 17:23:04 CST 2004: Sendmail 111 | mimedefang 1
Tue Feb 3 17:23:09 CST 2004: Sendmail 115 | mimedefang 1
Tue Feb 3 17:23:14 CST 2004: Sendmail 120 | mimedefang 1
Tue Feb 3 17:23:19 CST 2004: Sendmail 131 | mimedefang 1
Tue Feb 3 17:23:32 CST 2004: Sendmail 145 | mimedefang 1
Tue Feb 3 17:23:45 CST 2004: Sendmail 172 | mimedefang 1
Tue Feb 3 17:23:55 CST 2004: Sendmail 184 | mimedefang 1
Tue Feb 3 17:24:00 CST 2004: Sendmail 191 | mimedefang 1
Tue Feb 3 17:24:06 CST 2004: Sendmail 197 | mimedefang 1
Tue Feb 3 17:24:11 CST 2004: Sendmail 204 | mimedefang 1
Tue Feb 3 17:24:16 CST 2004: Sendmail 209 | mimedefang 1
Tue Feb 3 17:24:21 CST 2004: Sendmail 213 | mimedefang 1
Tue Feb 3 17:24:27 CST 2004: Sendmail 220 | mimedefang 1
Tue Feb 3 17:24:32 CST 2004: Sendmail 225 | mimedefang 1
Tue Feb 3 17:24:37 CST 2004: Sendmail 228 | mimedefang 1
Tue Feb 3 17:24:42 CST 2004: Sendmail 237 | mimedefang 1
Tue Feb 3 17:24:48 CST 2004: Sendmail 239 | mimedefang 1
Tue Feb 3 17:24:53 CST 2004: Sendmail 243 | mimedefang 1
Tue Feb 3 17:24:58 CST 2004: Sendmail 253 | mimedefang 1
Tue Feb 3 17:25:04 CST 2004: Sendmail 261 | mimedefang 1
Tue Feb 3 17:25:09 CST 2004: Sendmail 264 | mimedefang 1
Tue Feb 3 17:25:14 CST 2004: Sendmail 266 | mimedefang 1
Tue Feb 3 17:25:20 CST 2004: Sendmail 266 | mimedefang 1
Tue Feb 3 17:25:25 CST 2004: Sendmail 266 | mimedefang 1
Tue Feb 3 17:25:30 CST 2004: Sendmail 266 | mimedefang 1
Tue Feb 3 17:25:35 CST 2004: Sendmail 266 | mimedefang 1
Tue Feb 3 17:25:41 CST 2004: Sendmail 266 | mimedefang 1
Tue Feb 3 17:25:46 CST 2004: Sendmail 266 | mimedefang 1
Tue Feb 3 17:25:57 CST 2004: Sendmail 266 | mimedefang 1
Tue Feb 3 17:26:03 CST 2004: Sendmail 265 | mimedefang 1
Tue Feb 3 17:26:09 CST 2004: Sendmail 265 | mimedefang 1
Tue Feb 3 17:26:14 CST 2004: Sendmail 266 | mimedefang 1
Tue Feb 3 17:26:19 CST 2004: Sendmail 266 | mimedefang 1
Tue Feb 3 17:26:29 CST 2004: Sendmail 266 | mimedefang 1
Tue Feb 3 17:26:44 CST 2004: Sendmail 264 | mimedefang 1
Tue Feb 3 17:26:58 CST 2004: Sendmail 260 | mimedefang 1
Tue Feb 3 17:27:03 CST 2004: Sendmail 264 | mimedefang 1
Tue Feb 3 17:27:19 CST 2004: Sendmail 257 | mimedefang 1
Tue Feb 3 17:27:26 CST 2004: Sendmail 261 | mimedefang 1
Tue Feb 3 17:27:42 CST 2004: Sendmail 250 | mimedefang 1
Tue Feb 3 17:27:50 CST 2004: Sendmail 251 | mimedefang 1
Tue Feb 3 17:28:02 CST 2004: Sendmail 256 | mimedefang 1
Tue Feb 3 17:28:14 CST 2004: Sendmail 261 | mimedefang 1
Tue Feb 3 17:28:29 CST 2004: Sendmail 247 | mimedefang 1
Tue Feb 3 17:28:35 CST 2004: Sendmail 248 | mimedefang 1
Tue Feb 3 17:28:45 CST 2004: Sendmail 246 | mimedefang 1
Tue Feb 3 17:28:55 CST 2004: Sendmail 244 | mimedefang 1
Tue Feb 3 17:29:00 CST 2004: Sendmail 255 | mimedefang 1
Tue Feb 3 17:29:15 CST 2004: Sendmail 262 | mimedefang 1
Tue Feb 3 17:29:31 CST 2004: Sendmail 265 | mimedefang 1
Tue Feb 3 17:29:49 CST 2004: Sendmail 253 | mimedefang 1
Tue Feb 3 17:30:06 CST 2004: Sendmail 266 | mimedefang 1
Tue Feb 3 17:30:11 CST 2004: Sendmail 261 | mimedefang 1
Tue Feb 3 17:30:17 CST 2004: Sendmail 261 | mimedefang 1
Tue Feb 3 17:30:22 CST 2004: Sendmail 261 | mimedefang 1
Tue Feb 3 17:30:27 CST 2004: Sendmail 266 | mimedefang 1
Tue Feb 3 17:30:33 CST 2004: Sendmail 51 | mimedefang 0
Tue Feb 3 17:30:38 CST 2004: Sendmail 37 | mimedefang 0
Tue Feb 3 17:30:43 CST 2004: Sendmail 41 | mimedefang 10
Tue Feb 3 17:30:48 CST 2004: Sendmail 41 | mimedefang 8
Tue Feb 3 17:30:53 CST 2004: Sendmail 40 | mimedefang 5
Tue Feb 3 17:30:59 CST 2004: Sendmail 45 | mimedefang 12
Tue Feb 3 17:31:04 CST 2004: Sendmail 44 | mimedefang 9
Tue Feb 3 17:31:09 CST 2004: Sendmail 49 | mimedefang 13
Tue Feb 3 17:31:14 CST 2004: Sendmail 45 | mimedefang 7
Tue Feb 3 17:31:19 CST 2004: Sendmail 45 | mimedefang 9
Tue Feb 3 17:31:25 CST 2004: Sendmail 44 | mimedefang 9
Tue Feb 3 17:31:30 CST 2004: Sendmail 46 | mimedefang 14
Tue Feb 3 17:31:35 CST 2004: Sendmail 44 | mimedefang 10
Tue Feb 3 17:31:40 CST 2004: Sendmail 39 | mimedefang 7
Tue Feb 3 17:31:46 CST 2004: Sendmail 38 | mimedefang 5
Tue Feb 3 17:31:51 CST 2004: Sendmail 38 | mimedefang 6
Tue Feb 3 17:31:56 CST 2004: Sendmail 38 | mimedefang 6
Tue Feb 3 17:32:01 CST 2004: Sendmail 37 | mimedefang 5
But the multiplexor stays running the whole time. When i just restart MD,
sendmail process drop and everything goes back to normal. Here is an
interesting entry:
[root at norm log2]# grep StartSlave maillog2
Feb 3 17:20:00 norm mimedefang-multiplexor: stats 1075850400.273
StartSlave slave=0 nslaves=15 nbusy=0 reason="Bringing slaves up to
minSlaves (15)"
Feb 3 17:21:36 norm mimedefang-multiplexor: stats 1075850496.470
StartSlave slave=14 nslaves=15 nbusy=0 reason="Bringing slaves up to
minSlaves (15)"
Feb 3 17:30:41 norm mimedefang-multiplexor: stats 1075851041.298
StartSlave slave=0 nslaves=1 nbusy=0 reason="Bringing slaves up to
minSlaves (15)"
Feb 3 17:30:42 norm mimedefang-multiplexor: stats 1075851042.305
StartSlave slave=1 nslaves=2 nbusy=0 reason="Bringing slaves up to
minSlaves (15)"
Feb 3 17:30:43 norm mimedefang-multiplexor: stats 1075851043.315
StartSlave slave=2 nslaves=3 nbusy=1 reason="Bringing slaves up to
minSlaves (15)"
Feb 3 17:30:44 norm mimedefang-multiplexor: stats 1075851044.325
StartSlave slave=3 nslaves=4 nbusy=1 reason="Bringing slaves up to
minSlaves (15)"
Feb 3 17:30:45 norm mimedefang-multiplexor: stats 1075851045.335
StartSlave slave=4 nslaves=5 nbusy=1 reason="Bringing slaves up to
minSlaves (15)"
Feb 3 17:30:46 norm mimedefang-multiplexor: stats 1075851046.345
StartSlave slave=5 nslaves=6 nbusy=4 reason="Bringing slaves up to
minSlaves (15)"
Looking at the timestamps, the multipleor stopped spawning slaves at 17:21
which is when the problems started. It did not do another StartSlave
until i restarted MD, 9 minutes later.
Any ideas why the multiplexor decides to quit spawning slaves? My next
step is to turn on multiplexor debugging. Hopefully it doesn't kill the
box... i am logging to a ramdisk though. BTW, this is MD 2.35.
ray
--
=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=
Ray DeJean http://www.r-a-y.org
Systems Engineer Southeastern Louisiana University
IBM Certified Specialist AIX Administration, AIX Support
=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=
More information about the MIMEDefang
mailing list