On Thu, Jul 13, 2017 at 08:23:26AM -0700, Daniel Caulfield wrote:
> Apologies but I tried to post the log in the body but the forum is
> responding [Spam Detector] Message contains common spam words. and won't let
> me post it so the contents are in teh attached txt file.
>
> Thanks
> Danny MailLog.txt
> <http://postfix.1071664.n5.nabble.com/file/n91274/MailLog.txt>
Frequent restarts of the queue manager are counterproductive.
Avoid running automated or frequent "postfix reload" commands:
Jul 13 00:15:28 mes-l2-rly-01 postfix/qmgr[29046]: 2412B80170667: skipped,
still being delivered
Jul 13 00:25:13 mes-l2-rly-01 postfix/qmgr[1137]: 2412B80170667: skipped, still
being delivered
Jul 13 00:29:41 mes-l2-rly-01 postfix/qmgr[3082]: 2412B80170667: skipped, still
being delivered
Jul 13 00:49:26 mes-l2-rly-01 postfix/qmgr[12235]: 2412B80170667: skipped,
still being delivered
Jul 13 01:09:02 mes-l2-rly-01 postfix/qmgr[21501]: 2412B80170667: skipped,
still being delivered
Jul 13 01:15:36 mes-l2-rly-01 postfix/qmgr[24294]: 2412B80170667: skipped,
still being delivered
Jul 13 01:16:37 mes-l2-rly-01 postfix/qmgr[24763]: 2412B80170667: skipped,
still being delivered
Jul 13 01:17:36 mes-l2-rly-01 postfix/qmgr[24763]: 2412B80170667: skipped,
still being delivered
Jul 13 01:18:46 mes-l2-rly-01 postfix/qmgr[25652]: 2412B80170667: skipped,
still being delivered
Jul 13 01:20:55 mes-l2-rly-01 postfix/qmgr[26549]: 2412B80170667: skipped,
still being delivered
Jul 13 01:23:01 mes-l2-rly-01 postfix/qmgr[27670]: 2412B80170667: skipped,
still being delivered
Jul 13 01:25:16 mes-l2-rly-01 postfix/qmgr[28600]: 2412B80170667: skipped,
still being delivered
Jul 13 01:27:18 mes-l2-rly-01 postfix/qmgr[29484]: 2412B80170667: skipped,
still being delivered
Jul 13 01:29:32 mes-l2-rly-01 postfix/qmgr[30504]: 2412B80170667: skipped,
still being delivered
Jul 13 01:32:05 mes-l2-rly-01 postfix/qmgr[31652]: 2412B80170667: skipped,
still being delivered
Jul 13 02:07:16 mes-l2-rly-01 postfix/qmgr[14734]: 2412B80170667: skipped,
still being delivered
Jul 13 02:09:27 mes-l2-rly-01 postfix/qmgr[15637]: 2412B80170667: skipped,
still being delivered
Jul 13 02:11:55 mes-l2-rly-01 postfix/qmgr[16595]: 2412B80170667: skipped,
still being delivered
Jul 13 02:39:49 mes-l2-rly-01 postfix/qmgr[28892]: 2412B80170667: skipped,
still being delivered
Jul 13 02:43:41 mes-l2-rly-01 postfix/qmgr[30604]: 2412B80170667: skipped,
still being delivered
Jul 13 02:45:47 mes-l2-rly-01 postfix/qmgr[31551]: 2412B80170667: skipped,
still being delivered
Jul 13 02:47:51 mes-l2-rly-01 postfix/qmgr[32452]: 2412B80170667: skipped,
still being delivered
Jul 13 02:53:48 mes-l2-rly-01 postfix/qmgr[2553]: 2412B80170667: skipped, still
being delivered
Jul 13 02:55:56 mes-l2-rly-01 postfix/qmgr[3616]: 2412B80170667: skipped, still
being delivered
Jul 13 02:58:28 mes-l2-rly-01 postfix/qmgr[4844]: 2412B80170667: skipped, still
being delivered
Jul 13 03:01:57 mes-l2-rly-01 postfix/qmgr[6209]: 2412B80170667: skipped, still
being delivered
Jul 13 03:04:08 mes-l2-rly-01 postfix/qmgr[7359]: 2412B80170667: skipped, still
being delivered
Jul 13 03:14:02 mes-l2-rly-01 postfix/qmgr[11562]: 2412B80170667: skipped,
still being delivered
Jul 13 03:16:08 mes-l2-rly-01 postfix/qmgr[12020]: 2412B80170667:
from=<redac...@fromdomain.co.uk>, status=expired, returned to sender
Jul 13 03:16:08 mes-l2-rly-01 postfix/qmgr[12020]: 2412B80170667: removed
There's your problem. Your SMTP delivery agent is blocked waiting
for something that never happens, until an interrupt after a 5 hour
watchdog timer. This suggests a buggy operating system, perhaps
a virtual machine that loses timer events, or some other O/S bug.
Jul 13 03:15:02 l2-rly-01 relay085/smtp[11563]: fatal: watchdog timeout
Jul 13 03:15:02 l2-rly-01 relay059/smtp[11564]: fatal: watchdog timeout
Jul 13 03:15:02 l2-rly-01 relay017/smtp[11567]: fatal: watchdog timeout
...
Jul 13 03:15:02 l2-rly-01 relay095/smtp[11647]: fatal: watchdog timeout
Jul 13 03:15:02 l2-rly-01 relay099/smtp[11655]: fatal: watchdog timeout
Jul 13 03:15:02 l2-rly-01 relay008/smtp[11654]: fatal: watchdog timeout
Jul 13 03:15:02 l2-rly-01 postfix/qmgr[11562]: fatal: 202298015A3D0: timeout
receiving delivery status from transport: relay085
Jul 13 03:15:03 l2-rly-01 postfix/master[9824]: warning: process
/usr/libexec/postfix/smtp pid 11563 exit status 1
Jul 13 03:15:03 l2-rly-01 postfix/master[9824]: warning: process
/usr/libexec/postfix/smtp pid 11564 exit status 1
Jul 13 03:15:03 l2-rly-01 postfix/master[9824]: warning:
/usr/libexec/postfix/smtp: bad command startup -- throttling
...
Jul 13 03:16:14 l2-rly-01 postfix/master[9824]: warning: process
/usr/libexec/postfix/smtp pid 12201 exit status 1
Jul 13 03:16:14 l2-rly-01 postfix/master[9824]: warning: process
/usr/libexec/postfix/smtp pid 12202 exit status 1
Jul 13 03:16:14 l2-rly-01 postfix/master[9824]: warning: process
/usr/libexec/postfix/smtp pid 12204 exit status 1
That's a rather large set of smtp(8) transports. Perhaps a snowshoe
bulk email setup. Good luck.
--
Viktor.
