Page 1 of 1
Receiving multiple copies of inbound mails
Posted: Wed May 10, 2006 6:06 pm
by KimVette
I'm running into an issue where users will often receive multiple copies of inbound emails. This does not happen all the time, and there is nothing in /var/log/mail which would give me any insight as to what the issue is. The problem does not appear to be on the client side because it occurs regardless of the client used.
Where do I start looking for this sort of issue?
Posted: Wed May 10, 2006 7:52 pm
by ScalixSupport
receive multiple copies of inbound emails
Where are the messages coming from? Is there a pattern? Do they come rapid fire or over time?
Inbound is handled by smtpd by default. If you telnet to your scalix server on port 25 does it say Scalix SMTP Relay? Then you've not changed the default.
You may want to turn on debugging by editing /var/opt/sys/general.cfg and adding
DEBUG_LOG=TRUE
then restart smtpd. The log file will be written to /var/opt/scalix/tmp/smtpd.log. Keep an eye on this file as there is nothing that rolls it. If it reaches 2gig smtpd will abort.
I'm wondering if the relay thinks it doesn't get the complete message so the sending side tries again.
Are you seeing anything in /var/opt/scalix/logs/fatal?
Thanks,
Don[/code][/quote]
Posted: Wed May 10, 2006 8:15 pm
by ScalixSupport
I would also recommend that you turn up audit logging for the Service Router and the Local Delivery. To do this, type:
Now, when someone reports duplicate messages, you can search for the subject line in the /var/opt/scalix/logs/audit file. You should see an entry for "routing" and one for "delivered". If you see two messages that say routing, then Scalix was handed two messages (check the message-ID's, they'll be different).
Thanks,
Rachel
Posted: Thu May 11, 2006 1:09 am
by ScalixSupport
To add to this...
Local Delivery has duplicate checking which prevents a message with the same message ID from being received. I can't remember the exact number but the message IDs are saved for a certain number of messages. This is to prevent the situation where a user may be a member of a number of PDLs and could, potentially, receive more than one copy of the same message within a very short period of time.
What is the interval between receiving the copies of the message ?
Cheers
Dave
Posted: Thu May 11, 2006 11:31 am
by KimVette
Hi all,
The messages come in all at once. However I just rebooted the server because incoming emails stalled (I do not know if it is related). As soon as I get a chance I will turn the debug level on as recommended and will post back with what I find.
--Kim
Posted: Thu May 11, 2006 1:06 pm
by KimVette
I presume you meant /var/opt/scalix/sys/general.cfg and /var/opt/scalix/sys/general.cfg?
Here is the log of an email from a cronjob which I just received at 12:00 - seven copies in my inbox but it shows up only once in the audit log:
Code: Select all
routing
time 1147363230 Thu May 11 12:00:30 2006 -240
type 0 message
priority 0 normal
sensitivity 0 normal
importance 0 normal
created-locally 0
hop-count 1
ua-message-id 200605111600.k4BG01bj006257(a)mail.biyn.local
mta-message-id 200605111600.k4BG01bj006257(a)mail.biyn.local
subject Cron <root@mail> freshclam
originator root / internet DDT1=RFC-822; DDV1=root@mail.biyn.local;
part-size 340
part-type 1166 DISTRIBUTION LIST
part-size 10152
part-type 1167 TEXT
recipient-to Kimberly Lazarski / mail, biyn/CN=Kimberly Lazarski
ack-req 0 none
queue LOCAL
max-nest-depth 0
message-size 12279mail:/var # find . -iname smtpd.log
mail:/var #
part-count 2
delivered-count 1
delivery
time 1147363235 Thu May 11 12:00:35 2006 -240
priority 0 normal
sensitivity 0 normal
importance 0 normal
type 0 message
create-time 1147363201 Thu May 11 12:00:01 2006 -240
ua-message-id 200605111600.k4BG01bj006257(a)mail.biyn.local
mta-message-id 200605111600.k4BG01bj006257(a)mail.biyn.local
subject Cron <root@mail> freshclam
originator root / internet DDT1=RFC-822; DDV1=root@mail.biyn.local;
recipient-to Kimberly Lazarski / mail, biyn/CN=Kimberly Lazarski
delivered-count 1
I turned on DEBUG_LOG in /var/opt/scalix/sys/general.cfg and restarted smtpd with the following commands:
omoff -d 0 && omon smtpd
However no /var/opt/scalix/tmp/smtpd.log file was created. I searched under /var/opt/scalix with find and no result was found:
mail:/var/opt/scalix # find . -iname smtpd.log
mail:/var/opt/scalix #
I then decided to check under /var:
mail:/var # find . -iname smtpd.log
mail:/var #
smtpd.log is not present anywhere.
The duplicates all come in at once, all with the same timestamp. This occurs regardless of email client used. At the moment I have my own account configured as an administrator (for sake of convenience until everything is fully configured, then I will turn that flag off) and my inbox appears to be the only one receiving the duplicate emails. Some emails are received only once as expected, some twice, and some as many as 17(!) times.
Posted: Thu May 11, 2006 1:29 pm
by ScalixSupport
The DEBUG_LOG=TRUE goes in the /var/opt/scalix/smtpd.cfg file and the smtpd.log file will be written to /var/opt/scalix/tmp. Now that you have audit logging set, when the dups happen again, please search the audit log for the subject and post *all* occurrences of that subject.
Thanks,
Rachel
Posted: Thu May 11, 2006 1:37 pm
by ScalixSupport
Right, sorry, yes, I meant /var/opt/scalix/sys/ and smtpd.cfg not general.cfg. It was a long day yesterday.
Thanks,
Don
Posted: Thu May 11, 2006 1:56 pm
by KimVette
ScalixSupport wrote:Right, sorry, yes, I meant /var/opt/scalix/sys/ and smtpd.cfg not general.cfg. It was a long day yesterday.
No harm done. :) I've enabled it and restarted smtpd, and sure enough /var/opt/scalix/tmp/smtpd.log was created. I'll keep an eye on it and post back. Thanks guys! :)
{edit: I just counted all the instances of one email that came in at 2:45am, and I have 29 copies of it.}
Posted: Fri May 12, 2006 1:57 pm
by KimVette
OK I did not receive duplicates for a while but now it's started up again. I received seven copies of the following:
Code: Select all
routing
time 1147451463 Fri May 12 12:31:03 2006 -240
type 0 message
priority 0 normal
sensitivity 0 normal
importance 0 normal
created-locally 0
hop-count 1
ua-message-id 005001c675e0(036)d6082560(036)0200a8c0(a)p43ghz
mta-message-id 005001c675e0(036)d6082560(036)0200a8c0(a)p43ghz
subject [ZR1] [TECH] Unlock a Bose Gold
originator owner-zr1net / internet DDT1=RFC-822; DDV1=owner-zr1net@LISTSERV.CORVETTEMUSEUM.COM;
part-size 681
part-type 1166 DISTRIBUTION LIST
part-size 304
part-type 1167 TEXT
recipient-bcc Kimberly Lazarski / mail, biyn/CN=Kimberly Lazarski
ack-req 0 none
queue LOCAL
max-nest-depth 0
message-size 5094
part-count 2
delivered-count 1
delivery
time 1147451464 Fri May 12 12:31:04 2006 -240
priority 0 normal
sensitivity 0 normal
importance 0 normal
type 0 message
ua-message-id 005001c675e0(036)d6082560(036)0200a8c0(a)p43ghz
mta-message-id 005001c675e0(036)d6082560(036)0200a8c0(a)p43ghz
create-time 1147451193 Fri May 12 12:26:33 2006 -240
subject [ZR1] [TECH] Unlock a Bose Gold
originator owner-zr1net / internet DDT1=RFC-822; DDV1=owner-zr1net@LISTSERV.CORVETTEMUSEUM.COM;
recipient-bcc Kimberly Lazarski / mail, biyn/CN=Kimberly Lazarski
delivered-count 1
Routing/delivery of that message only shows up once in that log.
Here is everything in /var/opt/scalix/tmp/smtpd.log for that message - only shows up once.
Code: Select all
Accepted client 1 from (listserv.corvettemuseum.com)
Cli 17950/1 sent : 220 mail.biyn.local ESMTP Scalix SMTP Relay 10.0.1.3; Fri, 12 May 2006 12:30:37 -0400 (EDT)
Cli 17950/1 rcvd: 'EHLO listserv.corvettemuseum.com'
Cli 17950/1 sent : 250-mail.biyn.local Hello listserv.corvettemuseum.com [12.180.241.90], pleased to meet you
250-AUTH CRAM-MD5 DIGEST-MD5 LOGIN PLAIN GSSAPI
250-AUTH=LOGIN
250-DSN
250 8BITMIME
Cli 17950/1 rcvd: 'MAIL FROM:<owner-zr1net@LISTSERV.CORVETTEMUSEUM.COM> BODY=8BITMIME'
Cli 17950/1 sent : 250 owner-zr1net@LISTSERV.CORVETTEMUSEUM.COM... Sender ok
Cli 17950/1 rcvd: 'RCPT TO:<kim@BIYN.COM>'
... new process 4475
17950: Allocate new sendmail process 4475
Cli 17950/1 rcvd S: '220 mail.biyn.local ESMTP Sendmail 8.13.4/8.13.4/Submit; Fri, 12 May 2006 12:30:38 -0400'
Cli 17950/1 rcvd S: '250 2.1.0 <owner-zr1net@LISTSERV.CORVETTEMUSEUM.COM>... Sender ok'
Cli 17950/1 sent S: rcpt to: <kim@BIYN.COM>
Cli 17950/1 rcvd S: '250 2.1.5 <kim@BIYN.COM>... Recipient ok'
Cli 17950/1 sent : 250 2.1.5 <kim@BIYN.COM>... Recipient ok
Cli 17950/1 rcvd: 'DATA'
Cli 17950/1 sent : 354 Enter mail, end with "." on a line by itself (relay)
Cli 17950/1 sent S: data
Cli 17950/1 rcvd S: '354 Enter mail, end with "." on a line by itself'
Cli 17950/1 sent S: Received: from listserv.corvettemuseum.com (listserv.corvettemuseum.com 12.180.241.90)
by mail.biyn.local (Scalix SMTP Relay 10.0.1.3)
via ESMTP; Fri, 12 May 2006 12:30:38 -0400 (EDT)
Received: from ncm-web (listserv.corvettemuseum.com) by listserv.corvettemuseum.com (LSMTP for Windows NT v1.1b) with SMTP id <0.00A43AF8@listserv.corvettemuseum.com>; Fri, 12 May 2006 11:26:36 -05
00
DomainKey-Signature: q=dns; d=LISTSERV.CORVETTEMUSEUM.COM; s=VETTE; c=nofws;
h=Received:MIME-Version:X-Priority:X-MSMail-Priority:X-Mailer:X-MimeOLE:Content-Type:Content-Transfer-Encoding:Message-ID:Date:Reply-To:Sender:From:Subject:To:List-Help:List-Unsubscribe:List-Subsc
ribe:List-Owner:List-Archive;
b=P7ZUL2MtHvu5ZQnBNHms1Rgp11OKeRjdz+/S1JFefXovK7LAhmc4ViM3BiJEJouGtyWRcHhUn0ivSNEBuc7Vs+5YL8c19Wg14BHY0+1sYjXuvy+TJWvZ/iotbfaSB32m;
Received: by LISTSERV.CORVETTEMUSEUM.COM (LISTSERV-TCP/IP release 14.5) with
spool id 6874849 for ZR1NET@LISTSERV.CORVETTEMUSEUM.COM; Fri, 12 May
2006 11:26:35 -0500
Received: from mtiwmhc11.worldnet.att.net by listserv.corvettemuseum.com (LSMTP
for Windows NT v1.1b) with SMTP id
Cli 17950/1 sent S: <0.00A43AF6@listserv.corvettemuseum.com>; Fri, 12 May 2006 11:26:35
-0500
Received: from p43ghz (67-41-112-172.hlrn.qwest.net[67.41.112.172]) by
worldnet.att.net (mtiwmhc11) with SMTP id
<200605121626341110050584e>; Fri, 12 May 2006 16:26:35 +0000
MIME-Version: 1.0
X-Priority: 3
X-MSMail-Priority: Normal
X-Mailer: Microsoft Outlook Express 6.00.2800.1506
X-MimeOLE: Produced By Microsoft MimeOLE V6.00.2800.1506
Cli 17950/1 sent S: Content-Type: text/plain; charset="iso-8859-1"
Content-Transfer-Encoding: quoted-printable
Message-ID: <005001c675e0$d6082560$0200a8c0@p43ghz>
Date: Fri, 12 May 2006 10:26:33 -0600
Reply-To: "rob.mcintosh" <rob.mcintosh@ATT.NET>
Sender: "ZR-1 Corvette enthusiast list." <ZR1NET@LISTSERV.CORVETTEMUSEUM.COM>
From: "rob.mcintosh" <rob.mcintosh@ATT.NET>
Subject: [ZR1] [TECH] Unlock a Bose Gold
To: ZR1NET@LISTSERV.CORVETTEMUSEUM.COM
Precedence: list
List-Help: <http://listserv.corvettemuseum.com/cgi-bin/wa.exe?LIST=ZR1NET>,
<mailto:LISTSERV@LISTSERV.CORVETTEMUSEUM.COM?body=INFO ZR1NET>
List-Unsubscribe: <mailto:ZR1NET-unsubscribe-request@LISTSERV.CORVETTEMUSEUM.COM>
List-Subscribe: <mailto:ZR1NET-subscribe-request@LISTSERV.CORVETTEMUSEUM.COM>
List-Owner: <mailto:ZR1NET-request@LISTSERV.CORVETTEMUSEUM.COM>
List-Archive: <http://listserv.corvettemuseum.com/cgi-bin/wa.exe?LIST=ZR1NET>
Does anyone remember how to unlock the Radio / CD? I just got a rebuilt =
Cli 17950/1 got .
Cli 17950/1 sent S: head unit and it displays the "LOC". So, I'm stuck until I get it =
unlocked.
Thanks, Rob.
Yellow '91 ZR-1 Callaway Aerobody
Problems? Contact Dave Bright at: zr1net@zr1.net
TOPICS: TECH: or NONTECH:
http://www.zr1netregistry.com
.
Cli 17950/1 rcvd S: '250 2.0.0 k4CGUbEX004475 Message accepted for delivery'
Cli 17950/1: Submitted message ': <005001c675e0$d6082560$0200a8c0@p43ghz>' to sendmail.
response: '250 2.0.0 k4CGUbEX004475 Message accepted for delivery'
Cli 17950/1 Finished submitting msg : <005001c675e0$d6082560$0200a8c0@p43ghz>
Cli 17950/1 sent : 250 2.0.0 k4CGUbEX004475 Message accepted for delivery
Cli 17950/1 rcvd: 'QUIT'
Cli 17950/1 sent : 221 mail.biyn.local closing connection
There are no fatal errors in /var/opt/scalix/logs/fatal since the last one I posted. What other info should I be providing here?
Posted: Fri May 12, 2006 2:20 pm
by ScalixSupport
Hi Kim,
Based on this, the message was only delivered once, so that may mean it's client related. How are you accessing the message, via Outlook with MAPI, SWA, POP, IMAP or ? It's is Outlook with MAPI, have you installed any plugins recently? If you look at the message headers, are the MSGIDs all the same?
Thanks,
Rachel
Posted: Fri May 12, 2006 2:37 pm
by KimVette
This appears to happen with the web client, kmail, evolution - you name it. I will do this though: I will stop using kmail (it's the one I use most of the time) and use evolution and see if the problem goes away. If it does, then we will know that kmail is the problem - in which case what should I do? Should I look into IMAP logs to see what is happening so that at least next time someone encounters this you'll quickly be able to ID the problem? I could wipe the profile from kontact (or simply rm the directories) but it won't help pinpoint the exact cause (e.g., is the problem in kmail or is it in the IMAP server)? Anyway before we jump to conclusions I'll use evolution for a bit and report back, then you can tell me how you want to proceed (if anything).
Thanks! :)
Posted: Tue May 16, 2006 12:12 pm
by KimVette
Hi guys, this turned out to be a client-side issue after all (I've avoided kmail since my last post last week). Apparantly kmail generates multiple copies of messages. Is there anything you would like me to capture in log files to help pinpoint the issue (not a big deal at this point, there is an excellent Thunderbird extension for removing duplicates), or should I just presume corruption of the local cache, remove the directory and rc files and see if the problem recurs?
One thing I didn't check was for multiple kmail processes. When I quit kmail I assumed it had exited. I could check to see if there is something funky going on when this occurs with kmail, but I'll wait until I see what you'd like me to do, because if this a problem someone else encounters it would be useful to be able to pinpoint the exact cause.
multiple copies of the same email
Posted: Wed Jul 05, 2006 6:32 pm
by tchen
KimVette,
I got the same problem recently, one of my college receives 1000+ times of the same email from another college, and is receiving 10+ copies of another email from the same person. I'm wondering how did you solve the problem.
I'm wondering if you can describe the kmail stuff a little more, and how come it process many copies. I just tried to use kmail trying to duplicate the problem but I only got one copy. Were you able to repeat this "multiple copy" problem with Kmail later? If so, how did you do that and how did you solve it?
According to my /var/mail/maillog, there is really 1000+ incoming mail with the same from and to, so I inclined to believe it's the client-side problem.
Thanks much in advance for any reply from you or any other guys.
tchen