- dovecot - dovecot.org

[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

[Dovecot] Dovecot Listescape plugin problem
by vupibi 18 Nov '13

18 Nov '13

Dovecot Version: 2.0.19 I'm on Ubuntu 12.04.3 using Zentyal with Dovecot for a mail Server. What I try to setup is the possibility to use slashes ("/") in folder names inside the IMAP account. Regularly if I name a folder "foo/bar", the system creates me a folder named "bar" inside a folder named "foo". Listescape should give me the possibility but it does not work. It's not possible for me to write inside the "/etc/dovecot/dovecot.conf" because Zentyal will overwrite all changes when restarting the server. So my Configs are in "/etc/dovecot/extra.conf" and look like this: mail_plugins = listescape (i tried "mail_plugins = $mail_plugins listescape" as well, no success) namespace inbox { separator = "\\" prefix = inbox = yes hidden = no list = yes location = maildir:/var/vmail/%d/%n:LAYOUT=fs } My dovecot -n output # 2.0.19: /etc/dovecot/dovecot.conf # OS: Linux 3.2.0-55-generic x86_64 Ubuntu 12.04.3 LTS ext4 auth_gssapi_hostname = mailand.sxps.local auth_krb5_keytab = /etc/dovecot/dovecot.keytab auth_mechanisms = gssapi plain first_valid_gid = 116 first_valid_uid = 108 log_timestamp = "%Y-%m-%d %H:%M:%S " mail_gid = 116 mail_home = /var/vmail/sieve/%d/%u mail_location = maildir:/var/vmail/%$ mail_plugins = listescape mail_uid = 108 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 { hidden = no inbox = yes list = yes location = maildir:/var/vmail/%d/%n:LAYOUT=fs prefix = separator = \ } passdb { args = /etc/dovecot/dovecot-ldap.conf driver = ldap } plugin { autocreate = Trash autocreate2 = Spam autocreate3 = Sent autocreate4 = Drafts autosubscribe = Trash autosubscribe2 = Spam autosubscribe3 = Sent autosubscribe4 = Drafts quota = maildir:User quota quota_rule = *:storage=0 sieve = /var/vmail/sieve/%d/%u/sieve-script sieve_global_path = /var/vmail/sieve/default.sieve sieve_storage = /var/vmail/sieve/%d/%u } protocols = pop3 imap sieve service auth { executable = /usr/lib/dovecot/auth unix_listener /var/spool/postfix/private/auth { group = postfix mode = 0660 user = postfix } unix_listener auth-master { group = ebox mode = 0600 user = ebox } user = dovecot } service imap-login { inet_listener imap { port = 143 } inet_listener imaps { port = 993 } } service managesieve-login { inet_listener sieve { port = 4190 } } service pop3-login { inet_listener pop3 { port = 110 } inet_listener pop3s { port = 995 } } ssl_cert = </etc/dovecot/ssl/dovecot.pem ssl_key = </etc/dovecot/ssl/dovecot.pem userdb { args = /etc/dovecot/dovecot-ldap.conf driver = ldap } protocol imap { mail_plugins = quota imap_quota autocreate } protocol pop3 { mail_plugins = quota } protocol lda { auth_socket_path = /var/run/dovecot/auth-master mail_plugins = sieve quota postmaster_address = admin(a)sxps.eu } Any Ideas? -- View this message in context: http://dovecot.2317879.n4.nabble.com/Dovecot-Listescape-plugin-problem-tp45… Sent from the Dovecot mailing list archive at Nabble.com.

1 0

[Dovecot] why the system give the user more size than quota
by ihab 18 Nov '13

18 Nov '13

Hello i put quota rules in the dovecot 1.2.15 the quota is per user and the system read from data base but the system give to user size more than quota any help please -- View this message in context: http://dovecot.2317879.n4.nabble.com/why-the-system-give-the-user-more-size… Sent from the Dovecot mailing list archive at Nabble.com.

2 1