[Dovecot] Dovecot LDA LDAP lookups on samba4 server ends very often in timeouts

marco at carcano.ch marco at carcano.ch
Wed Feb 20 14:25:39 EET 2013


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)





More information about the dovecot mailing list