[Dovecot] imap signal 6 crash with backtrace (1.2rc5)
GNU gdb 6.8-debian
Copyright (C) 2008 Free Software Foundation, Inc.
License GPLv3+: GNU GPL version 3 or later http://gnu.org/licenses/gpl.html
This is free software: you are free to change and redistribute it.
There is NO WARRANTY, to the extent permitted by law. Type "show copying"
and "show warranty" for details.
This GDB was configured as "i486-linux-gnu"...
Reading symbols from /lib/i686/cmov/libdl.so.2...Reading symbols from /usr/lib/debug/lib/i686/cmov/libdl-2.9.so...done.
done.
Loaded symbols for /lib/i686/cmov/libdl.so.2
Reading symbols from /lib/i686/cmov/libc.so.6...Reading symbols from /usr/lib/debug/lib/i686/cmov/libc-2.9.so...done.
done.
Loaded symbols for /lib/i686/cmov/libc.so.6
Reading symbols from /lib/ld-linux.so.2...Reading symbols from /usr/lib/debug/lib/ld-2.9.so...done.
done.
Loaded symbols for /lib/ld-linux.so.2
Reading symbols from /usr/local/lib/dovecot/imap/lib10_quota_plugin.so...done.
Loaded symbols for /usr/local/lib/dovecot/imap/lib10_quota_plugin.so
Reading symbols from /usr/local/lib/dovecot/imap/lib11_imap_quota_plugin.so...done.
Loaded symbols for /usr/local/lib/dovecot/imap/lib11_imap_quota_plugin.so
Reading symbols from /usr/local/lib/dovecot/imap/lib11_trash_plugin.so...done.
Loaded symbols for /usr/local/lib/dovecot/imap/lib11_trash_plugin.so
Reading symbols from /usr/local/lib/dovecot/imap/lib20_autocreate_plugin.so...done.
Loaded symbols for /usr/local/lib/dovecot/imap/lib20_autocreate_plugin.so
Reading symbols from /usr/local/lib/dovecot/imap/lib20_fts_plugin.so...done.
Loaded symbols for /usr/local/lib/dovecot/imap/lib20_fts_plugin.so
Reading symbols from /usr/local/lib/dovecot/imap/lib20_mail_log_plugin.so...done.
Loaded symbols for /usr/local/lib/dovecot/imap/lib20_mail_log_plugin.so
Reading symbols from /usr/local/lib/dovecot/imap/lib20_zlib_plugin.so...done.
Loaded symbols for /usr/local/lib/dovecot/imap/lib20_zlib_plugin.so
Reading symbols from /usr/lib/libz.so.1...done.
Loaded symbols for /usr/lib/libz.so.1
Reading symbols from /lib/libbz2.so.1.0...done.
Loaded symbols for /lib/libbz2.so.1.0
Reading symbols from /usr/local/lib/dovecot/imap/lib21_fts_squat_plugin.so...done.
Loaded symbols for /usr/local/lib/dovecot/imap/lib21_fts_squat_plugin.so
Reading symbols from /lib/libgcc_s.so.1...done.
Loaded symbols for /lib/libgcc_s.so.1
Core was generated by `imap [virus-al 127.0.0.1]'.
Program terminated with signal 6, Aborted.
[New process 2286]
#0 0xb8027424 in __kernel_vsyscall ()
#0 0xb8027424 in __kernel_vsyscall ()
No symbol table info available.
#1 0xb7ee2680 in *__GI_raise (sig=6)
at ../nptl/sysdeps/unix/sysv/linux/raise.c:64
resultvar = <value optimized out>
pid = -1207881740
selftid = 2286
#2 0xb7ee5d68 in *__GI_abort () at abort.c:88
act = {__sigaction_handler = {sa_handler = 0x9407170,
sa_sigaction = 0x9407170}, sa_mask = {__val = {3219650520, 135193418,
3087083232, 0, 3219650640, 3219650628, 512, 0, 0, 3219650704,
3087292016, 134566083, 639, 0, 0, 0, 1, 135264238, 512, 1, 3219650628,
155218256, 69, 3219650756, 3219650600, 135195014, 155218927, 3219650628,
3219650632, 640, 155218288, 3086525667}}, sa_flags = 640,
sa_restorer = 0x8105449
from my log:
Jun 20 15:00:13 postamt dovecot: IMAP(virus-al): /home/v/i/virus-al/Maildir/dovecot-uidlist: next_uid was lowered (70 -> 2)
Jun 20 15:00:13 postamt dovecot: IMAP(virus-al): Panic: file index-sync.c: line 25 (index_mailbox_set_recent_uid): assertion failed: (seq_range_exists(&ibox->recent_flags, uid))
Jun 20 15:00:13 postamt dovecot: IMAP(virus-al): Raw backtrace: imap [0x80f0491] -> imap [0x80f0512] -> imap [0x80efe99] -> imap [0x80ac7e1] -> imap(index_mailbox_set_recent_seq+0x40) [0x80ac830] -> imap(maildir_sync_index+0xaa0) [0x807b330] -> imap [0x8079a49] -> imap(maildir_storage_sync_init+0x125) [0x8079e15] -> imap(imap_sync_init+0x54) [0x806fe04] -> imap(cmd_sync_delayed+0x223) [0x8070103] -> imap(client_handle_input+0x1bc) [0x806772c] -> imap(client_input+0x5f) [0x8067eff] -> imap(io_loop_handler_run+0xe0) [0x80f90c0] -> imap(io_loop_run+0x20) [0x80f8530] -> imap(main+0x5f5) [0x8070985] -> /lib/i686/cmov/libc.so.6(__libc_start_main+0xe5) [0xb7ecd775] -> imap [0x805fce1]
Jun 20 15:00:13 postamt dovecot: dovecot: child 2286 (imap) killed with signal 6 (core dumped)
-- Ralf Hildebrandt Postfix - Einrichtung, Betrieb und Wartung Tel. +49 (0)30-450 570-155 http://www.computerbeschimpfung.de Sollen sich auch alle schämen, die gedankenlos sich der Wunder der Wissenschaft und Technik bedienen und nicht mehr davon geistig erfaßt haben, als die Kuh von der Botanik der Pflanzen, die sie mit Wohlbehagen frißt. -- A. Einstein
On Jun 20, 2009, at 9:27 AM, Ralf Hildebrandt wrote:
Jun 20 15:00:13 postamt dovecot: IMAP(virus-al): /home/v/i/virus-al/Maildir/dovecot-uidlist: next_uid was lowered (70
-> 2)
Any idea why this happened? next_uid really shouldn't get lowered. Did
something e.g. restore an older dovecot-uidlist to the Maildir?
- Timo Sirainen tss@iki.fi:
On Jun 20, 2009, at 9:27 AM, Ralf Hildebrandt wrote:
Jun 20 15:00:13 postamt dovecot: IMAP(virus-al): /home/v/i/virus-al/Maildir/dovecot-uidlist: next_uid was lowered (70 -> 2)
Any idea why this happened? next_uid really shouldn't get lowered. Did
something e.g. restore an older dovecot-uidlist to the Maildir?
virus-al is a mailbox that holds quarantined spam. I'm the only person accessing it AND when this happened, I was (as usual) the only person looking at the mailbox. I logged in using mutt from localhost via IMAP.
I checked: there are no scripts that (for example) delete files from the maildir
-- Ralf Hildebrandt Postfix - Einrichtung, Betrieb und Wartung Tel. +49 (0)30-450 570-155 http://www.computerbeschimpfung.de "Whom computers would destroy, they must first drive mad." -- Anon.
On Sat, 2009-06-20 at 22:02 +0200, Ralf Hildebrandt wrote:
- Timo Sirainen tss@iki.fi:
On Jun 20, 2009, at 9:27 AM, Ralf Hildebrandt wrote:
Jun 20 15:00:13 postamt dovecot: IMAP(virus-al): /home/v/i/virus-al/Maildir/dovecot-uidlist: next_uid was lowered (70 -> 2)
Any idea why this happened? next_uid really shouldn't get lowered. Did
something e.g. restore an older dovecot-uidlist to the Maildir?virus-al is a mailbox that holds quarantined spam. I'm the only person accessing it AND when this happened, I was (as usual) the only person looking at the mailbox. I logged in using mutt from localhost via IMAP.
I checked: there are no scripts that (for example) delete files from the maildir
Is it possible that the entire virus-al mailbox gets deleted and recreated (while the mailbox is open)? Do you have any "UIDVALIDITY changed" lines in logs? Wonder if these would do something:
http://hg.dovecot.org/dovecot-1.2/rev/51aee73e49a5 http://hg.dovecot.org/dovecot-1.2/rev/5a4875e92829
- Timo Sirainen tss@iki.fi:
Is it possible that the entire virus-al mailbox gets deleted and recreated (while the mailbox is open)?
No. There may be processes that delete files/mails from the Maildir, though.
Do you have any "UIDVALIDITY changed" lines in logs?
# zfgrep -h virus-al /var/log/OLD/2009-07-*/pop3d-imapd.log |grep UIDVALIDITY Jul 2 02:27:19 postamt dovecot: IMAP(virus-al): Maildir /home/v/i/virus-al/Maildir: UIDVALIDITY changed (1246493487 -> 1246494049) Jul 2 22:25:34 postamt dovecot: IMAP(virus-al): Maildir /home/v/i/virus-al/Maildir: UIDVALIDITY changed (1246564263 -> 1246566306) Jul 18 21:30:00 postamt dovecot: IMAP(virus-al): Maildir /home/v/i/virus-al/Maildir: UIDVALIDITY changed (1247941041 -> 1247944777) That's all for last month
Wonder if these would do something:
http://hg.dovecot.org/dovecot-1.2/rev/51aee73e49a5 http://hg.dovecot.org/dovecot-1.2/rev/5a4875e92829
-- Ralf Hildebrandt Geschäftsbereich IT | Abteilung Netzwerk Charité - Universitätsmedizin Berlin Campus Benjamin Franklin Hindenburgdamm 30 | D-12203 Berlin Tel. +49 30 450 570 155 | Fax: +49 30 450 570 962 ralf.hildebrandt@charite.de | http://www.charite.de
On Jul 27, 2009, at 3:11 AM, Ralf Hildebrandt wrote:
- Timo Sirainen tss@iki.fi:
Is it possible that the entire virus-al mailbox gets deleted and recreated (while the mailbox is open)?
No. There may be processes that delete files/mails from the Maildir,
though.Do you have any "UIDVALIDITY changed" lines in logs?
# zfgrep -h virus-al /var/log/OLD/2009-07-*/pop3d-imapd.log |grep
UIDVALIDITY Jul 2 02:27:19 postamt dovecot: IMAP(virus-al): Maildir /home/v/i/ virus-al/Maildir: UIDVALIDITY changed (1246493487 -> 1246494049) Jul 2 22:25:34 postamt dovecot: IMAP(virus-al): Maildir /home/v/i/ virus-al/Maildir: UIDVALIDITY changed (1246564263 -> 1246566306) Jul 18 21:30:00 postamt dovecot: IMAP(virus-al): Maildir /home/v/i/ virus-al/Maildir: UIDVALIDITY changed (1247941041 -> 1247944777) That's all for last month
OK, that definitely means something's either deleting dovecot-uidlist
files or changing that mailbox's index files in some way, which then
causes the UIDVALIDITY changed errors and/or next_uid changed errors.
Neither should really be happening.. I can always try to get those
crashes fixed, but I think the underlying problem is some external
script or something.
- Timo Sirainen tss@iki.fi:
# zfgrep -h virus-al /var/log/OLD/2009-07-*/pop3d-imapd.log |grep UIDVALIDITY Jul 2 02:27:19 postamt dovecot: IMAP(virus-al): Maildir /home/v/i/ virus-al/Maildir: UIDVALIDITY changed (1246493487 -> 1246494049) Jul 2 22:25:34 postamt dovecot: IMAP(virus-al): Maildir /home/v/i/ virus-al/Maildir: UIDVALIDITY changed (1246564263 -> 1246566306) Jul 18 21:30:00 postamt dovecot: IMAP(virus-al): Maildir /home/v/i/ virus-al/Maildir: UIDVALIDITY changed (1247941041 -> 1247944777) That's all for last month
OK, that definitely means something's either deleting dovecot-uidlist files or changing that mailbox's index files in some way, which then causes the UIDVALIDITY changed errors and/or next_uid changed errors. Neither should really be happening.. I can always try to get those crashes fixed, but I think the underlying problem is some external script or something.
Could be, but as you can see the crash happens WAY WAY later.
-- Ralf Hildebrandt Geschäftsbereich IT | Abteilung Netzwerk Charité - Universitätsmedizin Berlin Campus Benjamin Franklin Hindenburgdamm 30 | D-12203 Berlin Tel. +49 30 450 570 155 | Fax: +49 30 450 570 962 ralf.hildebrandt@charite.de | http://www.charite.de
On Jul 27, 2009, at 3:29 AM, Ralf Hildebrandt wrote:
OK, that definitely means something's either deleting dovecot-uidlist files or changing that mailbox's index files in some way, which then causes the UIDVALIDITY changed errors and/or next_uid changed errors. Neither should really be happening.. I can always try to get those crashes fixed, but I think the underlying problem is some external script or something.
Could be, but as you can see the crash happens WAY WAY later.
Yes, but the root cause could be the same thing. Sometimes it would
complain about UIDVALIDITY changing, sometimes about next_uid with a
crash.
participants (2)
-
Ralf Hildebrandt
-
Timo Sirainen