Crash with latest -git as of 2.2.devel (c06590c)

Reuben Farrelly reuben-dovecot at reub.net
Fri Sep 16 15:18:33 UTC 2016


Hi,

Looks like something has been committed to master-2.2 in the last week 
which is causing crashes as soon as the remote user logs in:

Sep 17 01:11:19 thunderstorm.reub.net dovecot: dsync-server(reuben): 
Error: read(lightning.reub.net) failed: EOF (last sent=mailbox_state, 
last recv=mail (EOL))
Sep 17 01:11:31 thunderstorm.reub.net dovecot: imap(reuben): Panic: file 
fts-api.c: line 128 (fts_backend_set_cur_mailbox): assertion failed: 
(ctx->cur_box != NULL)
Sep 17 01:11:31 thunderstorm.reub.net dovecot: imap(reuben): Error: Raw 
backtrace: /usr/lib64/dovecot/libdovecot.so.0(+0xb7f9f) [0x7f4cd5b4cf9f] 
-> /usr/lib64/dovecot/libdovecot.so.0(+0xb9515) [0x7f4cd5b4e515] -> 
/usr/lib64/dovecot/libdovecot.so.0(i_fatal+0) [0x7f4cd5b4d332] -> 
/usr/lib64/dovecot/lib20_fts_plugin.so(+0x96b4) [0x7f4cd50b86b4] -> 
/usr/lib64/dovecot/lib20_fts_plugin.so(fts_backend_update_deinit+0x45) 
[0x7f4cd50b872b] -> /usr/lib64/dovecot/lib20_fts_plugin.so(+0x142ed) 
[0x7f4cd50c32ed] -> 
/usr/lib64/dovecot/libdovecot-storage.so.0(maildir_sync_index+0x98e) 
[0x7f4cd5e4eed7] -> /usr/lib64/dovecot/libdovecot-storage.so.0(+0x5f209) 
[0x7f4cd5e51209] -> /usr/lib64/dovecot/libdovecot-storage.so.0(+0x5f497) 
[0x7f4cd5e51497] -> 
/usr/lib64/dovecot/libdovecot-storage.so.0(maildir_storage_sync_init+0xb2) 
[0x7f4cd5e517de] -> 
/usr/lib64/dovecot/libdovecot-storage.so.0(mailbox_sync_init+0x6d) 
[0x7f4cd5e2cc41] -> dovecot/imap(imap_sync_init+0xc7) [0x42e408] -> 
dovecot/imap() [0x42f96b] -> dovecot/imap() [0x42febc] -> 
dovecot/imap(cmd_sync_delayed+0x34) [0x42fef2] -> 
dovecot/imap(client_handle_input+0x138) [0x41e9d1] -> 
dovecot/imap(client_input+0xf5) [0x41eb02] -> 
/usr/lib64/dovecot/libdovecot.so.0(io_loop_call_io+0xcd) 
[0x7f4cd5b6b054] -> 
/usr/lib64/dovecot/libdovecot.so.0(io_loop_handler_run_internal+0x209) 
[0x7f4cd5b6d5c1] -> 
/usr/lib64/dovecot/libdovecot.so.0(io_loop_handler_run+0x24) 
[0x7f4cd5b6b1fd] -> /usr/lib64/dovecot/libdovecot.so.0(io_loop_run+0xaf) 
[0x7f4cd5b6b149] -> 
/usr/lib64/dovecot/libdovecot.so.0(master_service_run+0x2e) 
[0x7f4cd5aca603] -> dovecot/imap(main+0x300) [0x431169] -> 
/lib64/libc.so.6(__libc_start_main+0xf0) [0x7f4cd571c710] -> 
dovecot/imap(_start+0x29) [0x40c7b9]
Sep 17 01:11:31 thunderstorm.reub.net dovecot: imap(reuben): Fatal: 
master: service(imap): child 1740 killed with signal 6 (core dumped)


thunderstorm reuben # file core
core: ELF 64-bit LSB core file x86-64, version 1 (SYSV), SVR4-style, 
from 'dovecot/imap postlogin', real uid: 0, effective uid: 0, real gid: 
0, effective gid: 0, execfn: '/usr/libexec/dovecot/imap', platform: 'x86_64'
thunderstorm reuben # gdb /usr/libexec/dovecot/imap core
GNU gdb (Gentoo 7.11.1 vanilla) 7.11.1
Copyright (C) 2016 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 "x86_64-pc-linux-gnu".
Type "show configuration" for configuration details.
For bug reporting instructions, please see:
<https://bugs.gentoo.org/>.
Find the GDB manual and other documentation resources online at:
<http://www.gnu.org/software/gdb/documentation/>.
For help, type "help".
Type "apropos word" to search for commands related to "word"...
Reading symbols from /usr/libexec/dovecot/imap...done.
[New LWP 1740]

warning: Could not load shared library symbols for linux-vdso.so.1.
Do you need "set solib-search-path" or "set sysroot"?
[Thread debugging using libthread_db enabled]
Using host libthread_db library "/lib64/libthread_db.so.1".
Core was generated by `dovecot/imap postlogin'.
Program terminated with signal SIGABRT, Aborted.
#0  0x00007f4cd572f1f8 in raise () from /lib64/libc.so.6
(gdb) bt full
#0  0x00007f4cd572f1f8 in raise () from /lib64/libc.so.6
No symbol table info available.
#1  0x00007f4cd573064a in abort () from /lib64/libc.so.6
No symbol table info available.
#2  0x00007f4cd5b4cfee in default_fatal_finish (type=LOG_TYPE_PANIC, 
status=0) at failures.c:201
         backtrace = 0x27446a0 
"/usr/lib64/dovecot/libdovecot.so.0(+0xb7f9f) [0x7f4cd5b4cf9f] -> 
/usr/lib64/dovecot/libdovecot.so.0(+0xb9515) [0x7f4cd5b4e515] -> 
/usr/lib64/dovecot/libdovecot.so.0(i_fatal+0) [0x7f4cd5b4d332] -> /usr"...
#3  0x00007f4cd5b4e515 in i_internal_fatal_handler (ctx=0x7fff509f3750,
     format=0x7f4cd50cb000 "file %s: line %d (%s): assertion failed: 
(%s)", args=0x7fff509f3770) at failures.c:670
         status = 0
#4  0x00007f4cd5b4d332 in i_panic (format=0x7f4cd50cb000 "file %s: line 
%d (%s): assertion failed: (%s)")
     at failures.c:275
         ctx = {type = LOG_TYPE_PANIC, exit_status = 0, timestamp = 0x0, 
timestamp_usecs = 0}
         args = <error reading variable args (Attempt to dereference a 
generic pointer.)>
#5  0x00007f4cd50b86b4 in fts_backend_set_cur_mailbox (ctx=0x26f6e70) at 
fts-api.c:128
         __FUNCTION__ = "fts_backend_set_cur_mailbox"
#6  0x00007f4cd50b872b in fts_backend_update_deinit (_ctx=0x268b3b8) at 
fts-api.c:143
         ctx = 0x26f6e70
         backend = 0x26825e0
         ret = 32588
#7  0x00007f4cd50c32ed in fts_mailbox_sync_notify (box=0x268aaa0, uid=0, 
sync_type=(unknown: 0)) at fts-storage.c:665
         flist = 0x2683000
         fbox = 0x268b258
#8  0x00007f4cd5e4eed7 in maildir_sync_index (ctx=0x268eb40, 
partial=true) at maildir-sync-index.c:649
         mbox = 0x268aaa0
         view = 0x26f4f30
         view2 = 0x0
         iter = 0x0
         trans = 0x270ef20
         hdr = 0x268b8b0
         empty_hdr = {major_version = 176 '\260', minor_version = 57 
'9', base_header_size = 20639,
           header_size = 32767, record_size = 3588796538, compat_flags = 
76 'L', unused = "\177\000",
           indexid = 1352612272, flags = 32512, uid_validity = 40955680, 
next_uid = 0, messages_count = 40849200,
           unused_old_recent_messages_count = 0, seen_messages_count = 
40857728, deleted_messages_count = 0,
           first_recent_uid = 136, first_unseen_uid_lowwater = 0, 
first_deleted_uid_lowwater = 40428608,
           log_file_seq = 0, log_file_tail_offset = 1352612384, 
log_file_head_offset = 32767,
           unused_old_sync_size = 139967982787820, unused_old_sync_stamp 
= 40913568, day_stamp = 0, day_first_uid = {
             41174496, 0, 41174440, 0, 40413856, 0, 1352612384, 32767}}
         rec = 0x26d7188
         seq = 0
         seq2 = 0
         uid = 1043
         prev_uid = 1043
         uflags = (MAILDIR_UIDLIST_REC_FLAG_MOVED | 
MAILDIR_UIDLIST_REC_FLAG_RECENT | MAILDIR_UIDLIST_REC_FLAG_NONSYNCED)
         filename = 0x2711a00 
"1474037293.M664229P7078.thunderstorm,S=18760,W=19334:2,Sd"
         uid_validity = 1466133870
         next_uid = 3585376580
         hdr_next_uid = 1044
         first_recent_uid = 1044
         first_uid = 1
         changes = 1
         ret = 0
         time_before_sync = 1474038691
         expunged_guid_128 = "\247\332=\207\337[1\a\367\217\025\307\024|-Y"
         private_flags_mask = 0
         expunged = true
         full_rescan = false
         __FUNCTION__ = "maildir_sync_index"
#9  0x00007f4cd5e51209 in maildir_sync_context (ctx=0x27445a8, 
forced=false, find_uid=0x0, lost_files_r=0x7fff509f3afa)
     at maildir-sync.c:951
         sync_flags = MAILDIR_UIDLIST_SYNC_PARTIAL
         flags = (MAILDIR_UIDLIST_REC_FLAG_NEW_DIR | 
MAILDIR_UIDLIST_REC_FLAG_MOVED | MAILDIR_UIDLIST_REC_FLAG_RECENT | 
MAILDIR_UIDLIST_REC_FLAG_NONSYNCED | MAILDIR_UIDLIST_REC_FLAG_RACING | 
unknown: 32736)
         new_changed = false
         cur_changed = false
         lock_failure = false
         fname = 0x268aaa0 ""
         why = (unknown: 0)
         ret = 1
         __FUNCTION__ = "maildir_sync_context"
#10 0x00007f4cd5e51497 in maildir_sync_run (mbox=0x268aaa0, 
flags=MAILBOX_SYNC_FLAG_EXPUNGE, force_resync=false,
     uid=0x0, lost_files_r=0x7fff509f3afa) at maildir-sync.c:1024
         _data_stack_cur_id = 5
         ctx = 0x27445a8
         retry = false
         lost_files = false
         ret = 0
#11 0x00007f4cd5e517de in maildir_storage_sync_init (box=0x268aaa0, 
flags=MAILBOX_SYNC_FLAG_EXPUNGE)
     at maildir-sync.c:1097
         mbox = 0x268aaa0
         lost_files = false
         force_resync = false
         ret = 0
         __FUNCTION__ = "maildir_storage_sync_init"
#12 0x00007f4cd5e2cc41 in mailbox_sync_init (box=0x268aaa0, 
flags=MAILBOX_SYNC_FLAG_EXPUNGE) at mail-storage.c:1728
         _data_stack_cur_id = 4
         ctx = 0x0
#13 0x000000000042e408 in imap_sync_init (client=0x2687680, 
box=0x268aaa0, imap_flags=IMAP_SYNC_FLAG_SAFE,
     flags=MAILBOX_SYNC_FLAG_EXPUNGE) at imap-sync.c:229
         ctx = 0x268b770
         __FUNCTION__ = "imap_sync_init"
#14 0x000000000042f96b in cmd_sync_client (sync_cmd=0x2688280) at 
imap-sync.c:737
         client = 0x2687680
         ctx = 0x2
         flags = MAILBOX_SYNC_FLAG_EXPUNGE
         imap_flags = IMAP_SYNC_FLAG_SAFE
         no_newmail = false
#15 0x000000000042febc in cmd_sync_delayed_real (client=0x2687680) at 
imap-sync.c:858
         cmd = 0x2688280
         first_expunge = 0x2688280
         first_nonexpunge = 0x0
         __FUNCTION__ = "cmd_sync_delayed_real"
#16 0x000000000042fef2 in cmd_sync_delayed (client=0x2687680) at 
imap-sync.c:866
         _data_stack_cur_id = 3
         ret = 80
#17 0x000000000041e9d1 in client_handle_input (client=0x2687680) at 
imap-client.c:1118
         ret = false
         remove_io = false
         handled_commands = true
         __FUNCTION__ = "client_handle_input"
#18 0x000000000041eb02 in client_input (client=0x2687680) at 
imap-client.c:1149
         cmd = 0x7fff509f3cc0
         output = 0x26880a0
         bytes = 28
         __FUNCTION__ = "client_input"
#19 0x00007f4cd5b6b054 in io_loop_call_io (io=0x268ca30) at ioloop.c:564
         ioloop = 0x264f760
         t_id = 2
         __FUNCTION__ = "io_loop_call_io"
#20 0x00007f4cd5b6d5c1 in io_loop_handler_run_internal 
(ioloop=0x264f760) at ioloop-epoll.c:220
         ctx = 0x2651270
         events = 0x26520a0
         event = 0x26520a0
         list = 0x26881d0
         io = 0x268ca30
         tv = {tv_sec = 256, tv_usec = 707889}
         events_count = 5
         msecs = 256708
         ret = 1
         i = 0
         j = 0
         call = true
         __FUNCTION__ = "io_loop_handler_run_internal"
#21 0x00007f4cd5b6b1fd in io_loop_handler_run (ioloop=0x264f760) at 
ioloop.c:612
No locals.
#22 0x00007f4cd5b6b149 in io_loop_run (ioloop=0x264f760) at ioloop.c:588
         __FUNCTION__ = "io_loop_run"
#23 0x00007f4cd5aca603 in master_service_run (service=0x264f600, 
callback=0x430dd6 <client_connected>)
     at master-service.c:641
No locals.
#24 0x0000000000431169 in main (argc=2, argv=0x264f390) at main.c:460
         set_roots = {0x43bbe0 <imap_setting_parser_info>, 0x646340 
<lda_setting_parser_info>, 0x0}
         login_set = {auth_socket_path = 0x2647048 "\001", 
postlogin_socket_path = 0x2647078 "z`\005",
           postlogin_timeout_secs = 60, callback = 0x430af0 
<login_client_connected>,
           failure_callback = 0x430d41 <login_client_failed>, 
request_auth_token = 1}
         service_flags = MASTER_SERVICE_FLAG_KEEP_CONFIG_OPEN
         storage_service_flags = 
(MAIL_STORAGE_SERVICE_FLAG_DISALLOW_ROOT | 
MAIL_STORAGE_SERVICE_FLAG_AUTOEXPUNGE)
         username = 0x0
         auth_socket_path = 0x43cd85 "auth-master"
         c = -1
(gdb)

Reuben


More information about the dovecot mailing list