Panic: file mail-transaction-log-file.c: line 104 (mail_transaction_log_file_free): assertion failed: (!file->locked)

[Arkadiusz Miśkiewicz]

On Tuesday 01 of November 2016, Aki Tuomi wrote:
> On 25.08.2016 10:29, Aki Tuomi wrote:
> > On 14.07.2016 10:56, Arkadiusz Miśkiewicz wrote:
> >> 2.2.25 (also happens on 2.2.24). Happens every time I try to make
> >> deliver and only for this user:
> >> 
> >> Jul 14 09:52:02 mbox dovecot: lmtp(25601): Connect from local
> >> Jul 14 09:52:02 mbox dovecot: lmtp(powiadomienia):
> >> session=<CRU2EaJEh1cBZAAALZVUYQ>, Error: Index
> >> /var/mail/powiadomienia/dovecot.index: Lost log for seq=1009 offset=40:
> >> Missing middle file seq=1009 (between 1009..4294967295)
> >> Jul 14 09:52:02 mbox dovecot: lmtp(powiadomienia):
> >> session=<CRU2EaJEh1cBZAAALZVUYQ>, Warning: fscking index file
> >> /var/mail/powiadomienia/dovecot.index Jul 14 09:52:02 mbox dovecot:
> >> lmtp(powiadomienia): session=<CRU2EaJEh1cBZAAALZVUYQ>, Error: Fixed
> >> index file /var/mail/powiadomienia/dovecot.index: log_file_seq 1009 ->
> >> 1011 Jul 14 09:52:02 mbox dovecot: lmtp(powiadomienia):
> >> session=<CRU2EaJEh1cBZAAALZVUYQ>, Panic: file
> >> mail-transaction-log-file.c: line 104 (mail_transaction_log_file_free):
> >> assertion failed: (!file->locked) Jul 14 09:52:02 mbox dovecot:
> >> lmtp(powiadomienia): session=<CRU2EaJEh1cBZAAALZVUYQ>, Error: Raw
> >> backtrace: /usr/lib64/dovecot/libdovecot.so.0(+0x8d7d2)
> >> [0x7feb89fc97d2] -> /usr/lib64/dovecot/libdovecot.so.0(+0x8d8bd)
> >> [0x7feb89fc98bd] -> /usr/lib64/dovecot/libdovecot.so.0(i_fatal+0)
> >> [0x7feb89f67e31] ->
> >> /usr/lib64/dovecot/libdovecot-storage.so.0(mail_transaction_log_file_fr
> >> ee+0x160) [0x7feb8a331fa0] -> /usr/lib64/dovecot/libdovecot-
> >> storage.so.0(mail_transaction_logs_clean+0x4d) [0x7feb8a3360ed] ->
> >> /usr/lib64/dovecot/libdovecot-storage.so.0(mail_transaction_log_close+0
> >> x30) [0x7feb8a336230] -> /usr/lib64/dovecot/libdovecot-
> >> storage.so.0(mail_transaction_log_move_to_memory+0xd5) [0x7feb8a3363e5]
> >> ->
> >> /usr/lib64/dovecot/libdovecot-storage.so.0(mail_index_move_to_memory+0x
> >> a0) [0x7feb8a330440] -> /usr/lib64/dovecot/libdovecot-
> >> storage.so.0(mail_index_write+0x183) [0x7feb8a32e9d3] ->
> >> /usr/lib64/dovecot/libdovecot-storage.so.0(mail_index_fsck+0xc1f)
> >> [0x7feb8a3186ff] ->
> >> /usr/lib64/dovecot/libdovecot-storage.so.0(mail_index_sync_map+0x49b)
> >> [0x7feb8a322eab] ->
> >> /usr/lib64/dovecot/libdovecot-storage.so.0(mail_index_map+0x71)
> >> [0x7feb8a31a231] ->
> >> /usr/lib64/dovecot/libdovecot-storage.so.0(+0xe0fed) [0x7feb8a32ffed]
> >> -> /usr/lib64/dovecot/libdovecot-storage.so.0(+0xe15f3)
> >> [0x7feb8a3305f3] ->
> >> /usr/lib64/dovecot/libdovecot-storage.so.0(mail_index_open+0x78)
> >> [0x7feb8a3306d8] ->
> >> /usr/lib64/dovecot/libdovecot-storage.so.0(index_storage_mailbox_open+0
> >> x92) [0x7feb8a309202] ->
> >> /usr/lib64/dovecot/libdovecot-storage.so.0(+0x6c0e2) [0x7feb8a2bb0e2]
> >> -> /usr/lib64/dovecot/libdovecot-storage.so.0(+0x6c1c8)
> >> [0x7feb8a2bb1c8] ->
> >> /usr/lib64/dovecot/plugins/lib20_zlib_plugin.so(+0x2fdc)
> >> [0x7feb85697fdc] ->
> >> /usr/lib64/dovecot/libdovecot-storage.so.0(+0x450c6) [0x7feb8a2940c6]
> >> -> /usr/lib64/dovecot/libdovecot-storage.so.0(mailbox_open+0x20)
> >> [0x7feb8a294240] ->
> >> /usr/lib64/dovecot/libdovecot-lda.so.0(mail_deliver_save_open+0xad)
> >> [0x7feb8a58d1ad] ->
> >> /usr/lib64/dovecot/libdovecot-lda.so.0(mail_deliver_save+0xbb)
> >> [0x7feb8a58d48b] ->
> >> /usr/lib64/dovecot/libdovecot-lda.so.0(mail_deliver+0x123)
> >> [0x7feb8a58d9e3] -> dovecot/lmtp [DATA powiadomienia]() [0x406bc8] ->
> >> /usr/lib64/dovecot/libdovecot.so.0(io_loop_call_io+0x4c)
> >> [0x7feb89fdd67c] ->
> >> /usr/lib64/dovecot/libdovecot.so.0(io_loop_handler_run_internal+0x101)
> >> [0x7feb89fdeb01] ->
> >> /usr/lib64/dovecot/libdovecot.so.0(io_loop_handler_run+0x25)
> >> [0x7feb89fdd705]
> >> Jul 14 09:52:02 mbox dovecot: lmtp(powiadomienia):
> >> session=<CRU2EaJEh1cBZAAALZVUYQ>, Fatal: master: service(lmtp): child
> >> 25601 killed with signal 6 (core dumps disabled)
> > 
> > Hi!
> > 
> > Are you still able to reproduce this? Any hope for backtrace with gdb?
> > 
> > gdb /path/to/binary /path/to/core
> > bt full
> > 
> > Aki
> 
> Ping

Sorry, I deleted index for that login and things started to work again, so 
have no way to reproduce anymore.

Also no core dump for that issue.

When it happens again I'll check backtrace.

> Aki

-- 
Arkadiusz Miśkiewicz, arekm / ( maven.pl | pld-linux.org )