From: Eric A. <er...@er...> - 2015-02-12 06:20:59
|
I'm trying to use my newfound powers to figure out something that's bothered me for a long time. In one of my accounts, new messages delivered to the INBOX will, for a day or two after delivery, keep showing up as new every time I sync the server. I discovered that the message is actually being given a new UID somewhere along the line (which is gmail <-> mbsync <-> dovecot <-> Gnus), so it keeps showing up as a new message. After a day or two, it stops happening. I have three accounts backed by gmail. This happens consistently on one account, occasionally on another, and never on the third. I'm posting some logs, which I hope will at least show where the problem is. It starts with a -VV output for mbsync, showing the message getting added as new. Then there's a second mbsync run, showing the same message getting added again, under a new UID. Then there's the output of recording Gnus talking to Dovecot. I think I went through the process a couple of times between mbsync runs, so the UID went from 9971 to 9974, but it's still the same message, and the Gnus/Dovecot record should come directly after the second mbsync run. I hope this is enough to at least know where to look next... Thanks, Eric First mbsync run: M: >>> 4 SELECT "INBOX" S: >>> 3 SELECT "INBOX" S: * FLAGS (\Answered \Flagged \Deleted \Seen \Draft) S: * OK [PERMANENTFLAGS (\Answered \Flagged \Deleted \Seen \Draft \*)] Flags permitted. S: * 9777 EXISTS S: * 0 RECENT S: * OK [UNSEEN 9695] First unseen. S: * OK [UIDVALIDITY 1422727093] UIDs valid S: * OK [UIDNEXT 9972] Predicted next UID S: * OK [HIGHESTMODSEQ 11916] Highest S: 3 OK [READ-WRITE] Select completed (0.015 secs). M: * FLAGS (\Answered \Flagged \Draft \Deleted \Seen $Phishing $Forwarded JunkRecorded $NotJunk NotJunk $NotPhishing $Junk) M: * OK [PERMANENTFLAGS (\Answered \Flagged \Draft \Deleted \Seen $Phishing $Forwarded JunkRecorded $NotJunk NotJunk $NotPhishing $Junk \*)] Flags permitted. M: * OK [UIDVALIDITY 671647711] UIDs valid. M: * 8091 EXISTS M: * 0 RECENT M: * OK [UIDNEXT 44243] Predicted next UID. M: * OK [HIGHESTMODSEQ 1640713] M: 4 OK [READ-WRITE] INBOX selected. (Success) M: >>> 5 UID FETCH 1:44242 (UID FLAGS) S: >>> 4 UID FETCH 1:9971 (UID FLAGS) S: * 1 FETCH (UID 1 FLAGS (\Seen)) S: * 2 FETCH (UID 2 FLAGS (\Seen)) [ELIDED] S: * 9776 FETCH (UID 9964 FLAGS (\Seen)) S: * 9777 FETCH (UID 9971 FLAGS (\Seen)) S: 4 OK Fetch completed. M: * 1 FETCH (UID 8544 FLAGS (\Seen)) M: * 2 FETCH (UID 28064 FLAGS (\Seen)) [ELIDED] M: * 8091 FETCH (UID 44242 FLAGS ()) M: 5 OK Success M: >>> 6 UID STORE 44242 +FLAGS.SILENT (\Deleted) S: >>> 5 UID FETCH 9971 (BODY.PEEK[]) S: * 9777 FETCH (UID 9971 BODY[] {22205} S: ========= [MESSAGE HERE] S: ========= S: ) S: 5 OK Fetch completed. (1 in progress) M: >>> 7 APPEND "INBOX" (\Seen) {22205} M: * 8091 EXPUNGE M: * 8090 EXISTS M: 6 OK Success M: + go ahead M: * 8091 EXISTS M: 7 OK [APPENDUID 671647711 44243] (Success) S: >>> 6 LOGOUT M: >>> 8 LOGOUT Second mbsync run: M: >>> 4 SELECT "INBOX" S: >>> 3 SELECT "INBOX" S: * FLAGS (\Answered \Flagged \Deleted \Seen \Draft) S: * OK [PERMANENTFLAGS (\Answered \Flagged \Deleted \Seen \Draft \*)] Flags permitted. S: * 9777 EXISTS S: * 0 RECENT S: * OK [UNSEEN 9695] First unseen. S: * OK [UIDVALIDITY 1422727093] UIDs valid S: * OK [UIDNEXT 9975] Predicted next UID S: * OK [HIGHESTMODSEQ 11929] Highest S: 3 OK [READ-WRITE] Select completed (0.014 secs). M: * FLAGS (\Answered \Flagged \Draft \Deleted \Seen $Phishing $Forwarded JunkRecorded $NotJunk NotJunk $NotPhishing $Junk) M: * OK [PERMANENTFLAGS (\Answered \Flagged \Draft \Deleted \Seen $Phishing $Forwarded JunkRecorded $NotJunk NotJunk $NotPhishing $Junk \*)] Flags permitted. M: * OK [UIDVALIDITY 671647711] UIDs valid. M: * 8091 EXISTS M: * 0 RECENT M: * OK [UIDNEXT 44245] Predicted next UID. M: * OK [HIGHESTMODSEQ 1640790] M: 4 OK [READ-WRITE] INBOX selected. (Success) M: >>> 5 UID FETCH 1:44244 (UID FLAGS) S: >>> 4 UID FETCH 1:9974 (UID FLAGS) S: * 1 FETCH (UID 1 FLAGS (\Seen)) S: * 2 FETCH (UID 2 FLAGS (\Seen)) [ELIDED] S: * 9776 FETCH (UID 9964 FLAGS (\Seen)) S: * 9777 FETCH (UID 9974 FLAGS (\Seen)) S: 4 OK Fetch completed. M: * 1 FETCH (UID 8544 FLAGS (\Seen)) M: * 2 FETCH (UID 28064 FLAGS (\Seen)) [ELIDED] M: * 8091 FETCH (UID 44244 FLAGS ()) M: 5 OK Success M: >>> 6 UID STORE 44244 +FLAGS.SILENT (\Deleted) S: >>> 5 UID FETCH 9974 (BODY.PEEK[]) S: * 9777 FETCH (UID 9974 BODY[] {22205} S: ========= [MESSAGE HERE] S: ========= S: ) S: 5 OK Fetch completed. (1 in progress) M: >>> 7 APPEND "INBOX" (\Seen) {22205} M: * 8091 EXPUNGE M: * 8090 EXISTS M: 6 OK Success M: + go ahead M: * 8091 EXISTS M: 7 OK [APPENDUID 671647711 44245] (Success) S: >>> 6 LOGOUT M: >>> 8 LOGOUT And this is my email client (Gnus) talking to dovecot. Getting "new mail": 14:00:22 [localhost] 1910 SELECT "INBOX" 14:00:22 [localhost] 1911 UID FETCH 1:* FLAGS 14:00:22 [localhost] 1912 UID FETCH 9975 (UID BODY.PEEK[HEADER]) 14:00:22 [localhost] 1913 UID COPY 9975 "INBOX" 14:00:22 [localhost] 1914 UID STORE 9975 +FLAGS.SILENT (\Deleted) 14:00:22 [localhost] 1915 UID EXPUNGE 9975 Later marking the message as seen: 14:00:46 [localhost] 1965 SELECT "INBOX" 14:00:46 [localhost] 1966 SELECT "INBOX" 14:00:46 [localhost] 1967 UID FETCH 9976 (UID RFC822.SIZE BODYSTRUCTURE BODY.PEEK[HEADER.FIELDS (Subject From Date Message-Id References In-Reply-To Xref To Newsgroups Cc)]) 14:00:48 [localhost] 1968 SELECT "INBOX" 14:00:48 [localhost] 1969 UID FETCH 9976 BODY.PEEK[] 14:00:49 [localhost] 1970 SELECT "INBOX" 14:00:49 [localhost] 1971 UID STORE 9976 +FLAGS.SILENT (\Seen) |