[Dovecot] uid=error and BUG: Unknown internal error dovecot 2.0.13

Robert Schetterer robert at schetterer.org
Tue Jun 7 22:30:04 EEST 2011


Am 07.06.2011 17:22, schrieb Robert Schetterer:
> Am 07.06.2011 16:28, schrieb Timo Sirainen:
>> On Tue, 2011-06-07 at 15:23 +0200, Robert Schetterer wrote:
>>> Hi Timo,
>>> i have a few ( not really many )
>>> errors like this with lmtp
>>>
>>> Jun  7 09:41:23 mail02 dovecot: lmtp(11034, user at user.de):
>>> DELlOoq+7U0aKwAAZA6IsQ: msgid=<4DEDD502.705020 at ameriton.com>: save
>>> failed to INBOX: BUG: Unknown internal error
>>
>> Something went wrong. Really shouldn't be happening.
>>
>>> Jun  7 09:36:22 mail01 dovecot: lmtp(26456, user at user.de): save:
>>> box=INBOX, uid=error,
>>> msgid=<OF671FDBE9.DD479224-ONC12578A8.00282832-C12578A8.0029C38D at hella.com>,
>>> size=7192
>>
>> Did this also get some error before that log line? Something went wrong
>> with finding the message's UID during saving..
> 
> havent super verbose logging enabled yet, this servers are massive in
> production with huge logs, lines found out by grep
> looking deeper by editor to the log i would say mail is stored whatever
> uid error was before and wasnt reported before ( with this small verbose )
>>
>>> any idea ?
>>
>> dovecot -n output would help guessing the reason.
> 
> # 2.0.13 (e76a3313a46c): /etc/dovecot/dovecot.conf
> # OS: Linux 2.6.35-25-generic x86_64 Ubuntu 10.04.2 LTS
> auth_mechanisms = plain login
> auth_socket_path = /var/run/dovecot/auth-userdb
> auth_verbose = yes
> auth_verbose_passwords = plain
> auth_worker_max_count = 300
> default_client_limit = 1024
> default_process_limit = 512
> dict {
>   quotadict = mysql:/etc/dovecot/dovecot-dict-quota-sql.conf.ext
> }
> disable_plaintext_auth = no
> first_valid_gid = 1001
> first_valid_uid = 1001
> hostname = host.host.de
> last_valid_gid = 1001
> last_valid_uid = 1001
> listen = *
> login_greeting = imap, pop ready
> mail_access_groups = vmail
> mail_fsync = always
> mail_gid = 1001
> mail_location = maildir:~/
> mail_nfs_index = yes
> mail_nfs_storage = yes
> mail_plugins = fts fts_squat mail_log autocreate notify
> mail_privileged_group = vmail
> mail_uid = 1001
> 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
> mmap_disable = yes
> namespace default {
>   hidden = no
>   inbox = yes
>   list = yes
>   location =
>   prefix =
>   separator = /
>   subscriptions = yes
>   type = private
> }
> passdb {
>   args = /etc/dovecot/dovecot-sql.conf.ext
>   driver = sql
> }
> plugin {
>   autocreate = Trash
>   autocreate2 = Junk
>   autocreate3 = Sent
>   autocreate4 = Drafts
>   autocreate5 = Templates
>   autocreate6 = Archives
>   autosubscribe = Trash
>   autosubscribe2 = Junk
>   autosubscribe3 = Sent
>   autosubscribe4 = Drafts
>   autosubscribe5 = Templates
>   autosubscribe6 = Archives
>   fts = squat
>   home = /usr/local/virtual/%d/%u
>   mail_log_events = delete undelete expunge copy mailbox_delete
> mailbox_rename
>   mail_log_fields = uid box msgid size
>   mail_log_group_events = yes
>   quota = dict:::proxy::quotadict
>   quota_warning = storage=95%% quota-warning 95 %u
>   quota_warning2 = storage=80%% quota-warning 80 %u
>   sieve = /usr/local/virtual/%d/%u/dovecot.sieve
>   sieve_dir = /usr/local/virtual/%d/%u/sieve
> }
> postmaster_address = hostmaster at host.host.de
> protocols = imap pop3 lmtp sieve
> sendmail_path = /usr/lib/sendmail
> service anvil {
>   client_limit = 10000
> }
> service auth-worker {
>   user = root
> }
> service auth {
>   client_limit = 10000
>   unix_listener /var/spool/postfix/private/auth {
>     group = postfix
>     mode = 0666
>     user = postfix
>   }
>   unix_listener auth-userdb {
>     group = vmail
>     mode = 0666
>     user = vmail
>   }
> }
> service dict {
>   extra_groups = vmail
>   unix_listener dict {
>     group = vmail
>     mode = 0660
>   }
> }
> service imap-login {
>   inet_listener imap {
>     port = 143
>   }
>   inet_listener imaps {
>     port = 993
>     ssl = yes
>   }
>   process_min_avail = 64
>   service_count = 1
> }
> service imap-postlogin {
>   executable = script-login /usr/local/bin/postlogin_imap.sh
>   user = root
> }
> service imap {
>   executable = imap imap-postlogin
>   process_limit = 1024
>   vsz_limit = 256 M
> }
> service lmtp {
>   unix_listener /var/spool/postfix/private/dovecot-lmtp {
>     group = postfix
>     mode = 0660
>     user = postfix
>   }
>   vsz_limit = 256 M
> }
> service managesieve-login {
>   inet_listener {
>     address = 127.0.0.1
>     port = 2000
>   }
>   vsz_limit = 256 M
> }
> service pop3-login {
>   inet_listener pop3 {
>     port = 110
>   }
>   inet_listener pop3s {
>     port = 995
>     ssl = yes
>   }
> }
> service pop3-postlogin {
>   executable = script-login /usr/local/bin/postlogin_pop3.sh
>   user = root
> }
> service pop3 {
>   executable = pop3 pop3-postlogin
>   process_limit = 1024
> }
> service quota-warning {
>   executable = script /usr/local/bin/quota-warning.sh
>   unix_listener quota-warning {
>     mode = 0666
>     user = vmail
>   }
>   user = vmail
> }
> ssl_ca = </etc/postfix/wildcard.pem
> ssl_cert = </etc/postfix/wildcard.pem
> ssl_key = </etc/postfix/wildcard.pem
> userdb {
>   args = /etc/dovecot/dovecot-sql.conf.ext
>   driver = sql
> }
> verbose_proctitle = yes
> protocol lda {
>   mail_plugins = quota sieve mail_log notify
> }
> protocol imap {
>   imap_client_workarounds = delay-newmail
>   imap_id_log = *
>   imap_id_send = *
>   imap_idle_notify_interval = 2 mins
>   imap_logout_format = bytes=%i/%o
>   imap_max_line_length = 128 k
>   mail_max_userip_connections = 100
>   mail_plugins = fts fts_squat mail_log autocreate notify quota imap_quota
> }
> protocol lmtp {
>   mail_plugins = quota sieve fts fts_squat mail_log notify
> }
> protocol sieve {
>   mail_max_userip_connections = 100
>   managesieve_implementation_string = Dovecot
>   managesieve_logout_format = bytes=%i/%o
> }
> protocol pop3 {
>   mail_max_userip_connections = 100
>   mail_plugins = quota mail_log fts fts_squat notify
>   pop3_client_workarounds = outlook-no-nuls oe-ns-eoh
>   pop3_enable_last = yes
>   pop3_logout_format = top=%t/%p, retr=%r/%b, del=%d/%m, size=%s
>   pop3_reuse_xuidl = no
>   pop3_uidl_format = %g
> }
> 
>>
>>> is it critical, possible to loose mails with this ?
>>
>> Shouldn't be possible to lose mails.
> 
> nobody reported it so far
>>
>>
> 
> 
 i have a nearly equal setup with same errors but more rare
( not so much traffic/mailboxes )

here i found with more verbose logging

Jun  7 17:47:19 mail01 dovecot: lmtp(3554, user at user.de): Debug:
Effective uid=1001, gid=1001, home=/usr/local/virtual/user.de/user at user.de/
Jun  7 17:47:19 mail01 dovecot: lmtp(3554, user at user.de): save:
box=INBOX, uid=error,
msgid=<42002737.2472109201307461419534.JavaMail.beawl at server.com>,
size=11968



other ok logs look like i.e

Jun  7 07:24:57 mail01 dovecot: lmtp(24871, user at user.de): Debug:
Effective uid=1001, gid=1001, home=/usr/local/virtual/user.de/user at user.de/
Jun  7 07:24:57 mail01 dovecot: lmtp(24871, user at user.de): save:
box=INBOX/welcome, uid=1661,
msgid=<20110607052457.07E04780040 at www.server.com>, size=10059

perhaps this helps


-- 
Best Regards

MfG Robert Schetterer

Germany/Munich/Bavaria


More information about the dovecot mailing list