dovecot - Jan 2009 - Bug or other issue? Jan 22 00:02:09 argon postfix/pipe[21251]: E8126A3671: to=<mbj@seonic.net>, orig_to=<root>, relay=dovecot, delay=0.38, delays=0.03/0/0/0.35, dsn=5.3.0, status=bounced (Command died with signal 6: "/usr/libexec/dovecot/deliver")

Hi,

I found the following in my logfiles: 
The failure is not reproducable at the moment. 
System is delivering mails to mailboxes with no problems at all.

It is a production installation with medium load.

dovecot --version: 1.1.7
OS: Linux (Gentoo/x86 stable) - grsec enabled kernel

deliver.log:

deliver(user at example.tld): Panic: file istream-tee.c: line 144
(i_stream_tee_read): assertion failed: (ret > 0)
deliver(user at example.tld): 
  Error: Raw backtrace: 
     /usr/libexec/dovecot/deliver [0x80c8032] -> 
    /usr/libexec/dovecot/deliver(default_fatal_handler+0x3a) [0x80c812a] -> 
    /usr/libexec/dovecot/deliver [0x80c796c] -> 
    /usr/libexec/dovecot/deliver [0x80cf5f8] -> 
    /usr/libexec/dovecot/deliver(i_stream_read+0x2d) [0x80cc5dd] -> 
    /usr/libexec/dovecot/deliver(i_stream_read_data+0x1d) [0x80cc6ed] -> 
    /usr/libexec/dovecot/deliver [0x80d52cb] -> 
    /usr/libexec/dovecot/deliver(o_stream_send_istream+0x35) [0x80d45b5] -> 
    /usr/libexec/dovecot/deliver(maildir_save_continue+0x32) [0x806fbb2] -> 
    /usr/libexec/dovecot/deliver(mail_storage_copy+0xd2) [0x8096512] -> 
    /usr/libexec/dovecot/deliver(maildir_copy+0x5b) [0x806c51b] -> 
    /usr/libexec/dovecot/deliver(deliver_save+0x17d) [0x805cbfd] -> 
    /usr/libexec/dovecot/deliver(main+0x161b) [0x805e41b] -> 
    /lib/libc.so.6(__libc_start_main+0xdc) [0x559a7fdc] -> 
    /usr/libexec/dovecot/deliver [0x805bb41]

mta.log (postfix with dovecot/deliver pipe transport):
  Jan 22 00:02:09 argon postfix/pipe[21251]: E8126A3671: to=<user at
example.tld>, orig_to=<localalias>, relay=dovecot, delay=0.38,
delays=0.03/0/0/0.35, dsn=5.3.0, status=bounced (Command died with signal 6:
"/usr/libexec/dovecot/deliver")

Neither the grsec.log nor dmesg said anything special.

The box had filesystem issues a month ago. I sucessufly fscked it. Do you think
its a hidden fs problem or a dovecot issue?

Here in Germay the time is at 0:30, I'll sleep some hours until I can
respond to "need more information request (conf-file, or so)"

Thx in advance!

Markus Schirp

Sry for this monster subject.
Fingers pasted something wrong....

On Thu, Jan 22, 2009 at 12:32:04AM +0100, Markus Schirp
wrote:
> Hi,
> 
> I found the following in my logfiles: 
> The failure is not reproducable at the moment. 
> System is delivering mails to mailboxes with no problems at all.
> 
> It is a production installation with medium load.
> 
> dovecot --version: 1.1.7
> OS: Linux (Gentoo/x86 stable) - grsec enabled kernel
> 
> deliver.log:
> 
> deliver(user at example.tld): Panic: file istream-tee.c: line 144
(i_stream_tee_read): assertion failed: (ret > 0)
> deliver(user at example.tld): 
>   Error: Raw backtrace: 
>      /usr/libexec/dovecot/deliver [0x80c8032] -> 
>     /usr/libexec/dovecot/deliver(default_fatal_handler+0x3a) [0x80c812a]
->
>     /usr/libexec/dovecot/deliver [0x80c796c] -> 
>     /usr/libexec/dovecot/deliver [0x80cf5f8] -> 
>     /usr/libexec/dovecot/deliver(i_stream_read+0x2d) [0x80cc5dd] -> 
>     /usr/libexec/dovecot/deliver(i_stream_read_data+0x1d) [0x80cc6ed] ->
>     /usr/libexec/dovecot/deliver [0x80d52cb] -> 
>     /usr/libexec/dovecot/deliver(o_stream_send_istream+0x35) [0x80d45b5]
->
>     /usr/libexec/dovecot/deliver(maildir_save_continue+0x32) [0x806fbb2]
->
>     /usr/libexec/dovecot/deliver(mail_storage_copy+0xd2) [0x8096512] -> 
>     /usr/libexec/dovecot/deliver(maildir_copy+0x5b) [0x806c51b] -> 
>     /usr/libexec/dovecot/deliver(deliver_save+0x17d) [0x805cbfd] -> 
>     /usr/libexec/dovecot/deliver(main+0x161b) [0x805e41b] -> 
>     /lib/libc.so.6(__libc_start_main+0xdc) [0x559a7fdc] -> 
>     /usr/libexec/dovecot/deliver [0x805bb41]
> 
> mta.log (postfix with dovecot/deliver pipe transport):
>   Jan 22 00:02:09 argon postfix/pipe[21251]: E8126A3671: to=<user at
example.tld>, orig_to=<localalias>, relay=dovecot, delay=0.38,
delays=0.03/0/0/0.35, dsn=5.3.0, status=bounced (Command died with signal 6:
"/usr/libexec/dovecot/deliver")
> 
> Neither the grsec.log nor dmesg said anything special.
> 
> The box had filesystem issues a month ago. I sucessufly fscked it. Do you
think its a hidden fs problem or a dovecot issue?
> 
> Here in Germay the time is at 0:30, I'll sleep some hours until I can
> respond to "need more information request (conf-file, or so)"
> 
> Thx in advance!
> 
> Markus Schirp
-- 
Markus Schirp
Seonic IT-Systems GbR
Anton Shatalov & Markus Schirp
Altenessenerstra?e 50
D-45141 Essen
www.seonic.net
info at seonic.net

On Thu, 2009-01-22 at 00:32 +0100, Markus Schirp wrote:
> deliver(user at example.tld): Panic: file istream-tee.c: line 144
(i_stream_tee_read): assertion failed: (ret > 0)
Looks like a bug. I'll see if I can figure out how to fix it.
> The box had filesystem issues a month ago. I sucessufly fscked it. Do you
think its a hidden fs problem or a dovecot issue?
It's most likely a Dovecot bug. The only alternative is a random
CPU/memory bug. In any case it's not a filesystem problem.

-------------- next part --------------
A non-text attachment was scrubbed...
Name: signature.asc
Type: application/pgp-signature
Size: 197 bytes
Desc: This is a digitally signed message part
URL:
<http://dovecot.org/pipermail/dovecot/attachments/20090125/963c31e6/attachment-0002.bin>