[Dovecot] 2.0 migration weirdnesses: logs and hang

Sat Aug 21 00:20:10 EEST 2010

On Friday, August 20 at 07:58 PM, quoth Timo Sirainen:
> Did you try this with IMAP client or via talking IMAP directly?

I did it with mutt as my IMAP client. I'm attaching a log of the IMAP 
conversation. The log ends at the point that I had to kill mutt to 
regain control. It looks like mutt is stuck waiting for Dovecot to 
reply "a0049 OK Idle completed."

> Is it really relevant that it's being done across namespaces?

I just tried it within the same namespace: no, it's not relevant.

> If it seems to be, try also within a namespace but with 
> maildir_copy_with_hardlinks=no?

I just tried it with maildir_copy_with_hardlinks=no; I got the exact 
same behavior.

~Kyle
-- 
To invent, you need a good imagination and a pile of junk.
                                                    -- Thomas Jefferson
-------------- next part --------------
5< * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE AUTH=PLAIN AUTH=LOGIN] There was suppose to be an earth-shattering KA-BOOM!!!
5> a0000 AUTHENTICATE LOGIN
5< + VXNlcm5hbWU6
5> ............
5< + UGFzc3dvcmQ6
5> ............
5< a0000 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS MULTIAPPEND UNSELECT IDLE CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS] Logged in
5> a0001 CAPABILITY
5< * CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS MULTIAPPEND UNSELECT IDLE CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS
5< a0001 OK Capability completed.
5< * LIST (\Noselect) "." ""
5< a0002 OK List completed.
5> a0003 STATUS "INBOX.Drafts" (MESSAGES)
5< * STATUS "INBOX.Drafts" (MESSAGES 0)
5< a0003 OK Status completed.
5< * FLAGS (\Answered \Flagged \Deleted \Seen \Draft $NotJunk JunkRecorded $Junk $Forwarded NotJunk Old $MDNSent)
5< * OK [PERMANENTFLAGS (\Answered \Flagged \Deleted \Seen \Draft $NotJunk JunkRecorded $Junk $Forwarded NotJunk Old $MDNSent \*)] Flags permitted.
5< * 40 EXISTS
5< * 0 RECENT
5< * OK [UIDVALIDITY 1173499967] UIDs valid
5< * OK [UIDNEXT 24152] Predicted next UID
5< * OK [HIGHESTMODSEQ 2994] Highest
5< a0004 OK [READ-WRITE] Select completed.
5> a0005 UID FETCH 1:24151 (UID FLAGS)
5< * 1 FETCH (UID 14477 FLAGS (\Seen))
5< * 2 FETCH (UID 18291 FLAGS (\Seen))
5< * 3 FETCH (UID 18332 FLAGS (\Seen))
5< * 4 FETCH (UID 18410 FLAGS (\Answered \Seen))
5< * 5 FETCH (UID 18490 FLAGS (\Seen))
5< * 6 FETCH (UID 18676 FLAGS (\Answered \Seen))
5< * 7 FETCH (UID 18688 FLAGS (\Answered \Seen))
5< * 8 FETCH (UID 19810 FLAGS (\Seen))
5< * 9 FETCH (UID 19950 FLAGS (\Seen))
5< * 10 FETCH (UID 19971 FLAGS (\Seen))
5< * 11 FETCH (UID 19973 FLAGS (\Seen))
5< * 12 FETCH (UID 20207 FLAGS (\Seen))
5< * 13 FETCH (UID 20362 FLAGS (\Seen))
5< * 14 FETCH (UID 20895 FLAGS (\Seen))
5< * 15 FETCH (UID 22321 FLAGS (\Seen $Forwarded))
5< * 16 FETCH (UID 23330 FLAGS (\Seen))
5< * 17 FETCH (UID 23652 FLAGS (\Answered \Seen))
5< * 18 FETCH (UID 23669 FLAGS (\Seen))
5< * 19 FETCH (UID 23672 FLAGS (\Seen))
5< * 20 FETCH (UID 23673 FLAGS (\Answered \Seen))
5< * 21 FETCH (UID 23675 FLAGS (\Seen))
5< * 22 FETCH (UID 23708 FLAGS (\Seen))
5< * 23 FETCH (UID 23709 FLAGS (\Seen))
5< * 24 FETCH (UID 23712 FLAGS (\Seen))
5< * 25 FETCH (UID 23714 FLAGS (\Seen))
5< * 26 FETCH (UID 23715 FLAGS (\Seen))
5< * 27 FETCH (UID 23722 FLAGS (\Seen))
5< * 28 FETCH (UID 23746 FLAGS (\Seen))
5< * 29 FETCH (UID 23749 FLAGS (\Seen))
5< * 30 FETCH (UID 23762 FLAGS (\Seen))
5< * 31 FETCH (UID 23764 FLAGS (\Seen))
5< * 32 FETCH (UID 23818 FLAGS (\Seen))
5< * 33 FETCH (UID 23827 FLAGS (\Seen))
5< * 34 FETCH (UID 23946 FLAGS (\Seen))
5< * 35 FETCH (UID 23979 FLAGS (\Seen))
5< * 36 FETCH (UID 24063 FLAGS (\Seen))
5< * 37 FETCH (UID 24114 FLAGS (\Seen))
5< * 38 FETCH (UID 24134 FLAGS (\Answered \Seen))
5< * 39 FETCH (UID 24149 FLAGS (\Seen))
5< * 40 FETCH (UID 24150 FLAGS (\Seen))
5> a0006 STATUS "INBOX.Family" (UIDNEXT UIDVALIDITY UNSEEN RECENT)
5< a0005 OK Fetch completed.
5< * STATUS "INBOX.Family" (RECENT 0 UIDNEXT 6183 UIDVALIDITY 1173549357 UNSEEN 0)
5< a0006 OK Status completed.
5< * STATUS "INBOX.Friends.WeBeSmart" (RECENT 0 UIDNEXT 1519 UIDVALIDITY 1173549210 UNSEEN 0)
5< a0007 OK Status completed.
5< * STATUS "INBOX.Subscribed.Debian.Security" (RECENT 0 UIDNEXT 927 UIDVALIDITY 1173549210 UNSEEN 0)
5< a0008 OK Status completed.
5< * STATUS "INBOX.Subscribed.Apple Security" (RECENT 0 UIDNEXT 221 UIDVALIDITY 1173549362 UNSEEN 0)
5< a0009 OK Status completed.
5< * STATUS "INBOX.Logs.Mail" (RECENT 0 UIDNEXT 9025 UIDVALIDITY 1173549210 UNSEEN 0)
5< a0010 OK Status completed.
5< * STATUS "INBOX.Logs.Salinan" (RECENT 0 UIDNEXT 5 UIDVALIDITY 1173549210 UNSEEN 0)
5< a0011 OK Status completed.
5< * STATUS "INBOX.Logs.Me" (RECENT 0 UIDNEXT 1 UIDVALIDITY 1173549210 UNSEEN 0)
5< a0012 OK Status completed.
5< * STATUS "INBOX.Logs.WOPR" (RECENT 0 UIDNEXT 3208 UIDVALIDITY 1173549210 UNSEEN 0)
5< a0013 OK Status completed.
5< * STATUS "INBOX.Subscribed.ThePeople" (RECENT 0 UIDNEXT 206 UIDVALIDITY 1173549210 UNSEEN 0)
5< a0014 OK Status completed.
5< * STATUS "INBOX.Subscribed.SST" (RECENT 0 UIDNEXT 60 UIDVALIDITY 1173549210 UNSEEN 0)
5< a0015 OK Status completed.
5< * STATUS "INBOX.Subscribed.Mutt" (RECENT 0 UIDNEXT 9788 UIDVALIDITY 1173549363 UNSEEN 0)
5< a0016 OK Status completed.
5< * STATUS "INBOX.Subscribed.MuttDev" (RECENT 0 UIDNEXT 4912 UIDVALIDITY 1173549363 UNSEEN 0)
5< a0017 OK Status completed.
5< * STATUS "INBOX.Subscribed.Qmail" (RECENT 0 UIDNEXT 6460 UIDVALIDITY 1173549363 UNSEEN 0)
5< a0018 OK Status completed.
5> a0019 IDLE
5< + idling
5> DONE
5< a0019 OK Idle completed.
5< * LIST (\Noselect \HasChildren) "." "INBOX.Subscribed"
5< a0020 OK List completed.
5> a0021 LIST "" "INBOX.Subscribed.De%"
5< * LIST (\Noselect \HasChildren) "." "INBOX.Subscribed.Debian"
5< a0021 OK List completed.
5> a0022 LIST "" "INBOX.Subscribed.Debian.Py%"
5< * LIST (\HasNoChildren) "." "INBOX.Subscribed.Debian.Pyzor"
5< a0022 OK List completed.
5> a0023 CLOSE
5< a0023 OK Close completed.
5< * STATUS "INBOX.Drafts" (MESSAGES 0)
5< a0024 OK Status completed.
5< * FLAGS (\Answered \Flagged \Deleted \Seen \Draft $NotJunk $Junk)
5< * OK [PERMANENTFLAGS (\Answered \Flagged \Deleted \Seen \Draft $NotJunk $Junk \*)] Flags permitted.
5< * 6 EXISTS
5< * 0 RECENT
5< * OK [UIDVALIDITY 1173549362] UIDs valid
5< * OK [UIDNEXT 41] Predicted next UID
5< * OK [HIGHESTMODSEQ 1] Highest
5< a0025 OK [READ-WRITE] Select completed.
5> a0026 UID FETCH 1:40 (UID FLAGS)
5< * 1 FETCH (UID 1 FLAGS (\Deleted \Seen $NotJunk))
5< * 2 FETCH (UID 20 FLAGS (\Deleted \Seen))
5< * 3 FETCH (UID 37 FLAGS (\Seen))
5< * 4 FETCH (UID 38 FLAGS (\Seen))
5< * 5 FETCH (UID 39 FLAGS (\Seen $NotJunk))
5< * 6 FETCH (UID 40 FLAGS (\Seen))
5> a0027 FETCH 1:6 (UID FLAGS INTERNALDATE RFC822.SIZE BODY.PEEK[HEADER.FIELDS (DATE FROM SUBJECT TO CC MESSAGE-ID REFERENCES CONTENT-TYPE CONTENT-DESCRIPTION IN-REPLY-TO REPLY-TO LINES LIST-POST X-LABEL X-Spam-WBS-Status)])
5< a0026 OK Fetch completed.
5< * 1 FETCH (UID 1 FLAGS (\Deleted \Seen $NotJunk) INTERNALDATE "17-Sep-2005 10:48:49 -0400" RFC822.SIZE 8591 BODY[HEADER.FIELDS (DATE FROM SUBJECT TO CC MESSAGE-ID REFERENCES CONTENT-TYPE CONTENT-DESCRIPTION IN-REPLY-TO REPLY-TO LINES LIST-POST X-LABEL X-SPAM-WBS-STATUS)] {377}
5< )
5< * 2 FETCH (UID 20 FLAGS (\Deleted \Seen) INTERNALDATE "11-May-2009 01:59:31 -0400" RFC822.SIZE 3359 BODY[HEADER.FIELDS (DATE FROM SUBJECT TO CC MESSAGE-ID REFERENCES CONTENT-TYPE CONTENT-DESCRIPTION IN-REPLY-TO REPLY-TO LINES LIST-POST X-LABEL X-SPAM-WBS-STATUS)] {379}
5< )
5< * 3 FETCH (UID 37 FLAGS (\Seen) INTERNALDATE "18-Jun-2010 19:09:18 -0400" RFC822.SIZE 6346 BODY[HEADER.FIELDS (DATE FROM SUBJECT TO CC MESSAGE-ID REFERENCES CONTENT-TYPE CONTENT-DESCRIPTION IN-REPLY-TO REPLY-TO LINES LIST-POST X-LABEL X-SPAM-WBS-STATUS)] {488}
5< )
5< * 4 FETCH (UID 38 FLAGS (\Seen) INTERNALDATE "01-Jul-2010 19:55:53 -0400" RFC822.SIZE 4134 BODY[HEADER.FIELDS (DATE FROM SUBJECT TO CC MESSAGE-ID REFERENCES CONTENT-TYPE CONTENT-DESCRIPTION IN-REPLY-TO REPLY-TO LINES LIST-POST X-LABEL X-SPAM-WBS-STATUS)] {514}
5< )
5< * 5 FETCH (UID 39 FLAGS (\Seen $NotJunk) INTERNALDATE "17-Sep-2005 10:48:49 -0400" RFC822.SIZE 8591 BODY[HEADER.FIELDS (DATE FROM SUBJECT TO CC MESSAGE-ID REFERENCES CONTENT-TYPE CONTENT-DESCRIPTION IN-REPLY-TO REPLY-TO LINES LIST-POST X-LABEL X-SPAM-WBS-STATUS)] {377}
5< )
5< * 6 FETCH (UID 40 FLAGS (\Seen) INTERNALDATE "11-May-2009 01:59:31 -0400" RFC822.SIZE 3359 BODY[HEADER.FIELDS (DATE FROM SUBJECT TO CC MESSAGE-ID REFERENCES CONTENT-TYPE CONTENT-DESCRIPTION IN-REPLY-TO REPLY-TO LINES LIST-POST X-LABEL X-SPAM-WBS-STATUS)] {379}
5< )
5< a0027 OK Fetch completed.
5> a0028 STATUS "INBOX" (UIDNEXT UIDVALIDITY UNSEEN RECENT)
5< * STATUS "INBOX" (RECENT 0 UIDNEXT 24152 UIDVALIDITY 1173499967 UNSEEN 0)
5< a0028 OK Status completed.
5< * STATUS "INBOX.Family" (RECENT 0 UIDNEXT 6183 UIDVALIDITY 1173549357 UNSEEN 0)
5< a0029 OK Status completed.
5< * STATUS "INBOX.Friends.WeBeSmart" (RECENT 0 UIDNEXT 1519 UIDVALIDITY 1173549210 UNSEEN 0)
5< a0030 OK Status completed.
5< * STATUS "INBOX.Subscribed.Debian.Security" (RECENT 0 UIDNEXT 927 UIDVALIDITY 1173549210 UNSEEN 0)
5< a0031 OK Status completed.
5< * STATUS "INBOX.Subscribed.Apple Security" (RECENT 0 UIDNEXT 221 UIDVALIDITY 1173549362 UNSEEN 0)
5< a0032 OK Status completed.
5< * STATUS "INBOX.Logs.Mail" (RECENT 0 UIDNEXT 9025 UIDVALIDITY 1173549210 UNSEEN 0)
5< a0033 OK Status completed.
5< * STATUS "INBOX.Logs.Salinan" (RECENT 0 UIDNEXT 5 UIDVALIDITY 1173549210 UNSEEN 0)
5< a0034 OK Status completed.
5< * STATUS "INBOX.Logs.Me" (RECENT 0 UIDNEXT 1 UIDVALIDITY 1173549210 UNSEEN 0)
5< a0035 OK Status completed.
5< * STATUS "INBOX.Logs.WOPR" (RECENT 0 UIDNEXT 3208 UIDVALIDITY 1173549210 UNSEEN 0)
5< a0036 OK Status completed.
5< * STATUS "INBOX.Subscribed.ThePeople" (RECENT 0 UIDNEXT 206 UIDVALIDITY 1173549210 UNSEEN 0)
5< a0037 OK Status completed.
5< * STATUS "INBOX.Subscribed.SST" (RECENT 0 UIDNEXT 60 UIDVALIDITY 1173549210 UNSEEN 0)
5< a0038 OK Status completed.
5< * STATUS "INBOX.Subscribed.Mutt" (RECENT 0 UIDNEXT 9788 UIDVALIDITY 1173549363 UNSEEN 0)
5< a0039 OK Status completed.
5< * STATUS "INBOX.Subscribed.MuttDev" (RECENT 0 UIDNEXT 4912 UIDVALIDITY 1173549363 UNSEEN 0)
5< a0040 OK Status completed.
5< * STATUS "INBOX.Subscribed.Qmail" (RECENT 0 UIDNEXT 6460 UIDVALIDITY 1173549363 UNSEEN 0)
5< a0041 OK Status completed.
5> a0042 IDLE
5< + idling
5> DONE
5< a0042 OK Idle completed.
5< * LIST (\Noselect \HasChildren) "." "INBOX.Archive"
5< a0043 OK List completed.
5> a0044 LIST "" "INBOX.Su%"
5< * LIST (\Noselect \HasChildren) "." "INBOX.Subscribed"
5< a0044 OK List completed.
5> a0045 STATUS "INBOX.Subscribed.testfolder" (UIDVALIDITY)
5< a0045 NO Mailbox doesn't exist: INBOX.Subscribed.testfolder
5> a0046 UID COPY 37:38 "INBOX.Subscribed.testfolder"
5< a0046 NO [TRYCREATE] Mailbox doesn't exist: INBOX.Subscribed.testfolder
5> a0047 CREATE "INBOX.Subscribed.testfolder"
5< a0047 OK Create completed.
5> a0048 UID COPY 37:38 "INBOX.Subscribed.testfolder"
5< a0048 OK [COPYUID 1225583175 37:38 1:2] Copy completed.
5> a0049 IDLE
5< + idling
5> DONE
5< a0049 OK Idle completed.
-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: application/pgp-signature
Size: 842 bytes
Desc: Digital signature
Url : http://dovecot.org/pipermail/dovecot/attachments/20100820/0efa0ba1/attachment.bin 