November 2013 - dovecot

[Dovecot] Dovecot 2.2.5: Panic: file mail-transaction-log.c: line 350 (mail_transaction_log_set_mailbox_sync_pos)
by Rob Dosogne 20 Nov '13

20 Nov '13

Hello, I have been seeing an issue with Dovecot 2.2.5 (which is the current version packaged by cPanel). This is happening with any action that tries to access the mailbox index via IMAP. This log and debug information comes from a force-resync attempt, however, the same errors are logged when the user tries to open the mailbox or move a message to it. (User, domain, and IPs in log have been changed for privacy reasons). System is a CentOS 5.10 (x64) VPS under Virtuozzo 4.0, and is not OOMing or hitting any container limits. User is connecting from multiple devices on the same IP, most of which are Apple Mail & iOS. Other users with only Apple Mail & iOS devices are also experiencing the same errors. Log: Nov 19 15:55:33 host dovecot: imap-login: Login: user=<user(a)domain.com>, method=PLAIN, rip=1.2.3.4, lip=5.6.7.8, mpid=13887, TLS, session=<A+FrZpDr/wAYzVli> Nov 19 15:55:33 host dovecot: imap: Debug: Loading modules from directory: /usr/lib64/dovecot Nov 19 15:55:33 host dovecot: imap: Debug: Module loaded: /usr/lib64/dovecot/lib01_acl_plugin.so Nov 19 15:55:33 host dovecot: imap: Debug: Module loaded: /usr/lib64/dovecot/lib10_quota_plugin.so Nov 19 15:55:33 host dovecot: imap: Debug: Module loaded: /usr/lib64/dovecot/lib11_imap_quota_plugin.so Nov 19 15:55:33 host dovecot: imap: Debug: Added userdb setting: mail=maildir:/home/username/mail/domain.com/user Nov 19 15:55:33 host dovecot: imap(user(a)domain.com): Debug: Effective uid=504, gid=502, home=/home/username/mail/domain.com/user Nov 19 15:55:33 host dovecot: imap(user(a)domain.com): Debug: acl: No acl_shared_dict setting - shared mailbox listing is disabled Nov 19 15:55:33 host dovecot: imap(user(a)domain.com): Debug: Quota root: name= backend=maildir args= Nov 19 15:55:33 host dovecot: imap(user(a)domain.com): Debug: Quota rule: root= mailbox=INBOX.Trash ignored Nov 19 15:55:33 host dovecot: imap(user(a)domain.com): Debug: Quota grace: root= bytes=0 (10%) Nov 19 15:55:33 host dovecot: imap(user(a)domain.com): Debug: Namespace : type=private, prefix=INBOX., sep=, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/home/username/mail/domain.com/user Nov 19 15:55:33 host dovecot: imap(user(a)domain.com): Debug: maildir++: root=/home/username/mail/domain.com/user, index=, indexpvt=, control=, inbox=/home/username/mail/domain.com/user, alt= Nov 19 15:55:33 host dovecot: imap(user(a)domain.com): Debug: acl: initializing backend with data: vfile Nov 19 15:55:33 host dovecot: imap(user(a)domain.com): Debug: acl: acl username = user(a)domain.com Nov 19 15:55:33 host dovecot: imap(user(a)domain.com): Debug: acl: owner = 1 Nov 19 15:55:33 host dovecot: imap(user(a)domain.com): Debug: acl vfile: Global ACL directory: (none) Nov 19 15:55:33 host dovecot: imap(user(a)domain.com): Debug: Namespace : type=private, prefix=, sep=, inbox=no, hidden=yes, list=no, subscriptions=no location=fail::LAYOUT=none Nov 19 15:55:33 host dovecot: imap(user(a)domain.com): Debug: none: root=, index=, indexpvt=, control=, inbox=, alt= Nov 19 15:55:33 host dovecot: imap(user(a)domain.com): Debug: acl vfile: file /home/username/mail/domain.com/user/.TrainSpam/dovecot-acl not found Nov 19 15:55:33 host dovecot: imap(user(a)domain.com): Debug: acl vfile: file /home/username/mail/domain.com/user/.Spam/dovecot-acl not found Nov 19 15:55:35 host dovecot: imap(user(a)domain.com): Error: broken sync positions in index file /home/username/mail/domain.com/user/.Spam/dovecot.index Nov 19 15:55:35 host dovecot: imap(user(a)domain.com): Warning: fscking index file /home/username/mail/domain.com/user/.Spam/dovecot.index Nov 19 15:55:35 host dovecot: imap(user(a)domain.com): Error: Fixed index file /home/username/mail/domain.com/user/.Spam/dovecot.index: log_file_tail_offset 3988 -> 3868 Nov 19 15:55:35 host dovecot: imap(user(a)domain.com): Panic: file mail-transaction-log.c: line 350 (mail_transaction_log_set_mailbox_sync_pos): assertion failed: (file_offset >= log->head->saved_tail_offset) Nov 19 15:55:35 host dovecot: imap(user(a)domain.com): Error: Raw backtrace: /usr/lib64/dovecot/libdovecot.so.0 [0x2b07be142400] -> /usr/lib64/dovecot/libdovecot.so.0 [0x2b07be142456] -> /usr/lib64/dovecot/libdovecot.so.0 [0x2b07be141df3] -> /usr/lib64/dovecot/libdovecot-storage.so.0 [0x2b07bdea066b] -> /usr/lib64/dovecot/libdovecot-storage.so.0(mail_index_sync_commit+0x89) [0x2b07bde97ed9] -> /usr/lib64/dovecot/libdovecot-storage.so.0 [0x2b07bde25e4d] -> /usr/lib64/dovecot/libdovecot-storage.so.0(maildir_transaction_save_commit_pre+0x856) [0x2b07bde22186] -> /usr/lib64/dovecot/libdovecot-storage.so.0 [0x2b07bde81cc8] -> /usr/lib64/dovecot/libdovecot-storage.so.0(mail_index_transaction_commit_full+0x86) [0x2b07bde8f466] -> /usr/lib64/dovecot/libdovecot-storage.so.0(index_transaction_commit+0xa5) [0x2b07bde820a5] -> /usr/lib64/dovecot/lib10_quota_plugin.so [0x2b07bef5252b] -> /usr/lib64/dovecot/lib01_acl_plugin.so [0x2b07bed40f77] -> /usr/lib64/dovecot/libdovecot-storage.so.0(mailbox_transaction_commit_ge Nov 19 15:55:35 host dovecot: imap(user(a)domain.com): Fatal: master: service(imap): child 13887 killed with signal 6 (core dumped) Debug: Core was generated by `dovecot/imap'. Program terminated with signal 6, Aborted. #0 0x00002b07be3e0265 in raise () from /lib64/libc.so.6 (gdb) bt full #0 0x00002b07be3e0265 in raise () from /lib64/libc.so.6 No symbol table info available. #1 0x00002b07be3e1d10 in abort () from /lib64/libc.so.6 No symbol table info available. #2 0x00002b07be14240d in default_fatal_finish (type=<value optimized out>, status=0) at failures.c:191 backtrace = 0x1773c798 "/usr/lib64/dovecot/libdovecot.so.0 [0x2b07be142400] -> /usr/lib64/dovecot/libdovecot.so.0 [0x2b07be142456] -> /usr/lib64/dovecot/libdovecot.so.0 [0x2b07be141df3] -> /usr/lib64/dovecot/libdovecot-stora"... #3 0x00002b07be142456 in i_internal_fatal_handler (ctx=0x7fff3c65f120, format=<value optimized out>, args=<value optimized out>) at failures.c:652 status = 0 #4 0x00002b07be141df3 in i_panic (format=0x363f <Address 0x363f out of bounds>) at failures.c:263 ctx = {type = LOG_TYPE_PANIC, exit_status = 0, timestamp = 0x0} args = {{gp_offset = 40, fp_offset = 48, overflow_arg_area = 0x7fff3c65f1f0, reg_save_area = 0x7fff3c65f130}} #5 0x00002b07bdea066b in mail_transaction_log_set_mailbox_sync_pos (log=<value optimized out>, file_seq=<value optimized out>, file_offset=<value optimized out>) at mail-transaction-log.c:350 __FUNCTION__ = "mail_transaction_log_set_mailbox_sync_pos" #6 0x00002b07bde97ed9 in mail_index_sync_update_mailbox_offset (_ctx=0x1776e588) at mail-index-sync.c:751 seq = 4 offset = 3868 #7 mail_index_sync_commit (_ctx=0x1776e588) at mail-index-sync.c:795 ctx = 0x17787570 index = 0x17770cf0 next_uid = <value optimized out> want_rotate = <value optimized out> index_undeleted = false delete_index = false ret = <value optimized out> #8 0x00002b07bde25e4d in maildir_sync_index_finish (ctx=0x1776e570, success=<value optimized out>) at maildir-sync-index.c:331 mbox = 0x1776ea30 time_diff = <value optimized out> ret = 0 #9 0x00002b07bde22186 in maildir_transaction_save_commit_pre (_ctx=0x177800e0) at maildir-save.c:1029 ctx = <value optimized out> _t = 0x17779bc0 sync_flags = <value optimized out> ret = 0 __FUNCTION__ = "maildir_transaction_save_commit_pre" #10 0x00002b07bde81cc8 in index_transaction_index_commit (index_trans=0x17779cb0, result_r=0x7fff3c65f470) at index-transaction.c:51 t = 0x17779bc0 pvt_sync_ctx = 0x0 ret = 0 __FUNCTION__ = "index_transaction_index_commit" #11 0x00002b07bde8f466 in mail_index_transaction_commit_full (_t=0x7fff3c65f498, result_r=0x7fff3c65f470) at mail-index-transaction.c:262 t = 0x17779cb0 index = 0x17770cf0 index_undeleted = false #12 0x00002b07bde820a5 in index_transaction_commit (t=0x17779bc0, changes_r=0x7fff3c65f5a0) at index-transaction.c:194 box = 0x1776ea30 itrans = 0x0 result = {log_file_seq = 0, log_file_offset = 0, commit_size = 0, ignored_modseq_changes = 0} ret = 0 #13 0x00002b07bef5252b in quota_mailbox_transaction_commit (ctx=0x17779bc0, changes_r=0x7fff3c65f5a0) at quota-storage.c:134 qt = 0x1777a890 #14 0x00002b07bed40f77 in acl_transaction_commit (ctx=0x17779bc0, changes_r=0x7fff3c65f5a0) at acl-mailbox.c:451 abox = 0x1776f048 ret = <value optimized out> #15 0x00002b07bde53975 in mailbox_transaction_commit_get_changes (_t=<value optimized out>, changes_r=0x7fff3c65f5a0) at mail-storage.c:1847 _data_stack_cur_id = 4 t = 0x17779bc0 save_count = 1 ret = <value optimized out> __FUNCTION__ = "mailbox_transaction_commit_get_changes" #16 0x000000000040c0d3 in cmd_copy_full () No symbol table info available. #17 0x000000000040c39b in cmd_copy () No symbol table info available. #18 0x0000000000414d97 in command_exec () No symbol table info available. #19 0x0000000000413b9b in client_command_input () No symbol table info available. #20 0x0000000000413c5f in client_command_input () No symbol table info available. #21 0x0000000000413e0f in client_handle_input () No symbol table info available. #22 0x0000000000414212 in client_input () No symbol table info available. #23 0x00002b07be150f16 in io_loop_call_io (io=0x1775c450) at ioloop.c:387 ioloop = 0x17744720 t_id = 2 #24 0x00002b07be15238d in io_loop_handler_run (ioloop=<value optimized out>) at ioloop-epoll.c:215 ctx = <value optimized out> event = 0x17746210 list = 0x1775c4a0 io = 0x363f tv = {tv_sec = 1799, tv_usec = 999752} events_count = <value optimized out> msecs = <value optimized out> ret = 1 i = 0 call = false __FUNCTION__ = "io_loop_handler_run" #25 0x00002b07be150ebd in io_loop_run (ioloop=0x17744720) at ioloop.c:406 No locals. #26 0x00002b07be107223 in master_service_run (service=0x177445b0, callback=0x363f) at master-service.c:566 No locals. #27 0x000000000041d437 in main () No symbol table info available. # dovecot -n # 2.2.5: /etc/dovecot/dovecot.conf # OS: Linux 2.6.18-028stab101.1 x86_64 CentOS release 5.10 (Final) auth_cache_size = 2 k auth_mechanisms = plain login auth_username_chars = "abcdefghijklmnopqrstuvwxyzABCDEFGHIJKLMNOPQRSTUVWXYZ0123456789!#$-=?^_{}~./@+%" disable_plaintext_auth = no lock_method = dotlock mail_debug = yes mailbox_idle_check_interval = 15 secs mmap_disable = yes namespace { inbox = yes location = prefix = INBOX. type = private } passdb { args = /usr/local/cpanel/bin/dovecot-wrap driver = checkpassword } plugin { acl = vfile quota = maildir quota_rule = INBOX.Trash:ignore } protocols = imap pop3 service auth { unix_listener auth-client { mode = 0666 } } service imap-login { client_limit = 500 inet_listener imap { address = * } inet_listener imaps { address = * } process_limit = 100 process_min_avail = 2 service_count = 1 vsz_limit = 64 M } service imap { process_limit = 512 vsz_limit = 256 M } service managesieve-login { client_limit = 500 process_limit = 100 process_min_avail = 2 service_count = 1 vsz_limit = 64 M } service managesieve { process_limit = 512 vsz_limit = 256 M } service pop3-login { client_limit = 500 inet_listener pop3 { address = * } inet_listener pop3s { address = * } process_limit = 100 process_min_avail = 2 service_count = 1 vsz_limit = 64 M } service pop3 { process_limit = 512 vsz_limit = 256 M } ssl_cert = </etc/dovecot/ssl/dovecot.crt ssl_cipher_list = ALL:!ADH:RC4+RSA:+HIGH:+MEDIUM:-LOW:-SSLv2:-EXP ssl_key = </etc/dovecot/ssl/dovecot.key userdb { driver = prefetch } userdb { args = /usr/local/cpanel/bin/dovecot-wrap driver = checkpassword } verbose_proctitle = yes protocol imap { imap_idle_notify_interval = 15 mins imap_logout_format = in=%i, out=%o, bytes=%i/%o mail_max_userip_connections = 100 mail_plugins = acl quota imap_quota } protocol pop3 { mail_max_userip_connections = 100 mail_plugins = quota pop3_logout_format = top=%t/%p, retr=%r/%b, del=%d/%m, size=%s, bytes=%i/%o pop3_uidl_format = UID%u-%v } protocol lda { postmaster_address = postmaster(a)example.com } Any help with this would be greatly appreciated! cheers, -- Rob

1 0

[Dovecot] Quick question on sieve
by LuKreme 20 Nov '13

20 Nov '13

I have a procmail recipe that does the majority of my heavy lifting for my mailing lists. It's pretty straightforward, but as I understand it, this isn't something sieve can do: # [ ] contains a space and a tab :0 * 9876543210^0 ^(List-Id:.*<|X-Mailing-List:[ ]*)\/[-A-z0-9_+]+ * 9876543210^0 ^(List-Post:[ ]*(<mailto:)?|List-Owner:[ ]*(<mailto:)?owner-)\/[-A-Z0-9_+]+ * 9876543210^0 ^(Sender:[ ]*owner-|X-BeenThere:[ ]*|Delivered-To:[ ]*mailing list )\/[-A-Za-z0-9_+]+ * 9876543210^0 ^Sender:.* List"? <(mailto:)?\/[-A-Z0-9_+]+ { LISTNAME=$MATCH } Basically, it checks the headers for any one of the headers List-ID: List-Post: List-Owner: Sender: owner- X-BeenThere: Delivered-To: Sender:.*List and parses out the name of the list, writing it to the LISTNAME variable For the vast majority of mailing lists the message is then written to .$LISTNAME/ This means that, by default, a new mailing list is automatically sorted to its own mailbox without my doing anything at all. Is this something hat Sieve can do? Does it have variable assignment? -- <div id="me"><div id="Dennis Miller>It's just my opinion. I could be wrong.</div>But I'm not.</div>

2 3

[Dovecot] NTLM authentication in Thunderbird vs Outlook 2010.
by Alexandr Sabitov 20 Nov '13

20 Nov '13

Hi List, I am using the Dovecot 2.1.12 with NTLM authentication enabled. The Dovecot is set up in cluster with directors, 60 000 connections simultaneously. I have noticed that NTLM authentication is processed differently for Thunderbird and Outlook 2010 users. It actually makes Outlook 2010 clients query LDAP more often that Thunderbird ones which is not good potentially for overall performance. Dovecot do not see a domain in NTLM Type 3 message but it does exist there. Could somebody explain please why it is happening? Tcpdump Thunderbird: 1) IP proxy.netregistry.net.19228 > dovecot-test-1.private.netregistry.net.pop3 E..3..@.}..........)K..n#...9...P..qXT..AUTH NTLM 2) IP dovecot-test-1.private.netregistry.net.pop3 > proxy.netregistry.net.19228 E..,..@.@.,....).....nK.9...#...P....W..+ 3) IP proxy.netregistry.net.19228 > dovecot-test-1.private.netregistry.net.pop3 E..V..@.}..........)K..n#...9...P..m.n..TlRMTVNTUAABAAAAB4IIAAAAAAAAAAAAAAAAAAAAAAA= 4) IP dovecot-test-1.private.netregistry.net.pop3 > proxy.netregistry.net.19228 E.....@.@.,@...).....nK.9...#..:P.......+ TlRMTVNTUAACAAAAHAAcADAAAAAFAooAbuK/LV9v9xIAA AAAAAAAACQAJABMAAAAZABvAHYAZQBjAG8AdAAtAHQAZQBzAHQALQAxAAMAHABkAG8 AdgBlAGMAbwB0AC0AdABlAHMAdAAtADEAAAAAAA== 5) IP proxy.netregistry.net.19228 > dovecot-test-1.private.netregistry.net.pop3 E..&..@.}..........)K..n#..:9...P...|...TlRMTVNTUAADAAAAGAAYAIwAAAAYABgApAAAAAAAAABAAA AAOAA4AEAAAAAUABQAeAAAAAAAAAAAAAAABQIIAG0AaQBnAHIAYQB0AGkAbwBuAC4AdAB lAHMAdABAAG4AZQB0AHcAbwByAGsALgBpAGQALgBhAHUAbQB5AHAAcgBvAGoAZQBjAHQA cwBEqdTLLSMLdQAAAAAAAAAAAAAAAAAAAADZv(...)= Base64 decoding of the last message (NTLM Type 3): NTLMSSP?.m.i.g.r.a.t.i.o.n...t.e.s.t.@.n.e.t.w.o.r.k...i.d...a.u.m.y.p.r.o.j.e.c.t.s.D-#?u.......................(....)Nh\P 6) IP dovecot-test-1.private.netregistry.net.pop3 > proxy.netregistry.net.19228 E..(..@.@.,....).....nK.9...#..8P.. .'.. 7) IP dovecot-test-1.private.netregistry.net.pop3 > proxy.netregistry.net.19228 E..8..@.@.,....).....nK.9...#..8P.. .c..+OK Logged in. >From logs: Nov 19 18:14:53 dovecot-test-1 dovecot: auth: Debug: ldap(migration.test(a)network.id.au,203.30.252.5,<sI7Ga4LrOADLHvwF>): pass search: base=ou=email, dc=netregistry, dc=net scope=subtree filter=(&(objectClass=nrPOPAccount)(uid=migration.test(a)network.id.au)) fields=uid,userPassword Nov 19 18:14:53 dovecot-test-1 dovecot: auth: Debug: ldap(migration.test(a)network.id.au,203.30.252.5,<sI7Ga4LrOADLHvwF>): result: uid=migration.test(a)network.id.au userPassword=Secret123 All good. Outlook 2010: 1) IP proxy.netregistry.net.47129 > dovecot-test-1.private.netregistry.net.pop3 E..3..@.}..........)...n...Q..f9P..qOv..AUTH NTLM 2) IP dovecot-test-1.private.netregistry.net.pop3 > proxy.netregistry.net.47129 E..,..@.@.P....).....n....f9...\P....W..+ 3) IP proxy.netregistry.net.47129 > dovecot-test-1.private.netregistry.net.pop3 E..b..@.}..........)...n...\..f=P..m....TlRMTVNTUAABAAAAB4IIogAAAAAAAAAAAAAAAAAAAAAFASgKAAAADw== 4) IP dovecot-test-1.private.netregistry.net.pop3 > proxy.netregistry.net.47129 E.....@.@.Pe...).....n....f=....P.......+ TlRMTVNTUAACAAAAHAAcADAAAAAFAooAQlAQ6i5tIiIAAAAAAAAAACQAJA BMAAAAZABvAHYAZQBjAG8AdAAtAHQAZQBzAHQALQAxAAMAHABkAG8AdgBlAGMAbwB0AC0AdABlAHM AdAAtADEAAAAAAA== 5) IP proxy.netregistry.net.47129 > dovecot-test-1.private.netregistry.net.pop3 E.....@.}..........)...n......f.P....c..TlRMTVNTUAADAAAAGAAYAJIAAAAYABgAqgAAABoAGgBIAAAAHAAcAGIAAAAUA BQAfgAAAAAAAADCAAAABQKIAgUBKAoAAAAPbgBlAHQAdwBvAHIAawAuAGkAZAAuAGEAdQBtAGkAZwByAG EAdABpAG8AbgAuAHQAZQBzAHQATQBZAFAAUgBPAEoARQBDAFQAUwADFLugRfGh3gAAAAAAAAAAAAAAAA AAAAA(...)= Base64 decoding of the last message (NTLM Type 3): NTLMSSP.....................H....b..~.?????( ...?n.e.t.w.o.r.k...i.d...a.u.m.i.g.r.a.t.i.o.n...t.e.s.t.M.Y.P.R.O.J.E.C.T.S.??E..(....)..q?%/ 6) IP dovecot-test-1.private.netregistry.net.pop3 > proxy.netregistry.net.47129 E..(..@.@.Q....).....n....f.....P.. .5.. 7) IP dovecot-test-1.private.netregistry.net.pop3 > proxy.netregistry.net.47129 E..E..@.@.P....).....n....f.....P.. .p..-ERR Authentication failed. >From logs: Nov 19 18:33:24 dovecot-test-1 dovecot: auth: Debug: ldap(migration.test,203.30.252.5,<Hab+rYLrPQDLHvwF>): pass search: base=ou=email, dc=netregistry, dc=net scope=subtree filter=(&(objectClass=nrPOPAccount)(uid=migration.test)) fields=uid,userPassword Nov 19 18:33:24 dovecot-test-1 dovecot: auth: ldap(migration.test,203.30.252.5,<Hab+rYLrPQDLHvwF>): unknown user Well, WHERE is my domain in the LDAP query? :) 8) IP proxy.netregistry.net.47129 > dovecot-test-1.private.netregistry.net.pop3 E..K..@.}..........)...n......f.P...X,..USER migration.test(a)network.id.au 9) IP dovecot-test-1.private.netregistry.net.pop3 > proxy.netregistry.net.47129 E..(..@.@.P....).....n....f.....P.. .... 10) IP dovecot-test-1.private.netregistry.net.pop3 > proxy.netregistry.net.47129 E..-..@.@.P....).....n....f.....P.. .X..+OK 11) IP proxy.netregistry.net.47129 > dovecot-test-1.private.netregistry.net.pop3 E..7..@.}..........)...n......f.P...l;..PASS Secret123 12) IP dovecot-test-1.private.netregistry.net.pop3 > proxy.netregistry.net.47129 E..(..@.@.P....).....n....f.....P.. .... 13) IP dovecot-test-1.private.netregistry.net.pop3 > proxy.netregistry.net.47129 E..8..@.@.P....).....n....f.....P.. .c..+OK Logged in. Configuration file This is LDAP configuration on one of director servers where clients are authenticated. uris = ldap://ldap-node-2.mynetwork.net, ldap://ldap-node-3.mynetwork.net debug_level = 0 base = ou=email, dc=netregistry, dc=net user_attrs = homeDirectory=home, uidNumber=uid, gidNumber=gid, mailQuotaSize=quota_rule=*:storage=%$ user_filter = (&(objectClass=nrPOPAccount)(uid=%u)) pass_attrs = uid=user, userPassword=password, =proxy=y, =destuser=%u, =pass=Secret456 pass_filter = (&(objectClass=nrPOPAccount)(uid=%u)) default_pass_scheme = PLAIN Regards, Alexandr Sabitov System Administrator

1 0

[Dovecot] Question about dovecot 2.2 and LIST command
by Jonathan Siegle 19 Nov '13

19 Nov '13

I am running dovecot 2.2 changeset 16983:d925674c4329 on AIX. We use mbox files with the "/" delimter. I have an imap protocol question. When I do: 1 list directory1/ * 1 OK List completed. Is that the correct answer? If I do 1 list "" * it shows * LIST (\Noselect \HasChildren) "/" "directory1" I forget why I think that even with the delimiter, I should see some information about it. Is this a silly test that no imap client does? Thanks, Jonathan

3 3

[Dovecot] Crash in dovecot 2.2.6
by Ed W 19 Nov '13

19 Nov '13

Hi, I recently upgraded from a dovecot 2.1 version to 2.2.6. I now have a single user who occasionally triggers a crash (just this one user it seems?). The user connects via LiveMail (v14.0.8117.) and IMAP. Oct 29 08:05:26 mail1 dovecot: imap(customer(a)example.org): Error: Raw backtrace: /usr/lib/dovecot/libdovecot.so.0(+0x6575a) [0xd94cc75a] -> /usr/lib/dovecot/libdovecot.so.0(+0x657cb) [0xd94cc7cb] -> /usr/lib/dovecot/libdovecot.so.0(i_fatal+0) [0xd9481991] -> /usr/lib/dovecot/libdovecot-storage.so.0(mail_index_transaction_export+0xa69) [0xd95e3e09] -> /usr/lib/dovecot/libdovecot-storage.so.0(+0xa9618) [0xd95e2618] -> /usr/lib/dovecot/libdovecot-storage.so.0(mail_index_transaction_commit_full+0xd1) [0xd95e2b31] -> /usr/lib/dovecot/libdovecot-storage.so.0(mail_index_transaction_commit+0x23) [0xd95e2c23] -> /usr/lib/dovecot/libdovecot-storage.so.0(mail_index_sync_commit+0xe9) [0xd95ec0c9] -> /usr/lib/dovecot/libdovecot-storage.so.0(+0x527d2) [0xd958b7d2] -> /usr/lib/dovecot/libdovecot-storage.so.0(+0x51b0c) [0xd958ab0c] -> /usr/lib/dovecot/libdovecot-storage.so.0(+0x51eea) [0xd958aeea] -> /usr/lib/dovecot/libdovecot-storage.so.0(maildir_storage_sync_init+0xef) [0xd958b2af] -> /usr/lib/dovecot/libdovecot-storage.so.0(mailbox_sync_init+0x3b) [0xd959c4ab] -> /usr/lib/dovecot/libdovecot-storage.so.0(mailbox_sync+0x3f) [0xd959c5ef] -> /usr/lib/dovecot/libdovecot-storage.so.0(index_storage_get_status+0x48) [0xd95cabc8] -> /usr/lib/dovecot/lib20_fts_plugin.so(+0xb362) [0xd928f362] -> /usr/lib/dovecot/libdovecot-storage.so.0(mailbox_get_status+0x49) [0xd959da79] -> dovecot/imap [customer(a)example.org 176.35.111.117 STATUS](imap_status_get+0x73) [0x8064503] -> dovecot/imap [customer(a)example.org 176.35.111.117 STATUS](cmd_status+0x189) [0x80591d9] -> dovecot/imap [customer(a)example.org 176.35.111.117 STATUS](command_exec+0x32) [0x805d762] -> dovecot/imap [customer(a)example.org 176.35.111.117 STATUS]() [0x805c747] -> dovecot/imap [customer(a)example.org 176.35.111.117 STATUS]() [0x805c891] -> dovecot/imap [customer(a)example.org 176.35.111.117 STATUS](client_handle_input+0x125) [0x805cae5] -> dovecot/imap [customer(a)example.org 176.35.111.117 STATUS](client_input+0x72) [0x805ce92] -> /usr/lib/dovecot/libdovecot.so.0(io_loop_call_io+0x44) [0xd94def84] -> /usr/lib/dovecot/libdovecot.so.0(io_loop_handler_run+0xce) [0xd94dff7e] -> /usr/lib/dovecot/libdovecot.so.0(io_loop_run+0x40) [0xd94dea40] -> /usr/lib/dovecot/libdovecot.so.0(master_service_run+0x2e) [0xd94874be] System is gentoo 32bit. Do you need configs? Thanks for any advice Ed W

2 3

[Dovecot] Problem migration from cyrus with imapc
by claus 19 Nov '13

19 Nov '13

Hi all, i have problem with namespace when i try to migrate from cyrus to dovecot with imapc. If i login to the old cyrus i get: telnet 192.168.8.164 143 Trying 192.168.8.164... Connected to 192.168.8.164. Escape character is '^]'. * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE STARTTLS AUTH=PLAIN AUTH=LOGIN AUTH=DIGEST-MD5 AUTH=CRAM-MD5] Dovecot ready. a login testbox(a)old.cyrus.de secret a namespace a OK [CAPABILITY IMAP4 IMAP4rev1 ACL QUOTA LITERAL+ MAILBOX-REFERRALS NAMESPACE UIDPLUS ID NO_ATOMIC_RENAME UNSELECT CHILDREN MULTIAPPEND BINARY SORT THREAD=ORDEREDSUBJECT THREAD=REFERENCES ANNOTATEMORE IDLE STARTTLS AUTH=DIGEST-MD5 AUTH=CRAM-MD5 SASL-IR X-NETSCAPE] User logged in * NAMESPACE (("INBOX." ".")) NIL (("" ".")) a OK Completed a list "" "*" * LIST (\HasChildren) "." "INBOX" * LIST (\HasNoChildren) "." "INBOX.Drafts" * LIST (\HasNoChildren) "." "INBOX.Gesendete Mails" * LIST (\HasNoChildren) "." "INBOX.Sent" * LIST (\HasNoChildren) "." "INBOX.Trash" * LIST (\HasNoChildren) "." "INBOX.spam" * LIST (\HasChildren) "." "INBOX.test1" * LIST (\HasNoChildren) "." "INBOX.test1.test11" a OK Completed after migration with: root@dovecot-backend1:~ # dsync -o imapc_user=testbox(a)old.cyrus.de -o imapc_password=secret -o imapc_host=192.168.8.164 -o imapc_features=rfc822.size -o mailbox_list_index=no -Dv backup -R -u m1234567890 imapc: i get the folowing result: telnet 192.168.8.164 143 Trying 192.168.8.164... Connected to 192.168.8.164. Escape character is '^]'. * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE STARTTLS AUTH=PLAIN AUTH=LOGIN AUTH=DIGEST-MD5 AUTH=CRAM-MD5] Dovecot ready. a login testmig(a)example.org test a OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS THREAD=ORDEREDSUBJECT MULTIAPPEND URL-PARTIAL CATENATE UNSELECT CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS SPECIAL-USE BINARY MOVE QUOTA] Logged in a namespace * NAMESPACE (("INBOX." ".")) NIL NIL a OK Namespace completed. a list "" "*" * LIST (\HasChildren) "." INBOX * LIST (\HasNoChildren) "." INBOX.INBOX.Drafts * LIST (\HasNoChildren) "." "INBOX.INBOX.Gesendete Mails" * LIST (\HasNoChildren) "." INBOX.INBOX.Sent * LIST (\HasNoChildren) "." INBOX.INBOX.Trash * LIST (\HasNoChildren) "." INBOX.INBOX.spam * LIST (\HasChildren) "." INBOX.INBOX.test1 * LIST (\HasNoChildren) "." INBOX.INBOX.test1.test11 * LIST (\Noselect \HasChildren) "." INBOX.INBOX a OK List completed. Can everyone explain me where the extra "prefix INBOX" came from and how i can resolve that? The migration should be transparent for users. Did you need any extra informations? 192.168.8.164 is a dovecot-proxy. Consolelog of dsync and doveconf -n follows. Thanks! claus root@dovecot-backend1:~ # doveconf -n # 2.2.6: /etc/dovecot/dovecot.conf # OS: Linux 3.0.93-0.8-xen x86_64 SUSE Linux Enterprise Server 11 (x86_64) auth_debug = yes auth_master_user_separator = * auth_verbose = yes disable_plaintext_auth = no login_trusted_networks = 192.168.8.164/32 mail_debug = yes mail_location = mdbox:~/mdbox:ALT=/altstorage/%h/mdbox mail_plugins = " quota stats" 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 namespace inbox { inbox = yes list = children location = prefix = INBOX. separator = . type = private } passdb { args = /etc/dovecot/dovecot-sql-master.conf.ext driver = sql master = yes pass = yes } passdb { args = /etc/dovecot/dovecot-sql.conf.ext default_fields = userdb_home=/vmail/%1Mu/%2.1Mu/%u driver = sql } plugin { quota = dict:User quota::file:%h/dovecot-quota quota_rule = *:storage=10240 sieve = ~/.dovecot.sieve sieve_dir = ~/sieve stats_refresh = 10 secs stats_track_cmds = yes } postmaster_address = postmaster(a)mnet-online.de protocols = imap pop3 lmtp sieve service imap { executable = imap postlogin } service lmtp { inet_listener lmtp { address = port = 24 } } service managesieve-login { inet_listener sieve { port = 4190 } } service pop3 { executable = pop3 postlogin } service postlogin { executable = script-login -d rawlog -b } service stats { fifo_listener stats-mail { mode = 0666 user = vmail } } ssl = no userdb { driver = prefetch } userdb { args = /etc/dovecot/dovecot-sql.conf.ext default_fields = home=/vmail/%1Mu/%2.1Mu/%u driver = sql } verbose_proctitle = yes protocol lmtp { mail_plugins = " quota stats sieve" } protocol imap { mail_plugins = " quota stats imap_quota imap_stats" } root@dovecot-backend1:~ # root@dovecot-backend1:~ # dsync -o imapc_user=testbox(a)old.cyrus.de -o imapc_password=secret -o imapc_host=1 92.168.8.164 -o imapc_features=rfc822.size -o mailbox_list_index=no -Dv backup -R -u m1234567890 imapc: doveadm(root): Debug: Loading modules from directory: /usr/lib64/dovecot/modules doveadm(root): Debug: Module loaded: /usr/lib64/dovecot/modules/lib10_quota_plugin.so doveadm(root): Debug: Module loaded: /usr/lib64/dovecot/modules/lib90_stats_plugin.so doveadm(root): Debug: Loading modules from directory: /usr/lib64/dovecot/modules/doveadm doveadm(root): Debug: Skipping module doveadm_acl_plugin, because dlopen() failed: /usr/lib64/dovecot/modules/dovead m/lib10_doveadm_acl_plugin.so: undefined symbol: acl_user_module (this is usually intentional, so just ignore this m essage) doveadm(root): Debug: Skipping module doveadm_expire_plugin, because dlopen() failed: /usr/lib64/dovecot/modules/dov eadm/lib10_doveadm_expire_plugin.so: undefined symbol: expire_set_deinit (this is usually intentional, so just ignor e this message) doveadm(root): Debug: Module loaded: /usr/lib64/dovecot/modules/doveadm/lib10_doveadm_quota_plugin.so doveadm(root): Debug: Module loaded: /usr/lib64/dovecot/modules/doveadm/lib10_doveadm_sieve_plugin.so doveadm(root): Debug: Skipping module doveadm_fts_plugin, because dlopen() failed: /usr/lib64/dovecot/modules/dovead m/lib20_doveadm_fts_plugin.so: undefined symbol: fts_backend_rescan (this is usually intentional, so just ignore thi s message) doveadm(m1234567890): Debug: auth input: m1234567890 home=/vmail/0/e/m1234567890 uid=10000 gid=10000 doveadm(m1234567890): Debug: Effective uid=10000, gid=10000, home=/vmail/0/e/m1234567890 doveadm(m1234567890): Debug: Quota root: name=User quota backend=dict args=:file:/vmail/0/e/m1234567890/dovecot-quot a doveadm(m1234567890): Debug: Quota rule: root=User quota mailbox=* bytes=10485760 messages=0 doveadm(m1234567890): Debug: Quota grace: root=User quota bytes=1048576 (10%) doveadm(m1234567890): Debug: dict quota: user=m1234567890, uri=file:/vmail/0/e/m1234567890/dovecot-quota, noenforcin g=0 doveadm(m1234567890): Debug: Namespace inbox: type=private, prefix=INBOX., sep=., inbox=yes, hidden=no, list=yes, su bscriptions=yes location=mdbox:~/mdbox:ALT=/altstorage//vmail/0/e/m1234567890/mdbox doveadm(m1234567890): Debug: fs: root=/vmail/0/e/m1234567890/mdbox, index=, indexpvt=, control=, inbox=, alt=/altsto rage//vmail/0/e/m1234567890/mdbox doveadm(m1234567890): Debug: Namespace : type=private, prefix=, sep=, inbox=no, hidden=yes, list=no, subscriptions=n o location=fail::LAYOUT=none doveadm(m1234567890): Debug: none: root=, index=, indexpvt=, control=, inbox=, alt= doveadm(m1234567890): Debug: Namespace INBOX.: Using permissions from /vmail/0/e/m1234567890/mdbox: mode=0700 gid=de fault dsync(m1234567890): Debug: Effective uid=10000, gid=10000, home=/vmail/0/e/m1234567890 dsync(m1234567890): Debug: Quota root: name=User quota backend=dict args=:file:/vmail/0/e/m1234567890/dovecot-quota dsync(m1234567890): Debug: Quota rule: root=User quota mailbox=* bytes=10485760 messages=0 dsync(m1234567890): Debug: Quota grace: root=User quota bytes=1048576 (10%) dsync(m1234567890): Debug: dict quota: user=m1234567890, uri=file:/vmail/0/e/m1234567890/dovecot-quota, noenforcing= 0 dsync(m1234567890): Debug: Namespace inbox: type=private, prefix=INBOX., sep=., inbox=yes, hidden=no, list=yes, subs criptions=yes location=imapc: dsync(m1234567890): Debug: imapc(192.168.8.164:143): Looking up IP address dsync(m1234567890): Debug: imapc(192.168.8.164:143): Connecting to 192.168.8.164:143 dsync(m1234567890): Debug: imapc(192.168.8.164:143): Server capabilities: IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE STARTTLS AUTH=PLAIN AUTH=LOGIN AUTH=DIGEST-MD5 AUTH=CRAM-MD5 dsync(m1234567890): Debug: imapc(192.168.8.164:143): Authenticating as testbox(a)post-ist-da.de dsync(m1234567890): Debug: imapc(192.168.8.164:143): Server capabilities: IMAP4 IMAP4rev1 ACL QUOTA LITERAL+ MAILBOX -REFERRALS NAMESPACE UIDPLUS ID NO_ATOMIC_RENAME UNSELECT CHILDREN MULTIAPPEND BINARY SORT THREAD=ORDEREDSUBJECT THR EAD=REFERENCES ANNOTATEMORE IDLE STARTTLS AUTH=DIGEST-MD5 AUTH=CRAM-MD5 SASL-IR X-NETSCAPE dsync(m1234567890): Debug: imapc(192.168.8.164:143): Authenticated successfully dsync(m1234567890): Debug: imapc: root=, index=, indexpvt=, control=, inbox=, alt= dsync(m1234567890): Debug: Namespace : type=private, prefix=, sep=, inbox=no, hidden=yes, list=no, subscriptions=no location=fail::LAYOUT=none dsync(m1234567890): Debug: none: root=, index=, indexpvt=, control=, inbox=, alt= dsync(m1234567890): Debug: brain M: in state=master_recv_handshake dsync(m1234567890): Debug: brain M: out state=send_mailbox_tree changed=1 dsync(m1234567890): Debug: brain S: out state=send_mailbox_tree changed=1 dsync(m1234567890): Debug: brain M: in state=send_mailbox_tree dsync(m1234567890): Debug: brain M: out state=send_mailbox_tree changed=1 dsync(m1234567890): Debug: brain S: in state=send_mailbox_tree dsync(m1234567890): Debug: brain S: out state=send_mailbox_tree changed=1 dsync(m1234567890): Debug: brain M: in state=send_mailbox_tree dsync(m1234567890): Debug: brain M: out state=send_mailbox_tree changed=1 dsync(m1234567890): Debug: brain S: in state=send_mailbox_tree dsync(m1234567890): Debug: brain S: out state=send_mailbox_tree changed=1 dsync(m1234567890): Debug: brain M: in state=send_mailbox_tree dsync(m1234567890): Debug: brain M: out state=send_mailbox_tree changed=1 dsync(m1234567890): Debug: brain S: in state=send_mailbox_tree dsync(m1234567890): Debug: brain S: out state=send_mailbox_tree changed=1 dsync(m1234567890): Debug: brain M: in state=send_mailbox_tree dsync(m1234567890): Debug: brain M: out state=send_mailbox_tree_deletes changed=1 dsync(m1234567890): Debug: brain S: in state=send_mailbox_tree dsync(m1234567890): Debug: brain S: out state=send_mailbox_tree changed=1 dsync(m1234567890): Debug: brain M: in state=send_mailbox_tree_deletes dsync(m1234567890): Debug: brain M: out state=recv_mailbox_tree changed=1 dsync(m1234567890): Debug: brain S: in state=send_mailbox_tree dsync(m1234567890): Debug: brain S: out state=send_mailbox_tree changed=1 dsync(m1234567890): Debug: brain M: in state=recv_mailbox_tree dsync(m1234567890): Debug: brain M: out state=recv_mailbox_tree changed=0 dsync(m1234567890): Debug: brain S: in state=send_mailbox_tree dsync(m1234567890): Debug: brain S: out state=send_mailbox_tree changed=1 dsync(m1234567890): Debug: brain M: in state=recv_mailbox_tree dsync(m1234567890): Debug: brain M: out state=recv_mailbox_tree changed=0 dsync(m1234567890): Debug: brain S: in state=send_mailbox_tree dsync(m1234567890): Debug: brain S: out state=send_mailbox_tree changed=1 dsync(m1234567890): Debug: brain M: in state=recv_mailbox_tree dsync(m1234567890): Debug: brain M: out state=recv_mailbox_tree changed=0 dsync(m1234567890): Debug: brain S: in state=send_mailbox_tree dsync(m1234567890): Debug: brain S: out state=send_mailbox_tree changed=1 dsync(m1234567890): Debug: brain M: in state=recv_mailbox_tree dsync(m1234567890): Debug: brain M: out state=recv_mailbox_tree changed=0 dsync(m1234567890): Debug: brain S: in state=send_mailbox_tree dsync(m1234567890): Debug: brain S: out state=send_mailbox_tree changed=1 dsync(m1234567890): Debug: brain M: in state=recv_mailbox_tree dsync(m1234567890): Debug: brain M: out state=recv_mailbox_tree changed=0 dsync(m1234567890): Debug: brain S: in state=send_mailbox_tree dsync(m1234567890): Debug: brain S: out state=send_mailbox_tree_deletes changed=1 dsync(m1234567890): Debug: brain M: in state=recv_mailbox_tree dsync(m1234567890): Debug: brain M: out state=recv_mailbox_tree_deletes changed=1 dsync(m1234567890): Debug: brain S: in state=send_mailbox_tree_deletes dsync(m1234567890): Debug: brain S: out state=recv_mailbox_tree changed=1 dsync(m1234567890): Debug: brain M: in state=recv_mailbox_tree_deletes dsync(m1234567890): Debug: doveadm-sieve: Iterating Sieve mailbox attributes dsync(m1234567890): Debug: sieve: Pigeonhole version 0.4.2 initializing dsync(m1234567890): Debug: sieve: include: sieve_global_dir is not set; it is currently not possible to include `:gl obal' scripts. dsync(m1234567890): Debug: sieve-storage: using active sieve script path: /vmail/0/e/m1234567890/.dovecot.sieve dsync(m1234567890): Debug: sieve-storage: using sieve script storage directory: /vmail/0/e/m1234567890/sieve dsync(m1234567890): Debug: sieve-storage: permission lookup failed from /vmail/0/e/m1234567890/sieve dsync(m1234567890): Debug: sieve-storage: created storage directory /vmail/0/e/m1234567890/sieve/tmp dsync(m1234567890): Debug: sieve-storage: relative path to sieve storage in active link: sieve/ dsync(m1234567890): Debug: Namespace INBOX.: /vmail/0/e/m1234567890/mdbox/mailboxes/INBOX doesn't exist yet, using d efault permissions dsync(m1234567890): Debug: Namespace INBOX.: Using permissions from /vmail/0/e/m1234567890/mdbox: mode=0700 gid=defa ult dsync(m1234567890): Debug: Namespace INBOX.: /vmail/0/e/m1234567890/mdbox/mailboxes/INBOX/Drafts doesn't exist yet, using default permissions dsync(m1234567890): Debug: Namespace INBOX.: /vmail/0/e/m1234567890/mdbox/mailboxes/INBOX/Gesendete Mails doesn't ex ist yet, using default permissions dsync(m1234567890): Debug: Namespace INBOX.: /vmail/0/e/m1234567890/mdbox/mailboxes/INBOX/Sent doesn't exist yet, us ing default permissions dsync(m1234567890): Debug: Namespace INBOX.: /vmail/0/e/m1234567890/mdbox/mailboxes/INBOX/Trash doesn't exist yet, u sing default permissions dsync(m1234567890): Debug: Namespace INBOX.: /vmail/0/e/m1234567890/mdbox/mailboxes/INBOX/spam doesn't exist yet, us ing default permissions dsync(m1234567890): Debug: Namespace INBOX.: /vmail/0/e/m1234567890/mdbox/mailboxes/INBOX/test1 doesn't exist yet, u sing default permissions dsync(m1234567890): Debug: Namespace INBOX.: /vmail/0/e/m1234567890/mdbox/mailboxes/INBOX/test1/test11 doesn't exist yet, using default permissions dsync(m1234567890): Debug: brain M: out state=master_send_mailbox changed=1 dsync(m1234567890): Debug: brain S: in state=recv_mailbox_tree dsync(m1234567890): Debug: brain S: out state=recv_mailbox_tree_deletes changed=1 dsync(m1234567890): Debug: brain M: in state=master_send_mailbox dsync(m1234567890): Debug: brain M: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain S: in state=recv_mailbox_tree_deletes dsync(m1234567890): Debug: brain S: out state=slave_recv_mailbox changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX' recv_state=mailbox send_state=mailbox dsync(m1234567890): Debug: brain M: out box 'INBOX' recv_state=mailbox send_state=mailbox changed=0 dsync(m1234567890): Debug: brain M: out state=sync_mails changed=0 dsync(m1234567890): Debug: brain S: in state=slave_recv_mailbox dsync(m1234567890): Debug: doveadm-sieve: Iterating Sieve mailbox attributes dsync(m1234567890): Debug: sieve: Pigeonhole version 0.4.2 initializing dsync(m1234567890): Debug: sieve: include: sieve_global_dir is not set; it is currently not possible to include `:gl obal' scripts. dsync(m1234567890): Debug: sieve-storage: using active sieve script path: /vmail/0/e/m1234567890/.dovecot.sieve dsync(m1234567890): Debug: sieve-storage: using sieve script storage directory: /vmail/0/e/m1234567890/sieve dsync(m1234567890): Debug: sieve-storage: using permissions from /vmail/0/e/m1234567890/sieve: mode=0700 gid=-1 dsync(m1234567890): Debug: sieve-storage: relative path to sieve storage in active link: sieve/ dsync(m1234567890): Debug: brain S: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX' recv_state=mailbox send_state=mailbox dsync(m1234567890): Debug: brain M: out box 'INBOX' recv_state=attributes send_state=mail_requests changed=1 dsync(m1234567890): Debug: brain M: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain S: in state=sync_mails dsync(m1234567890): Debug: brain S: in box 'INBOX' recv_state=mail_requests send_state=attributes dsync(m1234567890): Debug: brain S: out box 'INBOX' recv_state=mail_requests send_state=changes changed=1 dsync(m1234567890): Debug: brain S: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX' recv_state=attributes send_state=mail_requests dsync(m1234567890): Debug: brain M: out box 'INBOX' recv_state=changes send_state=mail_requests changed=1 dsync(m1234567890): Debug: brain M: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain S: in state=sync_mails dsync(m1234567890): Debug: brain S: in box 'INBOX' recv_state=mail_requests send_state=changes dsync(m1234567890): Debug: brain S: out box 'INBOX' recv_state=mail_requests send_state=changes changed=1 dsync(m1234567890): Debug: brain S: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX' recv_state=changes send_state=mail_requests dsync(m1234567890): Debug: brain M: Import INBOX: Import change GUID= UID=17 hdr_hash=1789a3a22dd73779304ac0b58acc37 a4 dsync(m1234567890): Debug: brain M: Import INBOX: Last common UID=0 dsync(m1234567890): Debug: brain M: out box 'INBOX' recv_state=changes send_state=mail_requests changed=1 dsync(m1234567890): Debug: brain M: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain S: in state=sync_mails dsync(m1234567890): Debug: brain S: in box 'INBOX' recv_state=mail_requests send_state=changes dsync(m1234567890): Debug: brain S: out box 'INBOX' recv_state=mail_requests send_state=changes changed=1 dsync(m1234567890): Debug: brain S: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX' recv_state=changes send_state=mail_requests dsync(m1234567890): Debug: brain M: Import INBOX: Import change GUID= UID=66 hdr_hash=774ba1d07279fbe1d87762463a8ab8 27 dsync(m1234567890): Debug: brain M: out box 'INBOX' recv_state=changes send_state=mail_requests changed=1 dsync(m1234567890): Debug: brain M: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain S: in state=sync_mails dsync(m1234567890): Debug: brain S: in box 'INBOX' recv_state=mail_requests send_state=changes dsync(m1234567890): Debug: brain S: out box 'INBOX' recv_state=mail_requests send_state=changes changed=1 dsync(m1234567890): Debug: brain S: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX' recv_state=changes send_state=mail_requests dsync(m1234567890): Debug: brain M: Import INBOX: Import change GUID= UID=272 hdr_hash=68a2925178bf0e3dda93c8b77229a f3b dsync(m1234567890): Debug: brain M: out box 'INBOX' recv_state=changes send_state=mail_requests changed=1 dsync(m1234567890): Debug: brain M: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain S: in state=sync_mails dsync(m1234567890): Debug: brain S: in box 'INBOX' recv_state=mail_requests send_state=changes dsync(m1234567890): Debug: brain S: out box 'INBOX' recv_state=mail_requests send_state=changes changed=1 dsync(m1234567890): Debug: brain S: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX' recv_state=changes send_state=mail_requests dsync(m1234567890): Debug: brain M: Import INBOX: Import change GUID= UID=2721 hdr_hash=68a2925178bf0e3dda93c8b77229 af3b dsync(m1234567890): Debug: brain M: out box 'INBOX' recv_state=changes send_state=mail_requests changed=1 dsync(m1234567890): Debug: brain M: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain S: in state=sync_mails dsync(m1234567890): Debug: brain S: in box 'INBOX' recv_state=mail_requests send_state=changes dsync(m1234567890): Debug: brain S: out box 'INBOX' recv_state=mail_requests send_state=changes changed=1 dsync(m1234567890): Debug: brain S: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX' recv_state=changes send_state=mail_requests dsync(m1234567890): Debug: brain M: Import INBOX: Import change GUID= UID=2822 hdr_hash=d5c0ccf93da2b285badf6e43cb95 856d dsync(m1234567890): Debug: brain M: out box 'INBOX' recv_state=changes send_state=mail_requests changed=1 dsync(m1234567890): Debug: brain M: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain S: in state=sync_mails dsync(m1234567890): Debug: brain S: in box 'INBOX' recv_state=mail_requests send_state=changes dsync(m1234567890): Debug: brain S: out box 'INBOX' recv_state=mail_requests send_state=changes changed=1 dsync(m1234567890): Debug: brain S: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX' recv_state=changes send_state=mail_requests dsync(m1234567890): Debug: brain M: Import INBOX: Import change GUID= UID=2826 hdr_hash=5626d629f014622b9cafbe769542 de7f dsync(m1234567890): Debug: brain M: out box 'INBOX' recv_state=changes send_state=mail_requests changed=1 dsync(m1234567890): Debug: brain M: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain S: in state=sync_mails dsync(m1234567890): Debug: brain S: in box 'INBOX' recv_state=mail_requests send_state=changes dsync(m1234567890): Debug: brain S: out box 'INBOX' recv_state=mail_requests send_state=changes changed=1 dsync(m1234567890): Debug: brain S: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX' recv_state=changes send_state=mail_requests dsync(m1234567890): Debug: brain M: Import INBOX: Import change GUID= UID=2827 hdr_hash=c76df14284fa8963e7c2abd677b0 b4e6 dsync(m1234567890): Debug: brain M: out box 'INBOX' recv_state=changes send_state=mail_requests changed=1 dsync(m1234567890): Debug: brain M: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain S: in state=sync_mails dsync(m1234567890): Debug: brain S: in box 'INBOX' recv_state=mail_requests send_state=changes dsync(m1234567890): Debug: brain S: out box 'INBOX' recv_state=mail_requests send_state=mails changed=1 dsync(m1234567890): Debug: brain S: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX' recv_state=changes send_state=mail_requests dsync(m1234567890): Debug: brain M: out box 'INBOX' recv_state=mails send_state=mail_requests changed=1 dsync(m1234567890): Debug: brain M: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain S: in state=sync_mails dsync(m1234567890): Debug: brain S: in box 'INBOX' recv_state=mail_requests send_state=mails dsync(m1234567890): Debug: brain S: out box 'INBOX' recv_state=mail_requests send_state=mails changed=1 dsync(m1234567890): Debug: brain S: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX' recv_state=mails send_state=mail_requests dsync(m1234567890): Debug: brain M: out box 'INBOX' recv_state=mails send_state=mail_requests changed=1 dsync(m1234567890): Debug: brain M: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain S: in state=sync_mails dsync(m1234567890): Debug: brain S: in box 'INBOX' recv_state=mail_requests send_state=mails dsync(m1234567890): Debug: brain S: out box 'INBOX' recv_state=mail_requests send_state=mails changed=1 dsync(m1234567890): Debug: brain S: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX' recv_state=mails send_state=mail_requests dsync(m1234567890): Debug: brain M: out box 'INBOX' recv_state=mails send_state=mail_requests changed=1 dsync(m1234567890): Debug: brain M: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain S: in state=sync_mails dsync(m1234567890): Debug: brain S: in box 'INBOX' recv_state=mail_requests send_state=mails dsync(m1234567890): Debug: brain S: out box 'INBOX' recv_state=mail_requests send_state=mails changed=1 dsync(m1234567890): Debug: brain S: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX' recv_state=mails send_state=mail_requests dsync(m1234567890): Debug: brain M: out box 'INBOX' recv_state=mails send_state=mail_requests changed=1 dsync(m1234567890): Debug: brain M: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain S: in state=sync_mails dsync(m1234567890): Debug: brain S: in box 'INBOX' recv_state=mail_requests send_state=mails dsync(m1234567890): Debug: brain S: out box 'INBOX' recv_state=mail_requests send_state=mails changed=1 dsync(m1234567890): Debug: brain S: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX' recv_state=mails send_state=mail_requests dsync(m1234567890): Debug: brain M: out box 'INBOX' recv_state=mails send_state=mail_requests changed=1 dsync(m1234567890): Debug: brain M: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain S: in state=sync_mails dsync(m1234567890): Debug: brain S: in box 'INBOX' recv_state=mail_requests send_state=mails dsync(m1234567890): Debug: brain S: out box 'INBOX' recv_state=mail_requests send_state=mails changed=1 dsync(m1234567890): Debug: brain S: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX' recv_state=mails send_state=mail_requests dsync(m1234567890): Debug: brain M: out box 'INBOX' recv_state=mails send_state=mail_requests changed=1 dsync(m1234567890): Debug: brain M: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain S: in state=sync_mails dsync(m1234567890): Debug: brain S: in box 'INBOX' recv_state=mail_requests send_state=mails dsync(m1234567890): Debug: brain S: out box 'INBOX' recv_state=mail_requests send_state=mails changed=1 dsync(m1234567890): Debug: brain S: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX' recv_state=mails send_state=mail_requests dsync(m1234567890): Debug: brain M: out box 'INBOX' recv_state=mails send_state=mail_requests changed=1 dsync(m1234567890): Debug: brain M: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain S: in state=sync_mails dsync(m1234567890): Debug: brain S: in box 'INBOX' recv_state=mail_requests send_state=mails dsync(m1234567890): Debug: brain S: out box 'INBOX' recv_state=mail_requests send_state=mails changed=1 dsync(m1234567890): Debug: brain S: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX' recv_state=mails send_state=mail_requests dsync(m1234567890): Debug: brain M: out box 'INBOX' recv_state=mails send_state=done changed=1 dsync(m1234567890): Debug: brain M: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain S: in state=sync_mails dsync(m1234567890): Debug: brain S: in box 'INBOX' recv_state=mail_requests send_state=mails dsync(m1234567890): Debug: brain S: out box 'INBOX' recv_state=recv_last_common send_state=mails changed=1 dsync(m1234567890): Debug: brain S: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX' recv_state=mails send_state=done dsync(m1234567890): Debug: brain M: import mail uid 17 guid dsync(m1234567890): Debug: brain M: Import INBOX: Import mail body for GUID= UID=17 dsync(m1234567890): Debug: brain M: out box 'INBOX' recv_state=mails send_state=done changed=1 dsync(m1234567890): Debug: brain M: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain S: in state=sync_mails dsync(m1234567890): Debug: brain S: in box 'INBOX' recv_state=recv_last_common send_state=mails dsync(m1234567890): Debug: brain S: out box 'INBOX' recv_state=recv_last_common send_state=mails changed=1 dsync(m1234567890): Debug: brain S: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX' recv_state=mails send_state=done dsync(m1234567890): Debug: brain M: import mail uid 66 guid dsync(m1234567890): Debug: brain M: Import INBOX: Import mail body for GUID= UID=66 dsync(m1234567890): Debug: brain M: out box 'INBOX' recv_state=mails send_state=done changed=1 dsync(m1234567890): Debug: brain M: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain S: in state=sync_mails dsync(m1234567890): Debug: brain S: in box 'INBOX' recv_state=recv_last_common send_state=mails dsync(m1234567890): Debug: brain S: out box 'INBOX' recv_state=recv_last_common send_state=mails changed=1 dsync(m1234567890): Debug: brain S: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX' recv_state=mails send_state=done dsync(m1234567890): Debug: brain M: import mail uid 272 guid dsync(m1234567890): Debug: brain M: Import INBOX: Import mail body for GUID= UID=272 dsync(m1234567890): Debug: brain M: out box 'INBOX' recv_state=mails send_state=done changed=1 dsync(m1234567890): Debug: brain M: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain S: in state=sync_mails dsync(m1234567890): Debug: brain S: in box 'INBOX' recv_state=recv_last_common send_state=mails dsync(m1234567890): Debug: brain S: out box 'INBOX' recv_state=recv_last_common send_state=mails changed=1 dsync(m1234567890): Debug: brain S: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX' recv_state=mails send_state=done dsync(m1234567890): Debug: brain M: import mail uid 2721 guid dsync(m1234567890): Debug: brain M: Import INBOX: Import mail body for GUID= UID=2721 dsync(m1234567890): Debug: brain M: out box 'INBOX' recv_state=mails send_state=done changed=1 dsync(m1234567890): Debug: brain M: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain S: in state=sync_mails dsync(m1234567890): Debug: brain S: in box 'INBOX' recv_state=recv_last_common send_state=mails dsync(m1234567890): Debug: brain S: out box 'INBOX' recv_state=recv_last_common send_state=mails changed=1 dsync(m1234567890): Debug: brain S: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX' recv_state=mails send_state=done dsync(m1234567890): Debug: brain M: import mail uid 2822 guid dsync(m1234567890): Debug: brain M: Import INBOX: Import mail body for GUID= UID=2822 dsync(m1234567890): Debug: brain M: out box 'INBOX' recv_state=mails send_state=done changed=1 dsync(m1234567890): Debug: brain M: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain S: in state=sync_mails dsync(m1234567890): Debug: brain S: in box 'INBOX' recv_state=recv_last_common send_state=mails dsync(m1234567890): Debug: brain S: out box 'INBOX' recv_state=recv_last_common send_state=mails changed=1 dsync(m1234567890): Debug: brain S: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX' recv_state=mails send_state=done dsync(m1234567890): Debug: brain M: import mail uid 2826 guid dsync(m1234567890): Debug: brain M: Import INBOX: Import mail body for GUID= UID=2826 dsync(m1234567890): Debug: brain M: out box 'INBOX' recv_state=mails send_state=done changed=1 dsync(m1234567890): Debug: brain M: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain S: in state=sync_mails dsync(m1234567890): Debug: brain S: in box 'INBOX' recv_state=recv_last_common send_state=mails dsync(m1234567890): Debug: brain S: out box 'INBOX' recv_state=recv_last_common send_state=mails changed=1 dsync(m1234567890): Debug: brain S: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX' recv_state=mails send_state=done dsync(m1234567890): Debug: brain M: import mail uid 2827 guid dsync(m1234567890): Debug: brain M: Import INBOX: Import mail body for GUID= UID=2827 dsync(m1234567890): Debug: brain M: out box 'INBOX' recv_state=mails send_state=done changed=1 dsync(m1234567890): Debug: brain M: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain S: in state=sync_mails dsync(m1234567890): Debug: brain S: in box 'INBOX' recv_state=recv_last_common send_state=mails dsync(m1234567890): Debug: brain S: out box 'INBOX' recv_state=recv_last_common send_state=done changed=1 dsync(m1234567890): Debug: brain S: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX' recv_state=mails send_state=done dsync(m1234567890): Debug: brain M: Import INBOX: Saved UIDs: 17,66,272,2721,2822,2826:2827 dsync(m1234567890): Debug: brain M: Import INBOX: Finish update: min_next_uid=2828 min_first_recent_uid=1 min_highes t_modseq=1 min_highest_pvt_modseq=0 dsync(m1234567890): Debug: brain M: Import INBOX: Reassign UIDs: 1:16,18:65,67:271,273:2720,2722:2821,2823:2825 dsync(m1234567890): Debug: brain M: out box 'INBOX' recv_state=recv_last_common send_state=done changed=1 dsync(m1234567890): Debug: brain M: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain S: in state=sync_mails dsync(m1234567890): Debug: brain S: in box 'INBOX' recv_state=recv_last_common send_state=done dsync(m1234567890): Debug: brain S: out box '' recv_state=recv_last_common send_state=done changed=1 dsync(m1234567890): Debug: brain S: out state=slave_recv_mailbox changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX' recv_state=recv_last_common send_state=done dsync(m1234567890): Debug: brain M: out box '' recv_state=recv_last_common send_state=done changed=1 dsync(m1234567890): Debug: brain M: out state=master_send_mailbox changed=1 dsync(m1234567890): Debug: brain S: in state=slave_recv_mailbox dsync(m1234567890): Debug: brain S: out state=slave_recv_mailbox changed=0 dsync(m1234567890): Debug: brain M: in state=master_send_mailbox dsync(m1234567890): Debug: brain M: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain S: in state=slave_recv_mailbox dsync(m1234567890): Debug: brain S: out state=slave_recv_mailbox changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX.INBOX.Drafts' recv_state=mailbox send_state=mailbox dsync(m1234567890): Debug: brain M: out box '' recv_state=mailbox send_state=mailbox changed=1 dsync(m1234567890): Debug: brain M: out state=master_send_mailbox changed=1 dsync(m1234567890): Debug: brain S: in state=slave_recv_mailbox dsync(m1234567890): Debug: brain S: out state=slave_recv_mailbox changed=0 dsync(m1234567890): Debug: brain M: in state=master_send_mailbox dsync(m1234567890): Debug: brain M: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain S: in state=slave_recv_mailbox dsync(m1234567890): Debug: brain S: out state=slave_recv_mailbox changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX.INBOX.Gesendete Mails' recv_state=mailbox send_state=mailbox dsync(m1234567890): Debug: brain M: out box '' recv_state=mailbox send_state=mailbox changed=1 dsync(m1234567890): Debug: brain M: out state=master_send_mailbox changed=1 dsync(m1234567890): Debug: brain S: in state=slave_recv_mailbox dsync(m1234567890): Debug: brain S: out state=slave_recv_mailbox changed=0 dsync(m1234567890): Debug: brain M: in state=master_send_mailbox dsync(m1234567890): Debug: brain M: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain S: in state=slave_recv_mailbox dsync(m1234567890): Debug: brain S: out state=slave_recv_mailbox changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX.INBOX.Sent' recv_state=mailbox send_state=mailbox dsync(m1234567890): Debug: brain M: out box '' recv_state=mailbox send_state=mailbox changed=1 dsync(m1234567890): Debug: brain M: out state=master_send_mailbox changed=1 dsync(m1234567890): Debug: brain S: in state=slave_recv_mailbox dsync(m1234567890): Debug: brain S: out state=slave_recv_mailbox changed=0 dsync(m1234567890): Debug: brain M: in state=master_send_mailbox dsync(m1234567890): Debug: brain M: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain S: in state=slave_recv_mailbox dsync(m1234567890): Debug: brain S: out state=slave_recv_mailbox changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX.INBOX.Trash' recv_state=mailbox send_state=mailbox dsync(m1234567890): Debug: brain M: out box '' recv_state=mailbox send_state=mailbox changed=1 dsync(m1234567890): Debug: brain M: out state=master_send_mailbox changed=1 dsync(m1234567890): Debug: brain S: in state=slave_recv_mailbox dsync(m1234567890): Debug: brain S: out state=slave_recv_mailbox changed=0 dsync(m1234567890): Debug: brain M: in state=master_send_mailbox dsync(m1234567890): Debug: brain M: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain S: in state=slave_recv_mailbox dsync(m1234567890): Debug: brain S: out state=slave_recv_mailbox changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX.INBOX.spam' recv_state=mailbox send_state=mailbox dsync(m1234567890): Debug: brain M: out box '' recv_state=mailbox send_state=mailbox changed=1 dsync(m1234567890): Debug: brain M: out state=master_send_mailbox changed=1 dsync(m1234567890): Debug: brain S: in state=slave_recv_mailbox dsync(m1234567890): Debug: brain S: out state=slave_recv_mailbox changed=0 dsync(m1234567890): Debug: brain M: in state=master_send_mailbox dsync(m1234567890): Debug: brain M: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain S: in state=slave_recv_mailbox dsync(m1234567890): Debug: brain S: out state=slave_recv_mailbox changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX.INBOX.test1' recv_state=mailbox send_state=mailbox dsync(m1234567890): Debug: brain M: out box '' recv_state=mailbox send_state=mailbox changed=1 dsync(m1234567890): Debug: brain M: out state=master_send_mailbox changed=1 dsync(m1234567890): Debug: brain S: in state=slave_recv_mailbox dsync(m1234567890): Debug: brain S: out state=slave_recv_mailbox changed=0 dsync(m1234567890): Debug: brain M: in state=master_send_mailbox dsync(m1234567890): Debug: brain M: out state=sync_mails changed=1 dsync(m1234567890): Debug: brain S: in state=slave_recv_mailbox dsync(m1234567890): Debug: brain S: out state=slave_recv_mailbox changed=1 dsync(m1234567890): Debug: brain M: in state=sync_mails dsync(m1234567890): Debug: brain M: in box 'INBOX.INBOX.test1.test11' recv_state=mailbox send_state=mailbox dsync(m1234567890): Debug: brain M: out box '' recv_state=mailbox send_state=mailbox changed=1 dsync(m1234567890): Debug: brain M: out state=master_send_mailbox changed=1 dsync(m1234567890): Debug: brain S: in state=slave_recv_mailbox dsync(m1234567890): Debug: brain S: out state=slave_recv_mailbox changed=0 dsync(m1234567890): Debug: brain M: in state=master_send_mailbox dsync(m1234567890): Debug: brain M: out state=done changed=1 dsync(m1234567890): Debug: brain S: in state=slave_recv_mailbox dsync(m1234567890): Debug: brain S: out state=done changed=1 dsync(m1234567890): Debug: brain M: in state=done dsync(m1234567890): Debug: brain M: out state=done changed=1 dsync(m1234567890): Debug: brain S: in state=done dsync(m1234567890): Debug: brain S: out state=done changed=1 dsync(m1234567890): Debug: imapc(192.168.8.164:143): Disconnected root@dovecot-backend1:~ #

2 1

[Dovecot] Deleted messages reappearing
by Joseba Torre 19 Nov '13

19 Nov '13

Hi all, in the last days, probably since I upgraded to 2.2.7, I'm seeing lots of deleted messages reappearing in my folders. This is after this error in the log: Nov 13 14:55:16 buzones2 dovecot: imap-login: Login: user=<username>, method=PLAIN, rip=source_ip, lip=dest_ip, mpid=15510, TLS, session=<2bmiUA/rZACe4wQZ> Nov 13 14:55:16 buzones2 dovecot: imap(username): Error: mdbox /users/username/mdbox/mailboxes/backups/dbox-Mails: map uidvalidity mismatch (0 vs 1384350727) Nov 13 14:55:16 buzones2 dovecot: imap(username): Disconnected: Internal error occurred. Refer to server log for more information. [2013-11-13 14:55:16] in=227 out=2745 Nov 13 14:55:16 buzones2 dovecot: imap(username): Warning: fscking index file /users/username/mdbox/storage/dovecot.map.index Nov 13 14:55:17 buzones2 dovecot: imap(username): Warning: mdbox /users/username/mdbox/storage: rebuilding indexes Nov 13 14:55:17 buzones2 dovecot: imap(username): Error: /users/username/mdbox/mailboxes/INBOX/dbox-Mails/dovecot.index reset, view is now inconsistent Nov 13 14:55:17 buzones2 dovecot: imap(username): Disconnected: IMAP session state is inconsistent, please relogin. in=383 out=4820 Nov 13 14:55:18 buzones2 dovecot: imap-login: Login: user=<username>, method=PLAIN, rip=source_ip, lip=dest_ip, mpid=15511, TLS, session=<HKq2UA/rZQCe4wQZ> Nov 13 14:55:19 buzones2 dovecot: imap-login: Login: user=<username>, method=PLAIN, rip=source_ip, lip=dest_ip, mpid=15512, TLS, session=<pRzNUA/rZgCe4wQZ> Nov 13 15:13:07 buzones2 dovecot: imap-login: Login: user=<username>, method=PLAIN, rip=source_ip, lip=dest_ip, mpid=15538, TLS, session=<hDV3kA/rTACe4wQZ> Nov 13 15:13:36 buzones2 dovecot: imap(username): Disconnected: Logged out in=1791 out=119343 Nov 13 15:13:44 buzones2 dovecot: imap-login: Login: user=<username>, method=PLAIN, rip=source_ip, lip=dest_ip, mpid=15539, TLS, session=<B02lkg/rTQCe4wQZ> [username@buzones2 ~]$ dovecot --version 2.2.7 The first days I was using thunderbird, and as we talked in other thread I thought it was a client issue, but now I've seen the same problem with kmail and mutt. Regards, Joseba Torre

3 5

[Dovecot] Mail filters in incoming message
by Jos Chrispijn 19 Nov '13

19 Nov '13

With my iMap client, I use various mail rules to move specific email to the right mail folders. Downside of that is that is this mailclient is not online, all email is stuck in my inbox, which makes reading email by mobile phone kind of chaotic. I have seen several Pigeonhole Sieve examples, but I would like to know if there is a nub solution (I am not a programmer) to run filters when they enter my mail server (Postfix 2.10.2,1 icw Dovecot 1.2.17) or if there is a user friendly GUI available to set these filters? Perhaps Anyone interested to create such a GUI? Best regards, Jos Chrispijn --- Artificial intelligence is no match for natural stupidity

4 4

[Dovecot] wiki links
by Timo Sirainen 19 Nov '13

19 Nov '13

Since google points only to wiki.dovecot.org, I changed it to now redirect all requests to wiki2. I didn’t want to originally do this to avoid breaking all old links, but maybe it’s not that bad anymore. Most links exist anyway for wiki2 too.

1 0

[Dovecot] Calling dovecot-lda from maildrop/shell
by Florian Lindner 19 Nov '13

19 Nov '13

Hello, I want to make final delivery for some of my mailboxes using dovecot-lda, since I'm interested in using sieve. My usual MDA which is called from postfix is and will be maildrop. First try was to call dovecot-lda from command line: /usr/lib/dovecot/dovecot-lda -d spam(a)xgm.de < testmail which gave return code 75 (EX_TEMPFAIL) and logged: dovecot: auth-worker(3533): mysql(localhost): Connected to database system dovecot: imap-login: Login: user=<spam(a)xgm.de>, method=PLAIN, rip=::1, lip=::1, mpid=3535, secured, session=<jwXWP3HrwgAAAAAAAAAAAAAAAAAAAAAB> dovecot: imap(spam(a)xgm.de): Disconnected: Logged out in=91 out=830 dovecot: lda(spam(a)xgm.de): Error: user spam(a)xgm.de: Initialization failed: Namespace '': Mail storage autodetection failed with home=/home/flindner/Mail/spam dovecot: lda(spam(a)xgm.de): Fatal: Invalid user settings. Refer to server log for more information. My userdb/password is like that home == maildir == /home/flindner/Mail/spam (without prefixed "maildir:"). It works fine for dovecot-imap, it detects that spam is a maildir. I've also tried prefixing home and or maildir with "maildir:" or calling: HOME=maildir:~/Mail/spam /usr/lib/dovecot/dovecot-lda < testmail which gave: dovecot: lda: Error: user flindner: Relative home directory paths not supported: maildir:/home/flindner/Mail/spam dovecot: lda: Fatal: Invalid user settings. Refer to server log for more information. What is the problem with the LDA here? Thanks, Florian

2 3