dovecot - Mar 2007 - assertion failed (1.0-rc27)

Under Dovecot 1.0-rc27 on Solaris 10 we noticed this error today affecting
one of our users repeatedly:

Mar 28 14:02:01 myhost dovecot: IMAP(myuser): file mbox-sync-rewrite.c: line 423
(mbox_sync_read_and_move): assertion failed: (need_space ==
(uoff_t)-mails[idx].space)
Mar 28 14:02:01 myhost dovecot: IMAP(myuser): Raw backtrace: 0x7ac54 ->
0x42cd4 -> 0x3e968 -> 0x3f54c -> 0x3f980 -> 0x6c834 -> 0x298a0
-> 0x2114c -> 0x212e8 -> 0x22d60 -> 0x22cd8 -> 0x22ed8 ->
0x81004 -> 0x808d8 -> 0x2b3f8 -> 0x1db5c
Mar 28 14:02:01 myhost dovecot: child 24818 (imap) killed with signal 6

I have no idea what the user experienced, as we simply noticed this in the
logs (which appear on our consoles, too).

I hope this is enough information, as we do not run with core dumps enabled
on our production servers.

-- 

Steven F. Siirila			Office: Lind Hall, Room 130B
Internet Services			E-mail: sfs at umn.edu
Office of Information Technology	Voice: (612) 626-0244
University of Minnesota			Fax: (612) 626-7593

On 28.3.2007, at 22.13, Steven F Siirila wrote:
> Under Dovecot 1.0-rc27 on Solaris 10 we noticed this error today  
> affecting
> one of our users repeatedly:
>
> Mar 28 14:02:01 myhost dovecot: IMAP(myuser): file mbox-sync- 
> rewrite.c: line 423 (mbox_sync_read_and_move): assertion failed:  
> (need_space == (uoff_t)-mails[idx].space)
In rc28 I've changed this assert to something that prints more useful  
information. This assert alone doesn't really tell anything what the  
problem could have been.

Anyway if it happened repeatedly, it would be nice to get the  
anonymized mbox and index files. See http://wiki.dovecot.org/ 
MboxProblems

-------------- next part --------------
A non-text attachment was scrubbed...
Name: PGP.sig
Type: application/pgp-signature
Size: 186 bytes
Desc: This is a digitally signed message part
URL:
<http://dovecot.org/pipermail/dovecot/attachments/20070328/ea9d5adc/attachment.bin>

On Wed, Mar 28, 2007 at 10:24:40PM +0300, Timo Sirainen
wrote:
> On 28.3.2007, at 22.13, Steven F Siirila wrote:
> 
> >Under Dovecot 1.0-rc27 on Solaris 10 we noticed this error today  
> >affecting
> >one of our users repeatedly:
> >
> >Mar 28 14:02:01 myhost dovecot: IMAP(myuser): file mbox-sync- 
> >rewrite.c: line 423 (mbox_sync_read_and_move): assertion failed:  
> >(need_space == (uoff_t)-mails[idx].space)
> 
> In rc28 I've changed this assert to something that prints more useful  
> information. This assert alone doesn't really tell anything what the  
> problem could have been.
> 
> Anyway if it happened repeatedly, it would be nice to get the  
> anonymized mbox and index files. See http://wiki.dovecot.org/ 
> MboxProblems
I have reproduced this on a test box; here is the backtrace from the core
that was generated:

#0  0xff1c16e8 in _sys_siginfolist_data () from /lib/libc.so.1
#1  0xff15ff40 in __strxfrm_std () from /lib/libc.so.1
#2  0xff140160 in getutxline () from /lib/libc.so.1
#3  0x0007b1b8 in i_internal_panic_handler (
    fmt=0x929c8 "file %s: line %d (%s): assertion failed: (%s)", 
    args=0xffbfec98) at failures.c:403
#4  0x0007ac5c in i_panic (
    format=0x929c8 "file %s: line %d (%s): assertion failed: (%s)")
    at failures.c:183
#5  0x00042cdc in mbox_sync_rewrite (sync_ctx=0xffbff3f8, mail_ctx=0x92800, 
    end_offset=13387, move_diff=40611, extra_space=4295021294, first_seq=1, 
    last_seq=163) at mbox-sync-rewrite.c:579
#6  0x0003e970 in mbox_sync_do (sync_ctx=0xffbff3f8, flags=4290769568)
    at mbox-sync.c:1332
#7  0x0003f554 in mbox_sync (mbox=0xc1c48, flags=MBOX_SYNC_UNDIRTY)
    at mbox-sync.c:1800
#8  0x0003f988 in mbox_storage_sync_init (box=0xc1c48, 
    flags=MAILBOX_SYNC_FLAG_FULL_READ) at mbox-sync.c:1869
#9  0x0006c83c in mailbox_sync_init (box=0xc1c48, 
    flags=MAILBOX_SYNC_FLAG_FULL_READ) at mail-storage.c:406
#10 0x000298a8 in imap_sync_nonselected (box=0xc1c48, 
    flags=MAILBOX_SYNC_FLAG_FULL_READ) at imap-sync.c:196
#11 0x00021154 in _cmd_select_full (cmd=0xbbd7c, readonly=false)
    at cmd-select.c:39
#12 0x000212f0 in cmd_select (cmd=0xbbd7c) at cmd-select.c:92
#13 0x00022d68 in client_handle_input (cmd=0xbbd7c) at client.c:332
#14 0x00022ce0 in client_handle_input (cmd=0xbbd7c) at client.c:389
#15 0x00022ee0 in _client_input (context=0xbbd38) at client.c:432
#16 0x0008100c in io_loop_handler_run (ioloop=0xb9530) at ioloop-poll.c:199
#17 0x000808c8 in io_loop_run (ioloop=0xb9530) at ioloop.c:323
#18 0x0002b400 in main (argc=-4195374, argv=0xaf800, envp=0xb0be4)
    at main.c:287

I still have the core, in case there is other information from gdb that
would be useful in diagnosing this.

-- 

Steven F. Siirila			Office: Lind Hall, Room 130B
Internet Services			E-mail: sfs at umn.edu
Office of Information Technology	Voice: (612) 626-0244
University of Minnesota			Fax: (612) 626-7593