dovecot - Jul 2013 - dovecot 2.2 Panic: pool_data_stack_realloc(): stack frame changed

Hi again,

we've been trying dovecot 2.2 in our setup and we see thousands of 
messages like these :

Jul  4 12:29:47 pop01 dovecot: lmtp(2899): Debug: auth input: 
rigakis2 at otenet.gr home=/var/mail/folders/U/9/5/rigakis2 
quota_rule=*:storage=50M uid=531846 gid=100 
mail=mbox:~/:INBOX=/var/mail/U/9/5
/rigakis2:INDEX=/indexes/4/1/b/rigakis2 at otenet.gr
Jul  4 12:29:47 pop01 dovecot: lmtp(2899): Debug: Added userdb setting: 
mail=mbox:~/:INBOX=/var/mail/U/9/5/rigakis2:INDEX=/indexes/4/1/b/rigakis2 at
otenet.gr
Jul  4 12:29:47 pop01 dovecot: lmtp(2899, rigakis2 at otenet.gr): Debug: 
Effective uid=531846, gid=100, home=/var/mail/folders/U/9/5/rigakis2
Jul  4 12:29:47 pop01 dovecot: lmtp(2899, rigakis2 at otenet.gr): Debug: 
Quota root: name=User quota backend=dict args=:noenforcing:proxy::sqlquota
Jul  4 12:29:47 pop01 dovecot: lmtp(2899, rigakis2 at otenet.gr): Debug: 
Quota rule: root=User quota mailbox=* bytes=52428800 messages=0
Jul  4 12:29:47 pop01 dovecot: lmtp(2899, rigakis2 at otenet.gr): Debug: 
Quota grace: root=User quota bytes=5242880 (10%)
Jul  4 12:29:47 pop01 dovecot: lmtp(2899, rigakis2 at otenet.gr): Debug: 
dict quota: user=rigakis2 at otenet.gr, uri=proxy::sqlquota, noenforcing=1
Jul  4 12:29:47 pop01 dovecot: lmtp(2899, rigakis2 at otenet.gr): Debug: 
fs: root=/var/mail/folders/U/9/5/rigakis2, 
index=/indexes/4/1/b/rigakis2 at otenet.gr, indexpvt=, control=, 
inbox=/var/mail/U/9/5/
rigakis2, altJul  4 12:29:47 pop01 dovecot: lmtp(2899, rigakis2 at otenet.gr):
Debug:
sieve: Pigeonhole version 0.4.0 initializing
Jul  4 12:29:47 pop01 dovecot: lmtp(2899, rigakis2 at otenet.gr): Debug: 
sieve: include: sieve_global_dir is not set; it is currently not 
possible to include `:global' scripts.
Jul  4 12:29:47 pop01 dovecot: lmtp(2899, rigakis2 at otenet.gr): Debug: 
o6pTL5k/1VFTCwAAYg/qxw: sieve: using the following location for user's 
Sieve script: /var/mail/folders/U/9/5/rigakis2/.sieve;na
me=main script
Jul  4 12:29:47 pop01 dovecot: lmtp(2899, rigakis2 at otenet.gr): Debug: 
o6pTL5k/1VFTCwAAYg/qxw: sieve: loading script 
/var/mail/folders/U/9/5/rigakis2/.sieve;name=main script
Jul  4 12:29:47 pop01 dovecot: lmtp(2899, rigakis2 at otenet.gr): Debug: 
o6pTL5k/1VFTCwAAYg/qxw: sieve: script binary 
/var/mail/folders/U/9/5/rigakis2/.sieve.svbin successfully loaded
Jul  4 12:29:47 pop01 dovecot: lmtp(2899, rigakis2 at otenet.gr): Debug: 
o6pTL5k/1VFTCwAAYg/qxw: sieve: binary save: not saving binary 
/var/mail/folders/U/9/5/rigakis2/.sieve.svbin, because it is alre
ady stored
Jul  4 12:29:47 pop01 dovecot: lmtp(2899, rigakis2 at otenet.gr): Debug: 
o6pTL5k/1VFTCwAAYg/qxw: sieve: executing script from 
/var/mail/folders/U/9/5/rigakis2/.sieve.svbin
Jul  4 12:29:47 pop01 dovecot: lmtp(2899, rigakis2 at otenet.gr): Panic: 
pool_data_stack_realloc(): stack frame changed
Jul  4 12:29:47 pop01 dovecot: lmtp(2899, rigakis2 at otenet.gr): Error: 
Raw backtrace: /opt/dovecot-2.2.4/lib/dovecot/libdovecot.so.0 
[0x2b0066488bb0] -> /opt/dovecot-2.2.4/lib/dovecot/libdovecot.so.
0 [0x2b0066488c06] -> /opt/dovecot-2.2.4/lib/dovecot/libdovecot.so.0 
[0x2b0066487dc3] -> /opt/dovecot-2.2.4/lib/dovecot/libdovecot.so.0 
[0x2b006649c5d1] -> /opt/dovecot-2.2.4/lib/dovecot/libdovecot
.so.0 [0x2b00664844ef] -> 
/opt/dovecot-2.2.4/lib/dovecot/libdovecot.so.0(buffer_write+0xf6) 
[0x2b0066484956] -> 
/opt/dovecot-2.2.4/lib/dovecot/libdovecot-sieve.so.0(rfc2822_header_append+0x35)
[0x2
b0066b2c695] -> 
/opt/dovecot-2.2.4/lib/dovecot/libdovecot-sieve.so.0(rfc2822_header_printf+0xbd)
[0x2b0066b2cbdd] -> /opt/dovecot-2.2.4/lib/dovecot/libdovecot-sieve.so.0 
[0x2b0066b6141c] -> /opt/do
vecot-2.2.4/lib/dovecot/libdovecot-sieve.so.0 [0x2b0066b5fd63] -> 
/opt/dovecot-2.2.4/lib/dovecot/libdovecot-sieve.so.0(sieve_result_execute+0x220)
[0x2b0066b407b0] -> /opt/dovecot-2.2.4/lib/dovecot
/libdovecot-sieve.so.0(sieve_execute+0x
Jul  4 12:29:47 pop01 dovecot: lmtp(2899, rigakis2 at otenet.gr): Fatal: 
master: service(lmtp): child 2899 killed with signal 6 (core not dumped)

I tried to get a core dump but I can't, apparently I'm doing something 
wrong.

Any ideas ?

dovecot -n :
# 2.2.4: /opt/dovecot-2.2.4/etc/dovecot/dovecot.conf
# OS: Linux 2.6.18-348.6.1.el5 x86_64 CentOS release 5.9 (Final)
auth_cache_negative_ttl = 10 mins
auth_cache_size = 5 M
auth_cache_ttl = 10 mins
auth_verbose = yes
default_client_limit = 5000
default_process_limit = 500
deliver_log_format = msgid=%m: from=%f size=%p %$
dict {
   sqlquota = mysql:/opt/dovecot/etc/dovecot/dovecot-dict-sql.conf.ext
}
disable_plaintext_auth = no
first_valid_uid = 20
lda_mailbox_autocreate = yes
lda_mailbox_autosubscribe = yes
listen = *
log_timestamp = "%Y-%m-%d %H:%M:%S "
login_greeting = OTENET ready
login_trusted_networks = 83.235.66.0/24
mail_access_groups = mail otemail disk root
mail_fsync = always
mail_nfs_index = yes
mail_nfs_storage = yes
mail_plugins = quota
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 ihave imapflags notify
mbox_lock_timeout = 3 secs
mbox_read_locks = dotlock fcntl
mmap_disable = yes
passdb {
   args = /opt/dovecot/etc/dovecot/dovecot-ldap.conf.ext
   driver = ldap
}
plugin {
   mail_log_events = delete undelete expunge copy mailbox_delete 
mailbox_rename flag_change append
   mail_log_fields = uid box msgid size
   quota = dict:User quota::noenforcing:proxy::sqlquota
   sieve = ~/.sieve
   sieve_dir = ~/sieve
   sieve_extensions = +notify +imapflags
}
postmaster_address = postmaster at otenet.gr
quota_full_tempfail = yes
service auth-worker {
   user = dovenull
}
service dict {
   unix_listener dict {
     group = otemail
     mode = 0666
   }
}
service imap-login {
   inet_listener imap {
     port = 143
   }
   inet_listener imaps {
     port = 993
     ssl = yes
   }
}
service lmtp {
   client_limit = 1
   inet_listener lmtp {
     port = 24
   }
}
service pop3-login {
   inet_listener pop3 {
     port = 110
   }
   inet_listener pop3s {
     port = 995
     ssl = yes
   }
}
service quota-warning {
   executable = script /opt/dovecot/etc/dovecot/quota-warning.sh
   user = dovecot
}
ssl = no
userdb {
   args = /opt/dovecot/etc/dovecot/dovecot-ldap.conf.ext
   driver = ldap
}
verbose_proctitle = yes
protocol lmtp {
   mail_plugins = quota sieve
}
protocol lda {
   mail_plugins = quota sieve
}
protocol imap {
   imap_client_workarounds = delay-newmail tb-extra-mailbox-sep
   mail_max_userip_connections = 100
   mail_plugins = quota imap_quota notify
}
protocol pop3 {
   mail_max_userip_connections = 100
   mail_plugins = quota notify
   pop3_client_workarounds = outlook-no-nuls oe-ns-eoh
   pop3_fast_size_lookups = yes
   pop3_lock_session = yes
   pop3_reuse_xuidl = yes
   pop3_uidl_format = %08Xu%08Xv
}

Never mind I just saw there's a newer version of pigeonhole that fixes 
it. Sigh...

Changelog v0.4.1:

  - Fixed assertion failure due to datastack problem in message header
    composition.

Dalevizo