[Dovecot] Mbox corruption - Inbox beginning with 'FFrom'

[Uwe Klaus]

I have two accounts where the inboxes sometimes get corrupted. In that
case they begin with "FFrom".

I saw similar reports in July/August last year. So I applied the patch
http://dovecot.org/pipermail/dovecot/attachments/20100720/e87b32ae/attachment-0001.bin

Yesterday a mbox corruption happened again. Here are the log lines.

Jun 29 18:08:12 mail dovecot: pop3-login: Login: user=<xxxxxx>, 
method=PLAIN, rip=92.229.xxx.xxx, lip=194.94.211.50, TLS
Jun 29 18:08:12 mail dovecot: POP3(xxxxxx): Disconnected: Logged out 
top=0/0, retr=0/0, del=0/76, size=32800751
Jun 29 18:09:12 mail dovecot: pop3-login: Login: user=<xxxxxx>, 
method=PLAIN, rip=92.229.xxx.xxx, lip=194.94.211.50, TLS
Jun 29 18:09:12 mail dovecot: POP3(xxxxxx): Disconnected: Logged out 
top=0/0, retr=0/0, del=0/76, size=32800751
Jun 29 18:09:48 mail dovecot: deliver(xxxxxx): sieve: 
msgid=<D0FBDD35-A53D-4940-A770-DDA06AD0E70B at hgb-leipzig.de>: stored mail 
into mailbox 'INBOX'
Jun 29 18:09:48 mail sm-mta[27493]: p5TFl24L014636: to=xxxxxx, 
delay=00:22:46, xdelay=00:00:00, mailer=local, pri=17899944, dsn=2.0.0, 
stat=Sent
Jun 29 18:10:12 mail dovecot: pop3-login: Login: user=<xxxxxx>, 
method=PLAIN, rip=92.229.xxx.xxx, lip=194.94.211.50, TLS
Jun 29 18:10:13 mail dovecot: POP3(xxxxxx): mbox got corrupted, state=1
Jun 29 18:10:13 mail dovecot: POP3(xxxxxx): mbox got corrupted, state=2
Jun 29 18:10:13 mail dovecot: POP3(xxxxxx): mbox got corrupted, state=4
Jun 29 18:10:13 mail dovecot: POP3(xxxxxx): Disconnected: Logged out 
top=0/0, retr=1/2066, del=6/77, size=32802800
Jun 29 18:11:12 mail dovecot: pop3-login: Login: user=<xxxxxx>, 
method=PLAIN, rip=92.229.xxx.xxx, lip=194.94.211.50, TLS
Jun 29 18:11:12 mail dovecot: POP3(xxxxxx): Disconnected: Logged out 
top=0/0, retr=0/0, del=0/71, size=32777356
Jun 29 18:12:12 mail dovecot: pop3-login: Login: user=<xxxxxx>, 
method=PLAIN, rip=92.229.xxx.xxx, lip=194.94.211.50, TLS
Jun 29 18:12:12 mail dovecot: POP3(xxxxxx): Disconnected: Logged out 
top=0/0, retr=0/0, del=0/71, size=32777356

Jun 29 18:19:12 mail dovecot: pop3-login: Login: user=<xxxxxx>, 
method=PLAIN, rip=92.229.xxx.xxx, lip=194.94.211.50, TLS
Jun 29 18:19:12 mail dovecot: POP3(xxxxxx): Disconnected: Logged out 
top=0/0, retr=0/0, del=0/71, size=32777356
Jun 29 18:19:56 mail dovecot: deliver(xxxxxx): mbox got corrupted, state=0
Jun 29 18:19:56 mail dovecot: deliver(xxxxxx): sieve: 
msgid=<20110629175547.33981yt5jzia7f9f at mail.hgb-leipzig.de>: failed to 
store into mailbox 'INBOX': Mailbox isn't a valid mbox file
Jun 29 18:19:56 mail dovecot: deliver(xxxxxx): sieve: script 
/home/xxxxxx/.dovecot.sieve failed with unsuccessful implicit keep
Jun 29 18:19:57 mail sm-mta[12213]: p5TFvI0W029582: to=xxxxxx, 
delay=00:22:39, xdelay=00:00:01, mailer=local, pri=17900128, dsn=2.0.0, 
stat=Sent
Jun 29 18:20:12 mail dovecot: pop3-login: Login: user=<xxxxxx>, 
method=PLAIN, rip=92.229.xxx.xxx, lip=194.94.211.50, TLS
Jun 29 18:20:12 mail dovecot: POP3(xxxxxx): mbox got corrupted, state=0
Jun 29 18:20:12 mail dovecot: POP3(xxxxxx): Couldn't init INBOX: Mailbox 
isn't a valid mbox file
Jun 29 18:20:12 mail dovecot: POP3(xxxxxx): Mailbox init failed top=0/0, 
retr=0/0, del=0/0, size=0

After removing the first "F":
Jun 29 19:06:11 mail dovecot: pop3-login: Login: user=<xxxxxx>, 
method=PLAIN, rip=77.5.xxx.xxx, lip=194.94.211.50, TLS Jun 29 19:06:13 
mail dovecot: POP3(xxxxxx): Disconnected: Logged out top=0/0, retr=2/4502, 
del=0/73, size=32781824


# dovecot --version
1.2.17

# dovecot -n
# 1.2.17: /usr/pkg/etc/dovecot.conf
Warning: fd limit 128 is lower than what Dovecot can use under full load 
(more than 768). Either grow the limit or change login_max_processes_count 
and max_mail_processes settings
# OS: NetBSD 5.1_STABLE amd64
protocols: imap imaps pop3 pop3s
ssl_key_file: /etc/openssl/certs/dovecot.pem
disable_plaintext_auth: no
login_dir: /var/run/dovecot/login
login_executable(default): /usr/pkg/libexec/dovecot/imap-login
login_executable(imap): /usr/pkg/libexec/dovecot/imap-login
login_executable(pop3): /usr/pkg/libexec/dovecot/pop3-login
first_valid_uid: 400
last_valid_uid: 10000
mail_location: mbox:~/mail:INBOX=/var/mail/%u:INDEX=/var/indexes/%u
mail_executable(default): /usr/pkg/libexec/dovecot/imap
mail_executable(imap): /usr/pkg/libexec/dovecot/imap
mail_executable(pop3): /usr/pkg/libexec/dovecot/pop3
mail_plugin_dir(default): /usr/pkg/lib/dovecot/imap
mail_plugin_dir(imap): /usr/pkg/lib/dovecot/imap
mail_plugin_dir(pop3): /usr/pkg/lib/dovecot/pop3
pop3_uidl_format(default): %08Xu%08Xv
pop3_uidl_format(imap): %08Xu%08Xv
pop3_uidl_format(pop3): %08Xv%08Xu
lda:
   postmaster_address: postmaster at hgb-leipzig.de
   mail_plugins: sieve
auth default:
   username_format: %Lu
   verbose: yes
   passdb:
     driver: passwd
   userdb:
     driver: passwd
plugin:
   sieve: ~/.dovecot.sieve
   sieve_global_path: /var/lib/dovecot/sieve/default.sieve
   sieve_dir: ~/sieve
   sieve_global_dir: /var/lib/dovecot/sieve/global/


Regards,
Uwe