[Mailman-Users] Part of moderated mails postponed to later digests

[Mark Sapiro]

On 11/12/15 5:06 AM, Henrik Rasmussen wrote:
> 
>> What does the vette log say about the messages that don't appear in a digest until the following week? Do they show as approved at the same time as the others that are in the first digest or not until later.
> I am still trying to figure that out, as I still need to know exactly when the list admin has approved messages. The vette log shows a lot of  Refused posting labeled with the list name, but all the held message approved messages does not contain information on which list the message was approved, so I can't use the vette log to determine when messages on the list was approved.


Yes, that is a bug, <https://bugs.launchpad.net/mailman/+bug/1295875>,
fixed in MM 2.1.18.


> But I wonder why the smtp log contains so many entries postings to exactly 1209 recipients on the list, even the list has 1222 normal members and 6493 digest members. Why only 6 times 1209. And are the 1209 digest members while the 6 recips could be regular members or something?
> 
>    /var/log/mailman/smtp Nov 09 09 06 44 2015 (16804) <mailman.577.1447056402.16802.LISTNAME at LISTSERVER.dk> smtp to LISTNAME for 1 recips, completed in 0.098 seconds
>    /var/log/mailman/smtp Nov 09 09 06 45 2015 (16804) <mailman.577.1447056402.16802.LISTNAME at LISTSERVER.dk> smtp to LISTNAME for 6 recips, completed in 0.200 seconds
>    /var/log/mailman/smtp Nov 09 09 06 45 2015 (16804) <mailman.577.1447056402.16802.LISTNAME at LISTSERVER.dk> smtp to LISTNAME for 6 recips, completed in 0.200 seconds
>    /var/log/mailman/smtp Nov 09 09 06 45 2015 (16804) <mailman.577.1447056402.16802.LISTNAME at LISTSERVER.dk> smtp to LISTNAME for 6 recips, completed in 0.200 seconds
>    /var/log/mailman/smtp Nov 09 09 06 45 2015 (16804) <mailman.577.1447056402.16802.LISTNAME at LISTSERVER.dk> smtp to LISTNAME for 6 recips, completed in 0.200 seconds
>    /var/log/mailman/smtp Nov 09 09 06 45 2015 (16804) <mailman.577.1447056402.16802.LISTNAME at LISTSERVER.dk> smtp to LISTNAME for 6 recips, completed in 0.200 seconds
>    /var/log/mailman/smtp Nov 09 09 06 45 2015 (16804) <mailman.577.1447056402.16802.LISTNAME at LISTSERVER.dk> smtp to LISTNAME for 6 recips, completed in 0.200 seconds


The above are a Mailman notice. It is unclear to me exactly what is
happening here? The first log message is the message to LISTNAME-owner
and the next is the resending of that message to the 6 owner/moderator
addresses. The next 5 appear to be duplicates because the 0.200 seconds
are identical and even if there were 6 separate sends, that totals 1.2
seconds, yet the timestamp never advanced even 1 second.


>    /var/log/mailman/smtp Nov 09 13 28 33 2015 (16804) <6f7a7eb8e5e442668ea1a864e84c2530 at pobox.engr.local> smtp to LISTNAME for 1209 recips, completed in 110.189 seconds
>    /var/log/mailman/smtp Nov 09 13 28 33 2015 (16804) <6f7a7eb8e5e442668ea1a864e84c2530 at pobox.engr.local> smtp to LISTNAME for 1209 recips, completed in 110.189 seconds
>    /var/log/mailman/smtp Nov 09 13 28 33 2015 (16804) <6f7a7eb8e5e442668ea1a864e84c2530 at pobox.engr.local> smtp to LISTNAME for 1209 recips, completed in 110.189 seconds
>    /var/log/mailman/smtp Nov 09 13 28 33 2015 (16804) <6f7a7eb8e5e442668ea1a864e84c2530 at pobox.engr.local> smtp to LISTNAME for 1209 recips, completed in 110.189 seconds
>    /var/log/mailman/smtp Nov 09 13 28 33 2015 (16804) <6f7a7eb8e5e442668ea1a864e84c2530 at pobox.engr.local> smtp to LISTNAME for 1209 recips, completed in 110.189 seconds
>    /var/log/mailman/smtp Nov 09 13 28 33 2015 (16804) <6f7a7eb8e5e442668ea1a864e84c2530 at pobox.engr.local> smtp to LISTNAME for 1209 recips, completed in 110.189 seconds


Here again this appears to be 6 duplicate copies of one message. It
could be 6 approved posts each delivered to 1209 eligible non-digest
members upon approval (the other 13 non-digest members could be delivery
disabled or direct addressees with 'no dups' or a combination), but it
is not 6 posts because the log messages are all for the same Message-ID
and all from the same (pid = 16804) OutgoingRunner and delivery takes
over 110 seconds, but the timestamp doesn't advance. Also, 6 separate
sends to 1209 recips would be very unlikely to be completed in the exact
same time of 110.189 seconds.


>    /var/log/mailman/post Nov 09 13 28 33 2015 (16804) post to LISTNAME from someone at example.com, size=5675, message-id=<6f7a7eb8e5e442668ea1a864e84c2530 at pobox.engr.local>, success


And the post log shows only one post.


> Where can I see which messages was contained in which digests and when? My problem is that I don't seem to be able to tell whether the list admin is right or not. Not that I doubt his statement, but I really like to be able to tell when what happens.


The send of the digests would be logged as above in the smtp log at 'Nov
09 12 xx xx'. To see which messages it contained, you'd have to see the
digest itself. That info isn't logged.

You could subscribe yourself to the MIME digest. Then you'd see the
messages in it and their Message-IDs. The Message-IDs should be in the
vette log. Also, for the smtp log entry above like

Nov 09 13 28 33 2015 (16804)
<6f7a7eb8e5e442668ea1a864e84c2530 at pobox.engr.local> smtp to LISTNAME for
1209 recips, completed in 110.189 seconds

there should be a vette log entry with the same Message-ID and a
timestamp about 110-115 seconds earlier.

The six apparent duplications of certain smtp log entries is very
puzzling. I don't know what would cause that.

-- 
Mark Sapiro <mark at msapiro.net>        The highway is for gamblers,
San Francisco Bay Area, California    better use your sense - B. Dylan