Julian Cowley
2007-Sep-28 03:07 UTC
[Dovecot] Dovecot raw backtrace when copying to folder
Seeing a problem with a certain user causing dovecot to crash when copying
mail to a folder. This looks similar to a bug someone on the mailing list
posted recently.
Since the backtrace mentions the index, I tried deleting all of the index
files for the user (including subfolders) but it had no effect. Seems that
the bug still happens when creating the index for the first time.
Unfortunately, I can't pin down if there is a particular message causing
the problem but I'll see if I can track it using strace.
Lines are broken for clarity. This is dovecot 1.0.5 on CentOS 4.5.
Sep 27 16:41:42 cookie dovecot: imap-login: Login: user=<user at domain>,
method=PLAIN, rip=::ffff:xx.xx.xx.xx, lip=::ffff:yy.yy.yy.yy, TLS
Sep 27 16:41:53 cookie dovecot: IMAP(user at domain): Trying to allocate 0 bytes
Sep 27 16:41:53 cookie dovecot: IMAP(user at domain): Raw backtrace:
imap [0x80ad7d4] ->
imap [0x80ad239] ->
imap [0x80b5624] ->
imap(i_malloc+0x1b) [0x80b0e1b] ->
imap [0x8083037] ->
imap [0x80833e6] ->
imap(index_storage_search_init+0xf4) [0x80836f4] ->
imap(cmd_copy+0x145) [0x8056d15] ->
imap(cmd_uid+0x7c) [0x805a74c] ->
imap [0x805b2d5] ->
imap [0x805b25e] ->
imap(_client_input+0x8c) [0x805b43c] ->
imap(io_loop_handler_run+0x169) [0x80b3e09] ->
imap(io_loop_run+0x28) [0x80b3178] ->
imap(main+0x49f) [0x806384f] ->
/lib/tls/libc.so.6(__libc_start_main+0xd3) [0x506de3] ->
imap [0x8055db1]
Sep 27 16:41:53 cookie dovecot: child 26079 (imap) killed with signal 6
Julian Cowley wrote:> Seeing a problem with a certain user causing dovecot to crash when > copying mail to a folder. This looks similar to a bug someone on the > mailing list posted recently.Hi, first of all I would like to report success with moving from UW-imapd to Dovecot 1.0.5 for a ~100 user setup on Debian Etch. Everything works nicely, including the snarf plugin. Only a small problem: In the last four days Dovecot crashed twice with a similar error message posted in this thread: dovecot: 2007-09-25 16:54:08 Panic: Lost login process PID 420 dovecot: 2007-09-25 16:54:08 Error: Raw backtrace: dovecot [0x805616a] -> dovecot [0x8055fdc] -> dovecot [0x804d83a] -> dovecot [0x8050572] -> dovecot [0x8059e0e] -> dovecot [0x8059780] -> dovecot [0x80587a8] -> dovecot [0x8050f5c] -> /lib/tls/libc.so.6(__libc_start_main+0xc8) [0xb7eb1ea8] -> dovecot [0x804a391] dovecot: 2007-09-27 12:13:02 Panic: Lost login process PID 5160 dovecot: 2007-09-27 12:13:02 Error: Raw backtrace: dovecot [0x805616a] -> dovecot [0x8055fdc] -> dovecot [0x804d83a] -> dovecot [0x8050572] -> dovecot [0x8059e0e] -> dovecot [0x8059780] -> dovecot [0x80587a8] -> dovecot [0x8050f5c] -> /lib/tls/libc.so.6(__libc_start_main+0xc8) [0xb7e24ea8] -> dovecot [0x804a391] I witnessed the first crash, an user moved some messages out of a larger mbox file to the trash, using Thunderbird 2.0.0.6/Windows + IMAP. After starting the move, TB showed an hourglas for approx. 10 minutes, then the messages were moved. I guess during these 10 minutes Dovecot crashed and was restarted through new TB commands send to the server. Dovecot is started through inetd. I am not sure if I can reproduce the bug because the user with the first crash uses Dovecot already for several months and he said he saw this problem already several times but he was unable to tell me a procedure to reproduce the bug. Greetings, Bernd Kuhls PS: My config # dovecot -n # 1.0.5: /etc/dovecot/dovecot.conf log_path: /var/log/dovecot/dovecot.main log_timestamp: %Y-%m-%d %H:%M:%S protocols: imap imaps pop3 pop3s disable_plaintext_auth: no login_dir: /var/run/dovecot/login login_executable(default): /usr/lib/dovecot/imap-login login_executable(imap): /usr/lib/dovecot/imap-login login_executable(pop3): /usr/lib/dovecot/pop3-login login_max_processes_count: 256 mail_extra_groups: mail mail_location: mbox:~/.imap_mail:INBOX=/var/mail/%u:INDEX=/var/mail/indexes/%u mail_executable(default): /usr/lib/dovecot/imap mail_executable(imap): /usr/lib/dovecot/imap mail_executable(pop3): /usr/lib/dovecot/pop3 mail_plugins(default): quota imap_quota mbox_snarf mail_plugins(imap): quota imap_quota mbox_snarf mail_plugins(pop3): mbox_snarf mail_plugin_dir(default): /usr/lib/dovecot/modules/imap mail_plugin_dir(imap): /usr/lib/dovecot/modules/imap mail_plugin_dir(pop3): /usr/lib/dovecot/modules/pop3 imap_client_workarounds(default): outlook-idle netscape-eoh tb-extra-mailbox-sep delay-newmail imap_client_workarounds(imap): outlook-idle netscape-eoh tb-extra-mailbox-sep delay-newmail imap_client_workarounds(pop3): outlook-idle pop3_uidl_format(default): pop3_uidl_format(imap): pop3_uidl_format(pop3): %08Xv%08Xu auth default: passdb: driver: pam userdb: driver: passwd plugin: quota: fs mbox_snarf: ~/.imap_mail/inbox
Marcus Rueckert
2007-Sep-29 18:09 UTC
[Dovecot] Dovecot raw backtrace when copying to folder
On 2007-09-29 20:06:45 +0200, Bernd Kuhls wrote:> dovecot: 2007-09-25 16:54:08 Panic: Lost login process PID 420 > dovecot: 2007-09-25 16:54:08 Error: Raw backtrace: > dovecot [0x805616a] -> > dovecot [0x8055fdc] -> > dovecot [0x804d83a] -> > dovecot [0x8050572] -> > dovecot [0x8059e0e] -> > dovecot [0x8059780] -> > dovecot [0x80587a8] -> > dovecot [0x8050f5c] -> > /lib/tls/libc.so.6(__libc_start_main+0xc8) [0xb7eb1ea8] -> > dovecot [0x804a391]your dovecot binaries are stripped. those backtraces are more or less useless without your binaries/system. can you install the debuginfo package or rebuild your installation without stripping? darix -- openSUSE - SUSE Linux is my linux openSUSE is good for you www.opensuse.org
Timo Sirainen
2007-Sep-30 12:17 UTC
[Dovecot] Dovecot raw backtrace when copying to folder
On Thu, 2007-09-27 at 17:07 -1000, Julian Cowley wrote:> Lines are broken for clarity. This is dovecot 1.0.5 on CentOS 4.5...> imap(i_malloc+0x1b) [0x80b0e1b] -> > imap [0x8083037] -> > imap [0x80833e6] -> > imap(index_storage_search_init+0xf4) [0x80836f4] ->Are you sure it's 1.0.5? This looks a lot like the crash that empty messagesets caused with post-1.0.5 hg version: http://hg.dovecot.org/dovecot-1.0/rev/f85adea35b74 Getting a proper gdb backtrace of the crash would anyway be better than this raw backtrace: http://dovecot.org/bugreport.html Or at least I'd like to know what gdb says with: gdb /usr/local/libexec/dovecot/imap l *0x8083037 l *0x80833e6 -------------- next part -------------- A non-text attachment was scrubbed... Name: signature.asc Type: application/pgp-signature Size: 189 bytes Desc: This is a digitally signed message part URL: <http://dovecot.org/pipermail/dovecot/attachments/20070930/7558b056/attachment-0002.bin>
Julian Cowley
2007-Sep-30 23:05 UTC
[Dovecot] Dovecot raw backtrace when copying to folder
On Sun, 30 Sep 2007, Timo Sirainen wrote:> On Thu, 2007-09-27 at 17:07 -1000, Julian Cowley wrote: >> Lines are broken for clarity. This is dovecot 1.0.5 on CentOS 4.5. > .. >> imap(i_malloc+0x1b) [0x80b0e1b] -> >> imap [0x8083037] -> >> imap [0x80833e6] -> >> imap(index_storage_search_init+0xf4) [0x80836f4] -> > > Are you sure it's 1.0.5? This looks a lot like the crash that empty > messagesets caused with post-1.0.5 hg version: > http://hg.dovecot.org/dovecot-1.0/rev/f85adea35b74You're right, it's not strictly 1.0.5 since I added the following patches. I'm not using the above empty messagesets patch though. Should I be? My patch file name hg revision ================================ ===========dovecot-cmd-create-fix.patch 6bbb20cdaf1e dovecot-deliver-dup-db-fix.patch 9d4e89fa62d0 dovecot-mailbox-idle-check.patch afb48abd91c0 dovecot-search-not-fix.patch d144e54f09e6 dovecot-search-opt-p1.patch fa89431f893e dovecot-search-opt-p2.patch 92b2bd6b6c2d dovecot-search-opt-p3.patch 678626efb942 In addition there are a few patches in my spec file which are inherited from the original by Axel Thimm, but these have been in there for donkey's years and are unlikely to be related to the problem.> Getting a proper gdb backtrace of the crash would anyway be better than > this raw backtrace: http://dovecot.org/bugreport.html > > Or at least I'd like to know what gdb says with: > > gdb /usr/local/libexec/dovecot/imap > l *0x8083037 > l *0x80833e6Unfortunately, it looks like I've built dovecot with stripped binaries, so there is no symbol information for the above locations. I'll rebuild with -g enabled and see if the bug can be reproduced. BTW, I have an strace of the crash if you are interested. I haven't posted it here since it contains user information, but I could send it directly to you.