Maria Arrea
2011-Jun-19 10:59 UTC
[Dovecot] Question about "Storage keeps breaking" in logs
Hello I have found this entries in my log /var/log/maillog.13.xz:Jun 6 17:03:26 buzon dovecot: imap(juanan): Error: mdbox /buzones//18/66/juanan/mailboxes/P7.Bielorrusia/cd-eslpod-enviado/dbox-Mails: Storage keeps breaking /var/log/maillog.108.xz:Mar 4 01:42:09 buzon dovecot: imap(lbandera): Error: mdbox /buzones/0b/11/lbandera/mailboxes/Sent/dbox-Mails: Storage keeps breaking /var/log/maillog.3.xz:Jun 16 11:48:29 buzon dovecot: imap(fjvalderrama): Error: mdbox /buzones/0f/99/fjvalderrama/mailboxes/Asignaturas 08-09/Calculo/dbox-Mails: Storage keeps breaking I have "rebuilt" those mdboxes with doveadm force-resync && doveadm quota recalc. Should I be worried? Why is this happening? Regards Maria Arrea
Timo Sirainen
2011-Jun-22 17:43 UTC
[Dovecot] Question about "Storage keeps breaking" in logs
On Sun, 2011-06-19 at 10:59 +0000, Maria Arrea wrote:> /var/log/maillog.13.xz:Jun 6 17:03:26 buzon dovecot: imap(juanan): Error: mdbox /buzones//18/66/juanan/mailboxes/P7.Bielorrusia/cd-eslpod-enviado/dbox-Mails: Storage keeps breaking > /var/log/maillog.108.xz:Mar 4 01:42:09 buzon dovecot: imap(lbandera): Error: mdbox /buzones/0b/11/lbandera/mailboxes/Sent/dbox-Mails: Storage keeps breaking > /var/log/maillog.3.xz:Jun 16 11:48:29 buzon dovecot: imap(fjvalderrama): Error: mdbox /buzones/0f/99/fjvalderrama/mailboxes/Asignaturas 08-09/Calculo/dbox-Mails: Storage keeps breaking > > > I have "rebuilt" those mdboxes with doveadm force-resync && doveadm quota recalc. Should I be worried? Why is this happening?Aren't there any other error/warning messages around them?
Maria Arrea
2011-Jun-22 19:32 UTC
[Dovecot] Question about "Storage keeps breaking" in logs
Here you have more detailed logs: First case: Jun 6 17:03:23 buzon dovecot: imap(juanan): Warning: mdbox /buzones/18/66/juanan/storage: rebuilding indexes Jun 6 17:03:26 buzon dovecot: imap(juanan): Error: mdbox /buzones/18/66/juanan/mailboxes/P7.Bielorrusia/cd-eslpod-enviado/dbox-Mails: Storage keeps breaking Jun 6 17:03:27 buzon dovecot: imap(juanan): Error: /indices_dovecot/indices/18/66/juanan/mailboxes/INBOX/dovecot.index reset, view is now inconsistent Jun 6 17:03:27 buzon dovecot: imap(juanan): Disconnected: IMAP session state is inconsistent, please relogin. bytes=5960/766852 Jun 6 17:03:27 buzon dovecot: imap(juanan): Error: /indices_dovecot/indices/18/66/juanan/mailboxes/P4. WebMed/dovecot.index reset, view is now inconsistent Jun 6 17:03:27 buzon dovecot: imap(juanan): Disconnected: IMAP session state is inconsistent, please relogin. bytes=1124/7377 Jun 6 17:03:27 buzon dovecot: imap(juanan): Error: Recent flags state corrupted for mailbox Deleted Messages Jun 6 17:03:27 buzon dovecot: imap(juanan): Error: /indices_dovecot/indices/18/66/juanan/mailboxes/Deleted Messages/dovecot.index reset, view is now inconsistent Jun 6 17:03:27 buzon dovecot: imap(juanan): Disconnected: IMAP session state is inconsistent, please relogin. bytes=5418/408785 Jun 6 17:09:23 buzon dovecot: imap(juanan): Warning: mdbox /buzones/us.es/18/66/juanan/storage: rebuilding indexes Jun 6 17:09:23 buzon dovecot: imap(juanan): Error: /indices_dovecot/indices/18/66/juanan/mailboxes/INBOX/dovecot.index reset, view is now inconsistent Jun 6 17:09:23 buzon dovecot: imap(juanan): Disconnected: IMAP session state is inconsistent, please relogin. bytes=2134/4879108 Jun 6 17:19:33 buzon dovecot: lda(juanan): sieve: msgid=<b146e978560145876c734b94c64e2a7c at wikijuanan.com>: stored mail into mailbox 'INBOX' Second case: Jun 16 11:48:24 buzon dovecot: imap-login: Login: user=<fjvalderrama at mydomain>, method=PLAIN, rip=192.168.1.150, lip=192.168.4.80, mpid=32282 Jun 16 11:48:24 buzon dovecot: imap(fjvalderrama at us.es): Warning: mdbox /buzones/0f/99/fjvalderrama/storage: rebuilding indexes Jun 16 11:48:29 buzon dovecot: imap-login: Login: user=<fjvalderrama at mydomain>, method=PLAIN, rip=192.168.1.150, lip=192.168.4.80, mpid=32510 Jun 16 11:48:29 buzon dovecot: imap(fjvalderrama at mydomain): Error: mdbox /buzones/mydomain/0f/99/fjvalderrama/mailboxes/Asignaturas 08-09/Calculo/dbox-Mails: Storage keeps breaking Jun 16 11:48:29 buzon dovecot: imap(fjvalderrama at mydomain): Error: mdbox /buzones/mydomain/0f/99/fjvalderrama/mailboxes/Asignaturas 08-09/Calculo/dbox-Mails: Storage keeps breaking Jun 16 11:48:29 buzon dovecot: imap(fjvalderrama at mydomain): Connection closed bytes=132/574 Jun 16 11:48:29 buzon dovecot: imap(fjvalderrama at mydomain): Connection closed bytes=60/406 Jun 16 11:48:29 buzon dovecot: imap(fjvalderrama at mydomain): Connection closed bytes=98/423 Jun 16 11:48:30 buzon dovecot: imap(fjvalderrama): Error: Recent flags state corrupted for mailbox Trash Jun 16 11:48:30 buzon dovecot: imap(fjvalderrama): Error: /indices_dovecot/indices/0f/99/fjvalderrama/mailboxes/Trash/dovecot.index reset, view is now inconsistent Jun 16 11:48:30 buzon dovecot: imap(fjvalderrama): Disconnected: IMAP session state is inconsistent, please relogin. bytes=20224/556432 Jun 16 11:48:30 buzon dovecot: imap(fjvalderrama): Error: Recent flags state corrupted for mailbox SEV/AsigVirtual Jun 16 11:48:30 buzon dovecot: imap(fjvalderrama): Error: /indices_dovecot/indices/0f/99/fjvalderrama/mailboxes/SEV/AsigVirtual/dovecot.index reset, view is now inconsistent Jun 16 11:48:30 buzon dovecot: imap(fjvalderrama): Disconnected: IMAP session state is inconsistent, please relogin. bytes=940/62147 Jun 16 11:48:30 buzon dovecot: imap(fjvalderrama): Error: /indices_dovecot/indices/0f/99/fjvalderrama/mailboxes/SEV/Miembros US/dovecot.index reset, view is now inconsistent Jun 16 11:48:30 buzon dovecot: imap(fjvalderrama): Disconnected: IMAP session state is inconsistent, please relogin. bytes=13061/37386 Jun 16 11:48:30 buzon dovecot: imap(fjvalderrama): Error: Recent flags state corrupted for mailbox INBOX Jun 16 11:48:30 buzon dovecot: imap(fjvalderrama at us.es): Error: /indices_dovecot/indices/0f/99/fjvalderrama/mailboxes/Drafts/dovecot.index reset, view is now inconsistent Jun 16 11:48:30 buzon dovecot: imap(fjvalderrama at us.es): Disconnected: IMAP session state is inconsistent, please relogin. bytes=3238/7247 Jun 16 11:48:30 buzon dovecot: imap(fjvalderrama): Error: /indices_dovecot/indices/0f/99/fjvalderrama/mailboxes/INBOX/dovecot.index reset, view is now inconsistent Jun 16 11:48:30 buzon dovecot: imap(fjvalderrama): Disconnected: IMAP session state is inconsistent, please relogin. bytes=1451/22771 Jun 16 11:48:34 buzon dovecot: imap-login: Login: user=<fjvalderrama at mydomain>, method=PLAIN, rip=192.168.1.150, lip=192.168.4.80, mpid=32745 Do you need dovecot config to debug this? I can give it to you if needed. Regards Maria ----- Original Message ----- From: Timo Sirainen Sent: 06/22/11 07:43 PM To: Maria Arrea Subject: Re: [Dovecot] Question about "Storage keeps breaking" in logs On Sun, 2011-06-19 at 10:59 +0000, Maria Arrea wrote: > /var/log/maillog.13.xz:Jun 6 17:03:26 buzon dovecot: imap(juanan): Error: mdbox /buzones//18/66/juanan/mailboxes/P7.Bielorrusia/cd-eslpod-enviado/dbox-Mails: Storage keeps breaking > /var/log/maillog.3.xz:Jun 16 11:48:29 buzon dovecot: imap(fjvalderrama): Error: mdbox /buzones/0f/99/fjvalderrama/mailboxes/Asignaturas 08-09/Calculo/dbox-Mails: Storage keeps breaking > > > I have "rebuilt" those mdboxes with doveadm force-resync && doveadm quota recalc. Should I be worried? Why is this happening? Aren't there any other error/warning messages around them?
Maria Arrea
2011-Jun-27 14:55 UTC
[Dovecot] Question about "Storage keeps breaking" in logs
Any hint about this problem? I have been google for a while without luck :( Regards Maria ----- Original Message ----- From: Maria Arrea Sent: 06/22/11 09:32 PM To: Timo Sirainen Subject: Re: [Dovecot] Question about "Storage keeps breaking" in logs Here you have more detailed logs: First case: Jun 6 17:03:23 buzon dovecot: imap(juanan): Warning: mdbox /buzones/18/66/juanan/storage: rebuilding indexes Jun 6 17:03:26 buzon dovecot: imap(juanan): Error: mdbox /buzones/18/66/juanan/mailboxes/P7.Bielorrusia/cd-eslpod-enviado/dbox-Mails: Storage keeps breaking Jun 6 17:03:27 buzon dovecot: imap(juanan): Error: /indices_dovecot/indices/18/66/juanan/mailboxes/INBOX/dovecot.index reset, view is now inconsistent Jun 6 17:03:27 buzon dovecot: imap(juanan): Disconnected: IMAP session state is inconsistent, please relogin. bytes=5960/766852 Jun 6 17:03:27 buzon dovecot: imap(juanan): Error: /indices_dovecot/indices/18/66/juanan/mailboxes/P4. WebMed/dovecot.index reset, view is now inconsistent Jun 6 17:03:27 buzon dovecot: imap(juanan): Disconnected: IMAP session state is inconsistent, please relogin. bytes=1124/7377 Jun 6 17:03:27 buzon dovecot: imap(juanan): Error: Recent flags state corrupted for mailbox Deleted Messages Jun 6 17:03:27 buzon dovecot: imap(juanan): Error: /indices_dovecot/indices/18/66/juanan/mailboxes/Deleted Messages/dovecot.index reset, view is now inconsistent Jun 6 17:03:27 buzon dovecot: imap(juanan): Disconnected: IMAP session state is inconsistent, please relogin. bytes=5418/408785 Jun 6 17:09:23 buzon dovecot: imap(juanan): Warning: mdbox /buzones/us.es/18/66/juanan/storage: rebuilding indexes Jun 6 17:09:23 buzon dovecot: imap(juanan): Error: /indices_dovecot/indices/18/66/juanan/mailboxes/INBOX/dovecot.index reset, view is now inconsistent Jun 6 17:09:23 buzon dovecot: imap(juanan): Disconnected: IMAP session state is inconsistent, please relogin. bytes=2134/4879108 Jun 6 17:19:33 buzon dovecot: lda(juanan): sieve: msgid=<b146e978560145876c734b94c64e2a7c at wikijuanan.com>: stored mail into mailbox 'INBOX' Second case: Jun 16 11:48:24 buzon dovecot: imap-login: Login: user=<fjvalderrama at mydomain>, method=PLAIN, rip=192.168.1.150, lip=192.168.4.80, mpid=32282 Jun 16 11:48:24 buzon dovecot: imap(fjvalderrama at us.es): Warning: mdbox /buzones/0f/99/fjvalderrama/storage: rebuilding indexes Jun 16 11:48:29 buzon dovecot: imap-login: Login: user=<fjvalderrama at mydomain>, method=PLAIN, rip=192.168.1.150, lip=192.168.4.80, mpid=32510 Jun 16 11:48:29 buzon dovecot: imap(fjvalderrama at mydomain): Error: mdbox /buzones/mydomain/0f/99/fjvalderrama/mailboxes/Asignaturas 08-09/Calculo/dbox-Mails: Storage keeps breaking Jun 16 11:48:29 buzon dovecot: imap(fjvalderrama at mydomain): Error: mdbox /buzones/mydomain/0f/99/fjvalderrama/mailboxes/Asignaturas 08-09/Calculo/dbox-Mails: Storage keeps breaking Jun 16 11:48:29 buzon dovecot: imap(fjvalderrama at mydomain): Connection closed bytes=132/574 Jun 16 11:48:29 buzon dovecot: imap(fjvalderrama at mydomain): Connection closed bytes=60/406 Jun 16 11:48:29 buzon dovecot: imap(fjvalderrama at mydomain): Connection closed bytes=98/423 Jun 16 11:48:30 buzon dovecot: imap(fjvalderrama): Error: Recent flags state corrupted for mailbox Trash Jun 16 11:48:30 buzon dovecot: imap(fjvalderrama): Error: /indices_dovecot/indices/0f/99/fjvalderrama/mailboxes/Trash/dovecot.index reset, view is now inconsistent Jun 16 11:48:30 buzon dovecot: imap(fjvalderrama): Disconnected: IMAP session state is inconsistent, please relogin. bytes=20224/556432 Jun 16 11:48:30 buzon dovecot: imap(fjvalderrama): Error: Recent flags state corrupted for mailbox SEV/AsigVirtual Jun 16 11:48:30 buzon dovecot: imap(fjvalderrama): Error: /indices_dovecot/indices/0f/99/fjvalderrama/mailboxes/SEV/AsigVirtual/dovecot.index reset, view is now inconsistent Jun 16 11:48:30 buzon dovecot: imap(fjvalderrama): Disconnected: IMAP session state is inconsistent, please relogin. bytes=940/62147 Jun 16 11:48:30 buzon dovecot: imap(fjvalderrama): Error: /indices_dovecot/indices/0f/99/fjvalderrama/mailboxes/SEV/Miembros US/dovecot.index reset, view is now inconsistent Jun 16 11:48:30 buzon dovecot: imap(fjvalderrama): Disconnected: IMAP session state is inconsistent, please relogin. bytes=13061/37386 Jun 16 11:48:30 buzon dovecot: imap(fjvalderrama): Error: Recent flags state corrupted for mailbox INBOX Jun 16 11:48:30 buzon dovecot: imap(fjvalderrama at us.es): Error: /indices_dovecot/indices/0f/99/fjvalderrama/mailboxes/Drafts/dovecot.index reset, view is now inconsistent Jun 16 11:48:30 buzon dovecot: imap(fjvalderrama at us.es): Disconnected: IMAP session state is inconsistent, please relogin. bytes=3238/7247 Jun 16 11:48:30 buzon dovecot: imap(fjvalderrama): Error: /indices_dovecot/indices/0f/99/fjvalderrama/mailboxes/INBOX/dovecot.index reset, view is now inconsistent Jun 16 11:48:30 buzon dovecot: imap(fjvalderrama): Disconnected: IMAP session state is inconsistent, please relogin. bytes=1451/22771 Jun 16 11:48:34 buzon dovecot: imap-login: Login: user=<fjvalderrama at mydomain>, method=PLAIN, rip=192.168.1.150, lip=192.168.4.80, mpid=32745 Do you need dovecot config to debug this? I can give it to you if needed. Regards Maria ----- Original Message ----- From: Timo Sirainen Sent: 06/22/11 07:43 PM To: Maria Arrea Subject: Re: [Dovecot] Question about "Storage keeps breaking" in logs On Sun, 2011-06-19 at 10:59 +0000, Maria Arrea wrote: > /var/log/maillog.13.xz:Jun 6 17:03:26 buzon dovecot: imap(juanan): Error: mdbox /buzones//18/66/juanan/mailboxes/P7.Bielorrusia/cd-eslpod-enviado/dbox-Mails: Storage keeps breaking > /var/log/maillog.3.xz:Jun 16 11:48:29 buzon dovecot: imap(fjvalderrama): Error: mdbox /buzones/0f/99/fjvalderrama/mailboxes/Asignaturas 08-09/Calculo/dbox-Mails: Storage keeps breaking > > > I have "rebuilt" those mdboxes with doveadm force-resync && doveadm quota recalc. Should I be worried? Why is this happening? Aren't there any other error/warning messages around them?
Maria Arrea
2011-Jun-28 11:01 UTC
[Dovecot] Question about "Storage keeps breaking" in logs
This dovecot box (RHEL 5.6 x64, dovecot 2.0.13, ext4) is a VM over vmware vsphere. The remote SAN disks are presented via iSCSI to the ESX hosts, and the ESX host show them as "local storage" /dev/sdX We have several VM sharing that datastore, no I/O contention detected. I will try with imaptest but this a production enviroment, I will take a couple of weeks to get a "mainteneance window" for this. Any other idea about this? Maybe this problem is load-related? Regards Maria ----- Original Message ----- From: Timo Sirainen Sent: 06/28/11 01:33 AM To: Maria Arrea Subject: Re: [Dovecot] Question about "Storage keeps breaking" in logs On Wed, 2011-06-22 at 19:32 +0000, Maria Arrea wrote: > Jun 6 17:03:23 buzon dovecot: imap(juanan): Warning: mdbox /buzones/18/66/juanan/storage: rebuilding indexes Problem 1: It doesn't log the reason for this rebuild. I can find only one place in code why that could happen, fixed: http://hg.dovecot.org/dovecot-2.0/rev/028b953e7040 > Jun 6 17:03:26 buzon dovecot: imap(juanan): Error: mdbox /buzones/18/66/juanan/mailboxes/P7.Bielorrusia/cd-eslpod-enviado/dbox-Mails: Storage keeps breaking Problem 2: It apparently can't fix that error. But in my tests it gets fixed fine.. > Jun 6 17:03:27 buzon dovecot: imap(juanan): Error: Recent flags state corrupted for mailbox Deleted Messages Probably related to the index rebuild. So this is a local disk with ext4 filesystem, nothing special? It shouldn't be breaking.. How often do these happen? Can you reproduce these by running imaptest against a test account? http://imapwiki.org/ImapTest