On 07/12/2020 16:58, Jiri wrote:
> Hi All,
> on my older production server (Deb9 dovecot version 2.2.27-3+deb9u6) a
> client reported that searching text in one mailbox failed while other
> mailboxes do search ok.
> He is using Rouddcube webmail and is searching the whole messages
> including the body.
> Errorlog shows panic and assertion failed logs.
> So I'v moved the 'problematic' mailbox to my latest production server
> (Deb10 2.3.4.1-5+deb10u4 = 2.3.4.1 (f79e8e7e4)) where I have more RAM
> to eliminate that and the same error appeared.
> There is nothing special about the mailbox, roughly 600MiB and 2200
> messges.
> Other much larger mailboxes on the same server do search fine.
> After turning on imap logging in Roundcube mail, the critical command
> was:
> A0004 UID SEARCH RETURN (ALL) TEXT whatever
> I increased default_process_limit and default_client_limit, I run:
> doveadm index -u t...@mail2.swpraha.cz -q INBOX
> but didn't help.
>
>
> I used the gdb method to reproduce it and to get the whole info
> including backtrace:
>
> vmail@s6mail:~$ gdb --args /usr/lib/dovecot/imap -u t...@mail2.swpraha.cz
> (gdb) r
> Starting program: /usr/lib/dovecot/imap -u t...@mail2.swpraha.cz
> process 4458 is executing new program: /usr/bin/doveconf
> process 4458 is executing new program: /usr/lib/dovecot/imap
> * PREAUTH [CAPABILITY IMAP4rev1 SASL-IR LOGIN-REFERRALS ID ENABLE IDLE
> SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS THREAD=ORDEREDSUBJECT
> MULTIAPPEND URL-PARTIAL CATENATE UNSELECT CHILDREN NAMESPACE UIDPLUS
> LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES
> WITHIN CONTEXT=SEARCH LIST-STATUS BINARY MOVE SNIPPET=FUZZY LITERAL+
> NOTIFY SPECIAL-USE QUOTA] Logged in as t...@mail2.swpraha.cz
> a select INBOX
> * FLAGS (\Answered \Flagged \Deleted \Seen \Draft $MDNSent Junk
> $Forwarded NonJunk)
> * OK [PERMANENTFLAGS (\Answered \Flagged \Deleted \Seen \Draft
> $MDNSent Junk $Forwarded NonJunk \*)] Flags permitted.
> * 682 EXISTS
> * 0 RECENT
> * OK [UNSEEN 639] First unseen.
> * OK [UIDVALIDITY 1147771637] UIDs valid
> * OK [UIDNEXT 104487] Predicted next UID
> * OK [HIGHESTMODSEQ 53108] Highest
> a OK [READ-WRITE] Select completed (0.002 + 0.000 + 0.001 secs).
> A0004 UID SEARCH RETURN (ALL) TEXT whatewer
> imap(t...@mail2.swpraha.cz)<4458><Auy4NnMnzl9qEQAAevFY8w>: Panic: file
> message-parser.c: line 174 (message_part_finish): assertion failed:
> (ctx->nested_parts_count > 0)
> imap(t...@mail2.swpraha.cz)<4458><Auy4NnMnzl9qEQAAevFY8w>: Error: Raw
> backtrace: /usr/lib/dovecot/libdovecot.so.0(+0xdb62b) [0x7ffff7dbb62b]
> -> /usr/lib/dovecot/libdovecot.so.0(+0xdb661) [0x7ffff7dbb661] ->
> /usr/lib/dovecot/libdovecot.so.0(+0x4a149) [0x7ffff7d2a149] ->
> /usr/lib/dovecot/libdovecot.so.0(+0x474ac) [0x7ffff7d274ac] ->
> /usr/lib/dovecot/libdovecot.so.0(message_parser_parse_next_block+0x104)
> [0x7ffff7da3844] ->
> /usr/lib/dovecot/libdovecot.so.0(message_search_msg+0xa8)
> [0x7ffff7da5df8] -> /usr/lib/dovecot/libdovecot-storage.so.0(+0xcf89e)
> [0x7ffff7f3e89e] ->
> /usr/lib/dovecot/libdovecot-storage.so.0(mail_search_args_foreach+0x45)
> [0x7ffff7ec0445] -> /usr/lib/dovecot/libdovecot-storage.so.0(+0xd0774)
> [0x7ffff7f3f774] -> /usr/lib/dovecot/libdovecot-storage.so.0(+0xd1a68)
> [0x7ffff7f40a68] ->
> /usr/lib/dovecot/libdovecot-storage.so.0(index_storage_search_next_nonblock+0x10d)
> [0x7ffff7f4118d] ->
> /usr/lib/dovecot/libdovecot-storage.so.0(mailbox_search_next_nonblock+0x28)
> [0x7ffff7ec9e58] -> /usr/lib/dovecot/imap [t...@mail2.swpraha.cz UID
> SEARCH](+0x2691f) [0x55555557a91f] -> /usr/lib/dovecot/imap
> [t...@mail2.swpraha.cz UID SEARCH](command_exec+0x70) [0x555555573d80]
> -> /usr/lib/dovecot/imap [t...@mail2.swpraha.cz UID SEARCH](+0x25ed2)
> [0x555555579ed2] ->
> /usr/lib/dovecot/libdovecot.so.0(io_loop_handle_timeouts+0x111)
> [0x7ffff7dd18f1] ->
> /usr/lib/dovecot/libdovecot.so.0(io_loop_handler_run_internal+0xd0)
> [0x7ffff7dd3070] ->
> /usr/lib/dovecot/libdovecot.so.0(io_loop_handler_run+0x4c)
> [0x7ffff7dd1b7c] -> /usr/lib/dovecot/libdovecot.so.0(io_loop_run+0x40)
> [0x7ffff7dd1ce0] ->
> /usr/lib/dovecot/libdovecot.so.0(master_service_run+0x13)
> [0x7ffff7d520d3] -> /usr/lib/dovecot/imap [t...@mail2.swpraha.cz UID
> SEARCH](main+0x325) [0x555555564bf5] ->
> /lib/x86_64-linux-gnu/libc.so.6(__libc_start_main+0xeb)
> [0x7ffff7b3c09b] -> /usr/lib/dovecot/imap [t...@mail2.swpraha.cz UID
> SEARCH](_start+0x2a) [0x555555564d8a]
>
> Program received signal SIGABRT, Aborted.
> __GI_raise (sig=sig@entry=6) at ../sysdeps/unix/sysv/linux/raise.c:50
> 50 ../sysdeps/unix/sysv/linux/raise.c: No such file or directory.
> (gdb) bt full
> #0 __GI_raise (sig=sig@entry=6) at ../sysdeps/unix/sysv/linux/raise.c:50
> set = {__val = {0, 9355454118400172544, 1928, 1862, 1863,
> 93824992506008, 65, 140737352109185, 140737351192526, 140737351951989,
> 257, 145, 206158430224, 140737488344944, 140737488344736,
> 140737351756174}}
> pid = <optimized out>
> tid = <optimized out>
> ret = <optimized out>
> #1 0x00007ffff7b3a535 in __GI_abort () at abort.c:79
> save_stage = 1
> act = {__sigaction_handler = {sa_handler = 0x9ef, sa_sigaction
> = 0x9ef}, sa_mask = {__val = {30216, 93824992300426, 0, 513, 513,
> 140737351192526, 140737351744763, 140737351192526, 9355454118400172544,
> 140737351192526, 140737351951234, 140737488344944,
> 93824992503880, 140737351192526, 140737351951609, 140737351192526}},
> sa_flags = -136625910, sa_restorer = 0x5}
> sigs = {__val = {32, 0 <repeats 15 times>}}
> #2 0x00007ffff7d2a481 in ?? () from /usr/lib/dovecot/libdovecot.so.0
> No symbol table info available.
> #3 0x00007ffff7dbb661 in default_fatal_handler () from
> /usr/lib/dovecot/libdovecot.so.0
> No symbol table info available.
> #4 0x00007ffff7d2a149 in i_panic () from
> /usr/lib/dovecot/libdovecot.so.0
> No symbol table info available.
> #5 0x00007ffff7d274ac in ?? () from /usr/lib/dovecot/libdovecot.so.0
> No symbol table info available.
> #6 0x00007ffff7da3844 in message_parser_parse_next_block () from
> /usr/lib/dovecot/libdovecot.so.0
> No symbol table info available.
> #7 0x00007ffff7da5df8 in message_search_msg () from
> /usr/lib/dovecot/libdovecot.so.0
> No symbol table info available.
> #8 0x00007ffff7f3e89e in ?? () from
> /usr/lib/dovecot/libdovecot-storage.so.0
> No symbol table info available.
> #9 0x00007ffff7ec0445 in mail_search_args_foreach () from
> /usr/lib/dovecot/libdovecot-storage.so.0
> No symbol table info available.
> #10 0x00007ffff7f3f774 in ?? () from
> /usr/lib/dovecot/libdovecot-storage.so.0
> No symbol table info available.
> #11 0x00007ffff7f40a68 in ?? () from
> /usr/lib/dovecot/libdovecot-storage.so.0
> No symbol table info available.
> #12 0x00007ffff7f4118d in index_storage_search_next_nonblock () from
> /usr/lib/dovecot/libdovecot-storage.so.0
> No symbol table info available.
> #13 0x00007ffff7ec9e58 in mailbox_search_next_nonblock () from
> /usr/lib/dovecot/libdovecot-storage.so.0
> No symbol table info available.
> #14 0x000055555557a91f in ?? ()
> No symbol table info available.
> #15 0x0000555555573d80 in command_exec ()
> No symbol table info available.
> #16 0x0000555555579ed2 in ?? ()
> No symbol table info available.
> #17 0x00007ffff7dd18f1 in io_loop_handle_timeouts () from
> /usr/lib/dovecot/libdovecot.so.0
> No symbol table info available.
> #18 0x00007ffff7dd3070 in io_loop_handler_run_internal () from
> /usr/lib/dovecot/libdovecot.so.0
> No symbol table info available.
> #19 0x00007ffff7dd1b7c in io_loop_handler_run () from
> /usr/lib/dovecot/libdovecot.so.0
> No symbol table info available.
> #20 0x00007ffff7dd1ce0 in io_loop_run () from
> /usr/lib/dovecot/libdovecot.so.0
> No symbol table info available.
> #21 0x00007ffff7d520d3 in master_service_run () from
> /usr/lib/dovecot/libdovecot.so.0
> No symbol table info available.
> #22 0x0000555555564bf5 in main ()
> No symbol table info available.
> (gdb)
>
>
> <snip> Thanks for any suggestion
> --Jiri Kacena
Looks like this (or something very similar) was already fixed
https://github.com/dovecot/core/commit/a668d767a710ca18ab6e7177d8e8be22a6b024fb
John
