Teemu Huovila
2015-May-06 06:57 UTC
Bug#776094: dovecot-imapd: corrupts mailbox after trying to retrieve it (fwd)
On 05/05/2015 05:26 PM, Santiago Vila wrote:> I have just verified with IMAP commands. This is the procedure: > > telnet localhost 143 > > and then type this: > > A0001 CAPABILITY > A0002 LOGIN "bluser" "bluser" > A0003 SELECT "inbox-b" > A0004 EXPUNGE > A0005 FETCH 1:12 RFC822.SIZE > A0006 FETCH 1 RFC822.HEADER > A0007 FETCH 1 BODY.PEEK[TEXT] > A0008 STORE 1 +FLAGS (\Seen \Deleted) > A0009 EXPUNGE > A0010 FETCH 1 RFC822.HEADER > A0011 FETCH 1 BODY.PEEK[TEXT] > A0012 STORE 1 +FLAGS (\Seen \Deleted) > A0013 EXPUNGE > A0014 FETCH 1 RFC822.HEADER > A0015 FETCH 1 BODY.PEEK[TEXT] > A0016 STORE 1 +FLAGS (\Seen \Deleted) > A0017 EXPUNGE > A0018 FETCH 1 RFC822.HEADER > A0019 FETCH 1 BODY.PEEK[TEXT] > A0020 STORE 1 +FLAGS (\Seen \Deleted) > A0021 EXPUNGE > A0022 FETCH 1 RFC822.HEADER > A0023 FETCH 1 BODY.PEEK[TEXT] > A0024 STORE 1 +FLAGS (\Seen \Deleted) > A0025 EXPUNGE > A0026 FETCH 1 RFC822.HEADER > A0027 FETCH 1 BODY.PEEK[TEXT] > A0028 STORE 1 +FLAGS (\Seen \Deleted) > A0029 EXPUNGE > A0030 FETCH 1 RFC822.HEADER > A0031 FETCH 1 BODY.PEEK[TEXT] > A0032 STORE 1 +FLAGS (\Seen \Deleted) > A0033 EXPUNGE > A0034 FETCH 1 RFC822.HEADER > A0035 FETCH 1 BODY.PEEK[TEXT] > A0036 STORE 1 +FLAGS (\Seen \Deleted) > A0037 EXPUNGE > A0038 FETCH 1 RFC822.HEADER > A0039 FETCH 1 BODY.PEEK[TEXT] > A0040 STORE 1 +FLAGS (\Seen \Deleted) > A0041 EXPUNGE > A0042 FETCH 1 RFC822.HEADER > A0043 FETCH 1 BODY.PEEK[TEXT] > A0044 STORE 1 +FLAGS (\Seen \Deleted) > A0045 EXPUNGE > A0046 FETCH 1 RFC822.HEADER > A0047 FETCH 1 BODY.PEEK[TEXT] > A0048 STORE 1 +FLAGS (\Seen \Deleted) > A0049 EXPUNGE > A0050 FETCH 1 RFC822.HEADER > A0051 LOGOUT > > After this, mbox folder inbox-b is corrupted, as the line saying > > From: abcdefghijklmnopqrstuvwxyzabcdefghijklmnopqrstuvwxyz at example.com > > becomes > > rstuvwxyzabcdefghijklmnopqrstuvwxyz at example.com > > > So: Could we please stop blaming fetchmail for this? > It's just the messenger.Could you also sprovide your "dovecot -n" output and any warnings and errors in dovecot logs. br, Teemu Huovila
Teemu Huovila
2015-May-06 06:59 UTC
Bug#776094: dovecot-imapd: corrupts mailbox after trying to retrieve it (fwd)
On 05/06/2015 09:57 AM, Teemu Huovila wrote:> On 05/05/2015 05:26 PM, Santiago Vila wrote: >> I have just verified with IMAP commands. This is the procedure: >> >> telnet localhost 143 >> >> and then type this: >> >> A0001 CAPABILITY >> A0002 LOGIN "bluser" "bluser" >> A0003 SELECT "inbox-b" >> A0004 EXPUNGE >> A0005 FETCH 1:12 RFC822.SIZE >> A0006 FETCH 1 RFC822.HEADER >> A0007 FETCH 1 BODY.PEEK[TEXT] >> A0008 STORE 1 +FLAGS (\Seen \Deleted) >> A0009 EXPUNGE >> A0010 FETCH 1 RFC822.HEADER >> A0011 FETCH 1 BODY.PEEK[TEXT] >> A0012 STORE 1 +FLAGS (\Seen \Deleted) >> A0013 EXPUNGE >> A0014 FETCH 1 RFC822.HEADER >> A0015 FETCH 1 BODY.PEEK[TEXT] >> A0016 STORE 1 +FLAGS (\Seen \Deleted) >> A0017 EXPUNGE >> A0018 FETCH 1 RFC822.HEADER >> A0019 FETCH 1 BODY.PEEK[TEXT] >> A0020 STORE 1 +FLAGS (\Seen \Deleted) >> A0021 EXPUNGE >> A0022 FETCH 1 RFC822.HEADER >> A0023 FETCH 1 BODY.PEEK[TEXT] >> A0024 STORE 1 +FLAGS (\Seen \Deleted) >> A0025 EXPUNGE >> A0026 FETCH 1 RFC822.HEADER >> A0027 FETCH 1 BODY.PEEK[TEXT] >> A0028 STORE 1 +FLAGS (\Seen \Deleted) >> A0029 EXPUNGE >> A0030 FETCH 1 RFC822.HEADER >> A0031 FETCH 1 BODY.PEEK[TEXT] >> A0032 STORE 1 +FLAGS (\Seen \Deleted) >> A0033 EXPUNGE >> A0034 FETCH 1 RFC822.HEADER >> A0035 FETCH 1 BODY.PEEK[TEXT] >> A0036 STORE 1 +FLAGS (\Seen \Deleted) >> A0037 EXPUNGE >> A0038 FETCH 1 RFC822.HEADER >> A0039 FETCH 1 BODY.PEEK[TEXT] >> A0040 STORE 1 +FLAGS (\Seen \Deleted) >> A0041 EXPUNGE >> A0042 FETCH 1 RFC822.HEADER >> A0043 FETCH 1 BODY.PEEK[TEXT] >> A0044 STORE 1 +FLAGS (\Seen \Deleted) >> A0045 EXPUNGE >> A0046 FETCH 1 RFC822.HEADER >> A0047 FETCH 1 BODY.PEEK[TEXT] >> A0048 STORE 1 +FLAGS (\Seen \Deleted) >> A0049 EXPUNGE >> A0050 FETCH 1 RFC822.HEADER >> A0051 LOGOUT >> >> After this, mbox folder inbox-b is corrupted, as the line saying >> >> From: abcdefghijklmnopqrstuvwxyzabcdefghijklmnopqrstuvwxyz at example.com >> >> becomes >> >> rstuvwxyzabcdefghijklmnopqrstuvwxyz at example.com >> >> >> So: Could we please stop blaming fetchmail for this? >> It's just the messenger. > Could you also sprovide your "dovecot -n" output and any warnings and errors in dovecot logs.Ah, found the dovecot -n earlier in the thread, but the logs would still be relevant. Teemu
Santiago Vila
2015-May-06 09:29 UTC
Bug#776094: dovecot-imapd: corrupts mailbox after trying to retrieve it (fwd)
Debug output: May 6 11:23:42 qemu-sid dovecot: master: Dovecot v2.2.16 starting up for imap (core dumps disabled) May 6 11:24:06 qemu-sid dovecot: imap-login: Login: user=<bluser>, method=PLAIN, rip=192.168.122.8, lip=192.168.122.8, mpid=441, secured, session=<HtpSW2YVaQDAqHoI> May 6 11:24:06 qemu-sid dovecot: imap(bluser): Debug: Loading modules from directory: /usr/lib/dovecot/modules May 6 11:24:06 qemu-sid dovecot: imap(bluser): Debug: Module loaded: /usr/lib/dovecot/modules/lib15_notify_plugin.so May 6 11:24:06 qemu-sid dovecot: imap(bluser): Debug: Module loaded: /usr/lib/dovecot/modules/lib20_mail_log_plugin.so May 6 11:24:06 qemu-sid dovecot: imap(bluser): Debug: Effective uid=1000, gid=1000, home=/home/bluser May 6 11:24:06 qemu-sid dovecot: imap(bluser): Debug: Namespace inbox: type=private, prefix=, sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=mbox:~/mail:INBOX=/var/mail/bluser May 6 11:24:06 qemu-sid dovecot: imap(bluser): Debug: fs: root=/home/bluser/mail, index=, indexpvt=, control=, inbox=/var/mail/bluser, altMay 6 11:24:06 qemu-sid dovecot: imap(bluser): delete: box=inbox-b, uid=15988, msgid=<20150113080229.634B31FE0F at example.com>, size=166 May 6 11:24:06 qemu-sid dovecot: imap(bluser): expunge: box=inbox-b, uid=15988, msgid=<20150113080229.634B31FE0F at example.com>, size=166 May 6 11:24:06 qemu-sid dovecot: imap(bluser): Error: Sync failed for mbox file /home/bluser/mail/inbox-b: seq=13 uid=15999 uid_broken=0 originally needed 0 bytes, now needs 23 bytes May 6 11:24:06 qemu-sid dovecot: imap(bluser): delete: box=inbox-b, uid=15989, msgid=<20150113080252.84E723FB47 at example.com>, size=238 May 6 11:24:06 qemu-sid dovecot: imap(bluser): expunge: box=inbox-b, uid=15989, msgid=<20150113080252.84E723FB47 at example.com>, size=238 May 6 11:24:06 qemu-sid dovecot: imap(bluser): delete: box=inbox-b, uid=15990, msgid=<20150113080253.3E89D5F8B1 at example.com>, size=238 May 6 11:24:06 qemu-sid dovecot: imap(bluser): expunge: box=inbox-b, uid=15990, msgid=<20150113080253.3E89D5F8B1 at example.com>, size=238 May 6 11:24:06 qemu-sid dovecot: imap(bluser): delete: box=inbox-b, uid=15991, msgid=<20150113080256.702551FD75 at example.com>, size=238 May 6 11:24:06 qemu-sid dovecot: imap(bluser): expunge: box=inbox-b, uid=15991, msgid=<20150113080256.702551FD75 at example.com>, size=238 May 6 11:24:06 qemu-sid dovecot: imap(bluser): delete: box=inbox-b, uid=15992, msgid=<20150113081736.928595F8B1 at example.com>, size=238 May 6 11:24:06 qemu-sid dovecot: imap(bluser): expunge: box=inbox-b, uid=15992, msgid=<20150113081736.928595F8B1 at example.com>, size=238 May 6 11:24:06 qemu-sid dovecot: imap(bluser): delete: box=inbox-b, uid=15993, msgid=<20150113090223.890703FBC5 at example.com>, size=166 May 6 11:24:06 qemu-sid dovecot: imap(bluser): expunge: box=inbox-b, uid=15993, msgid=<20150113090223.890703FBC5 at example.com>, size=166 May 6 11:24:06 qemu-sid dovecot: imap(bluser): delete: box=inbox-b, uid=15994, msgid=<20150113090225.EA3BF1FDD0 at example.com>, size=166 May 6 11:24:06 qemu-sid dovecot: imap(bluser): expunge: box=inbox-b, uid=15994, msgid=<20150113090225.EA3BF1FDD0 at example.com>, size=166 May 6 11:24:06 qemu-sid dovecot: imap(bluser): delete: box=inbox-b, uid=15995, msgid=<20150113090235.947643FB11 at example.com>, size=238 May 6 11:24:06 qemu-sid dovecot: imap(bluser): expunge: box=inbox-b, uid=15995, msgid=<20150113090235.947643FB11 at example.com>, size=238 May 6 11:24:06 qemu-sid dovecot: imap(bluser): delete: box=inbox-b, uid=15996, msgid=<20150113090252.ACE3D5F8B1 at example.com>, size=238 May 6 11:24:06 qemu-sid dovecot: imap(bluser): expunge: box=inbox-b, uid=15996, msgid=<20150113090252.ACE3D5F8B1 at example.com>, size=238 May 6 11:24:06 qemu-sid dovecot: imap(bluser): delete: box=inbox-b, uid=15997, msgid=<20150113090252.F11061FDAE at example.com>, size=238 May 6 11:24:06 qemu-sid dovecot: imap(bluser): expunge: box=inbox-b, uid=15997, msgid=<20150113090252.F11061FDAE at example.com>, size=238 May 6 11:24:06 qemu-sid dovecot: imap(bluser): delete: box=inbox-b, uid=15998, msgid=<20150113091737.92CD33FC52 at example.com>, size=238 May 6 11:24:06 qemu-sid dovecot: imap(bluser): expunge: box=inbox-b, uid=15998, msgid=<20150113091737.92CD33FC52 at example.com>, size=238 May 6 11:24:06 qemu-sid dovecot: imap(bluser): Disconnected: Logged out in=1364 out=6726
Possibly Parallel Threads
- Bug#776094: dovecot-imapd: corrupts mailbox after trying to retrieve it (fwd)
- Bug#776094: dovecot-imapd: corrupts mailbox after trying to retrieve it (fwd)
- Bug#776094: dovecot-imapd: corrupts mailbox after trying to retrieve it (fwd)
- Bug#776094: dovecot-imapd: corrupts mailbox after trying to retrieve it (fwd)
- Bug#776094: dovecot-imapd: corrupts mailbox after trying to retrieve it (fwd)