Hi there,

I have been pointed to a somewhat weird behaviour by the 1.8e listserv at
our site from a subscriber:

He complained that he received a message twice, with a delay of a couple
of days. At the bottom you'll find cutouts of the headers of the two
messages and the mail log entries corresponding to the message (same
message id and recipient).

The time when the second message was sent was right after a routine
listserv restart, I assume the message was one of the messages requeued
for delivery (55 messages were requeued according to the listserv.log-
sniplet also attached at the bottom).

My question is rather simple:

Why did the message get resent even though it did reach its recipient
correctly ?
I assume that the requeueing-logic of listserv does not differ send
processes per recipient but only knows when an atomic send from his
perspective (like in that case, where 20 recipients at the same target
were addressed with one smtp send) completely succeeded ?

Is that conclusion correct ?
What can be done about it ?
I'd like to rotate the logs on a regular basis so I have to
(automatically) restart the listserv about every 2-5 days. What procedure
should I choose for that if the way I am doing it now is insufficient
(logrotate under linux with prerotate = kill of lsv, postrotate = go bg).

Thanks in advance for your help and suggestions !

Best regards from Karlsruhe, Germany,

Daniel Zimmermann

----


Message header, original send and correct:

[...]
Received: from aegeeserv.aegee.uni-karlsruhe.de ([129.13.131.80]) by
mc1-f33.law16.hotmail.com with Microsoft SMTPSVC(5.0.2195.4905); Fri, 2
Aug 2002 04:26:15 -0700
Received: from aegeeserv (aegeeserv.aegee.uni-karlsruhe.de
[129.13.131.80])by aegeeserv.aegee.uni-karlsruhe.de (8.12.3/8.12.3/SuSE
Linux 0.6) with ESMTP id g729nxOV014600;Fri, 2 Aug 2002 13:25:50 +0200
Received: from AEGEE.UNI-KARLSRUHE.DE by AEGEE.UNI-KARLSRUHE.DE
(LISTSERV-TCP/IP release 1.8e) with spool id 0116 for
[log in to unmask]; Fri, 2 Aug 2002 13:25:44 +0200
Received: from mailgate.rz.uni-karlsruhe.de
(mailgate.rz.uni-karlsruhe.de [129.13.64.97]) by
aegeeserv.aegee.uni-karlsruhe.de (8.12.3/8.12.3/SuSE Linux 0.6) with
ESMTP id g72BPiMX015504 for <[log in to unmask]>; Fri, 2 Aug
2002 13:25:44 +0200
[...]

Header of the duplicate message:

[...]
Received: from aegeeserv.aegee.uni-karlsruhe.de ([129.13.131.80]) by
mc2-f8.law16.hotmail.com with Microsoft SMTPSVC(5.0.2195.4905); Tue, 6
Aug 2002 15:15:38 -0700
Received: from aegeeserv (aegeeserv.aegee.uni-karlsruhe.de
[129.13.131.80])by aegeeserv.aegee.uni-karlsruhe.de (8.12.3/8.12.3/SuSE
Linux 0.6) with ESMTP id g76MFOMZ011681;Wed, 7 Aug 2002 00:15:24 +0200
Received: from AEGEE.UNI-KARLSRUHE.DE by AEGEE.UNI-KARLSRUHE.DE
(LISTSERV-TCP/IP release 1.8e) with spool id 0116 for
[log in to unmask]; Fri, 2 Aug 2002 13:25:44 +0200
Received: from mailgate.rz.uni-karlsruhe.de
(mailgate.rz.uni-karlsruhe.de [129.13.64.97]) by
aegeeserv.aegee.uni-karlsruhe.de (8.12.3/8.12.3/SuSE Linux 0.6) with
ESMTP id g72BPiMX015504 for <[log in to unmask]>; Fri, 2 Aug
2002 13:25:44 +0200
[...]


The mail log shows the particular messages as follows (actual recipient
names have been replaced by numbers, it was 20 hotmail accounts
originally):

Aug  2 13:26:28 aegeeserv sendmail[15517]: g729nxOV014600:
to=<[log in to unmask]>,<[log in to unmask]>,...,<[log in to unmask]>, delay=00:00:38,
xdelay=00:00:17, mailer=esmtp, pri=3145601, relay=mx05.hotmail.com.
[65.54.254.129], dsn=2.0.0, stat=Sent (  <015a01c23a17$1faebf80
[log in to unmask]> Queued mail for delivery)


Aug  7 00:15:46 aegeeserv sendmail[11685]: g76MFOMZ011681:
to=<[log in to unmask]>,<[log in to unmask]>,...,<[log in to unmask]>, delay=00:00:22,
xdelay=00:00:17, mailer=esmtp, pri=3145601, relay=mx01.hotmail.com.
[65.54.254.145], dsn=2.0.0, stat=Sent (  <015a01c23a17$1faebf80
[log in to unmask]> Queued mail for delivery)


The listserv log says the following after the restart:

7 Aug 2002 00:15:24 LISTSERV(R) for unix version 1.8e starting...
7 Aug 2002 00:15:24  Copyright L-Soft international 1986-2002
7 Aug 2002 00:15:24 Requeuing 55 mail files for delivery...
Currently processed  10 files.
Currently processed  20 files.
Currently processed  30 files.
Currently processed  40 files.
Currently processed  50 files.
7 Aug 2002 00:17:44 SIGNUP files are being compressed...