[Dovecot] file mail-transaction-log-view.c: assertion failed (v1.0.12)
Hi all.
Looking at previous weeks logs I discovered strange errors: "file mail-transaction-log-view.c: assertion failed"
dovecot v 1.0.12
Server is hosting more than 500 virtual users. It is compleate dovecot error log; only my own mailbox is affected ...and imap folder affected is "dovecots mailing list" :) While "Corrupted transaction log file" as far as I know is not error to care about much, "assertion failed" is something new.
maibox are accessed only by dovecet and dovecot's LDA.
-------sart of log --- dovecot: Jul 02 11:27:21 Error: IMAP(neko@it4u.lv): Corrupted transaction log file /srv/vmail/.cache/it4u.lv/neko/.Listservers.Tech.Dovecot/dovecot.index.log: Append with UID 19202, but next_uid = 19203 dovecot: Jul 02 11:27:21 Error: IMAP(neko@it4u.lv): Corrupted transaction log file /srv/vmail/.cache/it4u.lv/neko/.Listservers.Tech.Dovecot/dovecot.index.log: Append with UID 19202, but next_uid = 19203 dovecot: Jul 02 11:27:21 Error: IMAP(neko@it4u.lv): Transaction log file /srv/vmail/.cache/it4u.lv/neko/.Listservers.Tech.Dovecot/dovecot.index.log: marked corrupted dovecot: Jul 02 11:27:22 Error: IMAP(neko@it4u.lv): Maildir /srv/vmail/it4u.lv/neko/.Listservers.Tech.Dovecot sync: UID inserted in the middle of mailbox (19203 > 19202, file = 1214987241.P26793Q0M308123.serv09,S=3912:2,) dovecot: Jul 02 11:39:34 Error: IMAP(neko@it4u.lv): Corrupted transaction log file /srv/vmail/.cache/it4u.lv/neko/.Listservers.Tech.Dovecot/dovecot.index.log: invalid indexid (1214987242 != 1213863362) dovecot: Jul 02 11:39:34 Warning: IMAP(neko@it4u.lv): fscking index file /srv/vmail/.cache/it4u.lv/neko/.Listservers.Tech.Dovecot/dovecot.index dovecot: Jul 02 11:39:34 Error: IMAP(neko@it4u.lv): Transaction log file /srv/vmail/.cache/it4u.lv/neko/.Listservers.Tech.Dovecot/dovecot.index.log: marked corrupted dovecot: Jul 02 11:39:34 Error: IMAP(neko@it4u.lv): Corrupted transaction log file /srv/vmail/.cache/it4u.lv/neko/.Listservers.Tech.Dovecot/dovecot.index.log: invalid new transaction log sequence (4 >= 4) dovecot: Jul 02 11:39:34 Warning: IMAP(neko@it4u.lv): fscking index file /srv/vmail/.cache/it4u.lv/neko/.Listservers.Tech.Dovecot/dovecot.index dovecot: Jul 02 11:39:34 Error: IMAP(neko@it4u.lv): file mail-transaction-log-view.c: line 107 (mail_transaction_log_view_set): assertion failed: (min_file_seq <= max_file_seq) dovecot: Jul 02 11:39:34 Error: IMAP(neko@it4u.lv): Raw backtrace: imap [0x5555555bd66e] -> imap [0x5555555bd3ae] -> imap(mail_transaction_log_view_set+0x3b3) [0x5555555aba23] -> imap [0x5555555a6d1f] -> imap(mail_index_view_sync_begin+0x3f3) [0x5555555a73d3] -> imap(index_mailbox_sync_init+0xa1) [0x555555595fc1] -> imap(imap_sync_init+0x56) [0x555555575756] -> imap(cmd_sync+0x7b) [0x55555557588b] -> imap [0x55555556bb12] -> imap(cmd_fetch+0x28d) [0x55555556be1d] -> imap(cmd_uid+0x4a) [0x55555556e9ba] -> imap [0x55555556eec7] -> imap [0x55555556ef59] -> imap(_client_input+0x6f) [0x55555556f5ff] -> imap(io_loop_handler_run+0x108) [0x5555555c2ea8] -> imap(io_loop_run+0x18) [0x5555555c2068] -> imap(main+0x41b) [0x5555555770cb] -> /lib64/libc.so.6(__libc_start_main+0xf4) [0x2b75b43d9b54] -> imap [0x55555556a6e9] dovecot: Jul 02 11:39:34 Error: child 22307 (imap) killed with signal 6 dovecot: Jul 02 11:39:34 Error: IMAP(neko@it4u.lv): Transaction log file /srv/vmail/.cache/it4u.lv/neko/.Listservers.Tech.Dovecot/dovecot.index.log: marked corrupted dovecot: Jul 02 12:13:21 Warning: IMAP(neko@it4u.lv): Unlinked a duplicate: 1214989937.P31578Q0M123879.serv09,S=4899:2, dovecot: Jul 02 12:48:57 Error: IMAP(neko@it4u.lv): Corrupted transaction log file /srv/vmail/.cache/it4u.lv/neko/.Listservers.Tech.Dovecot/dovecot.index.log: Append with UID 19209, but next_uid = 19210 dovecot: Jul 02 12:48:57 Error: IMAP(neko@it4u.lv): Corrupted transaction log file /srv/vmail/.cache/it4u.lv/neko/.Listservers.Tech.Dovecot/dovecot.index.log: Append with UID 19209, but next_uid = 19210 dovecot: Jul 02 12:48:57 Error: IMAP(neko@it4u.lv): Unexpected transaction log desync with index /srv/vmail/.cache/it4u.lv/neko/.Listservers.Tech.Dovecot/dovecot.index dovecot: Jul 02 12:48:57 Error: IMAP(neko@it4u.lv): Corrupted transaction log file /srv/vmail/.cache/it4u.lv/neko/.Listservers.Tech.Dovecot/dovecot.index.log: Append with UID 19209, but next_uid = 19210 dovecot: Jul 02 12:48:57 Error: IMAP(neko@it4u.lv): Transaction log file /srv/vmail/.cache/it4u.lv/neko/.Listservers.Tech.Dovecot/dovecot.index.log: marked corrupted dovecot: Jul 02 12:48:59 Error: IMAP(neko@it4u.lv): Maildir /srv/vmail/it4u.lv/neko/.Listservers.Tech.Dovecot sync: UID inserted in the middle of mailbox (19210 > 19209, file = 1214992136.P29477Q0M844419.serv09,S=6950:2,) dovecot: Jul 02 12:51:00 Error: IMAP(neko@it4u.lv): Corrupted transaction log file /srv/vmail/.cache/it4u.lv/neko/.Listservers.Tech.Dovecot/dovecot.index.log: invalid indexid (1214992140 != 1214987242) dovecot: Jul 02 12:51:00 Warning: IMAP(neko@it4u.lv): fscking index file /srv/vmail/.cache/it4u.lv/neko/.Listservers.Tech.Dovecot/dovecot.index dovecot: Jul 02 12:51:00 Error: IMAP(neko@it4u.lv): Transaction log file /srv/vmail/.cache/it4u.lv/neko/.Listservers.Tech.Dovecot/dovecot.index.log: marked corrupted dovecot: Jul 02 12:51:00 Error: IMAP(neko@it4u.lv): Corrupted transaction log file /srv/vmail/.cache/it4u.lv/neko/.Listservers.Tech.Dovecot/dovecot.index.log: invalid new transaction log sequence (5 >= 5) dovecot: Jul 02 12:51:00 Warning: IMAP(neko@it4u.lv): fscking index file /srv/vmail/.cache/it4u.lv/neko/.Listservers.Tech.Dovecot/dovecot.index dovecot: Jul 02 12:51:00 Error: IMAP(neko@it4u.lv): file mail-transaction-log-view.c: line 107 (mail_transaction_log_view_set): assertion failed: (min_file_seq <= max_file_seq) dovecot: Jul 02 12:51:00 Error: IMAP(neko@it4u.lv): Raw backtrace: imap [0x5555555bd66e] -> imap [0x5555555bd3ae] -> imap(mail_transaction_log_view_set+0x3b3) [0x5555555aba23] -> imap [0x5555555a6d1f] -> imap(mail_index_view_sync_begin+0x3f3) [0x5555555a73d3] -> imap(index_mailbox_sync_init+0xa1) [0x555555595fc1] -> imap(imap_sync_init+0x56) [0x555555575756] -> imap(cmd_sync+0x7b) [0x55555557588b] -> imap [0x55555556bb12] -> imap(cmd_fetch+0x28d) [0x55555556be1d] -> imap(cmd_uid+0x4a) [0x55555556e9ba] -> imap [0x55555556eec7] -> imap [0x55555556ef59] -> imap(_client_input+0x6f) [0x55555556f5ff] -> imap(io_loop_handler_run+0x108) [0x5555555c2ea8] -> imap(io_loop_run+0x18) [0x5555555c2068] -> imap(main+0x41b) [0x5555555770cb] -> /lib64/libc.so.6(__libc_start_main+0xf4) [0x2b733287bb54] -> imap [0x55555556a6e9] dovecot: Jul 02 12:51:00 Error: child 4479 (imap) killed with signal 6 dovecot: Jul 02 12:51:00 Error: IMAP(neko@it4u.lv): Transaction log file /srv/vmail/.cache/it4u.lv/neko/.Listservers.Tech.Dovecot/dovecot.index.log: marked corrupted dovecot: Jul 02 14:11:40 Warning: IMAP(neko@it4u.lv): Unlinked a duplicate: 1214987033.P23952Q0M811113.serv09,S=4807:2,S dovecot: Jul 04 12:25:20 Error: IMAP(neko@it4u.lv): Corrupted transaction log file /srv/vmail/.cache/it4u.lv/neko/.Listservers.Tech.Dovecot/dovecot.index.log: Append with UID 19272, but next_uid = 19273 dovecot: Jul 04 12:25:20 Error: IMAP(neko@it4u.lv): Corrupted transaction log file /srv/vmail/.cache/it4u.lv/neko/.Listservers.Tech.Dovecot/dovecot.index.log: Append with UID 19272, but next_uid = 19273 dovecot: Jul 04 12:25:20 Error: IMAP(neko@it4u.lv): Corrupted transaction log file /srv/vmail/.cache/it4u.lv/neko/.Listservers.Tech.Dovecot/dovecot.index.log: Append with UID 19272, but next_uid = 19273 dovecot: Jul 04 12:25:21 Error: IMAP(neko@it4u.lv): Transaction log file /srv/vmail/.cache/it4u.lv/neko/.Listservers.Tech.Dovecot/dovecot.index.log: marked corrupted dovecot: Jul 04 12:25:21 Error: IMAP(neko@it4u.lv): Transaction log got desynced for index /srv/vmail/.cache/it4u.lv/neko/.Listservers.Tech.Dovecot/dovecot.index dovecot: Jul 04 12:25:23 Warning: IMAP(neko@it4u.lv): Unlinked a duplicate: 1215162799.P18322Q0M736352.serv09,S=13262:2, dovecot: Jul 04 12:25:23 Error: IMAP(neko@it4u.lv): Transaction log got desynced for index /srv/vmail/.cache/it4u.lv/neko/.Listservers.Tech.Dovecot/dovecot.index dovecot: Jul 04 12:25:28 Error: IMAP(neko@it4u.lv): Maildir /srv/vmail/it4u.lv/neko/.Listservers.Tech.Dovecot sync: UID inserted in the middle of mailbox (19273 > 19272, file = 1215163520.P28321Q0M199676.serv09,S=3477:2,)
-------end of log ---
On Thu, 2008-07-10 at 08:07 +0300, Uldis Pakuls wrote:
Hi all.
Looking at previous weeks logs I discovered strange errors: "file mail-transaction-log-view.c: assertion failed"
dovecot v 1.0.12
I don't think I'll bother trying to figure out v1.0 indexing problems anymore. I've done a lot of fixes and better error checking for v1.1. Maybe it'll show something more useful.
dovecot: Jul 02 11:27:22 Error: IMAP(neko@it4u.lv): Maildir /srv/vmail/it4u.lv/neko/.Listservers.Tech.Dovecot sync: UID inserted in the middle of mailbox (19203 > 19202, file = 1214987241.P26793Q0M308123.serv09,S=3912:2,) .. dovecot: Jul 02 12:48:59 Error: IMAP(neko@it4u.lv): Maildir /srv/vmail/it4u.lv/neko/.Listservers.Tech.Dovecot sync: UID inserted in the middle of mailbox (19210 > 19209, file = 1214992136.P29477Q0M844419.serv09,S=6950:2,) .. dovecot: Jul 02 14:11:40 Warning: IMAP(neko@it4u.lv): Unlinked a duplicate: 1214987033.P23952Q0M811113.serv09,S=4807:2,S .. dovecot: Jul 04 12:25:23 Warning: IMAP(neko@it4u.lv): Unlinked a duplicate: 1215162799.P18322Q0M736352.serv09,S=13262:2,
But there is something really wrong if these keep happening. What OS/filesystem do you use?
Timo Sirainen wrote:
On Thu, 2008-07-10 at 08:07 +0300, Uldis Pakuls wrote:
Hi all.
Looking at previous weeks logs I discovered strange errors: "file mail-transaction-log-view.c: assertion failed"
dovecot v 1.0.12
I don't think I'll bother trying to figure out v1.0 indexing problems anymore. I've done a lot of fixes and better error checking for v1.1. Maybe it'll show something more useful.
I seems time to upgrade...
dovecot: Jul 02 11:27:22 Error: IMAP(neko@it4u.lv): Maildir /srv/vmail/it4u.lv/neko/.Listservers.Tech.Dovecot sync: UID inserted in the middle of mailbox (19203 > 19202, file = 1214987241.P26793Q0M308123.serv09,S=3912:2,)
But there is something really wrong if these keep happening. What OS/filesystem do you use? SuSE Linux 10.3 64 bit. (2.5.22.17 kernel) and Reiserfs
On Mon, 2008-07-14 at 12:52 +0300, Uldis Pakuls wrote:
Timo Sirainen wrote:
On Thu, 2008-07-10 at 08:07 +0300, Uldis Pakuls wrote:
Hi all.
Looking at previous weeks logs I discovered strange errors: "file mail-transaction-log-view.c: assertion failed"
dovecot v 1.0.12
I don't think I'll bother trying to figure out v1.0 indexing problems anymore. I've done a lot of fixes and better error checking for v1.1. Maybe it'll show something more useful.
I seems time to upgrade...
dovecot: Jul 02 11:27:22 Error: IMAP(neko@it4u.lv): Maildir /srv/vmail/it4u.lv/neko/.Listservers.Tech.Dovecot sync: UID inserted in the middle of mailbox (19203 > 19202, file = 1214987241.P26793Q0M308123.serv09,S=3912:2,)
But there is something really wrong if these keep happening. What OS/filesystem do you use? SuSE Linux 10.3 64 bit. (2.5.22.17 kernel) and Reiserfs
Well, I've no idea. Can you easily break it using imaptest? http://imapwiki.org/ImapTest
participants (2)
-
Timo Sirainen
-
Uldis Pakuls