Hello!
I have regular delays in message processing. Say, my server (Linux/Postfix) receives a letter at 17:36 and quickly delivers it to the mailman queue (?). Then message is hold for about 25 minutes and is processed again at 18:01.
It is strange to me, why mailman keeps the message so long? How can I investigate such situation?
Maybe, other messages in queue could be the reason?
$ ls -1 /var/lib/mailman/qfiles/in/|wc -l 0 $ ls -1 /var/lib/mailman/qfiles/out/|wc -l 120 $ ls -1 /var/lib/mailman/qfiles/retry/|wc -l 121
There are 8 Incoming, 8 Outgoing, 4 Retry and 4 Bounce qrunners on the server. Nothing interesting in /var/log/mailman/error for that period.
I cite mail logs here in hope they can help:
/var/log/maillog: ... Jan 7 17:36:24 lists postfix/smtpd[9319]: 3E5FFBE21CF: client=localhost.localdomain[127.0.0.1] Jan 7 17:36:24 lists postfix/cleanup[9736]: 3E5FFBE21CF: message-id=<20080107143624.1A3761AE802A@granary.armor.altlinux.org> Jan 7 17:36:24 lists postfix/qmgr[32764]: 3E5FFBE21CF: from=<upravdom@altlinux.org>, size=2326, nrcpt=1 (queue active) Jan 7 17:36:24 lists postfix/local[9765]: 3E5FFBE21CF: to=<sisyphus-incominger@lists.altlinux.org>, relay=local, delay=0.27, delays=0.14/0/0/0.13, dsn=2.0.0, status=sent (delivered to command: /usr/lib64/mailman/mail/mailman post sisyphus-incominger) Jan 7 17:36:24 lists postfix/qmgr[32764]: 3E5FFBE21CF: removed ...
/var/log/mailman/post: ... Jan 07 18:01:41 2008 (12250) post to sisyphus-incominger from upravdom@altlinux.org, size=4003, message-id=<20080107143624.1A3761AE802A@granary.armor.altlinux.org>, success ...
/var/log/mailman/smtp: ... Jan 07 18:01:41 2008 (12250) <20080107143624.1A3761AE802A@granary.armor.altlinux.org> smtp to sisyphus-incominger for 15 recips, completed in 0.057 seconds ...
/var/log/maillog: ... Jan 7 18:01:41 lists postfix/smtpd[11539]: 064E7BE1B56: client=localhost.localdomain[127.0.0.1] Jan 7 18:01:41 lists postfix/cleanup[14067]: 064E7BE1B56: message-id=<20080107143624.1A3761AE802A@granary.armor.altlinux.org> Jan 7 18:01:41 lists postfix/qmgr[32764]: 064E7BE1B56: from=<sisyphus-incominger-bounces@lists.altlinux.org>, size=4204, nrcpt=3 (queue active) ... Jan 7 18:01:41 lists postfix/smtpd[11539]: 099D8BE1D7D: client=localhost.localdomain[127.0.0.1] Jan 7 18:01:41 lists postfix/cleanup[14067]: 099D8BE1D7D: message-id=<20080107143624.1A3761AE802A@granary.armor.altlinux.org> Jan 7 18:01:41 lists postfix/qmgr[32764]: 099D8BE1D7D: from=<sisyphus-incominger-bounces@lists.altlinux.org>, size=4204, nrcpt=5 (queue active) ... Jan 7 18:01:41 lists postfix/smtpd[11539]: 0D9A3BE2078: client=localhost.localdomain[127.0.0.1] Jan 7 18:01:41 lists postfix/cleanup[14067]: 0D9A3BE2078: message-id=<20080107143624.1A3761AE802A@granary.armor.altlinux.org> Jan 7 18:01:41 lists postfix/qmgr[32764]: 0D9A3BE2078: from=<sisyphus-incominger-bounces@lists.altlinux.org>, size=4204, nrcpt=7 (queue active) ... (skipped SMTP delivery) ... Jan 7 18:01:41 lists postfix/qmgr[32764]: 064E7BE1B56: removed Jan 7 18:01:44 lists postfix/qmgr[32764]: 099D8BE1D7D: removed Jan 7 18:01:52 lists postfix/qmgr[32764]: 0D9A3BE2078: removed ...
-- Grigory Batalov, ALT Linux Team