[UPDATE]: Another Crash in service imap with version 2.2.13 - Debian Wheezy

Urban Loesch bind at enas.net
Thu Jun 26 13:10:46 UTC 2014


Hi,

short update.
I switched back to Debian Squeeze. Same Dovecot Version 2.2.13.
The crash happens also on Squeeze.

Very strange is that the crash don't happened yesterday.
And it happens only to one certain user. Other users with the same iOS "os-version=7.1.1 (11D201)" aren't affected.

Also very strange is, that the crash doens't happen every time (I don't saw that before).

Logs in cronological order from the last attempt:
...
Jun 26 15:00:07 mailstore dovecot: imap-login: ID sent: x-session-id=1ji1xbz8fACXEq4b, x-originating-ip=CLIENT_IP, x-originating-port=51580,
x-connected-ip=PROXY_IP, x-connected-port=143, x-proxy-ttl=4: user=<>, rip=CLIENT_IP, lip=PROXY_IP, secured, session=<1ji1xbz8fACXEq4b>
Jun 26 15:00:08 mailstore dovecot: imap-login: Login: user=<user at domain.net>, method=PLAIN, rip=CLIENT_IP, lip=PROXY_IP, mpid=6407, secured,
session=<1ji1xbz8fACXEq4b>
Jun 26 15:00:08 mailstore dovecot: imap(user at domain.net pid:6407 session:<1ji1xbz8fACXEq4b>): ID sent: name=iPhone Mail, version=11D201, os=iOS,
os-version=7.1.1 (11D201)
Jun 26 15:00:09 mailstore dovecot: imap(user at domain.net pid:6407 session:<1ji1xbz8fACXEq4b>): Fatal: master: service(imap): child 6407 killed with
signal 11 (core dumped)

Jun 26 15:00:09 mailstore dovecot: imap-login: ID sent: x-session-id=hWTKxbz8gACXEq4b, x-originating-ip=CLIENT_IP, x-originating-port=51584,
x-connected-ip=PROXY_IP, x-connected-port=143, x-proxy-ttl=4: user=<>, rip=CLIENT_IP, lip=PROXY_IP, secured, session=<hWTKxbz8gACXEq4b>
Jun 26 15:00:09 mailstore dovecot: imap-login: Login: user=<user at domain.net>, method=PLAIN, rip=CLIENT_IP, lip=PROXY_IP, mpid=46064, secured,
session=<hWTKxbz8gACXEq4b>
Jun 26 15:00:09 mailstore dovecot: imap(user at domain.net pid:46064 session:<hWTKxbz8gACXEq4b>): ID sent: name=iPhone Mail, version=11D201, os=iOS,
os-version=7.1.1 (11D201)
Jun 26 15:00:09 mailstore dovecot: imap(user at domain.net pid:46064 session:<hWTKxbz8gACXEq4b>): Fatal: master: service(imap): child 46064 killed with
signal 11 (core dumped)

Jun 26 15:00:18 mailstore dovecot: imap-login: ID sent: x-session-id=M7BRxrz8iQCXEq4b, x-originating-ip=CLIENT_IP, x-originating-port=51593,
x-connected-ip=PROXY_IP, x-connected-port=143, x-proxy-ttl=4: user=<>, rip=CLIENT_IP, lip=PROXY_IP, secured, session=<M7BRxrz8iQCXEq4b>
Jun 26 15:00:18 mailstore dovecot: imap-login: Login: user=<user at domain.net>, method=PLAIN, rip=CLIENT_IP, lip=PROXY_IP, mpid=41143, secured,
session=<M7BRxrz8iQCXEq4b>
Jun 26 15:00:18 mailstore dovecot: imap(user at domain.net pid:41143 session:<M7BRxrz8iQCXEq4b>): ID sent: name=iPhone Mail, version=11D201, os=iOS,
os-version=7.1.1 (11D201)
Jun 26 15:02:17 mailstore dovecot: imap(user at domain.net pid:41143 session:<M7BRxrz8iQCXEq4b>): Connection closed bytes=341/1991 session=<M7BRxrz8iQCXEq4b>
...

The last session has been endet normallly. Very strange to me.

I think this is a problem only with that specific user and his Iphone.
On the other hand, the crash isn't fine at all.

Thanks
Urban Loesch



-------- Original-Nachricht --------
Betreff: Another Crash in service imap with version 2.2.13 - Debian Wheezy
Datum: Thu, 26 Jun 2014 09:25:27 +0200
Von: Urban Loesch <bind at enas.net>
Antwort an: Dovecot Mailing List <dovecot at dovecot.org>
An: Dovecot Mailing List <dovecot at dovecot.org>

Hi,

yesterday I updated my second server from Debian Squeeze to Debian Wheezy.
Since todaay I get the followinig errors in my logs:

Error-Log:
...
Jun 26 09:08:28 mailstore dovecot: imap(user at domain.net pid:28898 session:<iuMX3Lf8fACXLrFC>): Fatal: master: service(imap): child 28898 killed with
signal 11 (core dumped)
...

Mail-log
...
Jun 26 09:08:28 mailstore dovecot: imap-login: ID sent: x-session-id=iuMX3Lf8fACXLrFC, x-originating-ip=CLIENT_IP, x-originating-port=52092,
x-connected-ip=PROXY_IP, x-connected-port=143, x-proxy-ttl=4: user=<>, rip=CLIENT_IP, lip=PROXY_IP, secured, session=<iuMX3Lf8fACXLrFC>
Jun 26 09:08:28 mailstore dovecot: imap-login: Login: user=<user at domain.net>, method=PLAIN, rip=CLIENT_IP, lip=PROXY_IP, mpid=28898, secured,
session=<iuMX3Lf8fACXLrFC>
Jun 26 09:08:28 mailstore dovecot: imap(user at domain.net pid:28898 session:<iuMX3Lf8fACXLrFC>): ID sent: name=iPhone Mail, version=11D201, os=iOS,
os-version=7.1.1 (11D201)
Jun 26 09:08:28 mailstore dovecot: imap(user at domain.net pid:28898 session:<iuMX3Lf8fACXLrFC>): Fatal: master: service(imap): child 28898 killed with
signal 11 (core dumped)
...

I made a backtrace:

-----> start backtrace <-----
[Thread debugging using libthread_db enabled]
Using host libthread_db library "/lib/x86_64-linux-gnu/libthread_db.so.1".
Core was generated by `dovecot/imap'.
Program terminated with signal 11, Segmentation fault.
#0  0x0000000000000000 in ?? ()
(gdb) bt full
#0  0x0000000000000000 in ?? ()
No symbol table info available.
#1  0x00007fbf26650c44 in mailbox_save_cancel (_ctx=<optimized out>) at mail-storage.c:2116
        ctx = 0xa95500
        keywords = 0x0
        mail = <optimized out>
#2  0x00007fbf2665104f in mailbox_save_begin (ctx=ctx at entry=0x7fff64b53048, input=0xaaaea0) at mail-storage.c:2041
        box = 0xa6f800
        ret = <optimized out>
#3  0x00007fbf26648bb6 in mail_storage_try_copy (mail=0xa90d20, _ctx=0x7fff64b53048) at mail-copy.c:76
        ctx = 0xa95500
        pmail = 0xa90d20
        input = 0xaaaea0
#4  mail_storage_copy (ctx=0x0, mail=0xa90d20) at mail-copy.c:101
No locals.
#5  0x00007fbf24f12679 in notify_copy (ctx=0xa95500, mail=0xa90d20) at notify-storage.c:107
        lt = 0xa8bdd0
        lbox = 0xa70870
        ret = <optimized out>
#6  0x00007fbf2573d06b in acl_copy (ctx=0xa95500, mail=0xa90d20) at acl-mailbox.c:435
        t = <optimized out>
        abox = 0xa6fe48
#7  0x00007fbf26650e8d in mailbox_copy (_ctx=_ctx at entry=0x7fff64b53190, mail=0xa90d20) at mail-storage.c:2152
        ctx = 0xa95500
        t = 0xa8de20
        keywords = 0x0
        pvt_flags = 0
        backend_mail = 0xa90d20
        ret = <optimized out>
        __FUNCTION__ = "mailbox_copy"
#8  0x000000000040dd34 in fetch_and_copy (copy_count_r=<synthetic pointer>, src_uidset_r=<synthetic pointer>, search_args=<optimized out>,
src_trans_r=0x7fff64b53168, t=0xa8de20, move=false, client=0xa00f40) at cmd-copy.c:70
        search_ctx = 0xa90a80
        src_trans = 0xa8f3f0
        srcset_ctx = {str = 0x9d4318, first_uid = 0, last_uid = 4294967295}
        ret = 1
        save_ctx = 0x0
        mail = 0xa90d20
        copy_count = 1
        src_uidset = 0x9d4318
#9  cmd_copy_full (cmd=0xa01a50, move=false) at cmd-copy.c:123
        client = 0xa00f40
        dest_storage = <optimized out>
        destbox = 0xa6f800
        t = 0xa8de20
        src_trans = <optimized out>
        search_args = 0xa6e7f0
        messageset = 0xa04410 "1309,1310"
        mailbox = 0xa04420 "[rolmail]/Unseen"
        src_uidset = <optimized out>
        sync_flags = 0
        imap_flags = 0
        changes = {pool = 0x7fff64b53290, uid_validity = 1689596352, saved_uids = {arr = {buffer = 0x9dd0f0, element_size = 10492496}, v = 0x9dd0f0,
v_modifiable = 0x9dd0f0}, ignored_modseq_changes = 10492496, changed = false,
          no_read_perm = false}
        copy_count = <optimized out>
        msg = <optimized out>
        ret = <optimized out>
        __FUNCTION__ = "cmd_copy_full"
#10 0x000000000041841c in command_exec (cmd=cmd at entry=0xa01a50) at imap-commands.c:158
        hook = 0x9dd100
        ret = <optimized out>
#11 0x0000000000417480 in client_command_input (cmd=cmd at entry=0xa01a50) at imap-client.c:778
        client = 0xa00f40
---Type <return> to continue, or q <return> to quit---
        command = <optimized out>
        __FUNCTION__ = "client_command_input"
#12 0x0000000000417514 in client_command_input (cmd=0xa01a50) at imap-client.c:839
        client = 0xa00f40
        command = <optimized out>
        __FUNCTION__ = "client_command_input"
#13 0x00000000004177f5 in client_handle_next_command (remove_io_r=<synthetic pointer>, client=0xa00f40) at imap-client.c:877
No locals.
#14 client_handle_input (client=client at entry=0xa00f40) at imap-client.c:889
        _data_stack_cur_id = 3
        ret = 2
        remove_io = false
        handled_commands = false
        __FUNCTION__ = "client_handle_input"
#15 0x0000000000417ba2 in client_input (client=0xa00f40) at imap-client.c:931
        cmd = <optimized out>
        output = 0xa062d0
        bytes = 39
        __FUNCTION__ = "client_input"
#16 0x00007fbf2637478e in io_loop_call_io (io=0x9ffb60) at ioloop.c:439
        ioloop = 0x9dc740
        t_id = <optimized out>
        __FUNCTION__ = "io_loop_call_io"
#17 0x00007fbf263757b7 in io_loop_handler_run_internal (ioloop=<optimized out>) at ioloop-epoll.c:206
        ctx = 0x9dd3d0
        events = 0xa955f0
        event = 0x9de240
        list = 0x9dee30
        io = 0xa955f0
        tv = {tv_sec = 29, tv_usec = 742827}
        events_count = <optimized out>
        msecs = <optimized out>
        ret = 1
        i = <optimized out>
        call = <optimized out>
        __FUNCTION__ = "io_loop_handler_run_internal"
#18 0x00007fbf26374819 in io_loop_call_io (io=0x9dc740) at ioloop.c:443
        ioloop = 0x7fff64b533f0
        t_id = 0
        __FUNCTION__ = "io_loop_call_io"
#19 0x00007fbf26321a23 in master_service_run (service=0x9dc740, callback=callback at entry=0x420d20 <client_connected>) at master-service.c:566
No locals.
#20 0x000000000040c1e8 in main (argc=1, argv=0x9dc390) at main.c:410
        set_roots = {0x428960, 0x0}
        login_set = {auth_socket_path = 0x9d4048 "\001", postlogin_socket_path = 0x0, postlogin_timeout_secs = 60, callback = 0x420bb0
<login_client_connected>, failure_callback = 0x4208c0 <login_client_failed>, request_auth_token = 1}
        service_flags = <optimized out>
        storage_service_flags = <optimized out>
        username = 0x9dc5d0 "@ǝ"
        c = <optimized out>
-----> end backtrace <-----

Have you any idea how I can solve this?

Many thanks
Urban Loesch

doveconf -n:
# 2.2.13 (705fd8f3f485): /etc/dovecot/dovecot.conf
# OS: Linux 3.4.67-vs2.3.3.9-rol-em64t-efigpt x86_64 Debian 7.5 ext4
auth_cache_negative_ttl = 0
auth_cache_size = 40 M
auth_cache_ttl = 1 weeks
auth_mechanisms = plain login
deliver_log_format = msgid=%m: %$ %p %w
disable_plaintext_auth = no
info_log_path = syslog
login_trusted_networks = INTERNAL_IP
mail_gid = mailstore
mail_location = mdbox:/home/vmail/%d/%n
mail_log_prefix = "%s(%u pid:%p session:<%{session}>): "
mail_plugins = " quota mail_log notify acl zlib stats virtual"
mail_uid = mailstore
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 duplicate imapflags notify
mdbox_rotate_size = 10 M
namespace {
  list = children
  location = mdbox:/home/vmail/%%d/%%n
  prefix = shared/%%u/
  separator = /
  subscriptions = no
  type = shared
}
namespace {
  hidden = no
  inbox = no
  list = children
  location = virtual:/home/virtual:INDEX=~/virtual
  prefix = [mymail]/
  separator = /
  subscriptions = yes
  type = private
}
namespace inbox {
  inbox = yes
  location =
  mailbox Drafts {
    special_use = \Drafts
  }
  mailbox Junk {
    special_use = \Junk
  }
  mailbox Sent {
    special_use = \Sent
  }
  mailbox "Sent Items" {
    special_use = \Sent
  }
  mailbox "Sent Messages" {
    special_use = \Sent
  }
  mailbox Trash {
    special_use = \Trash
  }
  mailbox [mymail]/All {
    auto = no
    special_use = \All
  }
  prefix =
  separator = /
  type = private
}
passdb {
  args = /etc/dovecot/dovecot-sql-account.conf
  driver = sql
}
plugin {
  acl = vfile
  acl_shared_dict = file:/home/vmail/%d/shared-mailboxes
  mail_log_events = delete undelete expunge copy mailbox_delete mailbox_rename flag_change save mailbox_create append
  mail_log_fields = uid box msgid size from
  mail_log_group_events = no
  quota = dict:Storage used::file:%h/dovecot-quota
  quota_rule2 = Trash:storage=+100M
  quota_warning = storage=95%% quota-warning 95 %u
  quota_warning2 = storage=80%% quota-warning 80 %u
  sieve = ~/.dovecot.sieve
  sieve_dir = ~/sieve
  sieve_extensions = +notify +imapflags
  sieve_max_redirects = 15
  stats_command_min_time = 1 mins
  stats_domain_min_time = 12 hours
  stats_ip_min_time = 12 hours
  stats_memory_limit = 16 M
  stats_refresh = 30 secs
  stats_session_min_time = 15 mins
  stats_track_cmds = no
  stats_user_min_time = 1 hours
  zlib_save = gz
  zlib_save_level = 9
}
protocols = imap pop3 lmtp sieve
service auth {
  unix_listener auth-userdb {
    group = mailstore
    mode = 0660
    user = root
  }
}
service imap-login {
  inet_listener imap {
    port = 143
  }
  process_limit = 48
  process_min_avail = 3
  service_count = 1
}
service imap {
  process_limit = 48
  process_min_avail = 2
  service_count = 1
}
service lmtp {
  inet_listener lmtp {
    port = 24
  }
  unix_listener /var/spool/postfix/private/dovecot-lmtp {
    group = postfix
    mode = 0666
    user = postfix
  }
}
service pop3-login {
  inet_listener pop3 {
    port = 110
  }
  process_limit = 16
  process_min_avail = 2
  service_count = 1
}
service pop3 {
  process_limit = 16
  process_min_avail = 2
  service_count = 1
}
service quota-warning {
  executable = script /usr/local/rol/dovecot/quota-warning.sh
  unix_listener quota-warning {
    user = mailstore
  }
  user = mailstore
}
service stats {
  fifo_listener stats-mail {
    mode = 0600
    user = mailstore
  }
}
ssl = no
ssl_cert = </etc/dovecot/certs/dovecot.pem
ssl_key = </etc/dovecot/private/dovecot.pem
userdb {
  args = /etc/dovecot/dovecot-sql-account.conf
  driver = sql
}
protocol lmtp {
  mail_fsync = optimized
  mail_plugins = " quota mail_log notify acl zlib stats virtual sieve zlib"
}
protocol imap {
  imap_client_workarounds = tb-extra-mailbox-sep
  imap_id_log = *
  imap_logout_format = bytes=%i/%o session=<%{session}>
  mail_max_userip_connections = 20
  mail_plugins = " quota mail_log notify acl zlib stats virtual imap_quota imap_acl imap_zlib imap_stats"
}
protocol pop3 {
  mail_plugins = " quota mail_log notify acl zlib stats virtual"
  pop3_client_workarounds = outlook-no-nuls oe-ns-eoh
  pop3_lock_session = yes
  pop3_logout_format = bytes_sent=%o top=%t/%p, retr=%r/%b, del=%d/%m, size=%s uidl_hash=%u session=<%{session}>
  pop3_reuse_xuidl = yes
}





More information about the dovecot mailing list