1 Topic by neo1701

  • neo1701
  • Member
  • Offline
  • Registered: 2024-04-06
  • Posts: 3

Topic: Dovecot - OpenLDAP login failure

==== REQUIRED BASIC INFO OF YOUR IREDMAIL SERVER ====
- iRedMail version (check /etc/iredmail-release): 1.6.8 OPENLDAP edition.
- Deployed with iRedMail Easy or the downloadable installer? downloadable installer
- Linux/BSD distribution name and version: Linux mail 6.1.0-18-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.1.76-1 (2024-02-01) x86_64 GNU/Linux
- Store mail accounts in which backend (LDAP/MySQL/PGSQL): LDAP
- Web server (Apache or Nginx): Nginx
- Manage mail accounts with iRedAdmin-Pro? partially
- [IMPORTANT] Related original log or error message is required if you're experiencing an issue.
====
Login (Dovecot, Roundcube, Thunderbird) fails with imported (not-managed mail accounts by iRedAdmin).

2024-04-06T14:13:47.194348+02:00 mail dovecot: imap-login: Login: user=<neo@hmail.simmy.org>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=1431835, TLS, TLSv1.3 with cipher TLS_AES_256_GCM_SHA384 (256/256 bits), session=<4rQegWwVDpF/AAAB>
2024-04-06T14:19:15.473586+02:00 mail dovecot: imap(neo@hmail.simmy.org)<1431835><4rQegWwVDpF/AAAB>: Disconnected: Logged out in=500 out=3275 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
2024-04-06T14:22:01.474501+02:00 mail dovecot: imap-login: Disconnected: Connection closed (auth failed, 1 attempts in 2 secs): user=<hschindler@hmail.simmy.org>, method=LOGIN, rip=127.0.0.1, lip=127.0.0.1, TLS, TLSv1.3 with cipher TLS_AES_256_GCM_SHA384 (256/256 bits), session=<GfZ9nmwV7K5/AAAB>
2024-04-06T14:22:34.355917+02:00 mail dovecot: imap-login: Disconnected: Connection closed (auth failed, 1 attempts in 2 secs): user=<hschindler@hmail.simmy.org>, method=LOGIN, rip=127.0.0.1, lip=127.0.0.1, TLS, TLSv1.3 with cipher TLS_AES_256_GCM_SHA384 (256/256 bits), session=<JrJzoGwVKu5/AAAB>

neo@hmail.simmy.org is created with iRedMailAdmin. Login works.
hschindler@hmail.simmy.org was imported to OpenLDAP. Login fails.
Both users show up correctly in iRedMailAdmin. Login with hschindler@hmail.simmy.org directly with OpenLDAP works.

The only significant difference in these user object I could find was the hashing algorithm of the userPassword field. It's SSHA-512. The imported users originally had all kinds of hashing algorithms. During the import I convert all to SSHA.

----

Spider Email Archiver: On-Premises, lightweight email archiving software developed by iRedMail team. Supports Amazon S3 compatible storage and custom branding.

2 Reply by ZhangHuangbin

  • ZhangHuangbin
  • ZhangHuangbin
  • iRedMail Developers
  • Offline
  • Registered: 2009-05-06
  • Posts: 31,082

Re: Dovecot - OpenLDAP login failure

Please turn on debug mode in Dovecot for troubleshooting.
https://docs.iredmail.org/debug.dovecot.html

3 Reply by neo1701

  • neo1701
  • Member
  • Offline
  • Registered: 2024-04-06
  • Posts: 3

Re: Dovecot - OpenLDAP login failure

I turned on debugging. And I sent you an unsuccesful (hschindler@hmail.simmy.org) login and a succesful (neo@hmail.simmy.org) login.

Here is the error already:
2024-04-07T17:10:19.398672+02:00 mail dovecot: auth: ldap(hschindler@hmail.simmy.org,127.0.0.1,<p1ROFoMVdM5/AAAB>): unknown user

I also checked the LDAP object. I realized that hschindler@hmail.simmy.org has these services NOT enabled:
dsync
imaptls
indexer-worker
managesievetls
pop3tls
quota-status
sievetls
smtptls
sogo
sogoactivesync
sogocalendar
sogowebmail       

I try the login with Roundcube.

Here is the complete log:
2024-04-07T17:10:19.396230+02:00 mail dovecot: auth: Debug: ldap(hschindler@hmail.simmy.org,127.0.0.1,<p1ROFoMVdM5/AAAB>): Performing passdb lookup
2024-04-07T17:10:19.396251+02:00 mail dovecot: auth: Debug: ldap(hschindler@hmail.simmy.org,127.0.0.1,<p1ROFoMVdM5/AAAB>): bind search: base=o=domains,dc=simmy,dc=ch filter=(&(objectClass=mailUser)(accountStatus=active)(!(domainStatus=disabled))(enabledService=mail)(enabledService=imaptls)(|(mail=hschindler@hmail.simmy.org)(&(enabledService=shadowaddress)(shadowAddress=hschindler@hmail.simmy.org))))
2024-04-07T17:10:19.396291+02:00 mail dovecot: auth: Error: ldap_search
2024-04-07T17:10:19.396313+02:00 mail dovecot: auth: Error: put_filter: "(&(objectClass=mailUser)(accountStatus=active)(!(domainStatus=disabled))(enabledService=mail)(enabledService=imaptls)(|(mail=hschindler@hmail.simmy.org)(&(enabledService=shadowaddress)(shadowAddress=hschindler@hmail.simmy.org))))"
2024-04-07T17:10:19.396331+02:00 mail dovecot: auth: Error: put_filter: AND
2024-04-07T17:10:19.396349+02:00 mail dovecot: auth: Error: put_filter_list "(objectClass=mailUser)(accountStatus=active)(!(domainStatus=disabled))(enabledService=mail)(enabledService=imaptls)(|(mail=hschindler@hmail.simmy.org)(&(enabledService=shadowaddress)(shadowAddress=hschindler@hmail.simmy.org)))"
2024-04-07T17:10:19.396366+02:00 mail dovecot: auth: Error: put_filter: "(objectClass=mailUser)"
2024-04-07T17:10:19.396429+02:00 mail dovecot: auth: Error: put_filter: simple
2024-04-07T17:10:19.396457+02:00 mail dovecot: auth: Error: put_simple_filter: "objectClass=mailUser"
2024-04-07T17:10:19.396480+02:00 mail dovecot: auth: Error: put_filter: "(accountStatus=active)"
2024-04-07T17:10:19.396498+02:00 mail dovecot: auth: Error: put_filter: simple
2024-04-07T17:10:19.396514+02:00 mail dovecot: auth: Error: put_simple_filter: "accountStatus=active"
2024-04-07T17:10:19.396533+02:00 mail dovecot: auth: Error: put_filter: "(!(domainStatus=disabled))"
2024-04-07T17:10:19.396553+02:00 mail dovecot: auth: Error: put_filter: NOT
2024-04-07T17:10:19.396619+02:00 mail dovecot: auth: Error: put_filter_list "(domainStatus=disabled)"
2024-04-07T17:10:19.396645+02:00 mail dovecot: auth: Error: put_filter: "(domainStatus=disabled)"
2024-04-07T17:10:19.396667+02:00 mail dovecot: auth: Error: put_filter: simple
2024-04-07T17:10:19.396685+02:00 mail dovecot: auth: Error: put_simple_filter: "domainStatus=disabled"
2024-04-07T17:10:19.396704+02:00 mail dovecot: auth: Error: put_filter: "(enabledService=mail)"
2024-04-07T17:10:19.396726+02:00 mail dovecot: auth: Error: put_filter: simple
2024-04-07T17:10:19.396747+02:00 mail dovecot: auth: Error: put_simple_filter: "enabledService=mail"
2024-04-07T17:10:19.396783+02:00 mail dovecot: auth: Error: put_filter: "(enabledService=imaptls)"
2024-04-07T17:10:19.396802+02:00 mail dovecot: auth: Error: put_filter: simple
2024-04-07T17:10:19.396832+02:00 mail dovecot: auth: Error: put_simple_filter: "enabledService=imaptls"
2024-04-07T17:10:19.396851+02:00 mail dovecot: auth: Error: put_filter: "(|(mail=hschindler@hmail.simmy.org)(&(enabledService=shadowaddress)(shadowAddress=hschindler@hmail.simmy.org)))"
2024-04-07T17:10:19.396877+02:00 mail dovecot: auth: Error: put_filter: OR
2024-04-07T17:10:19.396898+02:00 mail dovecot: auth: Error: put_filter_list "(mail=hschindler@hmail.simmy.org)(&(enabledService=shadowaddress)(shadowAddress=hschindler@hmail.simmy.org))"
2024-04-07T17:10:19.396928+02:00 mail dovecot: auth: Error: put_filter: "(mail=hschindler@hmail.simmy.org)"
2024-04-07T17:10:19.396947+02:00 mail dovecot: auth: Error: put_filter: simple
2024-04-07T17:10:19.396966+02:00 mail dovecot: auth: Error: put_simple_filter: "mail=hschindler@hmail.simmy.org"
2024-04-07T17:10:19.397026+02:00 mail dovecot: auth: Error: put_filter: "(&(enabledService=shadowaddress)(shadowAddress=hschindler@hmail.simmy.org))"
2024-04-07T17:10:19.397046+02:00 mail dovecot: auth: Error: put_filter: AND
2024-04-07T17:10:19.397062+02:00 mail dovecot: auth: Error: put_filter_list "(enabledService=shadowaddress)(shadowAddress=hschindler@hmail.simmy.org)"
2024-04-07T17:10:19.397092+02:00 mail dovecot: auth: Error: put_filter: "(enabledService=shadowaddress)"
2024-04-07T17:10:19.397143+02:00 mail dovecot: auth: Error: put_filter: simple
2024-04-07T17:10:19.397195+02:00 mail dovecot: auth: Error: put_simple_filter: "enabledService=shadowaddress"
2024-04-07T17:10:19.397214+02:00 mail dovecot: auth: Error: put_filter: "(shadowAddress=hschindler@hmail.simmy.org)"
2024-04-07T17:10:19.397248+02:00 mail dovecot: auth: Error: put_filter: simple
2024-04-07T17:10:19.397274+02:00 mail dovecot: auth: Error: put_simple_filter: "shadowAddress=hschindler@hmail.simmy.org"
2024-04-07T17:10:19.397297+02:00 mail dovecot: auth: Error: ldap_send_initial_request
2024-04-07T17:10:19.397323+02:00 mail dovecot: auth: Error: ldap_send_server_request
2024-04-07T17:10:19.397347+02:00 mail dovecot: auth: Error: ldap_result ld 0x561e7f843950 msgid -1
2024-04-07T17:10:19.397380+02:00 mail dovecot: auth: Error: wait4msg ld 0x561e7f843950 msgid -1 (timeout 0 usec)
2024-04-07T17:10:19.397402+02:00 mail dovecot: auth: Error: wait4msg continue ld 0x561e7f843950 msgid -1 all 0
2024-04-07T17:10:19.397430+02:00 mail dovecot: auth: Error: ** ld 0x561e7f843950 Connections:
2024-04-07T17:10:19.397447+02:00 mail dovecot: auth: Error: * host: 127.0.0.1  port: 389  (default)
2024-04-07T17:10:19.397496+02:00 mail dovecot: auth: Error: * from: IP=127.0.0.1:45604
2024-04-07T17:10:19.397561+02:00 mail dovecot: auth: Error:   refcnt: 2  status: Connected
2024-04-07T17:10:19.397587+02:00 mail dovecot: auth: Error:   last used: Sun Apr  7 17:10:19 2024
2024-04-07T17:10:19.397616+02:00 mail dovecot: auth: Error:
2024-04-07T17:10:19.397643+02:00 mail dovecot: auth: Error:
2024-04-07T17:10:19.397685+02:00 mail dovecot: auth: Error: ** ld 0x561e7f843950 Outstanding Requests:
2024-04-07T17:10:19.397711+02:00 mail dovecot: auth: Error:  * msgid 6,  origid 6, status InProgress
2024-04-07T17:10:19.397782+02:00 mail dovecot: auth: Error:    outstanding referrals 0, parent count 0
2024-04-07T17:10:19.397859+02:00 mail dovecot: auth: Error:   ld 0x561e7f843950 request count 1 (abandoned 0)
2024-04-07T17:10:19.397897+02:00 mail dovecot: auth: Error: ** ld 0x561e7f843950 Response Queue:
2024-04-07T17:10:19.397920+02:00 mail dovecot: auth: Error:    Empty
2024-04-07T17:10:19.397948+02:00 mail dovecot: auth: Error:   ld 0x561e7f843950 response count 0
2024-04-07T17:10:19.397999+02:00 mail dovecot: auth: Error: ldap_chkResponseList ld 0x561e7f843950 msgid -1 all 0
2024-04-07T17:10:19.398040+02:00 mail dovecot: auth: Error: ldap_chkResponseList returns ld 0x561e7f843950 NULL
2024-04-07T17:10:19.398079+02:00 mail dovecot: auth: Error: ldap_int_select
2024-04-07T17:10:19.398116+02:00 mail dovecot: auth: Error: read1msg: ld 0x561e7f843950 msgid -1 all 0
2024-04-07T17:10:19.398145+02:00 mail dovecot: auth: Error: ldap_find_request_by_msgid: msgid 6, lr 0x561e7f854db0 lr->lr_refcnt = 1
2024-04-07T17:10:19.398222+02:00 mail dovecot: auth: Error: read1msg: ld 0x561e7f843950 msgid 6 message type search-result
2024-04-07T17:10:19.398261+02:00 mail dovecot: auth: Error: read1msg: ld 0x561e7f843950 0 new referrals
2024-04-07T17:10:19.398290+02:00 mail dovecot: auth: Error: read1msg:  mark request completed, ld 0x561e7f843950 msgid 6
2024-04-07T17:10:19.398314+02:00 mail dovecot: auth: Error: request done: ld 0x561e7f843950 msgid 6
2024-04-07T17:10:19.398370+02:00 mail dovecot: auth: Error: res_errno: 0, res_error: <>, res_matched: <>
2024-04-07T17:10:19.398404+02:00 mail dovecot: auth: Error: ldap_return_request: lrx 0x561e7f854db0, lr 0x561e7f854db0
2024-04-07T17:10:19.398426+02:00 mail dovecot: auth: Error: ldap_return_request: lrx->lr_msgid 6, lrx->lr_refcnt is now 0, lr is still present
2024-04-07T17:10:19.398461+02:00 mail dovecot: auth: Error: ldap_free_request (origid 6, msgid 6)
2024-04-07T17:10:19.398533+02:00 mail dovecot: auth: Error: ldap_free_request_int: lr 0x561e7f854db0 msgid 6 removed
2024-04-07T17:10:19.398564+02:00 mail dovecot: auth: Error: ldap_do_free_request: asked to free lr 0x561e7f854db0 msgid 6 refcnt 0
2024-04-07T17:10:19.398596+02:00 mail dovecot: auth: Error: ldap_parse_result
2024-04-07T17:10:19.398619+02:00 mail dovecot: auth: Error: ldap_first_attribute
2024-04-07T17:10:19.398644+02:00 mail dovecot: auth: Debug: ldap(hschindler@hmail.simmy.org,127.0.0.1,<p1ROFoMVdM5/AAAB>): no fields returned by the server
2024-04-07T17:10:19.398699+02:00 mail dovecot: auth: Debug: ldap(hschindler@hmail.simmy.org,127.0.0.1,<p1ROFoMVdM5/AAAB>): Finished passdb lookup
2024-04-07T17:10:19.398719+02:00 mail dovecot: auth: Debug: auth(hschindler@hmail.simmy.org,127.0.0.1,<p1ROFoMVdM5/AAAB>): Auth request finished
2024-04-07T17:10:19.398672+02:00 mail dovecot: auth: ldap(hschindler@hmail.simmy.org,127.0.0.1,<p1ROFoMVdM5/AAAB>): unknown user
2024-04-07T17:10:19.398749+02:00 mail dovecot: auth: Error: ldap_msgfree
2024-04-07T17:10:19.398775+02:00 mail dovecot: auth: Error: ldap_result ld 0x561e7f843950 msgid -1
2024-04-07T17:10:19.398802+02:00 mail dovecot: auth: Error: wait4msg ld 0x561e7f843950 msgid -1 (timeout 0 usec)
2024-04-07T17:10:19.398844+02:00 mail dovecot: auth: Error: wait4msg continue ld 0x561e7f843950 msgid -1 all 0
2024-04-07T17:10:19.398871+02:00 mail dovecot: auth: Error: ** ld 0x561e7f843950 Connections:
2024-04-07T17:10:19.398914+02:00 mail dovecot: auth: Error: * host: 127.0.0.1  port: 389  (default)
2024-04-07T17:10:19.398942+02:00 mail dovecot: auth: Error: * from: IP=127.0.0.1:45604
2024-04-07T17:10:19.398969+02:00 mail dovecot: auth: Error:   refcnt: 1  status: Connected
2024-04-07T17:10:19.399006+02:00 mail dovecot: auth: Error:   last used: Sun Apr  7 17:10:19 2024
2024-04-07T17:10:19.399028+02:00 mail dovecot: auth: Error:
2024-04-07T17:10:19.399045+02:00 mail dovecot: auth: Error:
2024-04-07T17:10:19.399074+02:00 mail dovecot: auth: Error: ** ld 0x561e7f843950 Outstanding Requests:
2024-04-07T17:10:19.399092+02:00 mail dovecot: auth: Error:    Empty
2024-04-07T17:10:19.399115+02:00 mail dovecot: auth: Error:   ld 0x561e7f843950 request count 0 (abandoned 0)
2024-04-07T17:10:19.399131+02:00 mail dovecot: auth: Error: ** ld 0x561e7f843950 Response Queue:
2024-04-07T17:10:19.399157+02:00 mail dovecot: auth: Error:    Empty
2024-04-07T17:10:19.399175+02:00 mail dovecot: auth: Error:   ld 0x561e7f843950 response count 0
2024-04-07T17:10:19.399195+02:00 mail dovecot: auth: Error: ldap_chkResponseList ld 0x561e7f843950 msgid -1 all 0
2024-04-07T17:10:19.399212+02:00 mail dovecot: auth: Error: ldap_chkResponseList returns ld 0x561e7f843950 NULL
2024-04-07T17:10:19.399272+02:00 mail dovecot: auth: Error: ldap_int_select
2024-04-07T17:10:21.399422+02:00 mail dovecot: auth: Debug: client passdb out: FAIL#0111#011user=hschindler@hmail.simmy.org

024-04-07T17:17:49.513085+02:00 mail dovecot: auth: Debug: client in: AUTH#0111#011LOGIN#011service=imap#011secured=tls#011session=75QiMYMVVIp/AAAB#011lip=127.0.0.1#011rip=127.0.0.1#011lport=143#011rport=35412#011local_name=127.0.0.1
2024-04-07T17:17:49.513181+02:00 mail dovecot: auth: Debug: client passdb out: CONT#0111#011VXNlcm5hbWU6
2024-04-07T17:17:49.513291+02:00 mail dovecot: auth: Debug: client in: CONT#0111#011bmVvQGhtYWlsLnNpbW15Lm9yZw== (previous base64 data may contain sensitive data)
2024-04-07T17:17:49.513353+02:00 mail dovecot: auth: Debug: client passdb out: CONT#0111#011UGFzc3dvcmQ6
2024-04-07T17:17:49.513448+02:00 mail dovecot: auth: Debug: client in: CONT#0111#011bmNjLTE3MDFD (previous base64 data may contain sensitive data)
2024-04-07T17:17:49.513480+02:00 mail dovecot: auth: Debug: ldap(neo@hmail.simmy.org,127.0.0.1,<75QiMYMVVIp/AAAB>): Performing passdb lookup
2024-04-07T17:17:49.513505+02:00 mail dovecot: auth: Debug: ldap(neo@hmail.simmy.org,127.0.0.1,<75QiMYMVVIp/AAAB>): bind search: base=o=domains,dc=simmy,dc=ch filter=(&(objectClass=mailUser)(accountStatus=active)(!(domainStatus=disabled))(enabledService=mail)(enabledService=imaptls)(|(mail=neo@hmail.simmy.org)(&(enabledService=shadowaddress)(shadowAddress=neo@hmail.simmy.org))))
2024-04-07T17:17:49.513530+02:00 mail dovecot: auth: Error: ldap_search
2024-04-07T17:17:49.513556+02:00 mail dovecot: auth: Error: put_filter: "(&(objectClass=mailUser)(accountStatus=active)(!(domainStatus=disabled))(enabledService=mail)(enabledService=imaptls)(|(mail=neo@hmail.simmy.org)(&(enabledService=shadowaddress)(shadowAddress=neo@hmail.simmy.org))))"
2024-04-07T17:17:49.513589+02:00 mail dovecot: auth: Error: put_filter: AND
2024-04-07T17:17:49.513610+02:00 mail dovecot: auth: Error: put_filter_list "(objectClass=mailUser)(accountStatus=active)(!(domainStatus=disabled))(enabledService=mail)(enabledService=imaptls)(|(mail=neo@hmail.simmy.org)(&(enabledService=shadowaddress)(shadowAddress=neo@hmail.simmy.org)))"
2024-04-07T17:17:49.513637+02:00 mail dovecot: auth: Error: put_filter: "(objectClass=mailUser)"
2024-04-07T17:17:49.513661+02:00 mail dovecot: auth: Error: put_filter: simple
2024-04-07T17:17:49.513682+02:00 mail dovecot: auth: Error: put_simple_filter: "objectClass=mailUser"
2024-04-07T17:17:49.513705+02:00 mail dovecot: auth: Error: put_filter: "(accountStatus=active)"
2024-04-07T17:17:49.513728+02:00 mail dovecot: auth: Error: put_filter: simple
2024-04-07T17:17:49.513746+02:00 mail dovecot: auth: Error: put_simple_filter: "accountStatus=active"
2024-04-07T17:17:49.513768+02:00 mail dovecot: auth: Error: put_filter: "(!(domainStatus=disabled))"
2024-04-07T17:17:49.513785+02:00 mail dovecot: auth: Error: put_filter: NOT
2024-04-07T17:17:49.513805+02:00 mail dovecot: auth: Error: put_filter_list "(domainStatus=disabled)"
2024-04-07T17:17:49.513825+02:00 mail dovecot: auth: Error: put_filter: "(domainStatus=disabled)"
2024-04-07T17:17:49.513845+02:00 mail dovecot: auth: Error: put_filter: simple
2024-04-07T17:17:49.513869+02:00 mail dovecot: auth: Error: put_simple_filter: "domainStatus=disabled"
2024-04-07T17:17:49.513894+02:00 mail dovecot: auth: Error: put_filter: "(enabledService=mail)"
2024-04-07T17:17:49.513915+02:00 mail dovecot: auth: Error: put_filter: simple
2024-04-07T17:17:49.513946+02:00 mail dovecot: auth: Error: put_simple_filter: "enabledService=mail"
2024-04-07T17:17:49.513973+02:00 mail dovecot: auth: Error: put_filter: "(enabledService=imaptls)"
2024-04-07T17:17:49.513995+02:00 mail dovecot: auth: Error: put_filter: simple
2024-04-07T17:17:49.514015+02:00 mail dovecot: auth: Error: put_simple_filter: "enabledService=imaptls"
2024-04-07T17:17:49.514042+02:00 mail dovecot: auth: Error: put_filter: "(|(mail=neo@hmail.simmy.org)(&(enabledService=shadowaddress)(shadowAddress=neo@hmail.simmy.org)))"
2024-04-07T17:17:49.514063+02:00 mail dovecot: auth: Error: put_filter: OR
2024-04-07T17:17:49.514083+02:00 mail dovecot: auth: Error: put_filter_list "(mail=neo@hmail.simmy.org)(&(enabledService=shadowaddress)(shadowAddress=neo@hmail.simmy.org))"
2024-04-07T17:17:49.514104+02:00 mail dovecot: auth: Error: put_filter: "(mail=neo@hmail.simmy.org)"
2024-04-07T17:17:49.514124+02:00 mail dovecot: auth: Error: put_filter: simple
2024-04-07T17:17:49.514147+02:00 mail dovecot: auth: Error: put_simple_filter: "mail=neo@hmail.simmy.org"
2024-04-07T17:17:49.514169+02:00 mail dovecot: auth: Error: put_filter: "(&(enabledService=shadowaddress)(shadowAddress=neo@hmail.simmy.org))"
2024-04-07T17:17:49.514190+02:00 mail dovecot: auth: Error: put_filter: AND
2024-04-07T17:17:49.514216+02:00 mail dovecot: auth: Error: put_filter_list "(enabledService=shadowaddress)(shadowAddress=neo@hmail.simmy.org)"
2024-04-07T17:17:49.514238+02:00 mail dovecot: auth: Error: put_filter: "(enabledService=shadowaddress)"
2024-04-07T17:17:49.514259+02:00 mail dovecot: auth: Error: put_filter: simple
2024-04-07T17:17:49.514307+02:00 mail dovecot: auth: Error: put_simple_filter: "enabledService=shadowaddress"
2024-04-07T17:17:49.514333+02:00 mail dovecot: auth: Error: put_filter: "(shadowAddress=neo@hmail.simmy.org)"
2024-04-07T17:17:49.514355+02:00 mail dovecot: auth: Error: put_filter: simple
2024-04-07T17:17:49.514371+02:00 mail dovecot: auth: Error: put_simple_filter: "shadowAddress=neo@hmail.simmy.org"
2024-04-07T17:17:49.514397+02:00 mail dovecot: auth: Error: ldap_send_initial_request
2024-04-07T17:17:49.514422+02:00 mail dovecot: auth: Error: ldap_send_server_request
2024-04-07T17:17:49.514483+02:00 mail dovecot: auth: Error: ldap_result ld 0x558f3e056950 msgid -1
2024-04-07T17:17:49.514509+02:00 mail dovecot: auth: Error: wait4msg ld 0x558f3e056950 msgid -1 (timeout 0 usec)
2024-04-07T17:17:49.514559+02:00 mail dovecot: auth: Error: wait4msg continue ld 0x558f3e056950 msgid -1 all 0
2024-04-07T17:17:49.514586+02:00 mail dovecot: auth: Error: ** ld 0x558f3e056950 Connections:
2024-04-07T17:17:49.514610+02:00 mail dovecot: auth: Error: * host: 127.0.0.1  port: 389  (default)
2024-04-07T17:17:49.514634+02:00 mail dovecot: auth: Error: * from: IP=127.0.0.1:41714
2024-04-07T17:17:49.514686+02:00 mail dovecot: auth: Error:   refcnt: 2  status: Connected
2024-04-07T17:17:49.514710+02:00 mail dovecot: auth: Error:   last used: Sun Apr  7 17:17:49 2024
2024-04-07T17:17:49.514760+02:00 mail dovecot: auth: Error:
2024-04-07T17:17:49.514785+02:00 mail dovecot: auth: Error:
2024-04-07T17:17:49.514828+02:00 mail dovecot: auth: Error: ** ld 0x558f3e056950 Outstanding Requests:
2024-04-07T17:17:49.514853+02:00 mail dovecot: auth: Error:  * msgid 2,  origid 2, status InProgress
2024-04-07T17:17:49.514901+02:00 mail dovecot: auth: Error:    outstanding referrals 0, parent count 0
2024-04-07T17:17:49.514926+02:00 mail dovecot: auth: Error:   ld 0x558f3e056950 request count 1 (abandoned 0)
2024-04-07T17:17:49.514968+02:00 mail dovecot: auth: Error: ** ld 0x558f3e056950 Response Queue:
2024-04-07T17:17:49.514997+02:00 mail dovecot: auth: Error:    Empty
2024-04-07T17:17:49.515020+02:00 mail dovecot: auth: Error:   ld 0x558f3e056950 response count 0
2024-04-07T17:17:49.515097+02:00 mail dovecot: auth: Error: ldap_chkResponseList ld 0x558f3e056950 msgid -1 all 0
2024-04-07T17:17:49.515122+02:00 mail dovecot: auth: Error: ldap_chkResponseList returns ld 0x558f3e056950 NULL
2024-04-07T17:17:49.515144+02:00 mail dovecot: auth: Error: ldap_int_select
2024-04-07T17:17:49.515172+02:00 mail dovecot: auth: Error: read1msg: ld 0x558f3e056950 msgid -1 all 0
2024-04-07T17:17:49.515195+02:00 mail dovecot: auth: Error: ldap_find_request_by_msgid: msgid 2, lr 0x558f3e067350 lr->lr_refcnt = 1
2024-04-07T17:17:49.515246+02:00 mail dovecot: auth: Error: read1msg: ld 0x558f3e056950 msgid 2 message type search-entry
2024-04-07T17:17:49.515283+02:00 mail dovecot: auth: Error: ldap_return_request: lrx 0x558f3e067350, lr 0x558f3e067350
2024-04-07T17:17:49.515332+02:00 mail dovecot: auth: Error: ldap_return_request: lrx->lr_msgid 2, lrx->lr_refcnt is now 0, lr is still present
2024-04-07T17:17:49.515372+02:00 mail dovecot: auth: Error: ldap_result ld 0x558f3e056950 msgid -1
2024-04-07T17:17:49.515398+02:00 mail dovecot: auth: Error: wait4msg ld 0x558f3e056950 msgid -1 (timeout 0 usec)
2024-04-07T17:17:49.515425+02:00 mail dovecot: auth: Error: wait4msg continue ld 0x558f3e056950 msgid -1 all 0
2024-04-07T17:17:49.515449+02:00 mail dovecot: auth: Error: ** ld 0x558f3e056950 Connections:
2024-04-07T17:17:49.515471+02:00 mail dovecot: auth: Error: * host: 127.0.0.1  port: 389  (default)
2024-04-07T17:17:49.515530+02:00 mail dovecot: auth: Error: * from: IP=127.0.0.1:41714
2024-04-07T17:17:49.515580+02:00 mail dovecot: auth: Error:   refcnt: 2  status: Connected
2024-04-07T17:17:49.515608+02:00 mail dovecot: auth: Error:   last used: Sun Apr  7 17:17:49 2024
2024-04-07T17:17:49.515684+02:00 mail dovecot: auth: Error:
2024-04-07T17:17:49.515728+02:00 mail dovecot: auth: Error:
2024-04-07T17:17:49.515772+02:00 mail dovecot: auth: Error: ** ld 0x558f3e056950 Outstanding Requests:
2024-04-07T17:17:49.515800+02:00 mail dovecot: auth: Error:  * msgid 2,  origid 2, status InProgress
2024-04-07T17:17:49.515825+02:00 mail dovecot: auth: Error:    outstanding referrals 0, parent count 0
2024-04-07T17:17:49.515873+02:00 mail dovecot: auth: Error:   ld 0x558f3e056950 request count 1 (abandoned 0)
2024-04-07T17:17:49.515897+02:00 mail dovecot: auth: Error: ** ld 0x558f3e056950 Response Queue:
2024-04-07T17:17:49.515938+02:00 mail dovecot: auth: Error:    Empty
2024-04-07T17:17:49.515963+02:00 mail dovecot: auth: Error:   ld 0x558f3e056950 response count 0
2024-04-07T17:17:49.516011+02:00 mail dovecot: auth: Error: ldap_chkResponseList ld 0x558f3e056950 msgid -1 all 0
2024-04-07T17:17:49.516035+02:00 mail dovecot: auth: Error: ldap_chkResponseList returns ld 0x558f3e056950 NULL
2024-04-07T17:17:49.516080+02:00 mail dovecot: auth: Error: ldap_int_select
2024-04-07T17:17:49.516104+02:00 mail dovecot: auth: Error: read1msg: ld 0x558f3e056950 msgid -1 all 0
2024-04-07T17:17:49.516146+02:00 mail dovecot: auth: Error: ldap_find_request_by_msgid: msgid 2, lr 0x558f3e067350 lr->lr_refcnt = 1
2024-04-07T17:17:49.516175+02:00 mail dovecot: auth: Error: read1msg: ld 0x558f3e056950 msgid 2 message type search-result
2024-04-07T17:17:49.516222+02:00 mail dovecot: auth: Error: read1msg: ld 0x558f3e056950 0 new referrals
2024-04-07T17:17:49.516277+02:00 mail dovecot: auth: Error: read1msg:  mark request completed, ld 0x558f3e056950 msgid 2
2024-04-07T17:17:49.516311+02:00 mail dovecot: auth: Error: request done: ld 0x558f3e056950 msgid 2
2024-04-07T17:17:49.516335+02:00 mail dovecot: auth: Error: res_errno: 0, res_error: <>, res_matched: <>
2024-04-07T17:17:49.516364+02:00 mail dovecot: auth: Error: ldap_return_request: lrx 0x558f3e067350, lr 0x558f3e067350
2024-04-07T17:17:49.516389+02:00 mail dovecot: auth: Error: ldap_return_request: lrx->lr_msgid 2, lrx->lr_refcnt is now 0, lr is still present
2024-04-07T17:17:49.516434+02:00 mail dovecot: auth: Error: ldap_free_request (origid 2, msgid 2)
2024-04-07T17:17:49.516464+02:00 mail dovecot: auth: Error: ldap_free_request_int: lr 0x558f3e067350 msgid 2 removed
2024-04-07T17:17:49.516490+02:00 mail dovecot: auth: Error: ldap_do_free_request: asked to free lr 0x558f3e067350 msgid 2 refcnt 0
2024-04-07T17:17:49.516516+02:00 mail dovecot: auth: Error: ldap_parse_result
2024-04-07T17:17:49.516561+02:00 mail dovecot: auth: Error: ldap_first_attribute
2024-04-07T17:17:49.516612+02:00 mail dovecot: auth: Error: ldap_get_values
2024-04-07T17:17:49.516662+02:00 mail dovecot: auth: Error: ldap_next_attribute
2024-04-07T17:17:49.516706+02:00 mail dovecot: auth: Debug: ldap(neo@hmail.simmy.org,127.0.0.1,<75QiMYMVVIp/AAAB>): result: mail=neo@hmail.simmy.org; mail unused
2024-04-07T17:17:49.516747+02:00 mail dovecot: auth: Error: ldap_first_attribute
2024-04-07T17:17:49.516781+02:00 mail dovecot: auth: Error: ldap_get_values
2024-04-07T17:17:49.516812+02:00 mail dovecot: auth: Error: ldap_next_attribute
2024-04-07T17:17:49.516859+02:00 mail dovecot: auth: Error: ldap_get_dn
2024-04-07T17:17:49.516899+02:00 mail dovecot: auth: Error: ldap_bind
2024-04-07T17:17:49.516958+02:00 mail dovecot: auth: Error: ldap_simple_bind
2024-04-07T17:17:49.516996+02:00 mail dovecot: auth: Error: ldap_sasl_bind
2024-04-07T17:17:49.517027+02:00 mail dovecot: auth: Error: ldap_send_initial_request
2024-04-07T17:17:49.517062+02:00 mail dovecot: auth: Error: ldap_send_server_request
2024-04-07T17:17:49.517096+02:00 mail dovecot: auth: Error: ldap_msgfree
2024-04-07T17:17:49.517126+02:00 mail dovecot: auth: Error: ldap_msgfree
2024-04-07T17:17:49.517159+02:00 mail dovecot: auth: Error: ldap_result ld 0x558f3e056950 msgid -1
2024-04-07T17:17:49.517192+02:00 mail dovecot: auth: Error: wait4msg ld 0x558f3e056950 msgid -1 (timeout 0 usec)
2024-04-07T17:17:49.517223+02:00 mail dovecot: auth: Error: wait4msg continue ld 0x558f3e056950 msgid -1 all 0
2024-04-07T17:17:49.517253+02:00 mail dovecot: auth: Error: ** ld 0x558f3e056950 Connections:
2024-04-07T17:17:49.517285+02:00 mail dovecot: auth: Error: * host: 127.0.0.1  port: 389  (default)
2024-04-07T17:17:49.517318+02:00 mail dovecot: auth: Error: * from: IP=127.0.0.1:41714
2024-04-07T17:17:49.517352+02:00 mail dovecot: auth: Error:   refcnt: 2  status: Connected
2024-04-07T17:17:49.517384+02:00 mail dovecot: auth: Error:   last used: Sun Apr  7 17:17:49 2024
2024-04-07T17:17:49.517422+02:00 mail dovecot: auth: Error:
2024-04-07T17:17:49.517458+02:00 mail dovecot: auth: Error:
2024-04-07T17:17:49.517492+02:00 mail dovecot: auth: Error: ** ld 0x558f3e056950 Outstanding Requests:
2024-04-07T17:17:49.517525+02:00 mail dovecot: auth: Error:  * msgid 3,  origid 3, status InProgress
2024-04-07T17:17:49.517560+02:00 mail dovecot: auth: Error:    outstanding referrals 0, parent count 0
2024-04-07T17:17:49.517590+02:00 mail dovecot: auth: Error:   ld 0x558f3e056950 request count 1 (abandoned 0)
2024-04-07T17:17:49.517613+02:00 mail dovecot: auth: Error: ** ld 0x558f3e056950 Response Queue:
2024-04-07T17:17:49.517634+02:00 mail dovecot: auth: Error:    Empty
2024-04-07T17:17:49.517656+02:00 mail dovecot: auth: Error:   ld 0x558f3e056950 response count 0
2024-04-07T17:17:49.517679+02:00 mail dovecot: auth: Error: ldap_chkResponseList ld 0x558f3e056950 msgid -1 all 0
2024-04-07T17:17:49.517727+02:00 mail dovecot: auth: Error: ldap_chkResponseList returns ld 0x558f3e056950 NULL
2024-04-07T17:17:49.517751+02:00 mail dovecot: auth: Error: ldap_int_select
2024-04-07T17:17:49.517795+02:00 mail dovecot: auth: Error: ldap_result ld 0x558f3e056950 msgid -1
2024-04-07T17:17:49.517817+02:00 mail dovecot: auth: Error: wait4msg ld 0x558f3e056950 msgid -1 (timeout 0 usec)
2024-04-07T17:17:49.517865+02:00 mail dovecot: auth: Error: wait4msg continue ld 0x558f3e056950 msgid -1 all 0
2024-04-07T17:17:49.517886+02:00 mail dovecot: auth: Error: ** ld 0x558f3e056950 Connections:
2024-04-07T17:17:49.517934+02:00 mail dovecot: auth: Error: * host: 127.0.0.1  port: 389  (default)
2024-04-07T17:17:49.517955+02:00 mail dovecot: auth: Error: * from: IP=127.0.0.1:41714
2024-04-07T17:17:49.518004+02:00 mail dovecot: auth: Error:   refcnt: 2  status: Connected
2024-04-07T17:17:49.518027+02:00 mail dovecot: auth: Error:   last used: Sun Apr  7 17:17:49 2024
2024-04-07T17:17:49.518045+02:00 mail dovecot: auth: Error:
2024-04-07T17:17:49.518066+02:00 mail dovecot: auth: Error:
2024-04-07T17:17:49.518113+02:00 mail dovecot: auth: Error: ** ld 0x558f3e056950 Outstanding Requests:
2024-04-07T17:17:49.518137+02:00 mail dovecot: auth: Error:  * msgid 3,  origid 3, status InProgress
2024-04-07T17:17:49.518179+02:00 mail dovecot: auth: Error:    outstanding referrals 0, parent count 0
2024-04-07T17:17:49.518204+02:00 mail dovecot: auth: Error:   ld 0x558f3e056950 request count 1 (abandoned 0)
2024-04-07T17:17:49.518256+02:00 mail dovecot: auth: Error: ** ld 0x558f3e056950 Response Queue:
2024-04-07T17:17:49.518295+02:00 mail dovecot: auth: Error:    Empty
2024-04-07T17:17:49.518317+02:00 mail dovecot: auth: Error:   ld 0x558f3e056950 response count 0
2024-04-07T17:17:49.518334+02:00 mail dovecot: auth: Error: ldap_chkResponseList ld 0x558f3e056950 msgid -1 all 0
2024-04-07T17:17:49.518378+02:00 mail dovecot: auth: Error: ldap_chkResponseList returns ld 0x558f3e056950 NULL
2024-04-07T17:17:49.518402+02:00 mail dovecot: auth: Error: ldap_int_select
2024-04-07T17:17:49.518424+02:00 mail dovecot: auth: Error: read1msg: ld 0x558f3e056950 msgid -1 all 0
2024-04-07T17:17:49.518443+02:00 mail dovecot: auth: Error: ldap_find_request_by_msgid: msgid 3, lr 0x558f3e067620 lr->lr_refcnt = 1
2024-04-07T17:17:49.518494+02:00 mail dovecot: auth: Error: read1msg: ld 0x558f3e056950 msgid 3 message type bind
2024-04-07T17:17:49.518519+02:00 mail dovecot: auth: Error: read1msg: ld 0x558f3e056950 0 new referrals
2024-04-07T17:17:49.518561+02:00 mail dovecot: auth: Error: read1msg:  mark request completed, ld 0x558f3e056950 msgid 3
2024-04-07T17:17:49.518588+02:00 mail dovecot: auth: Error: request done: ld 0x558f3e056950 msgid 3
2024-04-07T17:17:49.518638+02:00 mail dovecot: auth: Error: res_errno: 0, res_error: <>, res_matched: <>
2024-04-07T17:17:49.518660+02:00 mail dovecot: auth: Error: ldap_return_request: lrx 0x558f3e067620, lr 0x558f3e067620
2024-04-07T17:17:49.518708+02:00 mail dovecot: auth: Error: ldap_return_request: lrx->lr_msgid 3, lrx->lr_refcnt is now 0, lr is still present
2024-04-07T17:17:49.518732+02:00 mail dovecot: auth: Error: ldap_free_request (origid 3, msgid 3)
2024-04-07T17:17:49.518774+02:00 mail dovecot: auth: Error: ldap_free_request_int: lr 0x558f3e067620 msgid 3 removed
2024-04-07T17:17:49.518799+02:00 mail dovecot: auth: Error: ldap_do_free_request: asked to free lr 0x558f3e067620 msgid 3 refcnt 0
2024-04-07T17:17:49.518849+02:00 mail dovecot: auth: Error: ldap_parse_result
2024-04-07T17:17:49.518876+02:00 mail dovecot: auth: Error: ldap_parse_result
2024-04-07T17:17:49.518918+02:00 mail dovecot: auth: Debug: ldap(neo@hmail.simmy.org,127.0.0.1,<75QiMYMVVIp/AAAB>): Finished passdb lookup
2024-04-07T17:17:49.518940+02:00 mail dovecot: auth: Debug: auth(neo@hmail.simmy.org,127.0.0.1,<75QiMYMVVIp/AAAB>): Auth request finished
2024-04-07T17:17:49.518981+02:00 mail dovecot: auth: Debug: client passdb out: OK#0111#011user=neo@hmail.simmy.org#011
2024-04-07T17:17:49.519012+02:00 mail dovecot: auth: Error: ldap_msgfree
2024-04-07T17:17:49.519055+02:00 mail dovecot: auth: Error: ldap_result ld 0x558f3e056950 msgid -1
2024-04-07T17:17:49.519079+02:00 mail dovecot: auth: Error: wait4msg ld 0x558f3e056950 msgid -1 (timeout 0 usec)
2024-04-07T17:17:49.519127+02:00 mail dovecot: auth: Error: wait4msg continue ld 0x558f3e056950 msgid -1 all 0
2024-04-07T17:17:49.519163+02:00 mail dovecot: auth: Error: ** ld 0x558f3e056950 Connections:
2024-04-07T17:17:49.519198+02:00 mail dovecot: auth: Error: * host: 127.0.0.1  port: 389  (default)
2024-04-07T17:17:49.519222+02:00 mail dovecot: auth: Error: * from: IP=127.0.0.1:41714
2024-04-07T17:17:49.519282+02:00 mail dovecot: auth: Error:   refcnt: 1  status: Connected
2024-04-07T17:17:49.519310+02:00 mail dovecot: auth: Error:   last used: Sun Apr  7 17:17:49 2024
2024-04-07T17:17:49.519332+02:00 mail dovecot: auth: Error:
2024-04-07T17:17:49.519385+02:00 mail dovecot: auth: Error:
2024-04-07T17:17:49.519410+02:00 mail dovecot: auth: Error: ** ld 0x558f3e056950 Outstanding Requests:
2024-04-07T17:17:49.519431+02:00 mail dovecot: auth: Error:    Empty
2024-04-07T17:17:49.519481+02:00 mail dovecot: auth: Error:   ld 0x558f3e056950 request count 0 (abandoned 0)
2024-04-07T17:17:49.519529+02:00 mail dovecot: auth: Error: ** ld 0x558f3e056950 Response Queue:
2024-04-07T17:17:49.519577+02:00 mail dovecot: auth: Error:    Empty
2024-04-07T17:17:49.519600+02:00 mail dovecot: auth: Error:   ld 0x558f3e056950 response count 0
2024-04-07T17:17:49.519618+02:00 mail dovecot: auth: Error: ldap_chkResponseList ld 0x558f3e056950 msgid -1 all 0
2024-04-07T17:17:49.519660+02:00 mail dovecot: auth: Error: ldap_chkResponseList returns ld 0x558f3e056950 NULL
2024-04-07T17:17:49.519703+02:00 mail dovecot: auth: Error: ldap_int_select
2024-04-07T17:17:49.519740+02:00 mail dovecot: auth: Debug: master in: REQUEST#0113766747137#0116263#0111#0110fce2c046a3f234ed948bc414c9580f1#011session_pid=6265#011request_auth_token
2024-04-07T17:17:49.519795+02:00 mail dovecot: auth: Debug: ldap(neo@hmail.simmy.org,127.0.0.1,<75QiMYMVVIp/AAAB>): Performing userdb lookup
2024-04-07T17:17:49.519822+02:00 mail dovecot: auth: Debug: ldap(neo@hmail.simmy.org,127.0.0.1,<75QiMYMVVIp/AAAB>): user search: base=o=domains,dc=simmy,dc=ch scope=subtree filter=(&(objectClass=mailUser)(accountStatus=active)(!(domainStatus=disabled))(enabledService=mail)(enabledService=imaptls)(|(mail=neo@hmail.simmy.org)(&(enabledService=shadowaddress)(shadowAddress=neo@hmail.simmy.org)))) fields=mail,mail,homeDirectory,mailboxFormat,mailboxFolder,mailQuota
2024-04-07T17:17:49.519845+02:00 mail dovecot: auth: Error: ldap_bind
2024-04-07T17:17:49.519889+02:00 mail dovecot: auth: Error: ldap_simple_bind
2024-04-07T17:17:49.519927+02:00 mail dovecot: auth: Error: ldap_sasl_bind
2024-04-07T17:17:49.519958+02:00 mail dovecot: auth: Error: ldap_send_initial_request
2024-04-07T17:17:49.519991+02:00 mail dovecot: auth: Error: ldap_send_server_request
2024-04-07T17:17:49.520016+02:00 mail dovecot: auth: Error: ldap_result ld 0x558f3e056950 msgid -1
2024-04-07T17:17:49.520034+02:00 mail dovecot: auth: Error: wait4msg ld 0x558f3e056950 msgid -1 (timeout 0 usec)
2024-04-07T17:17:49.520052+02:00 mail dovecot: auth: Error: wait4msg continue ld 0x558f3e056950 msgid -1 all 0
2024-04-07T17:17:49.520069+02:00 mail dovecot: auth: Error: ** ld 0x558f3e056950 Connections:
2024-04-07T17:17:49.520108+02:00 mail dovecot: auth: Error: * host: 127.0.0.1  port: 389  (default)
2024-04-07T17:17:49.520142+02:00 mail dovecot: auth: Error: * from: IP=127.0.0.1:41714
2024-04-07T17:17:49.520181+02:00 mail dovecot: auth: Error:   refcnt: 2  status: Connected
2024-04-07T17:17:49.520214+02:00 mail dovecot: auth: Error:   last used: Sun Apr  7 17:17:49 2024
2024-04-07T17:17:49.520247+02:00 mail dovecot: auth: Error:
2024-04-07T17:17:49.520291+02:00 mail dovecot: auth: Error:
2024-04-07T17:17:49.520371+02:00 mail dovecot: auth: Error: ** ld 0x558f3e056950 Outstanding Requests:
2024-04-07T17:17:49.520412+02:00 mail dovecot: auth: Error:  * msgid 4,  origid 4, status InProgress
2024-04-07T17:17:49.520446+02:00 mail dovecot: auth: Error:    outstanding referrals 0, parent count 0
2024-04-07T17:17:49.520487+02:00 mail dovecot: auth: Error:   ld 0x558f3e056950 request count 1 (abandoned 0)
2024-04-07T17:17:49.520520+02:00 mail dovecot: auth: Error: ** ld 0x558f3e056950 Response Queue:
2024-04-07T17:17:49.520549+02:00 mail dovecot: auth: Error:    Empty
2024-04-07T17:17:49.520581+02:00 mail dovecot: auth: Error:   ld 0x558f3e056950 response count 0
2024-04-07T17:17:49.520612+02:00 mail dovecot: auth: Error: ldap_chkResponseList ld 0x558f3e056950 msgid -1 all 0
2024-04-07T17:17:49.520674+02:00 mail dovecot: auth: Error: ldap_chkResponseList returns ld 0x558f3e056950 NULL
2024-04-07T17:17:49.520709+02:00 mail dovecot: auth: Error: ldap_int_select
2024-04-07T17:17:49.520749+02:00 mail dovecot: auth: Error: read1msg: ld 0x558f3e056950 msgid -1 all 0
2024-04-07T17:17:49.520782+02:00 mail dovecot: auth: Error: ldap_find_request_by_msgid: msgid 4, lr 0x558f3e06ea10 lr->lr_refcnt = 1
2024-04-07T17:17:49.520818+02:00 mail dovecot: auth: Error: read1msg: ld 0x558f3e056950 msgid 4 message type bind
2024-04-07T17:17:49.520852+02:00 mail dovecot: auth: Error: read1msg: ld 0x558f3e056950 0 new referrals
2024-04-07T17:17:49.520929+02:00 mail dovecot: auth: Error: read1msg:  mark request completed, ld 0x558f3e056950 msgid 4
2024-04-07T17:17:49.520960+02:00 mail dovecot: auth: Error: request done: ld 0x558f3e056950 msgid 4
2024-04-07T17:17:49.521007+02:00 mail dovecot: auth: Error: res_errno: 0, res_error: <>, res_matched: <>
2024-04-07T17:17:49.521031+02:00 mail dovecot: auth: Error: ldap_return_request: lrx 0x558f3e06ea10, lr 0x558f3e06ea10
2024-04-07T17:17:49.521056+02:00 mail dovecot: auth: Error: ldap_return_request: lrx->lr_msgid 4, lrx->lr_refcnt is now 0, lr is still present
2024-04-07T17:17:49.521082+02:00 mail dovecot: auth: Error: ldap_free_request (origid 4, msgid 4)
2024-04-07T17:17:49.521135+02:00 mail dovecot: auth: Error: ldap_free_request_int: lr 0x558f3e06ea10 msgid 4 removed
2024-04-07T17:17:49.521163+02:00 mail dovecot: auth: Error: ldap_do_free_request: asked to free lr 0x558f3e06ea10 msgid 4 refcnt 0
2024-04-07T17:17:49.521208+02:00 mail dovecot: auth: Error: ldap_parse_result
2024-04-07T17:17:49.521233+02:00 mail dovecot: auth: Error: ldap_search
2024-04-07T17:17:49.521305+02:00 mail dovecot: auth: Error: put_filter: "(&(objectClass=mailUser)(accountStatus=active)(!(domainStatus=disabled))(enabledService=mail)(enabledService=imaptls)(|(mail=neo@hmail.simmy.org)(&(enabledService=shadowaddress)(shadowAddress=neo@hmail.simmy.org))))"
2024-04-07T17:17:49.521337+02:00 mail dovecot: auth: Error: put_filter: AND
2024-04-07T17:17:49.521390+02:00 mail dovecot: auth: Error: put_filter_list "(objectClass=mailUser)(accountStatus=active)(!(domainStatus=disabled))(enabledService=mail)(enabledService=imaptls)(|(mail=neo@hmail.simmy.org)(&(enabledService=shadowaddress)(shadowAddress=neo@hmail.simmy.org)))"
2024-04-07T17:17:49.521415+02:00 mail dovecot: auth: Error: put_filter: "(objectClass=mailUser)"
2024-04-07T17:17:49.521460+02:00 mail dovecot: auth: Error: put_filter: simple
2024-04-07T17:17:49.521480+02:00 mail dovecot: auth: Error: put_simple_filter: "objectClass=mailUser"
2024-04-07T17:17:49.521496+02:00 mail dovecot: auth: Error: put_filter: "(accountStatus=active)"
2024-04-07T17:17:49.521512+02:00 mail dovecot: auth: Error: put_filter: simple
2024-04-07T17:17:49.521527+02:00 mail dovecot: auth: Error: put_simple_filter: "accountStatus=active"
2024-04-07T17:17:49.521543+02:00 mail dovecot: auth: Error: put_filter: "(!(domainStatus=disabled))"
2024-04-07T17:17:49.521562+02:00 mail dovecot: auth: Error: put_filter: NOT
2024-04-07T17:17:49.521581+02:00 mail dovecot: auth: Error: put_filter_list "(domainStatus=disabled)"
2024-04-07T17:17:49.521601+02:00 mail dovecot: auth: Error: put_filter: "(domainStatus=disabled)"
2024-04-07T17:17:49.521622+02:00 mail dovecot: auth: Error: put_filter: simple
2024-04-07T17:17:49.521639+02:00 mail dovecot: auth: Error: put_simple_filter: "domainStatus=disabled"
2024-04-07T17:17:49.521662+02:00 mail dovecot: auth: Error: put_filter: "(enabledService=mail)"
2024-04-07T17:17:49.521701+02:00 mail dovecot: auth: Error: put_filter: simple
2024-04-07T17:17:49.521731+02:00 mail dovecot: auth: Error: put_simple_filter: "enabledService=mail"
2024-04-07T17:17:49.521768+02:00 mail dovecot: auth: Error: put_filter: "(enabledService=imaptls)"
2024-04-07T17:17:49.521809+02:00 mail dovecot: auth: Error: put_filter: simple
2024-04-07T17:17:49.521831+02:00 mail dovecot: auth: Error: put_simple_filter: "enabledService=imaptls"
2024-04-07T17:17:49.521849+02:00 mail dovecot: auth: Error: put_filter: "(|(mail=neo@hmail.simmy.org)(&(enabledService=shadowaddress)(shadowAddress=neo@hmail.simmy.org)))"
2024-04-07T17:17:49.521876+02:00 mail dovecot: auth: Error: put_filter: OR
2024-04-07T17:17:49.521898+02:00 mail dovecot: auth: Error: put_filter_list "(mail=neo@hmail.simmy.org)(&(enabledService=shadowaddress)(shadowAddress=neo@hmail.simmy.org))"
2024-04-07T17:17:49.521922+02:00 mail dovecot: auth: Error: put_filter: "(mail=neo@hmail.simmy.org)"
2024-04-07T17:17:49.521973+02:00 mail dovecot: auth: Error: put_filter: simple
2024-04-07T17:17:49.521994+02:00 mail dovecot: auth: Error: put_simple_filter: "mail=neo@hmail.simmy.org"
2024-04-07T17:17:49.522038+02:00 mail dovecot: auth: Error: put_filter: "(&(enabledService=shadowaddress)(shadowAddress=neo@hmail.simmy.org))"
2024-04-07T17:17:49.522060+02:00 mail dovecot: auth: Error: put_filter: AND
2024-04-07T17:17:49.522116+02:00 mail dovecot: auth: Error: put_filter_list "(enabledService=shadowaddress)(shadowAddress=neo@hmail.simmy.org)"
2024-04-07T17:17:49.522140+02:00 mail dovecot: auth: Error: put_filter: "(enabledService=shadowaddress)"
2024-04-07T17:17:49.522182+02:00 mail dovecot: auth: Error: put_filter: simple
2024-04-07T17:17:49.522203+02:00 mail dovecot: auth: Error: put_simple_filter: "enabledService=shadowaddress"
2024-04-07T17:17:49.522248+02:00 mail dovecot: auth: Error: put_filter: "(shadowAddress=neo@hmail.simmy.org)"
2024-04-07T17:17:49.522273+02:00 mail dovecot: auth: Error: put_filter: simple
2024-04-07T17:17:49.522319+02:00 mail dovecot: auth: Error: put_simple_filter: "shadowAddress=neo@hmail.simmy.org"
2024-04-07T17:17:49.522350+02:00 mail dovecot: auth: Error: ldap_send_initial_request
2024-04-07T17:17:49.522372+02:00 mail dovecot: auth: Error: ldap_send_server_request
2024-04-07T17:17:49.522418+02:00 mail dovecot: auth: Error: ldap_msgfree
2024-04-07T17:17:49.522442+02:00 mail dovecot: auth: Error: ldap_result ld 0x558f3e056950 msgid -1
2024-04-07T17:17:49.522482+02:00 mail dovecot: auth: Error: wait4msg ld 0x558f3e056950 msgid -1 (timeout 0 usec)
2024-04-07T17:17:49.522508+02:00 mail dovecot: auth: Error: wait4msg continue ld 0x558f3e056950 msgid -1 all 0
2024-04-07T17:17:49.522546+02:00 mail dovecot: auth: Error: ** ld 0x558f3e056950 Connections:
2024-04-07T17:17:49.522571+02:00 mail dovecot: auth: Error: * host: 127.0.0.1  port: 389  (default)
2024-04-07T17:17:49.522596+02:00 mail dovecot: auth: Error: * from: IP=127.0.0.1:41714
2024-04-07T17:17:49.522638+02:00 mail dovecot: auth: Error:   refcnt: 2  status: Connected
2024-04-07T17:17:49.522666+02:00 mail dovecot: auth: Error:   last used: Sun Apr  7 17:17:49 2024
2024-04-07T17:17:49.522693+02:00 mail dovecot: auth: Error:
2024-04-07T17:17:49.522715+02:00 mail dovecot: auth: Error:
2024-04-07T17:17:49.522757+02:00 mail dovecot: auth: Error: ** ld 0x558f3e056950 Outstanding Requests:
2024-04-07T17:17:49.522778+02:00 mail dovecot: auth: Error:  * msgid 5,  origid 5, status InProgress
2024-04-07T17:17:49.522828+02:00 mail dovecot: auth: Error:    outstanding referrals 0, parent count 0
2024-04-07T17:17:49.522856+02:00 mail dovecot: auth: Error:   ld 0x558f3e056950 request count 1 (abandoned 0)
2024-04-07T17:17:49.522878+02:00 mail dovecot: auth: Error: ** ld 0x558f3e056950 Response Queue:
2024-04-07T17:17:49.522927+02:00 mail dovecot: auth: Error:    Empty
2024-04-07T17:17:49.522949+02:00 mail dovecot: auth: Error:   ld 0x558f3e056950 response count 0
2024-04-07T17:17:49.522991+02:00 mail dovecot: auth: Error: ldap_chkResponseList ld 0x558f3e056950 msgid -1 all 0
2024-04-07T17:17:49.523011+02:00 mail dovecot: auth: Error: ldap_chkResponseList returns ld 0x558f3e056950 NULL
2024-04-07T17:17:49.523073+02:00 mail dovecot: auth: Error: ldap_int_select
2024-04-07T17:17:49.523099+02:00 mail dovecot: auth: Error: read1msg: ld 0x558f3e056950 msgid -1 all 0
2024-04-07T17:17:49.523145+02:00 mail dovecot: auth: Error: ldap_find_request_by_msgid: msgid 5, lr 0x558f3e06eb40 lr->lr_refcnt = 1
2024-04-07T17:17:49.523171+02:00 mail dovecot: auth: Error: read1msg: ld 0x558f3e056950 msgid 5 message type search-entry
2024-04-07T17:17:49.523218+02:00 mail dovecot: auth: Error: ldap_return_request: lrx 0x558f3e06eb40, lr 0x558f3e06eb40
2024-04-07T17:17:49.523242+02:00 mail dovecot: auth: Error: ldap_return_request: lrx->lr_msgid 5, lrx->lr_refcnt is now 0, lr is still present
2024-04-07T17:17:49.523300+02:00 mail dovecot: auth: Error: ldap_result ld 0x558f3e056950 msgid -1
2024-04-07T17:17:49.523329+02:00 mail dovecot: auth: Error: wait4msg ld 0x558f3e056950 msgid -1 (timeout 0 usec)
2024-04-07T17:17:49.523348+02:00 mail dovecot: auth: Error: wait4msg continue ld 0x558f3e056950 msgid -1 all 0
2024-04-07T17:17:49.523396+02:00 mail dovecot: auth: Error: ** ld 0x558f3e056950 Connections:
2024-04-07T17:17:49.523417+02:00 mail dovecot: auth: Error: * host: 127.0.0.1  port: 389  (default)
2024-04-07T17:17:49.523465+02:00 mail dovecot: auth: Error: * from: IP=127.0.0.1:41714
2024-04-07T17:17:49.523487+02:00 mail dovecot: auth: Error:   refcnt: 2  status: Connected
2024-04-07T17:17:49.523558+02:00 mail dovecot: auth: Error:   last used: Sun Apr  7 17:17:49 2024
2024-04-07T17:17:49.523580+02:00 mail dovecot: auth: Error:
2024-04-07T17:17:49.523600+02:00 mail dovecot: auth: Error:
2024-04-07T17:17:49.523625+02:00 mail dovecot: auth: Error: ** ld 0x558f3e056950 Outstanding Requests:
2024-04-07T17:17:49.523649+02:00 mail dovecot: auth: Error:  * msgid 5,  origid 5, status InProgress
2024-04-07T17:17:49.523674+02:00 mail dovecot: auth: Error:    outstanding referrals 0, parent count 0
2024-04-07T17:17:49.523696+02:00 mail dovecot: auth: Error:   ld 0x558f3e056950 request count 1 (abandoned 0)
2024-04-07T17:17:49.523738+02:00 mail dovecot: auth: Error: ** ld 0x558f3e056950 Response Queue:
2024-04-07T17:17:49.523763+02:00 mail dovecot: auth: Error:    Empty
2024-04-07T17:17:49.523810+02:00 mail dovecot: auth: Error:   ld 0x558f3e056950 response count 0
2024-04-07T17:17:49.523831+02:00 mail dovecot: auth: Error: ldap_chkResponseList ld 0x558f3e056950 msgid -1 all 0
2024-04-07T17:17:49.523876+02:00 mail dovecot: auth: Error: ldap_chkResponseList returns ld 0x558f3e056950 NULL
2024-04-07T17:17:49.523900+02:00 mail dovecot: auth: Error: ldap_int_select
2024-04-07T17:17:49.523944+02:00 mail dovecot: auth: Error: read1msg: ld 0x558f3e056950 msgid -1 all 0
2024-04-07T17:17:49.523965+02:00 mail dovecot: auth: Error: ldap_find_request_by_msgid: msgid 5, lr 0x558f3e06eb40 lr->lr_refcnt = 1
2024-04-07T17:17:49.524006+02:00 mail dovecot: auth: Error: read1msg: ld 0x558f3e056950 msgid 5 message type search-result
2024-04-07T17:17:49.524031+02:00 mail dovecot: auth: Error: read1msg: ld 0x558f3e056950 0 new referrals
2024-04-07T17:17:49.524079+02:00 mail dovecot: auth: Error: read1msg:  mark request completed, ld 0x558f3e056950 msgid 5
2024-04-07T17:17:49.524104+02:00 mail dovecot: auth: Error: request done: ld 0x558f3e056950 msgid 5
2024-04-07T17:17:49.524152+02:00 mail dovecot: auth: Error: res_errno: 0, res_error: <>, res_matched: <>
2024-04-07T17:17:49.524174+02:00 mail dovecot: auth: Error: ldap_return_request: lrx 0x558f3e06eb40, lr 0x558f3e06eb40
2024-04-07T17:17:49.524221+02:00 mail dovecot: auth: Error: ldap_return_request: lrx->lr_msgid 5, lrx->lr_refcnt is now 0, lr is still present
2024-04-07T17:17:49.524242+02:00 mail dovecot: auth: Error: ldap_free_request (origid 5, msgid 5)
2024-04-07T17:17:49.524288+02:00 mail dovecot: auth: Error: ldap_free_request_int: lr 0x558f3e06eb40 msgid 5 removed
2024-04-07T17:17:49.524312+02:00 mail dovecot: auth: Error: ldap_do_free_request: asked to free lr 0x558f3e06eb40 msgid 5 refcnt 0
2024-04-07T17:17:49.524357+02:00 mail dovecot: auth: Error: ldap_parse_result
2024-04-07T17:17:49.524379+02:00 mail dovecot: auth: Error: ldap_first_attribute
2024-04-07T17:17:49.524426+02:00 mail dovecot: auth: Error: ldap_get_values
2024-04-07T17:17:49.524454+02:00 mail dovecot: auth: Error: ldap_next_attribute
2024-04-07T17:17:49.524474+02:00 mail dovecot: auth: Error: ldap_get_values
2024-04-07T17:17:49.524498+02:00 mail dovecot: auth: Error: ldap_next_attribute
2024-04-07T17:17:49.524519+02:00 mail dovecot: auth: Error: ldap_get_values
2024-04-07T17:17:49.524566+02:00 mail dovecot: auth: Error: ldap_next_attribute
2024-04-07T17:17:49.524587+02:00 mail dovecot: auth: Error: ldap_get_values
2024-04-07T17:17:49.524632+02:00 mail dovecot: auth: Error: ldap_next_attribute
2024-04-07T17:17:49.524658+02:00 mail dovecot: auth: Debug: ldap(neo@hmail.simmy.org,127.0.0.1,<75QiMYMVVIp/AAAB>): result: mail=neo@hmail.simmy.org homeDirectory=/var/vmail/vmail1/hmail.simmy.org/n/e/o/neo-2024.03.29.18.25.30/ mailboxFormat=maildir mailboxFolder=Maildir; mailboxFormat,homeDirectory,mail,mailboxFolder unused
2024-04-07T17:17:49.524702+02:00 mail dovecot: auth: Error: ldap_first_attribute
2024-04-07T17:17:49.524728+02:00 mail dovecot: auth: Error: ldap_get_values
2024-04-07T17:17:49.524769+02:00 mail dovecot: auth: Error: ldap_next_attribute
2024-04-07T17:17:49.524794+02:00 mail dovecot: auth: Error: ldap_get_values
2024-04-07T17:17:49.524838+02:00 mail dovecot: auth: Error: ldap_next_attribute
2024-04-07T17:17:49.524862+02:00 mail dovecot: auth: Error: ldap_get_values
2024-04-07T17:17:49.524929+02:00 mail dovecot: auth: Error: ldap_next_attribute
2024-04-07T17:17:49.524953+02:00 mail dovecot: auth: Error: ldap_get_values
2024-04-07T17:17:49.524970+02:00 mail dovecot: auth: Error: ldap_next_attribute
2024-04-07T17:17:49.525020+02:00 mail dovecot: auth: Debug: ldap(neo@hmail.simmy.org,127.0.0.1,<75QiMYMVVIp/AAAB>): Finished userdb lookup
2024-04-07T17:17:49.525050+02:00 mail dovecot: auth: Debug: master userdb out: USER#0113766747137#011neo@hmail.simmy.org#011master_user=neo@hmail.simmy.org#011home=/var/vmail/vmail1/hmail.simmy.org/n/e/o/neo-2024.03.29.18.25.30/#011mail=maildir:~/Maildir/#011auth_mech=LOGIN#011auth_token=313b055669228b74eb8c78db9a73985d4e73b801
2024-04-07T17:17:49.525117+02:00 mail dovecot: auth: Error: ldap_msgfree
2024-04-07T17:17:49.525142+02:00 mail dovecot: auth: Error: ldap_msgfree
2024-04-07T17:17:49.525184+02:00 mail dovecot: auth: Error: ldap_result ld 0x558f3e056950 msgid -1
2024-04-07T17:17:49.525209+02:00 mail dovecot: auth: Error: wait4msg ld 0x558f3e056950 msgid -1 (timeout 0 usec)
2024-04-07T17:17:49.525259+02:00 mail dovecot: auth: Error: wait4msg continue ld 0x558f3e056950 msgid -1 all 0
2024-04-07T17:17:49.525281+02:00 mail dovecot: auth: Error: ** ld 0x558f3e056950 Connections:
2024-04-07T17:17:49.525322+02:00 mail dovecot: auth: Error: * host: 127.0.0.1  port: 389  (default)
2024-04-07T17:17:49.525360+02:00 mail dovecot: auth: Error: * from: IP=127.0.0.1:41714
2024-04-07T17:17:49.525393+02:00 mail dovecot: auth: Error:   refcnt: 1  status: Connected
2024-04-07T17:17:49.525425+02:00 mail dovecot: auth: Error:   last used: Sun Apr  7 17:17:49 2024
2024-04-07T17:17:49.525454+02:00 mail dovecot: auth: Error:
2024-04-07T17:17:49.525477+02:00 mail dovecot: auth: Error:
2024-04-07T17:17:49.525856+02:00 mail dovecot: imap(neo@hmail.simmy.org)<6265><75QiMYMVVIp/AAAB>: Debug: Loading modules from directory: /usr/lib/dovecot/modules
2024-04-07T17:17:49.525891+02:00 mail dovecot: imap(neo@hmail.simmy.org)<6265><75QiMYMVVIp/AAAB>: Debug: Module loaded: /usr/lib/dovecot/modules/lib01_acl_plugin.so
2024-04-07T17:17:49.525922+02:00 mail dovecot: imap(neo@hmail.simmy.org)<6265><75QiMYMVVIp/AAAB>: Debug: Module loaded: /usr/lib/dovecot/modules/lib02_imap_acl_plugin.so
2024-04-07T17:17:49.525959+02:00 mail dovecot: imap(neo@hmail.simmy.org)<6265><75QiMYMVVIp/AAAB>: Debug: Module loaded: /usr/lib/dovecot/modules/lib10_last_login_plugin.so
2024-04-07T17:17:49.525999+02:00 mail dovecot: imap(neo@hmail.simmy.org)<6265><75QiMYMVVIp/AAAB>: Debug: Module loaded: /usr/lib/dovecot/modules/lib10_quota_plugin.so
2024-04-07T17:17:49.525518+02:00 mail dovecot: auth: Error: ** ld 0x558f3e056950 Outstanding Requests:
2024-04-07T17:17:49.525545+02:00 mail dovecot: auth: Error:    Empty
2024-04-07T17:17:49.525581+02:00 mail dovecot: auth: Error:   ld 0x558f3e056950 request count 0 (abandoned 0)
2024-04-07T17:17:49.525613+02:00 mail dovecot: auth: Error: ** ld 0x558f3e056950 Response Queue:
2024-04-07T17:17:49.525651+02:00 mail dovecot: auth: Error:    Empty
2024-04-07T17:17:49.525681+02:00 mail dovecot: auth: Error:   ld 0x558f3e056950 response count 0
2024-04-07T17:17:49.525719+02:00 mail dovecot: auth: Error: ldap_chkResponseList ld 0x558f3e056950 msgid -1 all 0
2024-04-07T17:17:49.525757+02:00 mail dovecot: auth: Error: ldap_chkResponseList returns ld 0x558f3e056950 NULL
2024-04-07T17:17:49.525786+02:00 mail dovecot: auth: Error: ldap_int_select

4 Reply by ZhangHuangbin

  • ZhangHuangbin
  • ZhangHuangbin
  • iRedMail Developers
  • Offline
  • Registered: 2009-05-06
  • Posts: 31,082

Re: Dovecot - OpenLDAP login failure

neo1701 wrote:

I also checked the LDAP object. I realized that hschindler@hmail.simmy.org has these services NOT enabled:
dsync
imaptls
indexer-worker
managesievetls
pop3tls
quota-status
sievetls
smtptls
sogo
sogoactivesync
sogocalendar
sogowebmail     

But if you want to login as this user, why not enable these services for this account?

5 Reply by neo1701

  • neo1701
  • Member
  • Offline
  • Registered: 2024-04-06
  • Posts: 3

Re: Dovecot - OpenLDAP login failure

Hello there

I copied a script for the import which was written for iRedMail 0.8.6. There were 21 services enabled. I thought that were all services that are need.
I added these missing services to the users. And now the login is working. For Roundcube and Sogo.

Thank you very much for your help.