[Dovecot] 1.2.9 imap crash with backtrace

Mon Jan 4 12:09:13 EET 2010

>From the log:

Jan  3 20:26:07 postamt dovecot: IMAP(nixxxxx): copy: uid=3526, dest=Deleted Messages, msgid=<DC8A4BE5-41A0-4240-8F07-090E95414F7A at charite.de>, size=2726
Jan  3 20:26:07 postamt dovecot: IMAP(nixxxxx): delete: uid=3526, msgid=<DC8A4BE5-41A0-4240-8F07-090E95414F7A at charite.de>
Jan  3 20:26:11 postamt dovecot: IMAP(nixxxxx): expunge: uid=3526, msgid=<DC8A4BE5-41A0-4240-8F07-090E95414F7A at charite.de>, size=2726
Jan  3 20:26:42 postamt dovecot: IMAP(nixxxxx): copy: uid=415, box=Deleted Messages, dest=INBOX, msgid=<d539ffeb1001030758n1acc33c1m1fc67e0f1d227e92 at mail.gmail.com>, size=3604
Jan  3 20:26:42 postamt dovecot: IMAP(nixxxxx): /home/n/i/nixxxxx/Maildir/dovecot-uidlist: Duplicate file entry at line 1:
1262534293.M822904P8880.postamt.charite.de,S=3604,W=3690:2,RSaf (uid 3528 -> 3531)
Jan  3 20:26:42 postamt dovecot: IMAP(nixxxxx): Panic: file maildir-uidlist.c: line 403 (maildir_uidlist_records_array_delete):
assertion failed: (pos != NULL)
Jan  3 20:26:42 postamt dovecot: IMAP(nixxxxx): Raw backtrace: imap [0x80f1631] -> imap [0x80f16a2] -> imap [0x80f1019] -> imap
[0x8088fa6] -> imap(maildir_uidlist_refresh+0x9e9) [0x8089fc9] -> imap [0x8086704] -> imap(maildir_storage_sync_init+0x125) [0x8086be5] ->
imap(imap_sync_init+0x54) [0x8070434] -> imap [0x8062aa2] -> imap [0x80a619c] -> imap(io_loop_handle_timeouts+0xe9) [0x80f9959] ->
imap(io_loop_handler_run+0x82) [0x80fa222] -> imap(io_loop_run+0x20) [0x80f96f0] -> imap(main+0x5d2) [0x8070f52] ->
/lib/i686/cmov/libc.so.6(__libc_start_main+0xe5) [0xb75bcb55] -> imap [0x80602d1]
Jan  3 20:26:42 postamt dovecot: IMAP(nixxxxx): delete: uid=415, box=Deleted Messages, msgid=<d539ffeb1001030758n1acc33c1m1fc67e0f1d227e92 at mail.gmail.com>
Jan  3 20:26:42 postamt dovecot: IMAP(nixxxxx): expunge: uid=415, box=Deleted Messages, msgid=<d539ffeb1001030758n1acc33c1m1fc67e0f1d227e92 at mail.gmail.com>, size=3604
Jan  3 20:26:45 postamt dovecot: IMAP(nixxxxx): Disconnected: Logged out bytes=63917/47203
Jan  3 20:26:45 postamt dovecot: IMAP(nixxxxx): Disconnected: Logged out bytes=360/168380
Jan  3 20:26:45 postamt dovecot: IMAP(nixxxxx): Disconnected: Logged out bytes=670290/672304


GNU gdb (GDB) 7.0-debian
Copyright (C) 2009 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".
For bug reporting instructions, please see:
<http://www.gnu.org/software/gdb/bugs/>...
Reading symbols from /usr/local/libexec/dovecot/imap...done.
Reading symbols from /lib/i686/cmov/libdl.so.2...Reading symbols from /usr/lib/debug/lib/i686/cmov/libdl-2.10.2.so...done.
(no debugging symbols found)...done.
Loaded symbols for /lib/i686/cmov/libdl.so.2
Reading symbols from /lib/i686/cmov/librt.so.1...Reading symbols from /usr/lib/debug/lib/i686/cmov/librt-2.10.2.so...done.
(no debugging symbols found)...done.
Loaded symbols for /lib/i686/cmov/librt.so.1
Reading symbols from /lib/i686/cmov/libc.so.6...Reading symbols from /usr/lib/debug/lib/i686/cmov/libc-2.10.2.so...done.
(no debugging symbols found)...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.10.2.so...done.
(no debugging symbols found)...done.
Loaded symbols for /lib/ld-linux.so.2
Reading symbols from /lib/i686/cmov/libpthread.so.0...Reading symbols from /usr/lib/debug/lib/i686/cmov/libpthread-2.10.2.so...done.
(no debugging symbols found)...done.
Loaded symbols for /lib/i686/cmov/libpthread.so.0
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...(no debugging symbols found)...done.
Loaded symbols for /usr/lib/libz.so.1
Reading symbols from /lib/libbz2.so.1.0...(no debugging symbols found)...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...(no debugging symbols found)...done.
Loaded symbols for /lib/libgcc_s.so.1
Core was generated by `imap [nixxxxx 87.162.56.10]'.
Program terminated with signal 6, Aborted.
#0  0xb7708424 in __kernel_vsyscall ()
#0  0xb7708424 in __kernel_vsyscall ()
No symbol table info available.
#1  0xb75d08e0 in *__GI_raise (sig=6)
    at ../nptl/sysdeps/unix/sysv/linux/raise.c:64
        resultvar = <value optimized out>
        pid = -1217490956
        selftid = 25023
#2  0xb75d3e15 in *__GI_abort () at abort.c:88
        act = {__sigaction_handler = {sa_handler = 0x9505708, 
            sa_sigaction = 0x9505708}, sa_mask = {__val = {3219827960, 
              135197034, 3077474016, 0, 3219828080, 3219828068, 128, 0, 0, 
              3219828144, 3077727856, 134566866, 549, 0, 0, 0, 1, 135270350, 
              128, 1, 3219828068, 156260072, 69, 3219828196, 3219828040, 
              135198630, 156260653, 3219828068, 3219828072, 550, 156260104, 
              3076940867}}, sa_flags = 550, 
          sa_restorer = 0x8106dd9 <write_full+57>}
        sigs = {__val = {32, 0 <repeats 31 times>}}
#3  0x080f1645 in default_fatal_finish (type=<value optimized out>, status=0)
    at failures.c:160
        backtrace = 0x95052d8 "imap [0x80f1631] -> imap [0x80f16a2] -> imap [0x80f1019] -> imap [0x8088fa6] -> imap(maildir_uidlist_refresh+0x9e9) [0x8089fc9] -> imap [0x8086704] -> imap(maildir_storage_sync_init+0x125) [0x8086be5]"...
#4  0x080f16a2 in i_internal_fatal_handler (type=LOG_TYPE_PANIC, status=0, 
    fmt=0x8109e2c "file %s: line %d (%s): assertion failed: (%s)", 
    args=0xbfeaae44 ":\355\020\b\223\001") at failures.c:443
No locals.
#5  0x080f1019 in i_panic (
    format=0x8109e2c "file %s: line %d (%s): assertion failed: (%s)")
    at failures.c:207
No locals.
#6  0x08088fa6 in maildir_uidlist_records_array_delete (uidlist=0x951b0b0, 
    rec=0x9534500) at maildir-uidlist.c:403
        recs = 0x9536fa0
        __PRETTY_FUNCTION__ = "maildir_uidlist_records_array_delete"
#7  0x08089fc9 in maildir_uidlist_next (uidlist=0x951b0b0)
    at maildir-uidlist.c:544
        rec = 0x95346b8
        uid = 3531
#8  maildir_uidlist_update_read (uidlist=0x951b0b0) at maildir-uidlist.c:741
        storage = 0x950f4b8
        orig_uid_validity = 1186140978
        fd = 14
        orig_next_uid = 3531
        input = 0x95347a0
        st = {st_dev = 65024, __pad1 = 0, __st_ino = 5228663, st_mode = 33152, 
          st_nlink = 1, st_uid = 50357, st_gid = 100, st_rdev = 0, __pad2 = 0, 
          st_size = 763, st_blksize = 4096, st_blocks = 8, st_atim = {
            tv_sec = 1262546767, tv_nsec = 269770575}, st_mtim = {
            tv_sec = 1262546802, tv_nsec = 221806856}, st_ctim = {
            tv_sec = 1262546802, tv_nsec = 221806856}, st_ino = 5228663}
        last_read_offset = 693
#9  maildir_uidlist_refresh (uidlist=0x951b0b0) at maildir-uidlist.c:889
        i = 0
        retry = <value optimized out>
        ret = <value optimized out>
#10 0x08086704 in have_recent_messages (ctx=0x9505210, forced=false, 
    find_uid=0x0, lost_files_r=0xbfeaaffb) at maildir-sync.c:659
        hdr = <value optimized out>
#11 maildir_sync_get_changes (ctx=0x9505210, forced=false, find_uid=0x0, 
    lost_files_r=0xbfeaaffb) at maildir-sync.c:679
        flags = <value optimized out>
#12 maildir_sync_context (ctx=0x9505210, forced=false, find_uid=0x0, 
    lost_files_r=0xbfeaaffb) at maildir-sync.c:714
        sync_flags = <value optimized out>
        flags = 135325664
        new_changed = <value optimized out>
        cur_changed = <value optimized out>
        lock_failure = <value optimized out>
        fname = 0x9515030 "/home/n/i/nixxxxx/Maildir"
        ret = <value optimized out>
        __PRETTY_FUNCTION__ = "maildir_sync_context"
#13 0x08086be5 in maildir_storage_sync_init (box=0x9514e98, flags=0)
    at maildir-sync.c:924
        _data_stack_cur_id = 4
        ctx = 0x9505210
        lost_files = <value optimized out>
        ret = 0
        __PRETTY_FUNCTION__ = "maildir_storage_sync_init"
#14 0x08070434 in imap_sync_init (client=0x950fba0, box=0x9514e98, 
    imap_flags=0, flags=0) at imap-sync.c:146
        ctx = 0x95191a0
        __PRETTY_FUNCTION__ = "imap_sync_init"
#15 0x08062aa2 in idle_sync_now (box=<value optimized out>, ctx=0x950feb8)
    at cmd-idle.c:127
        __PRETTY_FUNCTION__ = "idle_sync_now"
#16 0x080a619c in notify_delay_callback (ibox=0x9514e98)
    at index-mailbox-check.c:49
No locals.
#17 0x080f9959 in io_loop_handle_timeouts_real (ioloop=0x950d9b0)
    at ioloop.c:313
        item = 0x9563c78
        tv = {tv_sec = 0, tv_usec = 0}
        tv_call = {tv_sec = 1262546802, tv_usec = 336801}
        t_id = 3
#18 io_loop_handle_timeouts (ioloop=0x950d9b0) at ioloop.c:324
        _data_stack_cur_id = 2
#19 0x080fa222 in io_loop_handler_run (ioloop=0x950d9b0) at ioloop-epoll.c:180
        ctx = 0x950dab8
        event = <value optimized out>
        list = <value optimized out>
        io = <value optimized out>
        tv = {tv_sec = 0, tv_usec = 108829}
        t_id = <value optimized out>
        msecs = 109
        ret = 0
        i = <value optimized out>
        j = <value optimized out>
        call = <value optimized out>
#20 0x080f96f0 in io_loop_run (ioloop=0x950d9b0) at ioloop.c:335
No locals.
#21 0x08070f52 in main (argc=) at main.c:327
No locals.
$1 = {prev = 0x0, next = 0x0, client = 0x950fba0, pool = 0x950fe18, 
  tag = 0x950fea8 "104.6", name = 0x950feb0 "IDLE", args = 0x0, 
  cmd_flags = 10, func = 0x80628b0 <cmd_idle_continue>, context = 0x950feb8, 
  module_contexts = {arr = {buffer = 0x950fe70, element_size = 4}, 
    v = 0x950fe70, v_modifiable = 0x950fe70}, parser = 0x9512620, 
  state = CLIENT_COMMAND_STATE_WAIT_INPUT, sync = 0x0, uid = 0, cancel = 0, 
  param_error = 0, search_save_result = 0, temp_executed = 0}
(gdb) quit

----- End forwarded message -----

-- 
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 at charite.de | http://www.charite.de