Apple Event: May 7th at 7 am PT
Hello,
I am having an issue in Mail set up with Yahoo IMAP where Mail that has been deleted from the Inbox or filed into an IMAP subfolder is successfully moved to the corresponding subfolder/trash mailbox, but then reappears in the Inbox when checking mail. Is this an issue with Mail or Yahoo? How can I debug this problem? Can I output anything to a log file?
Steffan
What Mac and what version of OS X are you runnng?
This is normal for IMAP if the local changes have not been communicated to the server. Did you deliberately go offline to make the changes? Your local changes need to be communicated to the server before mail is downloaded.
The Mac in Question is an Intel iMac 5,1 2.3Ghz running OS X 10.4.11. It was working in online mode.
I've collected log information. The person using the email account has informed me that an email was received in the inbox at 14:49, but at 18:18 he noticed it had re-appeared in the inbox, despite having trashed the email. Then he trashed the duplicate email. Log information is below. Can you please take a look at it and see if it makes any sense of the issue?
Sorry if I've missed out any SELECT statements that would indicate which folder Mail is working in... please let me know if I missed anything from the log file.
It looks to me like the email is fetched in the inbox, moved to trash and set to deleted. Nothing is re-fetched into the inbox, yet another e-mail appears in the Trash. So is Mail accidentally showing another copy of the email in the inbox by accident, which is trashed for a second time, causing mail to upload the email to the Trash mailbox a second time?
LOG FILE
This seems to fetch it into the inbox for the first time: UID is 125143.. it is trashed pretty soon..
----------------------------------
READ Oct 29 14:51:42[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0x101105b0 -- thread:0xf18bb00
* 32 FETCH (FLAGS () UID 125143 INTERNALDATE "29-Oct-2012 14:50:12 +0000" RFC822.SIZE 20704 BODY[HEADER.FIELDS (DATE SUBJECT FROM TO CC MESSAGE-ID IN-REPLY-TO REFERENCES X-PRIORITY X-SPAM-FLAG RECEIVED-SPF CONTENT-TYPE)] {516}
yada yada...
165 OK FETCH completed
WROTE Oct 29 14:51:42[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0x101105b0 -- thread:0xf18bb00
166 UID FETCH 125143 BODY.PEEK[]
READ Oct 29 14:51:42[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0x101105b0 -- thread:0xf18bb00
* 32 FETCH (UID 125143 BODY[] {20775}
yada yada….
166 OK UID FETCH completed
WROTE Oct 29 14:56:04[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0x101105b0 -- thread:0x101f3b80
174 UID STORE 125143 +FLAGS.SILENT (\Seen)
WROTE Oct 29 14:56:04[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0x101105b0 -- thread:0x101f3b80
176 UID COPY 125143 Trash
READ Oct 29 14:56:05[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0x101105b0 -- thread:0x101f3b80
176 OK [COPYUID 4 125143 125143] UID COPY completed
WROTE Oct 29 14:56:05[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0x101105b0 -- thread:0x101f3b80
177 UID STORE 125143 +FLAGS.SILENT (\Seen \Deleted)
WROTE Oct 29 18:26:10[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0x11edc210 -- thread:0x10122d10
150 UID COPY 125189,125168,125152,125143,125117,125114 Trash
READ Oct 29 18:26:10[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0x11edc210 -- thread:0x10122d10
150 OK [COPYUID 4 125114,125117,125143,125152,125168,125189 125195:125200] UID COPY completed
WROTE Oct 29 18:26:11[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0x11edc210 -- thread:0x10122d10
151 UID STORE 125114,125117,125143,125152,125168,125189 +FLAGS.SILENT (\Seen \Deleted)
----------------------------------
This seems to work with a copy of the same email with a UID of 125147 that is also fetched from the Trash folder.
----------------------------------
WROTE Oct 29 14:56:05[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0xf1368c0 -- thread:0xfeeb290
13 SELECT Trash
READ Oct 29 14:56:05[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0xf1368c0 -- thread:0xfeeb290
* 168 FETCH (FLAGS (\Seen) UID 125147 INTERNALDATE "29-Oct-2012 14:50:12 +0000" RFC822.SIZE 20704 BODY[HEADER.FIELDS (DATE SUBJECT FROM TO CC MESSAGE-ID IN-REPLY-TO REFERENCES X-PRIORITY X-SPAM-FLAG RECEIVED-SPF CONTENT-TYPE)] {516}
yada yada email content...
14 OK UID FETCH completed
WROTE Oct 29 14:56:05[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0xf1368c0 -- thread:0xfeeb290
17 UID FETCH 125147 (BODYSTRUCTURE BODY.PEEK[HEADER] BODY.PEEK[HEADER])
READ Oct 29 14:56:06[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0xf1368c0 -- thread:0xfeeb290
* 168 FETCH (UID 125147 BODYSTRUCTURE (("text" "plain" ("charset" "us-ascii") NIL NIL "quoted-printable" 4730 91 NIL NIL ("en-US") NIL)("text" "html" ("charset" "us-ascii") NIL NIL "quoted-printable" 12284 204 NIL NIL ("en-US") NIL) "alternative" ("boundary" "_000_E8C44E43AA18444B85496F858533501087E73DC361LOEWYMAIL3loe_") NIL ) BODY[HEADER] {3435}
yada yada...
17 OK UID FETCH completed
WROTE Oct 29 14:56:06[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0xf1368c0 -- thread:0xfeeb290
18 UID FETCH 125147 (BODY.PEEK[1] BODY.PEEK[2])
READ Oct 29 14:56:06[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0xf1368c0 -- thread:0xfeeb290
* 168 FETCH (UID 125147 BODY[1] {4730}
yada yada…
READ Oct 29 14:56:06[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0xf1368c0 -- thread:0xfeeb290
BODY[2] {12284}
html body
READ Oct 29 14:56:06[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0xf1368c0 -- thread:0xfeeb290
yada html body text=
)
18 OK UID FETCH completed
READ Oct 29 15:54:27[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0xfef0a80 -- thread:0x111614c0
AGS (\Seen) UID 124967)
yada yada...
* 168 FETCH (FLAGS (\Seen) UID 125147)
----------------------------------
Then there is another copy of the same email with a different UID of 125207… it is fetched from the Trash.
----------------------------------
WROTE Oct 29 18:26:11[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0x135a5a20 -- thread:0x105f4a10
3 SELECT Trash
* 183 FETCH (FLAGS (\Seen) UID 125207 INTERNALDATE "29-Oct-2012 14:50:12 +0000" RFC822.SIZE 20704 BODY[HEADER.FIELDS (DATE SUBJECT FROM TO CC MESSAGE-ID IN-REPLY-TO REFERENCES X-PRIORITY X-SPAM-FLAG RECEIVED-SPF CONTENT-TYPE)] {516}
yada email content
)
WROTE Oct 29 18:26:12[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0x135a5a20 -- thread:0x105f4a10
8 UID FETCH 125207 (BODYSTRUCTURE BODY.PEEK[HEADER])
READ Oct 29 18:26:12[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0x135a5a20 -- thread:0x105f4a10
* 183 FETCH (UID 125207 BODYSTRUCTURE (("text" "plain" ("charset" "us-ascii") NIL NIL "quoted-printable" 4730 91 NIL NIL ("en-US") NIL)("text" "html" ("charset" "us-ascii") NIL NIL "quoted-printable" 12284 204 NIL NIL ("en-US") NIL) "alternative" ("boundary" "_000_E8C44E43AA18444B85496F858533501087E73DC361LOEWYMAIL3loe_") NIL ) BODY[HEADER] {3435}
yada email content
)
8 OK UID FETCH completed
WROTE Oct 29 18:26:14[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0x135a5a20 -- thread:0x105f4a10
21 UID FETCH 125207 (BODY.PEEK[1] BODY.PEEK[2])
READ Oct 29 18:26:15[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0x135a5a20 -- thread:0x105f4a10
* 183 FETCH (UID 125207 BODY[1] {4730} yada email content
21 OK UID FETCH completed
* 183 FETCH (FLAGS (\Seen) UID 125207)
* 183 FETCH (FLAGS (\Seen) UID 125207)
* 183 FETCH (FLAGS (\Seen) UID 125207)
I am not sure what is happening.
Try looking at the server with the web interface?
The web interface is also showing duplicate e-mails, therefore there is a problem in the way Mail handles deleted messages. I've analysed the logs again, and found that it may be related to a connection problem. See below.
So it looks like the issue is Mail not setting the "Deleted" flag on messages in the inbox. Currently, the settings are:
1. Store deleted messages on the server.
2. Move deleted messages to the Trash folder.
3. Erase deleted messages when: Never.
We are using Mail 3.1.3.
-------------------------------------------------------------------
E-mail arrives in inbox as UID 125998, it is seen (seen is set twice for some reason):
READ Nov 01 19:50:31[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0xf149190 -- thread:0x12aa02b0
* 99 FETCH (FLAGS () UID 125998....
800 OK FETCH completed
WROTE Nov 01 19:50:31[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0xf149190 -- thread:0x12aa02b0
801 UID FETCH 125998 BODY.PEEK[]
READ Nov 01 19:50:32[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0xf149190 -- thread:0x12aa02b0
* 99 FETCH (UID 125998 BODY[] {63579}
..... yada yada.....
WROTE Nov 01 20:00:16[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0xf149190 -- thread:0x12100bd0
811 UID STORE 125998 +FLAGS.SILENT (\Seen)
WROTE Nov 01 20:00:17[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0xf149190 -- thread:0x15272540
812 UID STORE 125998 +FLAGS.SILENT (\Seen)
-------------------------------------------------------------------
E-mail is Trashed:
WROTE Nov 01 20:00:18[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0xf149190 -- thread:0x15272540
813 UID COPY 125998 Trash
READ Nov 01 20:00:18[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0xf149190 -- thread:0x15272540
813 OK [COPYUID 4 125998 125991] UID COPY completed
-------------------------------------------------------------------
Looks like we get a connection problem:
CONNECTED Nov 01 20:00:18[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0x12617c50 -- thread:0x14d145d0
READ Nov 01 20:00:19[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0x12617c50 -- thread:0x14d145d0
* OK [CAPABILITY IMAP4rev1 ID NAMESPACE X-ID-ACLID UIDPLUS LITERAL+ CHILDREN XAPPLEPUSHSERVICE XYMHIGHESTMODSEQ AUTH=PLAIN AUTH=LOGIN AUTH=XYMCOOKIE AUTH=XYMECOOKIE AUTH=XYMCOOKIEB64 AUTH=XYMPKI] IMAP4rev1 imapgate-0.7.68_14.357900 imap406.mail.ird.yahoo.com
WROTE Nov 01 20:00:19[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0x12617c50 -- thread:0x14d145d0
1 LOGIN yada@yada.com *********
READ Nov 01 20:00:19[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0x12617c50 -- thread:0x14d145d0
1 OK AUTHENTICATE completed - Mailbox size in bytes is 7325843417
WROTE Nov 01 20:00:19[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0x12617c50 -- thread:0x14d145d0
2 CAPABILITY
READ Nov 01 20:00:19[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0x12617c50 -- thread:0x14d145d0
* CAPABILITY IMAP4rev1 ID NAMESPACE X-ID-ACLID UIDPLUS LITERAL+ XAPPLEPUSHSERVICE XYMHIGHESTMODSEQ MOVE
2 OK CAPABILITY completed
WROTE Nov 01 20:00:19[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0x12617c50 -- thread:0x14d145d0
-------------------------------------------------------------------
The Trash mailbox is selected:
3 SELECT Trash
READ Nov 01 20:00:19[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0x12617c50 -- thread:0x14d145d0
* 116 EXISTS
* 0 RECENT
* OK [UIDVALIDITY 4] UIDs valid
* OK [UIDNEXT 126000] Predicted next UID
* FLAGS (\Answered \Flagged \Deleted \Seen \Draft)
* OK [PERMANENTFLAGS (\Answered \Flagged \Deleted \Seen \Draft)] Permanent flags
* OK [HIGHESTMODSEQ 5805936670601542712]
3 OK [READ-WRITE] SELECT completed; now in selected state
WROTE Nov 01 20:00:19[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0xf149190 -- thread:0x15272540
-------------------------------------------------------------------
Then Mail tries to set the Deleted status on the inbox message from the selected TRASH mailbox. The message with UID 12999 in Trash has the same title as the inbox message, thus I assume Mail has assigned this UID to the mail as it was copied to TRASH...:
814 UID STORE 125998 +FLAGS.SILENT (\Seen \Deleted)
READ Nov 01 20:00:19[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0x12617c50 -- thread:0xfd66480
* 116 FETCH (FLAGS (\Seen) UID 125999 INTERNALDATE "01-Nov-2012 19:49:15 +0000" RFC822.SIZE 63499 BODY[HEADER.FIELDS (DATE SUBJECT FROM TO CC MESSAGE-ID IN-REPLY-TO REFERENCES X-PRIORITY X-SPAM-FLAG RECEIVED-SPF CONTENT-TYPE)] {479}
.... yada....
4 OK UID FETCH completed
WROTE Nov 01 20:00:19[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0x12617c50 -- thread:0xfd66480
6 UID FETCH 125999 (BODY.PEEK[] BODY.PEEK[])
READ Nov 01 20:00:20[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0x12617c50 -- thread:0xfd66480
* 116 FETCH (UID 125999 BODY[] {63579}
READ Nov 01 20:12:03[kCFStreamSocketSecurityLevelNegotiatedSSL] -- host:imap.mail.yahoo.com -- port:993 -- socket:0x11246270 -- thread:0x112dcff0
* 114 FETCH (FLAGS (\Seen) UID 125999)
-------------------------------------------------------------------
Later, a duplicate e-mail with the same UID as the deleted message appears in the inbox.
(SELECT INBOX...)
* 22 FETCH (FLAGS (\Seen) UID 125998)
Steffan Harris wrote:
Currently, the settings are:
1. Store deleted messages on the server.
2. Move deleted messages to the Trash folder.
3. Erase deleted messages when: Never.
You could try:
1 - Playing with the above settings
2 - Trying another account on the Mac
3 - Running 10.4.11 combo update to try and fix Mail
4 - Trying another email client
Well, after changing the settings to NOT Store deleted messages on the server, I haven't noticed any more problems. I've had another person notice deleted messages appearing in the inbox in Mail on their Gmail account, so I would hazard a guess that Mail's handling of this setting is unstable.
It is more likely to be a server fault although it could be be some sort of mismatch between the client and server.
IMAP Mail on Yahoo reappearing in Inbox after deleting/moving
