- dovecot - dovecot.org

[Dovecot] Full-text search
by Valery V. Sedletski 22 Feb '13

22 Feb '13

Hi all I discovered that the full-text search (fts) plugin can work without SQUAT/LUCENE/SOLR backend. I.e., Dovecot creates separate indexes for header search in files dovecot.index and dovecot.index.cache. Even, the search by headers is fast enough, and can search for phrases. Also, it seems that this buillt-in search is faster than Solr-based search. But if I enable the Solr backend (fts_solr), then the FTS generic plugin built-in search becomes disabled. But, Solr-based Full-text search is faster if search inside message bodies. Also, it appears that the built-in search inside message bodies works too, but very slow (it seems that is because it is dumb file-based search and does not use indexes at all) So, my question is: is it possible to combine the built-in search with Solr or Lucene plugin-based search so that the first one searches by headers, and the second one works by bodies? This could make the advantages of both search methods combined. WBR, valery

2 1

[Dovecot] LDA misbehavior?
by Eugene Paskevich 22 Feb '13

22 Feb '13

Hello, Could somebody please give me a hint on what might be going wrong? Feb 21 16:33:07 hopper dovecot: lda(eugene): Panic: file mail-storage.c: line 1757 (mailbox_transaction_commit_get_changes): assertion failed: (ret < 0 || seq_range_count(&changes_r->saved_uids) == save_count) Feb 21 16:33:07 hopper dovecot: lda(eugene): Error: Raw backtrace: /usr/lib/dovecot/libdovecot.so.0(+0x6019a) [0x7f38e5ef619a] -> /usr/lib/dovecot/ibdovecot.s o.0(i_syslog_fatal_handler+0x2b) [0x7f38e5ef621b] -> /usr/lib/dovecot/libdovecot.so.0(i_fatal+0) [0x7f38e5eb81f4] -> /usr/lib/dovecot/libdovecot-storage.so.0(+0x7c0de) [0x7f38e61cd0de] -> /usr/lib/dovecot/libdovecot-lda.so.0(mail_deliver_save+0x1b2) [0x7f38e6469d62] -> /usr/lib/dovecot/libdovecot-lda.so.0(mail_deliver+0xe3) [0x7f38e646a113] -> /usr/lib/dovecot/dovecot-lda(main+0x38a) [0x40295a] -> /lib/x86_64-linux-gnu/libc.so.6(__libc_start_main+0xfd) [0x7f38e5b2aead] -> /usr/lib/dovecot/dovecot-lda() [0x4031e1] Feb 21 16:33:07 hopper exim[10094]: 1U8XCf-0002Za-4s ** eugene(a)hopper.volia.net <root(a)hopper.volia.net> R=local_user T=dovecot_delivery: Child process of dovecot_delivery transport (running command "/usr/lib/dovecot/dovecot-lda -f $sender_address -a $original_local_part@$original_domain") was terminated by signal 6 (Aborted) Feb 21 16:33:07 hopper exim[10094]: 1U8XCf-0002Za-4s Frozen (delivery error message) It started a couple of days ago. Triggered by the mass delivery. E.g. by for i in {1..50}; do { mail -s "test $i" eugene(a)hopper.volia.net <<< "Try $i" & }; done I suspected that sieve might cause that, but disabling it gave nothing. Current root@hopper[pts/0]:/etc/dovecot> doveconf -n # 2.2.beta1 (808a64899e8a): /etc/dovecot/dovecot.conf # OS: Linux 3.2.0-4-amd64 x86_64 Debian 7.0 mail_location = maildir:~/Maildir namespace inbox { inbox = yes location = mailbox Drafts { special_use = \Drafts } mailbox Junk { special_use = \Junk } mailbox Sent { special_use = \Sent } mailbox "Sent Messages" { special_use = \Sent } mailbox Trash { special_use = \Trash } prefix = } passdb { driver = pam } postmaster_address = hopper(a)volia.net protocols = imap service imap-login { inet_listener imap { port = 143 } inet_listener imap2 { port = 1357 } } ssl_cert = </etc/dovecot/dovecot.pem ssl_key = </etc/dovecot/private/dovecot.pem userdb { driver = passwd } Is there any other info I could provide? -- Eugene Paskevich | *==)----------- | Plug me into eugene(a)raptor.kiev.ua | -----------(==* | The Matrix

2 1

[Dovecot] Bug: empty m-files
by Peer Heinlein 22 Feb '13

22 Feb '13

Having an empty m-File, doveadm force-resync isn't able to detect and repair this: -rw------- 1 vmail vmail 4948043 8. Jan 20:32 m.634 -rw------- 1 vmail vmail 5237411 8. Jan 20:32 m.635 -rw------- 1 vmail vmail 0 9. Jan 16:23 m.636 mail1:~ # doveadm force-resync -u user mailbox INBOX doveadm(user): Warning: fscking index file /vmail/example.com/user//mdbox/storage/dovecot.map.index doveadm(user): Warning: mdbox /vmail/example.com/user//mdbox/storage: rebuilding indexes doveadm(user): Error: Corrupted dbox file /vmail/example.com/user//mdbox/storage/m.636 (around offset=0): EOF while reading file header doveadm(user): Error: mdbox rebuild: Failed to fix file /vmail/example.com/user//mdbox/storage/m.636 doveadm(user): Error: mdbox /vmail/example.com/user//mdbox/storage: Duplicate GUID 2cafdd2f0224ec50773c0000085c1439 in m.627:16 and m.638:16 Peer -- Heinlein Support GmbH Schwedter Str. 8/9b, 10119 Berlin http://www.heinlein-support.de Tel: 030 / 405051-42 Fax: 030 / 405051-19 Zwangsangaben lt. §35a GmbHG: HRB 93818 B / Amtsgericht Berlin-Charlottenburg, Geschäftsführer: Peer Heinlein -- Sitz: Berlin

2 1

[Dovecot] Crash in dsync [dovecot 2.1.7]
by Joe Beaubien 22 Feb '13

22 Feb '13

Just taught i'd report this crash while running the ofllowing command: 19748 Aborted (core dumped) $DSYNC_BINARY -f -u document backup mdbox:$LOCAL_BACKUP_PATH/emails/document >> $LOG_FILE 2>&1 Here is the traceback I got: dsync(document): Error: Mailbox INBOX/Julie: Corrupted index, uidvalidity=0 dsync(document): Warning: mdbox /backup_hdd/current/emails/document/storage: rebuilding indexes dsync(document): Panic: file mdbox-storage-rebuild.c: line 66 (mdbox_storage_rebuild_init): assertion failed: (!storage->rebuilding_storage) dsync(document): Error: Raw backtrace: /opt/dovecot-2.1.7/lib/dovecot/libdovecot.so.0(+0x4203a) [0x7faa3bbde03a] -> /opt/dovecot-2.1.7/lib/dovecot/libdovecot.so.0(default_fatal_handler+0x2a) [0x7faa3bbde0fa] -> /opt/dovecot-2.1.7/lib/dovecot/libdovecot.so.0(i_fatal+0) [0x7faa3bbb53f0] -> /opt/dovecot-2.1.7/lib/dovecot/libdovecot-storage.so.0(mdbox_storage_rebuild_in_context+0x117e) [0x7faa3be656de] -> /opt/dovecot-2.1.7/lib/dovecot/libdovecot-storage.so.0(mdbox_sync_begin+0x23a) [0x7faa3be62b1a] -> /opt/dovecot-2.1.7/lib/dovecot/libdovecot-storage.so.0(mdbox_sync+0x33) [0x7faa3be630f3] -> /opt/dovecot-2.1.7/lib/dovecot/libdovecot-storage.so.0(mdbox_storage_sync_init+0x7e) [0x7faa3be631be] -> /opt/dovecot-2.1.7/lib/dovecot/libdovecot-storage.so.0(mailbox_sync_init+0x31) [0x7faa3bea2871] -> /opt/dovecot-2.1.7/lib/dovecot/libdovecot-storage.so.0(mailbox_sync+0x27) [0x7faa3bea2997] -> /opt/dovecot-2.1.7/lib/dovecot/libdovecot-storage.so.0(mailbox_mark_index_deleted+0x9b) [0x7faa3bea3aeb] -> /opt/dovecot-2.1.7/lib/dovecot/libdovecot-storage.so.0(mailbox_open+0x85) [0x7faa3bea3a25] -> /opt/dovecot-2.1.7/lib/dovecot/libdovecot-storage.so.0(mdbox_storage_rebuild_in_context+0xb7b) [0x7faa3be650db] -> /opt/dovecot-2.1.7/lib/dovecot/libdovecot-storage.so.0(mdbox_sync_begin+0x23a) [0x7faa3be62b1a] -> /opt/dovecot-2.1.7/lib/dovecot/libdovecot-storage.so.0(mdbox_sync+0x33) [0x7faa3be630f3] -> /opt/dovecot-2.1.7/lib/dovecot/libdovecot-storage.so.0(mdbox_storage_sync_init+0x7e) [0x7faa3be631be] -> /opt/dovecot-2.1.7/lib/dovecot/libdovecot-storage.so.0(mailbox_sync_init+0x31) [0x7faa3bea2871] -> /opt/dovecot-2.1.7/lib/dovecot/libdovecot-storage.so.0(mailbox_sync+0x27) [0x7faa3bea2997] -> /opt/dovecot-2.1.7/lib/dovecot/libdovecot-storage.so.0(index_storage_get_status+0x8a) [0x7faa3bec47da] -> /opt/dovecot/lib/dovecot/lib20_fts_plugin.so(+0xc002) [0x7faa3a775002] -> /opt/dovecot/bin/dsync() [0x42bf9a] -> /opt/dovecot/bin/dsync(dsync_worker_mailbox_iter_next+0x29) [0x428eb9] -> /opt/dovecot/bin/dsync() [0x423bfe] -> /opt/dovecot/bin/dsync(dsync_brain_sync+0x832) [0x4233a2] -> /opt/dovecot/bin/dsync(dsync_brain_sync_all+0x18) [0x423ee8] -> /opt/dovecot/bin/dsync() [0x420f36] -> /opt/dovecot/bin/dsync() [0x4215b2] -> /opt/dovecot/bin/dsync() [0x40e798] -> /opt/dovecot/bin/dsync() [0x40f1ea]

2 1

[Dovecot] Problem with managesieve proxy
by Lance A. Brown 22 Feb '13

22 Feb '13

Hi folks, I'm setting up a new dovecot email service and have a proxy server running in front of it to facilitate migrating users from my very old UW-IMAP-based mail server to the new one. I have a mysql proxy table that directs inbound IMAP and LMTP connections to the correct server, works great. Managesieve connections are not working through the proxy using either with the sieverules plugin for RoundCube or the Sieve plugin for Thunderbird. If I point them directly at the actual dovecot server everything works fine, so I must have something wrong in my proxy setup. This is what I get in the maillogs when I try to connect to the managesieve proxy: PROXY machine: Feb 21 16:16:38 sortie dovecot: managesieve-login: Error: proxy: Remote x.y.z.110:4190 disconnected: Connection closed (state=0, duration=0s): user=<brown>, method=PLAIN, rip=x.y.z.83, lip=x.y.z.109, TLS, session=<0jUal0LWswCYAxZT> Feb 21 16:16:38 sortie dovecot: managesieve-login: Disconnected (internal failure, 1 succesful auths): user=<brown>, method=PLAIN, rip=x.y.z.83, lip=x.y.z.109, TLS, session=<0jUal0LWswCYAxZT> SERVER machine: Feb 21 16:16:38 postie dovecot: managesieve-login: Disconnected: Too many invalid commands. (no auth attempts in 0 secs): user=<>, rip=x.y.z.109, lip=x.y.z.110, session=<REgal0LWnQCYAxZt> Output of a 'tcpdump -A' is attached as well if needed. x.y.z.83 - Roundcube webmail server x.y.z.109 - PROXY x.y.z.110 - SERVER If I turn off TLS all-around I get an error in the roundcube log that says: [20-Feb-2013 23:02:27] No supported authentication method found. The server supports these methods: , but we want to use: PLAIN (): [20-Feb-2013 23:02:27] Not currently in AUTHORISATION state (1): I am using Pigeonhole 0.3.3 on both proxy and server. Dovecot configurations for the proxy and server are attached below. Here are the SQL files referenced in the proxy configs. Long lines broke for readability. /etc/dovecot/dovecot-sql.conf.ext driver = mysql connect = host=localhost dbname=dovecot user=dovecot password=XXX password_query = SELECT NULL AS password, 'any-cert' as 'ssl', \ 'Y' as nopassword, host, user as destuser, \ 'Y' AS proxy \ FROM proxy WHERE user = '%u' /etc/dovecot/dovecot-sql-lmtp.conf.ext driver = mysql connect = host=localhost dbname=dovecot user=dovecot password=XXX password_query = SELECT NULL AS password, 'any-cert' as 'ssl', \ 'Y' as nopassword, host, user as destuser, 'Y' AS proxy \ FROM proxy WHERE user = '%n' The proxy DB table looks like this: - -- Table structure for table `proxy` -- CREATE TABLE IF NOT EXISTS `proxy` ( `id` int(11) NOT NULL AUTO_INCREMENT, `user` varchar(255) NOT NULL, `email` varchar(64) NOT NULL, `host` varchar(16) DEFAULT NULL, PRIMARY KEY (`id`), UNIQUE KEY `user_index` (`user`) ) ENGINE=MyISAM DEFAULT CHARSET=latin1 AUTO_INCREMENT=379 ; -- -- Dumping data for table `proxy` -- INSERT INTO `proxy` (`id`, `user`, `email`, `host`) VALUES (1, 'brown', '', 'x.y.z.110'), (2, 'fizgig', '', 'x.r.z.15'), 110 is the new dovecot IMAP server, 15 is the old UW-IMAP server. Any advice on getting managesieve proxy running would be greatly appreciated. Thanks, --[Lance] -- GPG Fingerprint: 409B A409 A38D 92BF 15D9 6EEE 9A82 F2AC 69AC 07B9 CACert.org Assurer

1 1

[Dovecot] Dovecot list archives not updating
by Mark Sapiro 22 Feb '13

22 Feb '13

The February archive indexes of this list haven't been updating since Feb 8. The text file at <http://dovecot.org/pipermail/dovecot/2013-February.txt> appears to be up to date with all posts, but the index pages at <http://dovecot.org/pipermail/dovecot/2013-February/thread.html>, <http://dovecot.org/pipermail/dovecot/2013-February/subject.html>, <http://dovecot.org/pipermail/dovecot/2013-February/author.html> and <http://dovecot.org/pipermail/dovecot/2013-February/date.html> have no posts after the one at <http://dovecot.org/pipermail/dovecot/2013-February/071213.html> from Fri Feb 8 16:31:49 EET 2013. Subsequent posts appear to not have individual HTML pages either as URLs <http://dovecot.org/pipermail/dovecot/2013-February/071214.html> and <http://dovecot.org/pipermail/dovecot/2013-February/071215.html> return 404 errors. I suspect there is a permissions issue starting soon after Fri Feb 8 16:31:49 EET 2013 and that Mailman's error log has lots of tracebacks from this. If that is the case, the messages were probably all shunted and running Mailman's bin/unshunt after fixing the underlying issue and also making sure that any messages older than Fri Feb 8 16:31:49 EET 2013 in Mailman's 'shunt' queue that should not also be reprocessed are removed. -- Mark Sapiro <mark(a)msapiro.net> The highway is for gamblers, San Francisco Bay Area, California better use your sense - B. Dylan

1 0

[Dovecot] Public folders and dsync replication Dovecot v2.1.7
by Steve Brown 21 Feb '13

21 Feb '13

Dear all I have a few public folders setup and working fine, and one such public folder is 'Public/General'. I'm trying to sort dsync replication out to another server and notice that the system log is full of error messages similar to: dovecot: doveadm: Error: dsync-remote(username: Error: Can't update subscription Pu blic/General: Invalid mailbox name The replication setup is basically as per the v2 wiki. It seems that when a mail client subscribes to a public folder, the folder name is put into '~/Maildir/subscriptions' and this seems to be the cause of the error during replication. The mails are not synchronised to the other server. Public namespace is: namespace { type = public separator = / prefix = Public/ location = maildir:/home/shared/public subscriptions = no list = yes } Hopefully somebody can send the clue bus over? Steve -- Steve Brown Operations Manager Connection Services Ltd +44 (0)1189 711 765 Direct +44 (0)870 751 7571 Fax www.connectionservices.com www.hotspot-directory.com

3 2

Re: [Dovecot] Dovecot LDA LDAP lookups on samba4 server ends very often in timeouts (Christian Wiese)
by Marco Carcano 21 Feb '13

21 Feb '13

Dear Christian thank you very much - you got it at the very first shot: modified /etc/ openldap/ldap.conf adding REFERRALS off en everything works without issues now many thanks Marco Carcano

1 0

[Dovecot] Dovecot LDA LDAP lookups on samba4 server ends very often in timeouts
by marco＠carcano.ch 20 Feb '13

20 Feb '13

I hope that someone will be so kind to help me into solving this really strange thing (don't know if it is a bug or not) I have a samba4 server and want to use postfix+dovecot - dovecot version is 2.0.11 as for the postfix side everything is OK (all the LDAP lookups works without any error, tested also manually with postmap -q) the real pain is with dovecot deliver: it seems that sometimes lda tries to lookup to the LDPA (samba 4) server, got a reply, an then report(after 2 minutes) a lookup timeout error the really strange thing is that (very seldom) lda works, but most of the times I got the timeout error. The strange thing is that if I use ldapsearch I never got timeout neither late replies, and even postfix performs its lookups without any issue it seems something related to lda itself (I do not know if I have a wrong configuration, but I think this is not a configuration issue, otherwise it should not work at all) here are the information logged when it does not work - after this log you will find the one when I got the failure (if needed I can provide a .pcap file too) (trailing and leading spaces of AT charcater has been added by me) ############################## FAULTY DELIVER LOG ############################################# Feb 20 12:20:50 sng02 postfix/smtpd[8928]: connect from localhost[127.0.0.1] Feb 20 12:21:14 sng02 postfix/smtpd[8928]: A38D4407F5: client=localhost[127.0.0.1] Feb 20 12:21:20 sng02 postfix/cleanup[8891]: A38D4407F5: warning: header Subject: prova from localhost[127.0.0.1]; from=<marco @ senderdomain.tld> to=<mac @ mydomain.com> proto=SMTP helo=<senderdomain.tld> Feb 20 12:21:20 sng02 postfix/cleanup[8891]: A38D4407F5: message-id=<20130220112114.A38D4407F5 @ srv01.mydomain.local> Feb 20 12:21:20 sng02 postfix/qmgr[8889]: A38D4407F5: from=<marco @ senderdomain.tld>, size=371, nrcpt=1 (queue active) Feb 20 12:21:20 sng02 dovecot: lda: Debug: Loading modules from directory: /usr/lib64/dovecot Feb 20 12:21:20 sng02 dovecot: lda: Debug: Module loaded: /usr/lib64/dovecot/lib10_quota_plugin.so Feb 20 12:21:20 sng02 dovecot: lda: Debug: Module loaded: /usr/lib64/dovecot/lib20_expire_plugin.so Feb 20 12:21:20 sng02 dovecot: lda: Debug: Module loaded: /usr/lib64/dovecot/lib90_sieve_plugin.so Feb 20 12:21:20 sng02 dovecot: auth: Debug: Loading modules from directory: /usr/lib64/dovecot/auth Feb 20 12:21:20 sng02 dovecot: auth: Debug: Module loaded: /usr/lib64/dovecot/auth/libauthdb_ldap.so Feb 20 12:21:20 sng02 dovecot: auth: Debug: Module loaded: /usr/lib64/dovecot/auth/libdriver_mysql.so Feb 20 12:21:20 sng02 dovecot: auth: Debug: Module loaded: /usr/lib64/dovecot/auth/libdriver_pgsql.so Feb 20 12:21:20 sng02 dovecot: auth: Debug: Module loaded: /usr/lib64/dovecot/auth/libdriver_sqlite.so Feb 20 12:21:20 sng02 dovecot: auth: Debug: Module loaded: /usr/lib64/dovecot/auth/libmech_gssapi.so Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_bind Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_simple_bind Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_sasl_bind Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_send_initial_request Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_new_connection 1 1 0 Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_int_open_connection Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_connect_to_host: TCP localhost:389 Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_new_socket: 16 Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_prepare_socket: 16 Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_connect_to_host: Trying ::1 389 Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_pvt_connect: fd: 16 tm: -1 async: 0 Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_open_defconn: successful Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_send_server_request Feb 20 12:21:20 sng02 dovecot: auth: Debug: master in: USER#0111#011marco.carcano#011service=lda Feb 20 12:21:20 sng02 dovecot: auth: Debug: password(marco.carcano): passdb doesn't support credential lookups Feb 20 12:21:20 sng02 dovecot: auth: Error: static(marco.carcano): passdb doesn't support lookups, can't verify user's existence Feb 20 12:21:20 sng02 dovecot: auth: Debug: ldap(marco.carcano): user search: base=DC=mydomain,DC=local scope=subtree filter=(sAMAccountname=marco.carcano) fields=Mailbox,dovecotMailQuota Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_result ld 0x6cba60 msgid -1 Feb 20 12:21:20 sng02 dovecot: auth: Error: wait4msg ld 0x6cba60 msgid -1 (timeout 0 usec) Feb 20 12:21:20 sng02 dovecot: auth: Error: wait4msg continue ld 0x6cba60 msgid -1 all 0 Feb 20 12:21:20 sng02 dovecot: auth: Error: ** ld 0x6cba60 Connections: Feb 20 12:21:20 sng02 dovecot: auth: Error: * host: localhost port: 389 (default) Feb 20 12:21:20 sng02 dovecot: auth: Error: refcnt: 2 status: Connected Feb 20 12:21:20 sng02 dovecot: auth: Error: last used: Wed Feb 20 12:21:20 2013 Feb 20 12:21:20 sng02 dovecot: auth: Error: Feb 20 12:21:20 sng02 dovecot: auth: Error: Feb 20 12:21:20 sng02 dovecot: auth: Error: ** ld 0x6cba60 Outstanding Requests: Feb 20 12:21:20 sng02 dovecot: auth: Error: * msgid 1, origid 1, status InProgress Feb 20 12:21:20 sng02 dovecot: auth: Error: outstanding referrals 0, parent count 0 Feb 20 12:21:20 sng02 dovecot: auth: Error: ld 0x6cba60 request count 1 (abandoned 0) Feb 20 12:21:20 sng02 dovecot: auth: Error: ** ld 0x6cba60 Response Queue: Feb 20 12:21:20 sng02 dovecot: auth: Error: Empty Feb 20 12:21:20 sng02 dovecot: auth: Error: ld 0x6cba60 response count 0 Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_chkResponseList ld 0x6cba60 msgid -1 all 0 Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_chkResponseList returns ld 0x6cba60 NULL Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_int_select Feb 20 12:21:20 sng02 dovecot: auth: Error: read1msg: ld 0x6cba60 msgid -1 all 0 Feb 20 12:21:20 sng02 dovecot: auth: Error: read1msg: ld 0x6cba60 msgid 1 message type bind Feb 20 12:21:20 sng02 dovecot: auth: Error: read1msg: ld 0x6cba60 0 new referrals Feb 20 12:21:20 sng02 dovecot: auth: Error: read1msg: mark request completed, ld 0x6cba60 msgid 1 Feb 20 12:21:20 sng02 dovecot: auth: Error: request done: ld 0x6cba60 msgid 1 Feb 20 12:21:20 sng02 dovecot: auth: Error: res_errno: 0, res_error: <>, res_matched: <> Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_free_request (origid 1, msgid 1) Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_parse_result Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_search Feb 20 12:21:20 sng02 dovecot: auth: Error: put_filter: "(sAMAccountname=marco.carcano)" Feb 20 12:21:20 sng02 dovecot: auth: Error: put_filter: simple Feb 20 12:21:20 sng02 dovecot: auth: Error: put_simple_filter: "sAMAccountname=marco.carcano" Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_send_initial_request Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_send_server_request Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_msgfree Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_result ld 0x6cba60 msgid -1 Feb 20 12:21:20 sng02 dovecot: auth: Error: wait4msg ld 0x6cba60 msgid -1 (timeout 0 usec) Feb 20 12:21:20 sng02 dovecot: auth: Error: wait4msg continue ld 0x6cba60 msgid -1 all 0 Feb 20 12:21:20 sng02 dovecot: auth: Error: ** ld 0x6cba60 Connections: Feb 20 12:21:20 sng02 dovecot: auth: Error: * host: localhost port: 389 (default) Feb 20 12:21:20 sng02 dovecot: auth: Error: refcnt: 2 status: Connected Feb 20 12:21:20 sng02 dovecot: auth: Error: last used: Wed Feb 20 12:21:20 2013 Feb 20 12:21:20 sng02 dovecot: auth: Error: Feb 20 12:21:20 sng02 dovecot: auth: Error: Feb 20 12:21:20 sng02 dovecot: auth: Error: ** ld 0x6cba60 Outstanding Requests: Feb 20 12:21:20 sng02 dovecot: auth: Error: * msgid 2, origid 2, status InProgress Feb 20 12:21:20 sng02 dovecot: auth: Error: outstanding referrals 0, parent count 0 Feb 20 12:21:20 sng02 dovecot: auth: Error: ld 0x6cba60 request count 1 (abandoned 0) Feb 20 12:21:20 sng02 dovecot: auth: Error: ** ld 0x6cba60 Response Queue: Feb 20 12:21:20 sng02 dovecot: auth: Error: Empty Feb 20 12:21:20 sng02 dovecot: auth: Error: ld 0x6cba60 response count 0 Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_chkResponseList ld 0x6cba60 msgid -1 all 0 Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_chkResponseList returns ld 0x6cba60 NULL Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_int_select Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_result ld 0x6cba60 msgid -1 Feb 20 12:21:20 sng02 dovecot: auth: Error: wait4msg ld 0x6cba60 msgid -1 (timeout 0 usec) Feb 20 12:21:20 sng02 dovecot: auth: Error: wait4msg continue ld 0x6cba60 msgid -1 all 0 Feb 20 12:21:20 sng02 dovecot: auth: Error: ** ld 0x6cba60 Connections: Feb 20 12:21:20 sng02 dovecot: auth: Error: * host: localhost port: 389 (default) Feb 20 12:21:20 sng02 dovecot: auth: Error: refcnt: 2 status: Connected Feb 20 12:21:20 sng02 dovecot: auth: Error: last used: Wed Feb 20 12:21:20 2013 Feb 20 12:21:20 sng02 dovecot: auth: Error: Feb 20 12:21:20 sng02 dovecot: auth: Error: Feb 20 12:21:20 sng02 dovecot: auth: Error: ** ld 0x6cba60 Outstanding Requests: Feb 20 12:21:20 sng02 dovecot: auth: Error: * msgid 2, origid 2, status InProgress Feb 20 12:21:20 sng02 dovecot: auth: Error: outstanding referrals 0, parent count 0 Feb 20 12:21:20 sng02 dovecot: auth: Error: ld 0x6cba60 request count 1 (abandoned 0) Feb 20 12:21:20 sng02 dovecot: auth: Error: ** ld 0x6cba60 Response Queue: Feb 20 12:21:20 sng02 dovecot: auth: Error: Empty Feb 20 12:21:20 sng02 dovecot: auth: Error: ld 0x6cba60 response count 0 Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_chkResponseList ld 0x6cba60 msgid -1 all 0 Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_chkResponseList returns ld 0x6cba60 NULL Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_int_select Feb 20 12:21:20 sng02 dovecot: auth: Error: read1msg: ld 0x6cba60 msgid -1 all 0 Feb 20 12:21:20 sng02 dovecot: auth: Error: read1msg: ld 0x6cba60 msgid 2 message type search-entry Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_first_attribute Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_get_values Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_next_attribute Feb 20 12:21:20 sng02 dovecot: auth: Debug: ldap(marco.carcano): result: dovecotMailQuota(quota_rule=%$)=*:storage=8192M Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_msgfree Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_result ld 0x6cba60 msgid -1 Feb 20 12:21:20 sng02 dovecot: auth: Error: wait4msg ld 0x6cba60 msgid -1 (timeout 0 usec) Feb 20 12:21:20 sng02 dovecot: auth: Error: wait4msg continue ld 0x6cba60 msgid -1 all 0 Feb 20 12:21:20 sng02 dovecot: auth: Error: ** ld 0x6cba60 Connections: Feb 20 12:21:20 sng02 dovecot: auth: Error: * host: localhost port: 389 (default) Feb 20 12:21:20 sng02 dovecot: auth: Error: refcnt: 2 status: Connected Feb 20 12:21:20 sng02 dovecot: auth: Error: last used: Wed Feb 20 12:21:20 2013 Feb 20 12:21:20 sng02 dovecot: auth: Error: Feb 20 12:21:20 sng02 dovecot: auth: Error: Feb 20 12:21:20 sng02 dovecot: auth: Error: ** ld 0x6cba60 Outstanding Requests: Feb 20 12:21:20 sng02 dovecot: auth: Error: * msgid 2, origid 2, status InProgress Feb 20 12:21:20 sng02 dovecot: auth: Error: outstanding referrals 0, parent count 0 Feb 20 12:21:20 sng02 dovecot: auth: Error: ld 0x6cba60 request count 1 (abandoned 0) Feb 20 12:21:20 sng02 dovecot: auth: Error: ** ld 0x6cba60 Response Queue: Feb 20 12:21:20 sng02 dovecot: auth: Error: Empty Feb 20 12:21:20 sng02 dovecot: auth: Error: ld 0x6cba60 response count 0 Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_chkResponseList ld 0x6cba60 msgid -1 all 0 Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_chkResponseList returns ld 0x6cba60 NULL Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_int_select Feb 20 12:21:20 sng02 dovecot: auth: Error: read1msg: ld 0x6cba60 msgid -1 all 0 Feb 20 12:21:20 sng02 dovecot: auth: Error: read1msg: ld 0x6cba60 msgid 2 message type search-reference Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_chase_v3referrals Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_url_parse_ext(ldap://mydomain.local/CN=Configuration,DC=mydomain,DC=local) Feb 20 12:21:20 sng02 dovecot: auth: Error: re_encode_request: new msgid 3, new dn <CN=Configuration,DC=mydomain,DC=local> Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_chase_v3referral: msgid 2, url "ldap://mydomain.local/CN=Configuration,DC=mydomain,DC=local" Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_send_server_request Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_new_connection 0 1 1 Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_int_open_connection Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_connect_to_host: TCP mydomain.local:389 Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_new_socket: 18 Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_prepare_socket: 18 Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_connect_to_host: Trying 192.168.32.2:389 Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_pvt_connect: fd: 18 tm: -1 async: 0 Feb 20 12:21:20 sng02 dovecot: auth: Error: anonymous rebind via ldap_sasl_bind("") Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_sasl_bind Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_send_initial_request Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_send_server_request Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_result ld 0x6cba60 msgid 4 Feb 20 12:21:20 sng02 dovecot: auth: Error: wait4msg ld 0x6cba60 msgid 4 (timeout 100000 usec) Feb 20 12:21:20 sng02 dovecot: auth: Error: wait4msg continue ld 0x6cba60 msgid 4 all 1 Feb 20 12:21:20 sng02 dovecot: auth: Error: ** ld 0x6cba60 Connections: Feb 20 12:21:20 sng02 dovecot: auth: Error: * host: mydomain.local port: 0 Feb 20 12:21:20 sng02 dovecot: auth: Error: refcnt: 2 status: Connected Feb 20 12:21:20 sng02 dovecot: auth: Error: last used: Wed Feb 20 12:21:20 2013 Feb 20 12:21:20 sng02 dovecot: auth: Error: rebind in progress Feb 20 12:21:20 sng02 dovecot: auth: Error: queue is empty Feb 20 12:21:20 sng02 dovecot: auth: Error: Feb 20 12:21:20 sng02 dovecot: auth: Error: * host: localhost port: 389 (default) Feb 20 12:21:20 sng02 dovecot: auth: Error: refcnt: 3 status: Connected Feb 20 12:21:20 sng02 dovecot: auth: Error: last used: Wed Feb 20 12:21:20 2013 Feb 20 12:21:20 sng02 dovecot: auth: Error: Feb 20 12:21:20 sng02 dovecot: auth: Error: Feb 20 12:21:20 sng02 dovecot: auth: Error: ** ld 0x6cba60 Outstanding Requests: Feb 20 12:21:20 sng02 dovecot: auth: Error: * msgid 4, origid 4, status InProgress Feb 20 12:21:20 sng02 dovecot: auth: Error: outstanding referrals 0, parent count 0 Feb 20 12:21:20 sng02 dovecot: auth: Error: * msgid 2, origid 2, status InProgress Feb 20 12:21:20 sng02 dovecot: auth: Error: outstanding referrals 1, parent count 0 Feb 20 12:21:20 sng02 dovecot: auth: Error: ld 0x6cba60 request count 2 (abandoned 0) Feb 20 12:21:20 sng02 dovecot: auth: Error: ** ld 0x6cba60 Response Queue: Feb 20 12:21:20 sng02 dovecot: auth: Error: Empty Feb 20 12:21:20 sng02 dovecot: auth: Error: ld 0x6cba60 response count 0 Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_chkResponseList ld 0x6cba60 msgid 4 all 1 Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_chkResponseList returns ld 0x6cba60 NULL Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_int_select Feb 20 12:21:20 sng02 dovecot: auth: Error: read1msg: ld 0x6cba60 msgid 4 all 1 Feb 20 12:21:20 sng02 dovecot: auth: Error: read1msg: ld 0x6cba60 msgid 2 message type search-result Feb 20 12:21:20 sng02 dovecot: auth: Error: read1msg: ld 0x6cba60 0 new referrals Feb 20 12:21:20 sng02 dovecot: auth: Error: read1msg: mark request completed, ld 0x6cba60 msgid 2 Feb 20 12:21:20 sng02 dovecot: auth: Error: wait4msg ld 0x6cba60 0 s 99952 us to go Feb 20 12:21:20 sng02 dovecot: auth: Error: wait4msg continue ld 0x6cba60 msgid 4 all 1 Feb 20 12:21:20 sng02 dovecot: auth: Error: ** ld 0x6cba60 Connections: Feb 20 12:21:20 sng02 dovecot: auth: Error: * host: mydomain.local port: 0 Feb 20 12:21:20 sng02 dovecot: auth: Error: refcnt: 2 status: Connected Feb 20 12:21:20 sng02 dovecot: auth: Error: last used: Wed Feb 20 12:21:20 2013 Feb 20 12:21:20 sng02 dovecot: auth: Error: rebind in progress Feb 20 12:21:20 sng02 dovecot: auth: Error: queue is empty Feb 20 12:21:20 sng02 dovecot: auth: Error: Feb 20 12:21:20 sng02 dovecot: auth: Error: * host: localhost port: 389 (default) Feb 20 12:21:20 sng02 dovecot: auth: Error: refcnt: 2 status: Connected Feb 20 12:21:20 sng02 dovecot: auth: Error: last used: Wed Feb 20 12:21:20 2013 Feb 20 12:21:20 sng02 dovecot: auth: Error: Feb 20 12:21:20 sng02 dovecot: auth: Error: Feb 20 12:21:20 sng02 dovecot: auth: Error: ** ld 0x6cba60 Outstanding Requests: Feb 20 12:21:20 sng02 dovecot: auth: Error: * msgid 4, origid 4, status InProgress Feb 20 12:21:20 sng02 dovecot: auth: Error: outstanding referrals 0, parent count 0 Feb 20 12:21:20 sng02 dovecot: auth: Error: * msgid 2, origid 2, status RequestCompleted Feb 20 12:21:20 sng02 dovecot: auth: Error: outstanding referrals 1, parent count 0 Feb 20 12:21:20 sng02 dovecot: auth: Error: ld 0x6cba60 request count 2 (abandoned 0) Feb 20 12:21:20 sng02 dovecot: auth: Error: ** ld 0x6cba60 Response Queue: Feb 20 12:21:20 sng02 dovecot: auth: Error: Empty Feb 20 12:21:20 sng02 dovecot: auth: Error: ld 0x6cba60 response count 0 Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_chkResponseList ld 0x6cba60 msgid 4 all 1 Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_chkResponseList returns ld 0x6cba60 NULL Feb 20 12:21:20 sng02 dovecot: auth: Error: ldap_int_select Feb 20 12:21:20 sng02 dovecot: auth: Error: read1msg: ld 0x6cba60 msgid 4 all 1 Feb 20 12:21:20 sng02 dovecot: auth: Error: read1msg: ld 0x6cba60 msgid 4 message type bind Feb 20 12:21:20 sng02 dovecot: auth: Error: read1msg: ld 0x6cba60 0 new referrals Feb 20 12:21:20 sng02 dovecot: auth: Error: read1msg: mark request completed, ld 0x6cba60 msgid 4 Feb 20 12:21:22 sng02 postfix/smtpd[8928]: disconnect from localhost[127.0.0.1] Feb 20 12:23:55 sng02 dovecot: lda: Error: userdb lookup(marco.carcano): Request timed out Feb 20 12:23:55 sng02 dovecot: lda: Fatal: Internal error occurred. Refer to server log for more information. Feb 20 12:23:56 sng02 postfix/pipe[8897]: A38D4407F5: to=<marco.carcano @ mydomain.local>, orig_to=<mac @ mydomain.com>, relay=dovecot, delay=171, delays=16/0.01/0/155, dsn=4.3.0, status=deferred (temporary failure) Feb 20 12:24:10 sng02 dovecot: master: Warning: Killed with signal 15 (by pid=8946 uid=0 code=kill) Feb 20 12:24:10 sng02 dovecot: auth: ldap(marco.carcano): Shutting down Feb 20 12:24:10 sng02 dovecot: auth: Debug: master out: FAIL#0111 Feb 20 12:24:10 sng02 dovecot: auth: Error: ldap_unbind Feb 20 12:24:10 sng02 dovecot: auth: Error: ldap_free_request (origid 2, msgid 3) Feb 20 12:24:10 sng02 dovecot: auth: Error: ldap_free_request (origid 2, msgid 2) Feb 20 12:24:10 sng02 dovecot: auth: Error: ldap_free_connection 1 1 Feb 20 12:24:10 sng02 dovecot: auth: Error: ldap_send_unbind Feb 20 12:24:10 sng02 dovecot: auth: Error: ldap_free_connection: actually freed Feb 20 12:24:10 sng02 dovecot: auth: Error: ldap_free_connection 1 1 Feb 20 12:24:10 sng02 dovecot: auth: Error: ldap_send_unbind Feb 20 12:24:10 sng02 dovecot: auth: Error: ldap_free_connection: actually freed Feb 20 12:24:18 sng02 postfix/postfix-script[8962]: stopping the Postfix mail system Feb 20 12:24:18 sng02 postfix/master[8886]: terminating on signal 15 ############################## END OF FAULTY DELIVER LOG ############################################# I'll add the log of a succefully delivered message in another mail (otherwise I exceed message size limit of the list)

2 1

Re: [Dovecot] Dovecot LDA LDAP lookups on samba4 server ends very often in timeouts
by marco＠carcano.ch 20 Feb '13

20 Feb '13

just to complete the informations of this thread, here is the log of a mail delivered succesfully (trailing and leading spaces of AT charcater has been added by me) ############################## MAIL SUCCEFFULLY DELIVERED LOG ############################################# Feb 19 17:41:01 sng02 postfix/smtpd[4006]: connect from localhost[127.0.0.1] Feb 19 17:41:28 sng02 postfix/smtpd[4006]: 95659407F5: client=localhost[127.0.0.1] Feb 19 17:41:36 sng02 postfix/cleanup[4011]: 95659407F5: warning: header Subject: prova from localhost[127.0.0.1]; from=<marco @ senderdomain.tld> to=<mac @ mydomain.com> proto=SMTP helo=<senderdomain.tld> Feb 19 17:41:36 sng02 postfix/cleanup[4011]: 95659407F5: message-id=<20130219164128.95659407F5 @ srv01.mydomain.local> Feb 19 17:41:36 sng02 postfix/qmgr[3992]: 95659407F5: from=<marco @ senderdomain.tld>, size=371, nrcpt=1 (queue active) Feb 19 17:41:36 sng02 dovecot: lda: Debug: Loading modules from directory: /usr/lib64/dovecot Feb 19 17:41:36 sng02 dovecot: lda: Debug: Module loaded: /usr/lib64/dovecot/lib10_quota_plugin.so Feb 19 17:41:36 sng02 dovecot: lda: Debug: Module loaded: /usr/lib64/dovecot/lib20_expire_plugin.so Feb 19 17:41:36 sng02 dovecot: lda: Debug: Module loaded: /usr/lib64/dovecot/lib90_sieve_plugin.so Feb 19 17:41:36 sng02 dovecot: auth: Debug: master in: USER#0111#011marco.carcano#011service=lda Feb 19 17:41:36 sng02 dovecot: auth: Debug: password(marco.carcano): passdb doesn't support credential lookups Feb 19 17:41:36 sng02 dovecot: auth: Error: static(marco.carcano): passdb doesn't support lookups, can't verify user's existence Feb 19 17:41:36 sng02 dovecot: auth: Debug: ldap(marco.carcano): user search: base=DC=mydomain,DC=local scope=subtree filter=(sAMAccountname=marco.carcano) fields=Mailbox,dovecotMailQuota Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap(marco.carcano): Connection appears to be hanging, reconnecting Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_unbind Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_free_request (origid 2, msgid 3) Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_free_request (origid 2, msgid 2) Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_free_connection 1 1 Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_send_unbind Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_free_connection: actually freed Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_free_connection 1 1 Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_send_unbind Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_free_connection: actually freed Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_create Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_bind Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_simple_bind Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_sasl_bind Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_send_initial_request Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_new_connection 1 1 0 Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_int_open_connection Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_connect_to_host: TCP localhost:389 Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_new_socket: 16 Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_prepare_socket: 16 Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_connect_to_host: Trying ::1 389 Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_pvt_connect: fd: 16 tm: -1 async: 0 Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_open_defconn: successful Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_send_server_request Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_result ld 0x666a60 msgid -1 Feb 19 17:41:36 sng02 dovecot: auth: Error: wait4msg ld 0x666a60 msgid -1 (timeout 0 usec) Feb 19 17:41:36 sng02 dovecot: auth: Error: wait4msg continue ld 0x666a60 msgid -1 all 0 Feb 19 17:41:36 sng02 dovecot: auth: Error: ** ld 0x666a60 Connections: Feb 19 17:41:36 sng02 dovecot: auth: Error: * host: localhost port: 389 (default) Feb 19 17:41:36 sng02 dovecot: auth: Error: refcnt: 2 status: Connected Feb 19 17:41:36 sng02 dovecot: auth: Error: last used: Tue Feb 19 17:41:36 2013 Feb 19 17:41:36 sng02 dovecot: auth: Error: Feb 19 17:41:36 sng02 dovecot: auth: Error: Feb 19 17:41:36 sng02 dovecot: auth: Error: ** ld 0x666a60 Outstanding Requests: Feb 19 17:41:36 sng02 dovecot: auth: Error: * msgid 1, origid 1, status InProgress Feb 19 17:41:36 sng02 dovecot: auth: Error: outstanding referrals 0, parent count 0 Feb 19 17:41:36 sng02 dovecot: auth: Error: ld 0x666a60 request count 1 (abandoned 0) Feb 19 17:41:36 sng02 dovecot: auth: Error: ** ld 0x666a60 Response Queue: Feb 19 17:41:36 sng02 dovecot: auth: Error: Empty Feb 19 17:41:36 sng02 dovecot: auth: Error: ld 0x666a60 response count 0 Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_chkResponseList ld 0x666a60 msgid -1 all 0 Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_chkResponseList returns ld 0x666a60 NULL Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_int_select Feb 19 17:41:36 sng02 dovecot: auth: Error: read1msg: ld 0x666a60 msgid -1 all 0 Feb 19 17:41:36 sng02 dovecot: auth: Error: read1msg: ld 0x666a60 msgid 1 message type bind Feb 19 17:41:36 sng02 dovecot: auth: Error: read1msg: ld 0x666a60 0 new referrals Feb 19 17:41:36 sng02 dovecot: auth: Error: read1msg: mark request completed, ld 0x666a60 msgid 1 Feb 19 17:41:36 sng02 dovecot: auth: Error: request done: ld 0x666a60 msgid 1 Feb 19 17:41:36 sng02 dovecot: auth: Error: res_errno: 0, res_error: <>, res_matched: <> Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_free_request (origid 1, msgid 1) Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_parse_result Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_search Feb 19 17:41:36 sng02 dovecot: auth: Error: put_filter: "(sAMAccountname=marco.carcano)" Feb 19 17:41:36 sng02 dovecot: auth: Error: put_filter: simple Feb 19 17:41:36 sng02 dovecot: auth: Error: put_simple_filter: "sAMAccountname=marco.carcano" Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_send_initial_request Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_send_server_request Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_search Feb 19 17:41:36 sng02 dovecot: auth: Error: put_filter: "(sAMAccountname=marco.carcano)" Feb 19 17:41:36 sng02 dovecot: auth: Error: put_filter: simple Feb 19 17:41:36 sng02 dovecot: auth: Error: put_simple_filter: "sAMAccountname=marco.carcano" Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_send_initial_request Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_send_server_request Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_msgfree Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_result ld 0x666a60 msgid -1 Feb 19 17:41:36 sng02 dovecot: auth: Error: wait4msg ld 0x666a60 msgid -1 (timeout 0 usec) Feb 19 17:41:36 sng02 dovecot: auth: Error: wait4msg continue ld 0x666a60 msgid -1 all 0 Feb 19 17:41:36 sng02 dovecot: auth: Error: ** ld 0x666a60 Connections: Feb 19 17:41:36 sng02 dovecot: auth: Error: * host: localhost port: 389 (default) Feb 19 17:41:36 sng02 dovecot: auth: Error: refcnt: 3 status: Connected Feb 19 17:41:36 sng02 dovecot: auth: Error: last used: Tue Feb 19 17:41:36 2013 Feb 19 17:41:36 sng02 dovecot: auth: Error: Feb 19 17:41:36 sng02 dovecot: auth: Error: Feb 19 17:41:36 sng02 dovecot: auth: Error: ** ld 0x666a60 Outstanding Requests: Feb 19 17:41:36 sng02 dovecot: auth: Error: * msgid 3, origid 3, status InProgress Feb 19 17:41:36 sng02 dovecot: auth: Error: outstanding referrals 0, parent count 0 Feb 19 17:41:36 sng02 dovecot: auth: Error: * msgid 2, origid 2, status InProgress Feb 19 17:41:36 sng02 dovecot: auth: Error: outstanding referrals 0, parent count 0 Feb 19 17:41:36 sng02 dovecot: auth: Error: ld 0x666a60 request count 2 (abandoned 0) Feb 19 17:41:36 sng02 dovecot: auth: Error: ** ld 0x666a60 Response Queue: Feb 19 17:41:36 sng02 dovecot: auth: Error: Empty Feb 19 17:41:36 sng02 dovecot: auth: Error: ld 0x666a60 response count 0 Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_chkResponseList ld 0x666a60 msgid -1 all 0 Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_chkResponseList returns ld 0x666a60 NULL Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_int_select Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_result ld 0x666a60 msgid -1 Feb 19 17:41:36 sng02 dovecot: auth: Error: wait4msg ld 0x666a60 msgid -1 (timeout 0 usec) Feb 19 17:41:36 sng02 dovecot: auth: Error: wait4msg continue ld 0x666a60 msgid -1 all 0 Feb 19 17:41:36 sng02 dovecot: auth: Error: ** ld 0x666a60 Connections: Feb 19 17:41:36 sng02 dovecot: auth: Error: * host: localhost port: 389 (default) Feb 19 17:41:36 sng02 dovecot: auth: Error: refcnt: 3 status: Connected Feb 19 17:41:36 sng02 dovecot: auth: Error: last used: Tue Feb 19 17:41:36 2013 Feb 19 17:41:36 sng02 dovecot: auth: Error: Feb 19 17:41:36 sng02 dovecot: auth: Error: Feb 19 17:41:36 sng02 dovecot: auth: Error: ** ld 0x666a60 Outstanding Requests: Feb 19 17:41:36 sng02 dovecot: auth: Error: * msgid 3, origid 3, status InProgress Feb 19 17:41:36 sng02 dovecot: auth: Error: outstanding referrals 0, parent count 0 Feb 19 17:41:36 sng02 dovecot: auth: Error: * msgid 2, origid 2, status InProgress Feb 19 17:41:36 sng02 dovecot: auth: Error: outstanding referrals 0, parent count 0 Feb 19 17:41:36 sng02 dovecot: auth: Error: ld 0x666a60 request count 2 (abandoned 0) Feb 19 17:41:36 sng02 dovecot: auth: Error: ** ld 0x666a60 Response Queue: Feb 19 17:41:36 sng02 dovecot: auth: Error: Empty Feb 19 17:41:36 sng02 dovecot: auth: Error: ld 0x666a60 response count 0 Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_chkResponseList ld 0x666a60 msgid -1 all 0 Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_chkResponseList returns ld 0x666a60 NULL Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_int_select Feb 19 17:41:36 sng02 dovecot: auth: Error: read1msg: ld 0x666a60 msgid -1 all 0 Feb 19 17:41:36 sng02 dovecot: auth: Error: read1msg: ld 0x666a60 msgid 2 message type search-entry Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_msgfree Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_result ld 0x666a60 msgid -1 Feb 19 17:41:36 sng02 dovecot: auth: Error: wait4msg ld 0x666a60 msgid -1 (timeout 0 usec) Feb 19 17:41:36 sng02 dovecot: auth: Error: wait4msg continue ld 0x666a60 msgid -1 all 0 Feb 19 17:41:36 sng02 dovecot: auth: Error: ** ld 0x666a60 Connections: Feb 19 17:41:36 sng02 dovecot: auth: Error: * host: localhost port: 389 (default) Feb 19 17:41:36 sng02 dovecot: auth: Error: refcnt: 3 status: Connected Feb 19 17:41:36 sng02 dovecot: auth: Error: last used: Tue Feb 19 17:41:36 2013 Feb 19 17:41:36 sng02 dovecot: auth: Error: Feb 19 17:41:36 sng02 dovecot: auth: Error: Feb 19 17:41:36 sng02 dovecot: auth: Error: ** ld 0x666a60 Outstanding Requests: Feb 19 17:41:36 sng02 dovecot: auth: Error: * msgid 3, origid 3, status InProgress Feb 19 17:41:36 sng02 dovecot: auth: Error: outstanding referrals 0, parent count 0 Feb 19 17:41:36 sng02 dovecot: auth: Error: * msgid 2, origid 2, status InProgress Feb 19 17:41:36 sng02 dovecot: auth: Error: outstanding referrals 0, parent count 0 Feb 19 17:41:36 sng02 dovecot: auth: Error: ld 0x666a60 request count 2 (abandoned 0) Feb 19 17:41:36 sng02 dovecot: auth: Error: ** ld 0x666a60 Response Queue: Feb 19 17:41:36 sng02 dovecot: auth: Error: Empty Feb 19 17:41:36 sng02 dovecot: auth: Error: ld 0x666a60 response count 0 Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_chkResponseList ld 0x666a60 msgid -1 all 0 Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_chkResponseList returns ld 0x666a60 NULL Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_int_select Feb 19 17:41:36 sng02 dovecot: auth: Error: read1msg: ld 0x666a60 msgid -1 all 0 Feb 19 17:41:36 sng02 dovecot: auth: Error: read1msg: ld 0x666a60 msgid 2 message type search-reference Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_chase_v3referrals Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_url_parse_ext(ldap://mydomain.local/CN=Configuration,DC=mydomain,DC=local) Feb 19 17:41:36 sng02 dovecot: auth: Error: re_encode_request: new msgid 4, new dn <CN=Configuration,DC=mydomain,DC=local> Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_chase_v3referral: msgid 2, url "ldap://mydomain.local/CN=Configuration,DC=mydomain,DC=local" Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_send_server_request Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_new_connection 0 1 1 Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_int_open_connection Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_connect_to_host: TCP mydomain.local:389 Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_new_socket: 18 Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_prepare_socket: 18 Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_connect_to_host: Trying 192.168.32.2:389 Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_pvt_connect: fd: 18 tm: -1 async: 0 Feb 19 17:41:36 sng02 dovecot: auth: Error: anonymous rebind via ldap_sasl_bind("") Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_sasl_bind Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_send_initial_request Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_send_server_request Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_result ld 0x666a60 msgid 5 Feb 19 17:41:36 sng02 dovecot: auth: Error: wait4msg ld 0x666a60 msgid 5 (timeout 100000 usec) Feb 19 17:41:36 sng02 dovecot: auth: Error: wait4msg continue ld 0x666a60 msgid 5 all 1 Feb 19 17:41:36 sng02 dovecot: auth: Error: ** ld 0x666a60 Connections: Feb 19 17:41:36 sng02 dovecot: auth: Error: * host: mydomain.local port: 0 Feb 19 17:41:36 sng02 dovecot: auth: Error: refcnt: 2 status: Connected Feb 19 17:41:36 sng02 dovecot: auth: Error: last used: Tue Feb 19 17:41:36 2013 Feb 19 17:41:36 sng02 dovecot: auth: Error: rebind in progress Feb 19 17:41:36 sng02 dovecot: auth: Error: queue is empty Feb 19 17:41:36 sng02 dovecot: auth: Error: Feb 19 17:41:36 sng02 dovecot: auth: Error: * host: localhost port: 389 (default) Feb 19 17:41:36 sng02 dovecot: auth: Error: refcnt: 4 status: Connected Feb 19 17:41:36 sng02 dovecot: auth: Error: last used: Tue Feb 19 17:41:36 2013 Feb 19 17:41:36 sng02 dovecot: auth: Error: Feb 19 17:41:36 sng02 dovecot: auth: Error: Feb 19 17:41:36 sng02 dovecot: auth: Error: ** ld 0x666a60 Outstanding Requests: Feb 19 17:41:36 sng02 dovecot: auth: Error: * msgid 5, origid 5, status InProgress Feb 19 17:41:36 sng02 dovecot: auth: Error: outstanding referrals 0, parent count 0 Feb 19 17:41:36 sng02 dovecot: auth: Error: * msgid 3, origid 3, status InProgress Feb 19 17:41:36 sng02 dovecot: auth: Error: outstanding referrals 0, parent count 0 Feb 19 17:41:36 sng02 dovecot: auth: Error: * msgid 2, origid 2, status InProgress Feb 19 17:41:36 sng02 dovecot: auth: Error: outstanding referrals 1, parent count 0 Feb 19 17:41:36 sng02 dovecot: auth: Error: ld 0x666a60 request count 3 (abandoned 0) Feb 19 17:41:36 sng02 dovecot: auth: Error: ** ld 0x666a60 Response Queue: Feb 19 17:41:36 sng02 dovecot: auth: Error: Empty Feb 19 17:41:36 sng02 dovecot: auth: Error: ld 0x666a60 response count 0 Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_chkResponseList ld 0x666a60 msgid 5 all 1 Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_chkResponseList returns ld 0x666a60 NULL Feb 19 17:41:36 sng02 dovecot: auth: Error: ldap_int_select Feb 19 17:41:36 sng02 dovecot: auth: Error: read1msg: ld 0x666a60 msgid 5 all 1 Feb 19 17:41:36 sng02 dovecot: auth: Error: read1msg: ld 0x666a60 msgid 2 message type search-result Feb 19 17:41:36 sng02 dovecot: auth: Error: read1msg: ld 0x666a60 0 new referrals Feb 19 17:41:36 sng02 dovecot: auth: Error: read1msg: mark request completed, ld 0x666a60 msgid 2 Feb 19 17:41:36 sng02 dovecot: auth: Error: wait4msg ld 0x666a60 0 s 99954 us to go Feb 19 17:41:36 sng02 dovecot: auth: Error: wait4msg continue ld 0x666a60 msgid 5 all 1 Feb 19 17:41:36 sng02 dovecot: auth: Error: ** ld 0x666a60 Connections: Feb 19 17:41:36 sng02 dovecot: auth: Error: * host: mydomain.local port: 0 Feb 19 17:41:36 sng02 dovecot: auth: Error: refcnt: 2 status: Connected Feb 19 17:41:36 sng02 dovecot: auth: Error: last used: Tue Feb 19 17:41:36 2013 Feb 19 17:41:36 sng02 dovecot: auth: Error: rebind in progress Feb 19 17:41:36 sng02 dovecot: auth: Error: queue is empty Feb 19 17:41:36 sng02 dovecot: auth: Error: Feb 19 17:41:36 sng02 dovecot: auth: Error: * host: localhost port: 389 (default) Feb 19 17:41:36 sng02 dovecot: auth: Error: refcnt: 3 status: Connected Feb 19 17:41:36 sng02 dovecot: auth: Error: last used: Tue Feb 19 17:41:36 2013 Feb 19 17:41:36 sng02 dovecot: auth: Error: Feb 19 17:41:36 sng02 dovecot: auth: Error: Feb 19 17:41:36 sng02 dovecot: auth: Error: ** ld 0x666a60 Outstanding Requests: Feb 19 17:41:36 sng02 dovecot: lda: Debug: auth input: marco.carcano quota_rule=*:storage=8192M Feb 19 17:41:36 sng02 dovecot: lda: Debug: Added userdb setting: plugin/quota_rule=*:storage=8192M Feb 19 17:41:36 sng02 dovecot: lda(marco.carcano): Debug: Effective uid=8, gid=12, home= Feb 19 17:41:36 sng02 dovecot: lda(marco.carcano): Debug: Quota root: name=User quota backend=maildir args= Feb 19 17:41:36 sng02 dovecot: lda(marco.carcano): Debug: Quota rule: root=User quota mailbox=* bytes=8589934592 messages=0 Feb 19 17:41:36 sng02 dovecot: lda(marco.carcano): Debug: Quota rule: root=User quota mailbox=Trash bytes=+104857600 messages=0 Feb 19 17:41:36 sng02 dovecot: lda(marco.carcano): Debug: Quota warning: bytes=8160437862 (95%) messages=0 reverse=no command=quota-warning 95 marco.carcano Feb 19 17:41:36 sng02 dovecot: lda(marco.carcano): Debug: Quota warning: bytes=7301444403 (85%) messages=0 reverse=no command=quota-warning 85 marco.carcano Feb 19 17:41:36 sng02 dovecot: lda(marco.carcano): Debug: Quota warning: bytes=6442450944 (75%) messages=0 reverse=no command=quota-warning 75 marco.carcano Feb 19 17:41:36 sng02 dovecot: lda(marco.carcano): Debug: Quota root: name=Shared quota backend=maildir args=ns=Public/ Feb 19 17:41:36 sng02 dovecot: lda(marco.carcano): Debug: Quota rule: root=Shared quota mailbox=* bytes=20971520000 messages=0 Feb 19 17:41:36 sng02 dovecot: lda: Error: user marco.carcano: Auth USER lookup failed Feb 19 17:41:36 sng02 dovecot: lda: Debug: auth input: Feb 19 17:41:36 sng02 dovecot: lda: Fatal: Internal error occurred. Refer to server log for more information. Feb 19 17:41:36 sng02 dovecot: lda(marco.carcano): Debug: Namespace : type=private, prefix=, sep=/, inbox=yes, hidden=no, list=yes, subscriptions=yes location=maildir:/home/mailboxstore/marco.carcano/Maildir Feb 19 17:41:36 sng02 dovecot: lda(marco.carcano): Debug: maildir++: root=/home/mailboxstore/marco.carcano/Maildir, index=, control=, inbox=/home/mailboxstore/marco.carcano/Maildir Feb 19 17:41:36 sng02 dovecot: lda(marco.carcano): Debug: Namespace : type=shared, prefix=shared/%u/, sep=/, inbox=no, hidden=no, list=children, subscriptions=no location=maildir:/home/mailboxstore/%Lu/Maildir:INDEX=/home/mailboxstore/marco.carcano/Maildir/shared/%Lu Feb 19 17:41:36 sng02 dovecot: lda(marco.carcano): Debug: shared: root=/var/run/dovecot, index=, control=, inbox= Feb 19 17:41:36 sng02 dovecot: lda(marco.carcano): Debug: Namespace : type=public, prefix=Public/, sep=/, inbox=no, hidden=no, list=yes, subscriptions=no location=maildir:/home/mailboxstore/public Feb 19 17:41:36 sng02 dovecot: lda(marco.carcano): Debug: maildir++: root=/home/mailboxstore/public, index=, control=, inbox= Feb 19 17:41:36 sng02 dovecot: lda(marco.carcano): Debug: expire: No expire setting - plugin disabled Feb 19 17:41:36 sng02 dovecot: lda(marco.carcano): Debug: Quota root: name=User quota backend=maildir args= Feb 19 17:41:36 sng02 dovecot: lda(marco.carcano): Debug: Quota rule: root=User quota mailbox=* bytes=1073741824 messages=0 Feb 19 17:41:36 sng02 dovecot: lda(marco.carcano): Debug: Quota rule: root=User quota mailbox=Trash bytes=+104857600 messages=0 Feb 19 17:41:36 sng02 dovecot: lda(marco.carcano): Debug: Quota warning: bytes=1020054732 (95%) messages=0 reverse=no command=quota-warning 95 marco.carcano Feb 19 17:41:36 sng02 dovecot: lda(marco.carcano): Debug: Quota warning: bytes=912680550 (85%) messages=0 reverse=no command=quota-warning 85 marco.carcano Feb 19 17:41:36 sng02 dovecot: lda(marco.carcano): Debug: Quota warning: bytes=805306368 (75%) messages=0 reverse=no command=quota-warning 75 marco.carcano Feb 19 17:41:36 sng02 dovecot: lda(marco.carcano): Debug: Quota root: name=Shared quota backend=maildir args=ns=Public/ Feb 19 17:41:36 sng02 dovecot: lda(marco.carcano): Debug: Quota rule: root=Shared quota mailbox=* bytes=20971520000 messages=0 Feb 19 17:41:36 sng02 dovecot: lda(marco.carcano): Debug: none: root=, index=, control=, inbox= Feb 19 17:41:36 sng02 dovecot: lda(marco.carcano): Error: User marco.carcano doesn't have home dir set, disabling duplicate database Feb 19 17:41:36 sng02 dovecot: lda(marco.carcano): Debug: sieve: user's script path /home/mailboxstore/marco.carcano/.dovecot.sieve doesn't exist (using global script path in stead) Feb 19 17:41:36 sng02 dovecot: lda(marco.carcano): Debug: sieve: user has no valid personal script Feb 19 17:41:36 sng02 dovecot: lda(marco.carcano): Debug: sieve: no scripts to execute: reverting to default delivery. Feb 19 17:41:36 sng02 postfix/pipe[3995]: 7D9AE407E7: to=<marco.carcano @ mydomain.local>, orig_to=<mac @ mydomain.com>, relay=dovecot, delay=17102, delays=17034/0.05/0/68, dsn=4.3.0, status=deferred (temporary failure) Feb 19 17:41:36 sng02 dovecot: lda(marco.carcano): msgid=<20130219164128.95659407F5 @ srv01.mydomain.local>: saved mail to INBOX Feb 19 17:41:36 sng02 postfix/pipe[4013]: 95659407F5: to=<marco.carcano @ mydomain.local>, orig_to=<mac @ mydomain.com>, relay=dovecot, delay=22, delays=22/0.02/0/0.14, dsn=2.0.0, status=sent (delivered via dovecot service) Feb 19 17:41:36 sng02 postfix/qmgr[3992]: 95659407F5: removed Feb 19 17:41:38 sng02 postfix/smtpd[4006]: disconnect from localhost[127.0.0.1] ############################## END OF MAIL SUCCEFFULLY DELIVERED LOG #############################################

1 0