Uldis Pakuls
2008-Jul-10 05:07 UTC
[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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at it4u.lv): Unlinked a duplicate: 1214989937.P31578Q0M123879.serv09,S=4899:2, dovecot: Jul 02 12:48:57 Error: IMAP(neko at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at it4u.lv): Unlinked a duplicate: 1214987033.P23952Q0M811113.serv09,S=4807:2,S dovecot: Jul 04 12:25:20 Error: IMAP(neko at 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 at 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 at 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 at 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 at 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 at it4u.lv): Unlinked a duplicate: 1215162799.P18322Q0M736352.serv09,S=13262:2, dovecot: Jul 04 12:25:23 Error: IMAP(neko at 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 at 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 ---
Timo Sirainen
2008-Jul-12 13:10 UTC
[Dovecot] file mail-transaction-log-view.c: assertion failed (v1.0.12)
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.12I 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 at 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 at 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 at it4u.lv): Unlinked a duplicate: 1214987033.P23952Q0M811113.serv09,S=4807:2,S..> dovecot: Jul 04 12:25:23 Warning: IMAP(neko at 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? -------------- next part -------------- A non-text attachment was scrubbed... Name: signature.asc Type: application/pgp-signature Size: 197 bytes Desc: This is a digitally signed message part URL: <http://dovecot.org/pipermail/dovecot/attachments/20080712/40a3c9ab/attachment-0002.bin>
Uldis Pakuls
2008-Jul-14 09:52 UTC
[Dovecot] file mail-transaction-log-view.c: assertion failed (v1.0.12)
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 at 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