[Dovecot] Dovecot LDA LDAP lookups on samba4 server ends very often in timeouts
Christian Wiese
christian.wiese at securepoint.de
Wed Feb 20 16:52:41 EET 2013
Hi Marco,
try to check if LDAP REFERRALS are enabled.
I had a similar issue some time ago when trying to authenticate against
an AD, which didn't worked (long timeouts) as long as I switched off
REFERRALS in the systems ldap.conf. (more info you might get by 'man
ldap.conf')
Cheers,
Chris
Am Wed, 20 Feb 2013 13:25:39 +0100
schrieb marco at carcano.ch:
>
> 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)
>
>
--
Kind regards,
Mit freundlichen Grüßen,
Christian Wiese
Follow us on Facebook:
<http://www.facebook.com/pages/Securepoint-GmbH/132451210137395>
Follow us on Twitter:
<http://twitter.com/SecurepointStat>
---------------------------------------------------------------------
Securepoint GmbH
Christian Wiese
Salzstr. 1
D-21335 Lueneburg
http://www.securepoint.de
Tele: ++49 4131 2401-0 Fax: ++49 4131 2401-50
Lueneburg HRB 1776
---------------------------------------------------------------------
CONFIDENTIALITY :
This e-mail and any attachments are confidential and may be privileged.
If you are not a named recipient, please notify the sender immediately
and do not disclose the contents to another person, use it for any
purpose or store or copy the information in any medium.
GEHEIMHALTUNGSPFLICHT :
Dieses E-Mail und alle damit verbundenen Anlagen sind vertraulich
und dürfen nur bestimmten Personen zugänglich gemacht werden.
Sofern Sie nicht zu den angegebenen Empfängern gehören, benachrichtigen
Sie bitte unverzüglich den Absender. Der Inhalt darf weder an Dritte
weitergegeben noch zu anderen Zwecken verwendet werden. Die
Informationen dürfen auch nicht auf einem Datenträger gespeichert
oder auf einen Datenträger kopiert werden.
More information about the dovecot
mailing list