[Dovecot] LDAP Authentication ok but errors

Héctor Moreno Blanco hmoreno at gmv.com
Tue May 10 19:21:02 EEST 2011


Hello Timo,

We have installed dovecot 2.0.10. With LDAP authentication. Users authenticate successfully but in the authentication process there are errors that I don't understand because it's everything all right:

May 10 18:00:49 carpe3 dovecot: auth: Error: ldap_unbind
May 10 18:00:49 carpe3 dovecot: auth: Error: ldap_free_connection 1 1
May 10 18:00:49 carpe3 dovecot: auth: Error: ldap_send_unbind
May 10 18:00:49 carpe3 dovecot: auth: Error: ldap_free_connection: actually freed
May 10 18:02:02 carpe3 dovecot: master: Dovecot v2.0.10 starting up (core dumps disabled)
May 10 18:02:02 carpe3 dovecot: auth: Error: ldap_bind
May 10 18:02:02 carpe3 dovecot: auth: Error: ldap_simple_bind
May 10 18:02:02 carpe3 dovecot: auth: Error: ldap_sasl_bind
May 10 18:02:02 carpe3 dovecot: auth: Error: ldap_send_initial_request
May 10 18:02:02 carpe3 dovecot: auth: Error: ldap_new_connection 1 1 0
May 10 18:02:02 carpe3 dovecot: auth: Error: ldap_int_open_connection
May 10 18:02:02 carpe3 dovecot: auth: Error: ldap_connect_to_host: TCP ldap_server:389
May 10 18:02:02 carpe3 dovecot: auth: Error: ldap_new_socket: 16
May 10 18:02:02 carpe3 dovecot: auth: Error: ldap_prepare_socket: 16
May 10 18:02:02 carpe3 dovecot: auth: Error: ldap_connect_to_host: Trying xxx.xxx.xxx.xxx:389
May 10 18:02:02 carpe3 dovecot: auth: Error: ldap_connect_timeout: fd: 16 tm: -1 async: 0
May 10 18:02:02 carpe3 dovecot: auth: Error: ldap_open_defconn: successful
May 10 18:02:02 carpe3 dovecot: auth: Error: ldap_send_server_request
May 10 18:02:02 carpe3 dovecot: auth: Error: ldap_result ld 0x1c8e6df0 msgid -1
May 10 18:02:02 carpe3 dovecot: auth: Error: ldap_chkResponseList ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:02 carpe3 dovecot: auth: Error: ldap_chkResponseList returns ld 0x1c8e6df0 NULL
May 10 18:02:02 carpe3 dovecot: auth: Error: wait4msg ld 0x1c8e6df0 msgid -1 (timeout 0 usec)
May 10 18:02:02 carpe3 dovecot: auth: Error: wait4msg continue ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:02 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Connections:
May 10 18:02:02 carpe3 dovecot: auth: Error: * host: ldap_server  port: 389  (default)
May 10 18:02:02 carpe3 dovecot: auth: Error:   refcnt: 2  status: Connected
May 10 18:02:02 carpe3 dovecot: auth: Error:   last used: Tue May 10 18:02:02 2011
May 10 18:02:02 carpe3 dovecot: auth: Error:
May 10 18:02:02 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Outstanding Requests:
May 10 18:02:02 carpe3 dovecot: auth: Error:  * msgid 1,  origid 1, status InProgress
May 10 18:02:02 carpe3 dovecot: auth: Error:    outstanding referrals 0, parent count 0
May 10 18:02:02 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Response Queue:
May 10 18:02:02 carpe3 dovecot: auth: Error:    Empty
May 10 18:02:02 carpe3 dovecot: auth: Error: ldap_chkResponseList ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:02 carpe3 dovecot: auth: Error: ldap_chkResponseList returns ld 0x1c8e6df0 NULL
May 10 18:02:02 carpe3 dovecot: auth: Error: ldap_int_select
May 10 18:02:02 carpe3 dovecot: auth: Error: read1msg: ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:02 carpe3 dovecot: auth: Error: read1msg: ld 0x1c8e6df0 msgid 1 message type bind
May 10 18:02:02 carpe3 dovecot: auth: Error: new result:  res_errno: 0, res_error: <>, res_matched: <>
May 10 18:02:02 carpe3 dovecot: auth: Error: read1msg: ld 0x1c8e6df0 0 new referrals
May 10 18:02:02 carpe3 dovecot: auth: Error: read1msg:  mark request completed, ld 0x1c8e6df0 msgid 1
May 10 18:02:02 carpe3 dovecot: auth: Error: request done: ld 0x1c8e6df0 msgid 1
May 10 18:02:02 carpe3 dovecot: auth: Error: res_errno: 0, res_error: <>, res_matched: <>
May 10 18:02:02 carpe3 dovecot: auth: Error: ldap_free_request (origid 1, msgid 1)
May 10 18:02:02 carpe3 dovecot: auth: Error: ldap_free_connection 0 1
May 10 18:02:02 carpe3 dovecot: auth: Error: ldap_free_connection: refcnt 1
May 10 18:02:02 carpe3 dovecot: auth: Error: ldap_parse_result
May 10 18:02:02 carpe3 dovecot: auth: Error: ldap_msgfree
May 10 18:02:02 carpe3 dovecot: auth: Error: ldap_result ld 0x1c8e6df0 msgid -1
May 10 18:02:02 carpe3 dovecot: auth: Error: ldap_chkResponseList ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:02 carpe3 dovecot: auth: Error: ldap_chkResponseList returns ld 0x1c8e6df0 NULL
May 10 18:02:02 carpe3 dovecot: auth: Error: wait4msg ld 0x1c8e6df0 msgid -1 (timeout 0 usec)
May 10 18:02:02 carpe3 dovecot: auth: Error: wait4msg continue ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:02 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Connections:
May 10 18:02:02 carpe3 dovecot: auth: Error: * host: ldap_server  port: 389  (default)
May 10 18:02:02 carpe3 dovecot: auth: Error:   refcnt: 1  status: Connected
May 10 18:02:02 carpe3 dovecot: auth: Error:   last used: Tue May 10 18:02:02 2011
May 10 18:02:02 carpe3 dovecot: auth: Error:
May 10 18:02:02 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Outstanding Requests:
May 10 18:02:02 carpe3 dovecot: auth: Error:    Empty
May 10 18:02:02 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Response Queue:
May 10 18:02:02 carpe3 dovecot: auth: Error:    Empty
May 10 18:02:02 carpe3 dovecot: auth: Error: ldap_chkResponseList ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:02 carpe3 dovecot: auth: Error: ldap_chkResponseList returns ld 0x1c8e6df0 NULL
May 10 18:02:02 carpe3 dovecot: auth: Error: ldap_int_select
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_search
May 10 18:02:13 carpe3 dovecot: auth: Error: put_filter: "(&(objectClass=qmailUser)(uid=USERID)(accountActive=TRUE))"
May 10 18:02:13 carpe3 dovecot: auth: Error: put_filter: AND
May 10 18:02:13 carpe3 dovecot: auth: Error: put_filter_list "(objectClass=qmailUser)(uid=USERID)(accountActive=TRUE)"
May 10 18:02:13 carpe3 dovecot: auth: Error: put_filter: "(objectClass=qmailUser)"
May 10 18:02:13 carpe3 dovecot: auth: Error: put_filter: simple
May 10 18:02:13 carpe3 dovecot: auth: Error: put_simple_filter: "objectClass=qmailUser"
May 10 18:02:13 carpe3 dovecot: auth: Error: put_filter: "(uid=USERID)"
May 10 18:02:13 carpe3 dovecot: auth: Error: put_filter: simple
May 10 18:02:13 carpe3 dovecot: auth: Error: put_simple_filter: "uid= USERID "
May 10 18:02:13 carpe3 dovecot: auth: Error: put_filter: "(accountActive=TRUE)"
May 10 18:02:13 carpe3 dovecot: auth: Error: put_filter: simple
May 10 18:02:13 carpe3 dovecot: auth: Error: put_simple_filter: "accountActive=TRUE"
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_build_search_req ATTRS:
May 10 18:02:13 carpe3 dovecot: auth: Error:     uid
May 10 18:02:13 carpe3 dovecot: auth: Error:     mailMessageStore
May 10 18:02:13 carpe3 dovecot: auth: Error:     mailQuotaSize
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_send_initial_request
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_send_server_request
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_result ld 0x1c8e6df0 msgid -1
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList returns ld 0x1c8e6df0 NULL
May 10 18:02:13 carpe3 dovecot: auth: Error: wait4msg ld 0x1c8e6df0 msgid -1 (timeout 0 usec)
May 10 18:02:13 carpe3 dovecot: auth: Error: wait4msg continue ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Connections:
May 10 18:02:13 carpe3 dovecot: auth: Error: * host: ldap_server  port: 389  (default)
May 10 18:02:13 carpe3 dovecot: auth: Error:   refcnt: 2  status: Connected
May 10 18:02:13 carpe3 dovecot: auth: Error:   last used: Tue May 10 18:02:13 2011
May 10 18:02:13 carpe3 dovecot: auth: Error:
May 10 18:02:13 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Outstanding Requests:
May 10 18:02:13 carpe3 dovecot: auth: Error:  * msgid 2,  origid 2, status InProgress
May 10 18:02:13 carpe3 dovecot: auth: Error:    outstanding referrals 0, parent count 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Response Queue:
May 10 18:02:13 carpe3 dovecot: auth: Error:    Empty
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList returns ld 0x1c8e6df0 NULL
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_int_select
May 10 18:02:13 carpe3 dovecot: auth: Error: read1msg: ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: read1msg: ld 0x1c8e6df0 msgid 2 message type search-entry
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_first_attribute
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_get_values
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_next_attribute
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_get_values
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_next_attribute
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_get_values
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_next_attribute
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_get_dn
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_msgfree
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_result ld 0x1c8e6df0 msgid -1
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList returns ld 0x1c8e6df0 NULL
May 10 18:02:13 carpe3 dovecot: auth: Error: wait4msg ld 0x1c8e6df0 msgid -1 (timeout 0 usec)
May 10 18:02:13 carpe3 dovecot: auth: Error: wait4msg continue ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Connections:
May 10 18:02:13 carpe3 dovecot: auth: Error: * host: ldap_server  port: 389  (default)
May 10 18:02:13 carpe3 dovecot: auth: Error:   refcnt: 2  status: Connected
May 10 18:02:13 carpe3 dovecot: auth: Error:   last used: Tue May 10 18:02:13 2011
May 10 18:02:13 carpe3 dovecot: auth: Error:
May 10 18:02:13 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Outstanding Requests:
May 10 18:02:13 carpe3 dovecot: auth: Error:  * msgid 2,  origid 2, status InProgress
May 10 18:02:13 carpe3 dovecot: auth: Error:    outstanding referrals 0, parent count 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Response Queue:
May 10 18:02:13 carpe3 dovecot: auth: Error:    Empty
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList returns ld 0x1c8e6df0 NULL
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_int_select
May 10 18:02:13 carpe3 dovecot: auth: Error: read1msg: ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: read1msg: ld 0x1c8e6df0 msgid 2 message type search-result
May 10 18:02:13 carpe3 dovecot: auth: Error: new result:  res_errno: 0, res_error: <>, res_matched: <>
May 10 18:02:13 carpe3 dovecot: auth: Error: read1msg: ld 0x1c8e6df0 0 new referrals
May 10 18:02:13 carpe3 dovecot: auth: Error: read1msg:  mark request completed, ld 0x1c8e6df0 msgid 2
May 10 18:02:13 carpe3 dovecot: auth: Error: request done: ld 0x1c8e6df0 msgid 2
May 10 18:02:13 carpe3 dovecot: auth: Error: res_errno: 0, res_error: <>, res_matched: <>
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_free_request (origid 2, msgid 2)
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_free_connection 0 1
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_free_connection: refcnt 1
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_parse_result
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_bind
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_simple_bind
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_sasl_bind
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_send_initial_request
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_send_server_request
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_msgfree
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_result ld 0x1c8e6df0 msgid -1
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList returns ld 0x1c8e6df0 NULL
May 10 18:02:13 carpe3 dovecot: auth: Error: wait4msg ld 0x1c8e6df0 msgid -1 (timeout 0 usec)
May 10 18:02:13 carpe3 dovecot: auth: Error: wait4msg continue ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Connections:
May 10 18:02:13 carpe3 dovecot: auth: Error: * host: ldap_server  port: 389  (default)
May 10 18:02:13 carpe3 dovecot: auth: Error:   refcnt: 2  status: Connected
May 10 18:02:13 carpe3 dovecot: auth: Error:   last used: Tue May 10 18:02:13 2011
May 10 18:02:13 carpe3 dovecot: auth: Error:
May 10 18:02:13 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Outstanding Requests:
May 10 18:02:13 carpe3 dovecot: auth: Error:  * msgid 3,  origid 3, status InProgress
May 10 18:02:13 carpe3 dovecot: auth: Error:    outstanding referrals 0, parent count 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Response Queue:
May 10 18:02:13 carpe3 dovecot: auth: Error:    Empty
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList returns ld 0x1c8e6df0 NULL
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_int_select
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_result ld 0x1c8e6df0 msgid -1
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList returns ld 0x1c8e6df0 NULL
May 10 18:02:13 carpe3 dovecot: auth: Error: wait4msg ld 0x1c8e6df0 msgid -1 (timeout 0 usec)
May 10 18:02:13 carpe3 dovecot: auth: Error: wait4msg continue ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Connections:
May 10 18:02:13 carpe3 dovecot: auth: Error: * host: ldap_server  port: 389  (default)
May 10 18:02:13 carpe3 dovecot: auth: Error:   refcnt: 2  status: Connected
May 10 18:02:13 carpe3 dovecot: auth: Error:   last used: Tue May 10 18:02:13 2011
May 10 18:02:13 carpe3 dovecot: auth: Error:
May 10 18:02:13 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Outstanding Requests:
May 10 18:02:13 carpe3 dovecot: auth: Error:  * msgid 3,  origid 3, status InProgress
May 10 18:02:13 carpe3 dovecot: auth: Error:    outstanding referrals 0, parent count 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Response Queue:
May 10 18:02:13 carpe3 dovecot: auth: Error:    Empty
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList returns ld 0x1c8e6df0 NULL
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_int_select
May 10 18:02:13 carpe3 dovecot: auth: Error: read1msg: ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: read1msg: ld 0x1c8e6df0 msgid 3 message type bind
May 10 18:02:13 carpe3 dovecot: auth: Error: new result:  res_errno: 0, res_error: <>, res_matched: <>
May 10 18:02:13 carpe3 dovecot: auth: Error: read1msg: ld 0x1c8e6df0 0 new referrals
May 10 18:02:13 carpe3 dovecot: auth: Error: read1msg:  mark request completed, ld 0x1c8e6df0 msgid 3
May 10 18:02:13 carpe3 dovecot: auth: Error: request done: ld 0x1c8e6df0 msgid 3
May 10 18:02:13 carpe3 dovecot: auth: Error: res_errno: 0, res_error: <>, res_matched: <>
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_free_request (origid 3, msgid 3)
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_free_connection 0 1
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_free_connection: refcnt 1
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_parse_result
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_parse_result
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_msgfree
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_result ld 0x1c8e6df0 msgid -1
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList returns ld 0x1c8e6df0 NULL
May 10 18:02:13 carpe3 dovecot: auth: Error: wait4msg ld 0x1c8e6df0 msgid -1 (timeout 0 usec)
May 10 18:02:13 carpe3 dovecot: auth: Error: wait4msg continue ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Connections:
May 10 18:02:13 carpe3 dovecot: auth: Error: * host: ldap_server  port: 389  (default)
May 10 18:02:13 carpe3 dovecot: auth: Error:   refcnt: 1  status: Connected
May 10 18:02:13 carpe3 dovecot: auth: Error:   last used: Tue May 10 18:02:13 2011
May 10 18:02:13 carpe3 dovecot: auth: Error:
May 10 18:02:13 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Outstanding Requests:
May 10 18:02:13 carpe3 dovecot: auth: Error:    Empty
May 10 18:02:13 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Response Queue:
May 10 18:02:13 carpe3 dovecot: auth: Error:    Empty
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList returns ld 0x1c8e6df0 NULL
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_int_select
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_bind
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_simple_bind
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_sasl_bind
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_send_initial_request
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_send_server_request
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_result ld 0x1c8e6df0 msgid -1
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList returns ld 0x1c8e6df0 NULL
May 10 18:02:13 carpe3 dovecot: auth: Error: wait4msg ld 0x1c8e6df0 msgid -1 (timeout 0 usec)
May 10 18:02:13 carpe3 dovecot: auth: Error: wait4msg continue ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Connections:
May 10 18:02:13 carpe3 dovecot: auth: Error: * host: ldap_server  port: 389  (default)
May 10 18:02:13 carpe3 dovecot: auth: Error:   refcnt: 2  status: Connected
May 10 18:02:13 carpe3 dovecot: auth: Error:   last used: Tue May 10 18:02:13 2011
May 10 18:02:13 carpe3 dovecot: auth: Error:
May 10 18:02:13 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Outstanding Requests:
May 10 18:02:13 carpe3 dovecot: auth: Error:  * msgid 4,  origid 4, status InProgress
May 10 18:02:13 carpe3 dovecot: auth: Error:    outstanding referrals 0, parent count 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Response Queue:
May 10 18:02:13 carpe3 dovecot: auth: Error:    Empty
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList returns ld 0x1c8e6df0 NULL
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_int_select
May 10 18:02:13 carpe3 dovecot: auth: Error: read1msg: ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: read1msg: ld 0x1c8e6df0 msgid 4 message type bind
May 10 18:02:13 carpe3 dovecot: auth: Error: new result:  res_errno: 0, res_error: <>, res_matched: <>
May 10 18:02:13 carpe3 dovecot: auth: Error: read1msg: ld 0x1c8e6df0 0 new referrals
May 10 18:02:13 carpe3 dovecot: auth: Error: read1msg:  mark request completed, ld 0x1c8e6df0 msgid 4
May 10 18:02:13 carpe3 dovecot: auth: Error: request done: ld 0x1c8e6df0 msgid 4
May 10 18:02:13 carpe3 dovecot: auth: Error: res_errno: 0, res_error: <>, res_matched: <>
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_free_request (origid 4, msgid 4)
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_free_connection 0 1
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_free_connection: refcnt 1
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_parse_result
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_search
May 10 18:02:13 carpe3 dovecot: auth: Error: put_filter: "(&(objectClass=qmailUser)(|(mailAlternateAddress= USERID)(mail= USERID)(uid= USERID))(accountActive=TRUE))"
May 10 18:02:13 carpe3 dovecot: auth: Error: put_filter: AND
May 10 18:02:13 carpe3 dovecot: auth: Error: put_filter_list "(objectClass=qmailUser)(|(mailAlternateAddress= USERID)(mail= USERID)(uid= USERID))(accountActive=TRUE)"
May 10 18:02:13 carpe3 dovecot: auth: Error: put_filter: "(objectClass=qmailUser)"
May 10 18:02:13 carpe3 dovecot: auth: Error: put_filter: simple
May 10 18:02:13 carpe3 dovecot: auth: Error: put_simple_filter: "objectClass=qmailUser"
May 10 18:02:13 carpe3 dovecot: auth: Error: put_filter: "(|(mailAlternateAddress= USERID)(mail= USERID)(uid= USERID))"
May 10 18:02:13 carpe3 dovecot: auth: Error: put_filter: OR
May 10 18:02:13 carpe3 dovecot: auth: Error: put_filter_list "(mailAlternateAddress= USERID)(mail= USERID)(uid= USERID)"
May 10 18:02:13 carpe3 dovecot: auth: Error: put_filter: "(mailAlternateAddress= USERID)"
May 10 18:02:13 carpe3 dovecot: auth: Error: put_filter: simple
May 10 18:02:13 carpe3 dovecot: auth: Error: put_simple_filter: "mailAlternateAddress= USERID "
May 10 18:02:13 carpe3 dovecot: auth: Error: put_filter: "(mail= USERID)"
May 10 18:02:13 carpe3 dovecot: auth: Error: put_filter: simple
May 10 18:02:13 carpe3 dovecot: auth: Error: put_simple_filter: "mail= USERID "
May 10 18:02:13 carpe3 dovecot: auth: Error: put_filter: "(uid= USERID)"
May 10 18:02:13 carpe3 dovecot: auth: Error: put_filter: simple
May 10 18:02:13 carpe3 dovecot: auth: Error: put_simple_filter: "uid= USERID "
May 10 18:02:13 carpe3 dovecot: auth: Error: put_filter: "(accountActive=TRUE)"
May 10 18:02:13 carpe3 dovecot: auth: Error: put_filter: simple
May 10 18:02:13 carpe3 dovecot: auth: Error: put_simple_filter: "accountActive=TRUE"
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_build_search_req ATTRS:
May 10 18:02:13 carpe3 dovecot: auth: Error:     mailMessageStore
May 10 18:02:13 carpe3 dovecot: auth: Error:     mailQuotaSize
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_send_initial_request
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_send_server_request
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_msgfree
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_result ld 0x1c8e6df0 msgid -1
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList returns ld 0x1c8e6df0 NULL
May 10 18:02:13 carpe3 dovecot: auth: Error: wait4msg ld 0x1c8e6df0 msgid -1 (timeout 0 usec)
May 10 18:02:13 carpe3 dovecot: auth: Error: wait4msg continue ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Connections:
May 10 18:02:13 carpe3 dovecot: auth: Error: * host: ldap_server  port: 389  (default)
May 10 18:02:13 carpe3 dovecot: auth: Error:   refcnt: 2  status: Connected
May 10 18:02:13 carpe3 dovecot: auth: Error:   last used: Tue May 10 18:02:13 2011
May 10 18:02:13 carpe3 dovecot: auth: Error:
May 10 18:02:13 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Outstanding Requests:
May 10 18:02:13 carpe3 dovecot: auth: Error:  * msgid 5,  origid 5, status InProgress
May 10 18:02:13 carpe3 dovecot: auth: Error:    outstanding referrals 0, parent count 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Response Queue:
May 10 18:02:13 carpe3 dovecot: auth: Error:    Empty
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList returns ld 0x1c8e6df0 NULL
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_int_select
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_result ld 0x1c8e6df0 msgid -1
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList returns ld 0x1c8e6df0 NULL
May 10 18:02:13 carpe3 dovecot: auth: Error: wait4msg ld 0x1c8e6df0 msgid -1 (timeout 0 usec)
May 10 18:02:13 carpe3 dovecot: auth: Error: wait4msg continue ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Connections:
May 10 18:02:13 carpe3 dovecot: auth: Error: * host: ldap_server  port: 389  (default)
May 10 18:02:13 carpe3 dovecot: auth: Error:   refcnt: 2  status: Connected
May 10 18:02:13 carpe3 dovecot: auth: Error:   last used: Tue May 10 18:02:13 2011
May 10 18:02:13 carpe3 dovecot: auth: Error:
May 10 18:02:13 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Outstanding Requests:
May 10 18:02:13 carpe3 dovecot: auth: Error:  * msgid 5,  origid 5, status InProgress
May 10 18:02:13 carpe3 dovecot: auth: Error:    outstanding referrals 0, parent count 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Response Queue:
May 10 18:02:13 carpe3 dovecot: auth: Error:    Empty
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList returns ld 0x1c8e6df0 NULL
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_int_select
May 10 18:02:13 carpe3 dovecot: auth: Error: read1msg: ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: read1msg: ld 0x1c8e6df0 msgid 5 message type search-entry
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_first_attribute
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_get_values
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_next_attribute
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_get_values
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_next_attribute
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_msgfree
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_result ld 0x1c8e6df0 msgid -1
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList returns ld 0x1c8e6df0 NULL
May 10 18:02:13 carpe3 dovecot: auth: Error: wait4msg ld 0x1c8e6df0 msgid -1 (timeout 0 usec)
May 10 18:02:13 carpe3 dovecot: auth: Error: wait4msg continue ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Connections:
May 10 18:02:13 carpe3 dovecot: auth: Error: * host: ldap_server  port: 389  (default)
May 10 18:02:13 carpe3 dovecot: auth: Error:   refcnt: 2  status: Connected
May 10 18:02:13 carpe3 dovecot: auth: Error:   last used: Tue May 10 18:02:13 2011
May 10 18:02:13 carpe3 dovecot: auth: Error:
May 10 18:02:13 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Outstanding Requests:
May 10 18:02:13 carpe3 dovecot: auth: Error:  * msgid 5,  origid 5, status InProgress
May 10 18:02:13 carpe3 dovecot: auth: Error:    outstanding referrals 0, parent count 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Response Queue:
May 10 18:02:13 carpe3 dovecot: auth: Error:    Empty
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList returns ld 0x1c8e6df0 NULL
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_int_select
May 10 18:02:13 carpe3 dovecot: auth: Error: read1msg: ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: read1msg: ld 0x1c8e6df0 msgid 5 message type search-result
May 10 18:02:13 carpe3 dovecot: auth: Error: new result:  res_errno: 0, res_error: <>, res_matched: <>
May 10 18:02:13 carpe3 dovecot: auth: Error: read1msg: ld 0x1c8e6df0 0 new referrals
May 10 18:02:13 carpe3 dovecot: auth: Error: read1msg:  mark request completed, ld 0x1c8e6df0 msgid 5
May 10 18:02:13 carpe3 dovecot: auth: Error: request done: ld 0x1c8e6df0 msgid 5
May 10 18:02:13 carpe3 dovecot: auth: Error: res_errno: 0, res_error: <>, res_matched: <>
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_free_request (origid 5, msgid 5)
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_free_connection 0 1
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_free_connection: refcnt 1
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_parse_result
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_msgfree
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_result ld 0x1c8e6df0 msgid -1
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList returns ld 0x1c8e6df0 NULL
May 10 18:02:13 carpe3 dovecot: auth: Error: wait4msg ld 0x1c8e6df0 msgid -1 (timeout 0 usec)
May 10 18:02:13 carpe3 dovecot: auth: Error: wait4msg continue ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Connections:
May 10 18:02:13 carpe3 dovecot: auth: Error: * host: ldap_server  port: 389  (default)
May 10 18:02:13 carpe3 dovecot: auth: Error:   refcnt: 1  status: Connected
May 10 18:02:13 carpe3 dovecot: auth: Error:   last used: Tue May 10 18:02:13 2011
May 10 18:02:13 carpe3 dovecot: auth: Error:
May 10 18:02:13 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Outstanding Requests:
May 10 18:02:13 carpe3 dovecot: auth: Error:    Empty
May 10 18:02:13 carpe3 dovecot: auth: Error: ** ld 0x1c8e6df0 Response Queue:
May 10 18:02:13 carpe3 dovecot: auth: Error:    Empty
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList ld 0x1c8e6df0 msgid -1 all 0
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_chkResponseList returns ld 0x1c8e6df0 NULL
May 10 18:02:13 carpe3 dovecot: auth: Error: ldap_int_select
May 10 18:02:13 carpe3 dovecot: imap-login: Login: user=< USERID >, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=11362, secured
May 10 18:02:25 carpe3 dovecot: imap(USERID): Disconnected: Logged out bytes=29/655

Here is my dovecot -n output:

# OS: Linux 2.6.18-128.1.1.el5 x86_64 Scientific Linux SL release 5.2 (Boron)
auth_cache_size = 500 k
auth_cache_ttl = 1000 secs
disable_plaintext_auth = no
first_valid_uid = 501
lock_method = dotlock
mail_fsync = always
mail_gid = vmail
mail_location = maildir:%h/Maildir
mail_nfs_index = yes
mail_nfs_storage = yes
mail_uid = vmail
mmap_disable = yes
namespace {
  inbox = yes
  location =
  prefix = INBOX.
  type = private
}
passdb {
  args = /etc/dovecot/dovecot-ldap.conf
  driver = ldap
}
plugin/quota = maildir
plugin/quota_warning = storage=75%% /usr/local/dovecot/bin/quota-warning.sh 75
plugin/quota_warning2 = storage=90%% /usr/local/dovecot/bin/quota-warning.sh 90
protocols = imap pop3
service auth {
  unix_listener auth-master {
    group = vmail
    mode = 0700
    user = vmail
  }
  user = root
  vsz_limit = 4 G
}
service imap-login {
  executable = /usr/local/dovecot/libexec/dovecot/imap-login
  inet_listener imap {
    address = *
    port = 143
  }
  inet_listener imaps {
    address = *
    port = 993
  }
  process_limit = 2048
  process_min_avail = 20
  user = dovecot
}
service imap {
  executable = /usr/local/dovecot/libexec/dovecot/imap
  process_limit = 2048
}
service pop3-login {
  executable = /usr/local/dovecot/libexec/dovecot/pop3-login
  inet_listener pop3 {
    address = *
    port = 110
  }
  inet_listener pop3s {
    address = *
    port = 995
  }
  process_limit = 2048
  process_min_avail = 20
  user = dovecot
}
service pop3 {
  executable = /usr/local/dovecot/libexec/dovecot/pop3
  process_limit = 2048
}
ssl_cert = </etc/ssl/correo_domain.crt
ssl_key = </etc/ssl/correo_domain.pem
syslog_facility = local3
userdb {
  args = /etc/dovecot/dovecot-ldap.conf
  driver = ldap
}
valid_chroot_dirs = /buzones/
protocol imap {
  imap_client_workarounds =
  mail_plugin_dir = /usr/local/dovecot/lib/dovecot
  mail_plugins = quota imap_quota
}
protocol pop3 {
  mail_plugin_dir = /usr/local/dovecot/lib/dovecot
  mail_plugins = quota
  pop3_uidl_format = %08Xu%08Xv
}
protocol lda {
  auth_socket_path = /usr/local/dovecot/var/run/dovecot/auth-master
  hostname = hostname.domain.es
  mail_plugin_dir = /usr/local/dovecot/lib/dovecot
  mail_plugins = quota
  postmaster_address = postmaster at domain.es
  quota_full_tempfail = no
  rejection_reason = Su mensaje para <%t> fue rechazado automaticamente por nuestro sistema :%n%r
  rejection_subject = Rechazado: %s
  sendmail_path = /usr/sbin/exim
}


An here is my dovecot-ldap.conf:

# INFORMACIÓN DE CONEXIÓN AL LDAP
hosts = virt_ldap
auth_bind = yes
ldap_version = 3
dnpass = passwd
dn = cn=XXXXXXXX,dc=domain,dc=es
debug_level =  -1
base = dc=domain,dc=es
deref = never
scope = subtree

## CONSULTA AL LDAP PARA AUTENTICACION
pass_filter = (&(objectClass=qmailUser)(uid=%u)(accountActive=TRUE))
pass_attrs = uid=user, mailMessageStore=home, userPassword=password, mailQuotaSize=quota_rule=*:bytes=%$

## CONSULTA AL LDAP COMO BBDD
user_attrs = mailMessageStore=home, mailQuotaSize=quota_rule=*:bytes=%$
user_filter = (&(objectClass=qmailUser)(|(mailAlternateAddress=%u)(mail=%u)(uid=%u))(accountActive=TRUE))

Thank you very much in advanced

Best regards,
Héctor Moreno Blanco.


______________________
This message including any attachments may contain confidential 
information, according to our Information Security Management System,
 and intended solely for a specific individual to whom they are addressed.
 Any unauthorised copy, disclosure or distribution of this message
 is strictly forbidden. If you have received this transmission in error,
 please notify the sender immediately and delete it.

______________________
Este mensaje, y en su caso, cualquier fichero anexo al mismo,
 puede contener informacion clasificada por su emisor como confidencial
 en el marco de su Sistema de Gestion de Seguridad de la 
Informacion siendo para uso exclusivo del destinatario, quedando 
prohibida su divulgacion copia o distribucion a terceros sin la 
autorizacion expresa del remitente. Si Vd. ha recibido este mensaje 
 erroneamente, se ruega lo notifique al remitente y proceda a su borrado. 
Gracias por su colaboracion.

______________________



More information about the dovecot mailing list