[bt full included] imap-login: Panic: file client-common.c: line 272 (client_destroy): assertion failed: (client->create_finished)

Tue Feb 20 00:14:30 EET 2018

Hi,

as discussed with cmouse on freenode two days ago please find attached 
necessary debug information on a crashing imap-login process, that 
appeared after upgrading to v2.3 (+ sieve). It is a standard setup in 
combination with postfix and thunderbird, roundcube and K9(Android) as 
imap clients. Panic happens 5-6 times a day, but no influence on user 
experience. Unfortunately i have no idea what might be causing it.

Let me know if you need more information.

Andreas

syslog: Feb 19 21:09:37 hafnium.d9c.eu dovecot[14324]: imap-login: 
Fatal: master: service(imap-login): child 30653 killed with signal 6 
(core dumped)

dovecot.log:
Feb 19 20:57:07 imap(user at d9c.eu)<30655><7C/91pVl0q3CJ7cC>: Info: 
Connection closed (IDLE running for 0.001 + waiting input for 1109.126 
secs, 2 B in + 10 B out, state=wait-input) in=699 out=
Feb 19 20:57:24 imap(user at d9c.eu)<30656><PuD+1pVl1a3CJ7cC>: Info: 
Connection closed (IDLE running for 0.001 + waiting input for 1123.798 
secs, 2 B in + 10 B out, state=wait-input) in=91 out=1
Feb 19 21:02:15 imap(user at d9c.eu)<30999><IS1w55VlKKrDJbhD>: Info: 
Connection closed (IDLE running for 0.001 + waiting input for 1143.235 
secs, 2 B in + 10 B out, state=wait-input) in=686 out=
Feb 19 21:02:23 imap(user at d9c.eu)<30998><5B5v55VlJqrDJbhD>: Info: 
Connection closed (IDLE running for 0.001 + waiting input for 1147.566 
secs, 2 B in + 10 B out, state=wait-input) in=99 out=1
Feb 19 21:08:36 imap(user at d9c.eu)<30657><D7QC15Vl1q3CJ7cC>: Info: 
Disconnected for inactivity in=91 out=919 deleted=0 expunged=0 trashed=0 
hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Feb 19 21:08:37 imap(user at d9c.eu)<30658><n6EG15Vl163CJ7cC>: Info: 
Disconnected for inactivity in=719 out=5345 deleted=0 expunged=0 
trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0

dovecot -n

# 2.3.0 (c8b89eb): /etc/dovecot/dovecot.conf
# Pigeonhole version 0.5.0.1 (d33dca20)
# OS: Linux 4.15.3-2-ARCH x86_64
auth_debug = yes
auth_mechanisms = plain login
auth_verbose = yes
debug_log_path = /var/log/dovecot-debug.log
info_log_path = /var/log/dovecot.log
login_log_format_elements = user=<%u> method=%m rip=%r lip=%l mpid=%e %c 
%k
mail_gid = vmail
mail_home = /app/vmail/%d/%n
mail_location = sdbox:%h/sdbox
mail_plugins = " quota"
mail_uid = vmail
managesieve_notify_capability = mailto
managesieve_sieve_capability = fileinto reject envelope 
encoded-character vacation subaddress comparator-i;ascii-numeric 
relational regex imap4flags copy include variables body enotify 
environment mailbox date index ihave duplicate mime foreverypart 
extracttext editheader
mdbox_rotate_interval = 1 days
mdbox_rotate_size = 8 M
namespace inbox {
   inbox = yes
   location =
   mailbox Drafts {
     special_use = \Drafts
   }
   mailbox Junk {
     special_use = \Junk
   }
   mailbox Sent {
     special_use = \Sent
   }
   mailbox "Sent Messages" {
     special_use = \Sent
   }
   mailbox Trash {
     special_use = \Trash
   }
   prefix =
}
passdb {
   args = /etc/dovecot/dovecot-sql.conf.ext
   driver = sql
}
plugin {
   quota = dict:user::file:%h/mdbox/dovecot-quota
   sieve = ~/.dovecot.sieve
   sieve_dir = ~/sieve
   sieve_extensions = +editheader
}
protocols = imap pop3 lmtp sieve
service auth-worker {
   user = $default_internal_user
}
service auth {
   unix_listener /var/spool/postfix-sasl/private/auth {
     group = postfix
     mode = 0660
     user = postfix
   }
}
service imap-login {
   chroot =
   executable = imap-login -D
   inet_listener imap {
     port = 143
   }
   inet_listener imaps {
     port = 0
   }
   service_count = 0
}
service lmtp {
   unix_listener /var/spool/postfix-mail/private/dovecot-lmtp {
     group = postfix
     mode = 0660
     user = postfix
   }
}
service managesieve-login {
   inet_listener sieve {
     port = 4190
   }
   process_min_avail = 1
}
service pop3-login {
   inet_listener pop3 {
     port = 0
   }
   inet_listener pop3s {
     port = 995
     ssl = yes
   }
}
ssl = required
ssl_cert = </etc/cert.ec.crt
ssl_cipher_list = 
ECDHE-ECDSA-CHACHA20-POLY1305:ECDHE-ECDSA-AES256-GCM-SHA384:ECDHE-ECDSA-AES256-SHA384:ECDHE-ECDSA-AES128-GCM-SHA256
ssl_dh =  # hidden, use -P to show it
ssl_key =  # hidden, use -P to show it
ssl_min_protocol = TLSv1.2
ssl_prefer_server_ciphers = yes
userdb {
   driver = prefetch
}
userdb {
   driver = static
}
protocol lmtp {
   mail_plugins = " quota sieve"
}
protocol imap {
   imap_client_workarounds = tb-extra-mailbox-sep
   mail_max_userip_connections = 25
   mail_plugins = " quota imap_quota"
}

(gdb) bt full
#0  0x00007fddd9433860 in raise () from /usr/lib/libc.so.6
No symbol table info available.
#1  0x00007fddd9434ec9 in abort () from /usr/lib/libc.so.6
No symbol table info available.
#2  0x00007fddd987a521 in default_fatal_finish (type=LOG_TYPE_PANIC, 
status=status at entry=0) at failures.c:228
         backtrace = 0x561a9c8612f0 
"/usr/lib/dovecot/libdovecot.so.0(+0xc4567) [0x7fddd987a567] -> 
/usr/lib/dovecot/libdovecot.so.0(+0xc462a) [0x7fddd987a62a] -> 
/usr/lib/dovecot/libdovecot.so.0(i_fatal+0) [0x7fddd97e9df9] -> 
/usr/lib/d"...
         recursed = 0
#3  0x00007fddd987a62a in i_internal_fatal_handler (ctx=0x7ffdbd74cd70, 
format=<optimized out>, args=<optimized out>) at failures.c:718
         status = 0
#4  0x00007fddd97e9df9 in i_panic (format=format at entry=0x7fddd9b35a88 
"file %s: line %d (%s): assertion failed: (%s)") at failures.c:306
         ctx = {type = LOG_TYPE_PANIC, exit_status = 0, timestamp = 0x0, 
timestamp_usecs = 0, log_prefix = 0x0}
         args = {{gp_offset = 40, fp_offset = 48, overflow_arg_area = 
0x7ffdbd74ce70, reg_save_area = 0x7ffdbd74cdb0}}
#5  0x00007fddd9b2e2e9 in client_destroy (client=<optimized out>, 
reason=reason at entry=0x7fddd9b35e21 "Disconnected: Shutting down") at 
client-common.c:272
         __func__ = "client_destroy"
#6  0x00007fddd9b2e624 in clients_destroy_all_reason 
(reason=0x7fddd9b35e21 "Disconnected: Shutting down") at 
client-common.c:430
         client = <optimized out>
         next = 0x0
#7  0x0000561a9aeeb12a in imap_login_deinit () at 
imap-login-client.c:764
No locals.
#8  0x00007fddd9b34587 in main_deinit () at main.c:475
         strp = <optimized out>
         strp = <optimized out>
         strp_end = <optimized out>
#9  login_binary_run (binary=<optimized out>, argc=<optimized out>, 
argv=<optimized out>) at main.c:550
         set_pool = 0x561a9c86b100
         login_socket = 0x7fddd9b37ab9 "login"
         c = <optimized out>
#10 0x00007fddd941ff4a in __libc_start_main () from /usr/lib/libc.so.6
No symbol table info available.
#11 0x0000561a9aeeaeaa in _start ()
No symbol table info available.
(gdb)