List-user-mailboxes error - Windows AD

NethServer Version: 8
Module: mail 1.3.3

I have a nethserver 8 installed with the latest updates on Feb 26, it is installed on a Centos 9
The domain is connected to Windows AD 2010
My email doesn’t work
When I go to list-user-mailboxes it gives me the following error
{“context”:{“action”:“list-user-mailboxes”,“data”:null,“extra”:{“eventId”:“5c78a5b4-a7cb-4b1e-a8b0-db3b5269876a”,“isNotificationHidden”:true,“title”:“Mostrar los buzones de usuario”},“id”:“a600edca-2246-4d49-bdfd-6384b96a9c17”,“parent”:“”,“queue”:“module/mail2/tasks”,“timestamp”:“2024-02-26T21:52:15.541163973Z”,“user”:“admin”},“status”:“aborted”,“progress”:0,“subTasks”:,“validated”:false,“result”:{“error”:“Traceback (most recent call last):\n File "/home/mail2/.config/actions/list-user-mailboxes/10list_user_mailboxes", line 16, in \n rusers = mail.doveadm_query("user", {"userMask":"*"})\n ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n File "/home/mail2/.config/pypkg/mail.py", line 271, in doveadm_query\n raise DoveadmError(oresp)\nmail.DoveadmError: [[‘error’, {‘type’: ‘exitCode’, ‘exitCode’: 65}, ‘user’]]\n”,“exit_code”:1,“file”:“task/module/mail2/a600edca-2246-4d49-bdfd-6384b96a9c17”,“output”:“”}}

When I connect with the user testmail through webtop, after a few minutes it gives the following error:

2024-02-26T18:45:14-03:00 [1:mail2:dovecot] auth: Error: auth-worker: Aborted PASSV request for testmail: Lookup timed out
2024-02-26T18:45:14-03:00 [1:mail2:dovecot] auth-worker(25): conn unix:auth-worker (pid=24,uid=90): auth-worker<1>: ldap(testmail,10.5.4.1,): Shutting down
2024-02-26T18:45:14-03:00 [1:mail2:dovecot] auth-worker(25): Warning: conn unix:auth-worker (pid=24,uid=90): Auth master disconnected us while handling request for testmail for 60 secs (result=FAIL)
2024-02-26T18:45:16-03:00 [1:mail2:dovecot] imap-login: Disconnected: Connection closed (auth service reported temporary failure): user=, method=PLAIN, rip=10.5.4.1, lip=10.5.4.1, secured, session=
2024-02-26T18:45:18-03:00 [1:mail2:dovecot] auth: Error: auth-worker: Aborted PASSV request for testmail: Lookup timed out
2024-02-26T18:45:18-03:00 [1:mail2:dovecot] auth-worker(27): conn unix:auth-worker (pid=24,uid=90): auth-worker<1>: ldap(testmail,10.5.4.1,<A+3Kz08SJMcKBQQB>): Shutting down
2024-02-26T18:45:18-03:00 [1:mail2:dovecot] auth-worker(27): Warning: conn unix:auth-worker (pid=24,uid=90): Auth master disconnected us while handling request for testmail for 60 secs (result=FAIL)
2024-02-26T18:45:20-03:00 [1:mail2:dovecot] imap-login: Disconnected: Aborted login by logging out (auth service reported temporary failure): user=, method=PLAIN, rip=10.5.4.1, lip=10.5.4.1, secured, session=<A+3Kz08SJMcKBQQB>
2024-02-26T18:46:20-03:00 [1:mail2:dovecot] auth: Error: auth-worker: Aborted PASSV request for testmail: Lookup timed out
2024-02-26T18:46:20-03:00 [1:mail2:dovecot] auth-worker(28): conn unix:auth-worker (pid=24,uid=90): auth-worker<1>: ldap(testmail,10.5.4.1,<5SRD008SQscKBQQB>): Shutting down
2024-02-26T18:46:20-03:00 [1:mail2:dovecot] auth-worker(28): Warning: conn unix:auth-worker (pid=24,uid=90): Auth master disconnected us while handling request for testmail for 60 secs (result=FAIL)
2024-02-26T18:46:21-03:00 [1:mail2:dovecot] auth: Error: auth-worker: Aborted USER request for admin: Lookup timed out
2024-02-26T18:46:21-03:00 [1:mail2:dovecot] imap(31): Error: auth-master: login: request [2095841281]: Login auth request failed: Internal auth failure (auth connected 60019 msecs ago, request took 60018 msecs, client-pid=30 client-id=1)
2024-02-26T18:46:21-03:00 [1:mail2:dovecot] auth-worker(32): Warning: conn unix:auth-worker (pid=24,uid=90): Auth master disconnected us while handling request for admin for 60 secs (result=FAIL)
2024-02-26T18:46:21-03:00 [1:mail2:dovecot] imap-login: Disconnected: Internal login failure (pid=30 id=1): user=, method=PLAIN, rip=10.5.4.1, lip=10.5.4.1, mpid=31, secured, session=
2024-02-26T18:46:22-03:00 [1:mail2:dovecot] imap-login: Disconnected: Connection closed (auth service reported temporary failure): user=, method=PLAIN, rip=10.5.4.1, lip=10.5.4.1, secured, session=<5SRD008SQscKBQQB>
2024-02-26T18:46:41-03:00 [1:mail2:agent@mail2] task/module/mail2/ab4fce17-634f-4af4-b066-efa571692d87: get-name/50get_name is starting
2024-02-26T18:46:41-03:00 [1:mail2:agent@mail2] task/module/mail2/d01d62bf-9dff-40fb-aa34-e28254358fc8: get-status/20read is starting
2024-02-26T18:46:41-03:00 [1:mail2:agent@mail2] task/module/mail2/8aaa1b65-984f-4dc2-a65a-10fa11ee7f9d: get-filter-configuration/10get_filter_configuration is starting
2024-02-26T18:46:41-03:00 [1:mail2:agent@mail2] task/module/mail2/eb230d12-caf4-4206-a5b9-65c5a16beeef: get-configuration/20read is starting
2024-02-26T18:46:43-03:00 [1:mail2:systemd] Created slice User Background Tasks Slice.
2024-02-26T18:46:43-03:00 [1:mail2:systemd] Starting Cleanup of User’s Temporary Files and Directories…
2024-02-26T18:46:44-03:00 [1:mail2:systemd] Finished Cleanup of User’s Temporary Files and Directories.
2024-02-26T18:46:44-03:00 [1:mail2:agent@mail2] task/module/mail2/ab4fce17-634f-4af4-b066-efa571692d87: action “get-name” status is “completed” (0) at step 50get_name
2024-02-26T18:46:44-03:00 [1:mail2:agent@mail2] systemctl --user is-enabled clamav.service
2024-02-26T18:46:44-03:00 [1:mail2:agent@mail2] enabled
2024-02-26T18:46:44-03:00 [1:mail2:agent@mail2] systemctl --user is-enabled freshclam.timer
2024-02-26T18:46:44-03:00 [1:mail2:agent@mail2] disabled
2024-02-26T18:46:44-03:00 [1:mail2:agent@mail2] task/module/mail2/eb230d12-caf4-4206-a5b9-65c5a16beeef: action “get-configuration” status is “completed” (0) at step validate-output.json
2024-02-26T18:46:45-03:00 [1:mail2:agent@mail2] task/module/mail2/8aaa1b65-984f-4dc2-a65a-10fa11ee7f9d: action “get-filter-configuration” status is “completed” (0) at step validate-output.json
2024-02-26T18:46:45-03:00 [1:mail2:agent@mail2] task/module/mail2/d01d62bf-9dff-40fb-aa34-e28254358fc8: action “get-status” status is “completed” (0) at step validate-output.json
2024-02-26T18:47:29-03:00 [1:mail2:dovecot] auth: Error: auth-worker: Aborted USER request for testmail: Lookup timed out
2024-02-26T18:47:29-03:00 [1:mail2:dovecot] imap(36): Error: auth-master: login: request [995229697]: Login auth request failed: Internal auth failure (auth connected 60037 msecs ago, request took 60036 msecs, client-pid=34 client-id=1)
2024-02-26T18:47:29-03:00 [1:mail2:dovecot] imap-login: Disconnected: Internal login failure (pid=34 id=1): user=, method=PLAIN, rip=10.5.4.1, lip=10.5.4.1, mpid=36, secured, session=
2024-02-26T18:47:29-03:00 [1:mail2:dovecot] auth-worker(33): Warning: conn unix:auth-worker (pid=24,uid=90): Auth master disconnected us while handling request for testmail for 60 secs (result=FAIL)
2024-02-26T18:47:35-03:00 [1:mail2:dovecot] auth: Error: auth-worker: Aborted PASSV request for testmail: Lookup timed out
2024-02-26T18:47:35-03:00 [1:mail2:dovecot] auth-worker(37): conn unix:auth-worker (pid=24,uid=90): auth-worker<1>: ldap(testmail,10.5.4.1,): Shutting down
2024-02-26T18:47:35-03:00 [1:mail2:dovecot] auth-worker(37): Warning: conn unix:auth-worker (pid=24,uid=90): Auth master disconnected us while handling request for testmail for 60 secs (result=FAIL)
2024-02-26T18:47:37-03:00 [1:mail2:dovecot] imap-login: Disconnected: Connection closed (auth service reported temporary failure): user=, method=PLAIN, rip=10.5.4.1, lip=10.5.4.1, secured, session=

It is not an authentication problem because when I connect with the same user to nextcloud it works correctly

Thank you

Hi @fjadaro and welcome!

JFYI, most active forum users are in the EU timezone, so please allow some time for them to react to your query.

Again, welcome and enjoy!

1 Like

Nextcloud has a credentials cache that could mask issues with your external AD provider.

Can you list users and groups under the Domains and Users page?

It shows me the users and groups correctly.
The problem also occurs with imap clients
With the beta version of the NS8 everything was working correctly.
Thank you

Look into ldapproxy1 app log, if you can see any error message.

If you can make an experiment with AD accounts, create a new user and try to log in with its credentials on Nextcloud.

I registered a new user and connected normally to Nextcloud.

I send the log of ldapproxy1

2024-02-27T16:35:21-03:00 [1:ldapproxy1:ldapproxy] 2024/02/27 19:35:21 [info] 25#25: *4205 client disconnected, bytes from/to client:294/422, bytes from/to upstream:422/294
2024-02-27T16:35:21-03:00 [1:ldapproxy1:ldapproxy] 2024/02/27 19:35:21 [info] 25#25: *4215 client 127.0.0.1:34122 connected to 127.0.0.1:20001
2024-02-27T16:35:21-03:00 [1:ldapproxy1:ldapproxy] 2024/02/27 19:35:21 [info] 25#25: *4217 client 127.0.0.1:34134 connected to 127.0.0.1:20001
2024-02-27T16:35:21-03:00 [1:ldapproxy1:ldapproxy] 2024/02/27 19:35:21 [info] 25#25: *4215 proxy 192.168.8.210:55790 connected to 192.168.1.200:389
2024-02-27T16:35:21-03:00 [1:ldapproxy1:ldapproxy] 2024/02/27 19:35:21 [info] 25#25: *4217 proxy 192.168.8.210:55798 connected to 192.168.1.200:389
2024-02-27T16:36:17-03:00 [1:ldapproxy1:ldapproxy] 2024/02/27 19:36:17 [info] 25#25: *4211 client disconnected, bytes from/to client:328/422, bytes from/to upstream:422/328
2024-02-27T16:36:17-03:00 [1:ldapproxy1:ldapproxy] 2024/02/27 19:36:17 [info] 25#25: *4213 client disconnected, bytes from/to client:66/22, bytes from/to upstream:22/66
2024-02-27T16:36:21-03:00 [1:ldapproxy1:ldapproxy] 2024/02/27 19:36:21 [info] 25#25: *4215 client disconnected, bytes from/to client:66/22, bytes from/to upstream:22/66
2024-02-27T16:36:21-03:00 [1:ldapproxy1:ldapproxy] 2024/02/27 19:36:21 [info] 25#25: *4217 client disconnected, bytes from/to client:66/22, bytes from/to upstream:22/66
2024-02-27T16:36:23-03:00 [1:ldapproxy1:ldapproxy] 2024/02/27 19:36:23 [info] 25#25: *4219 client 127.0.0.1:33918 connected to 127.0.0.1:20001
2024-02-27T16:36:23-03:00 [1:ldapproxy1:ldapproxy] 2024/02/27 19:36:23 [info] 25#25: *4221 client 127.0.0.1:33920 connected to 127.0.0.1:20001
2024-02-27T16:36:23-03:00 [1:ldapproxy1:ldapproxy] 2024/02/27 19:36:23 [info] 25#25: *4219 proxy 192.168.8.210:38938 connected to 192.168.1.200:389
2024-02-27T16:36:23-03:00 [1:ldapproxy1:ldapproxy] 2024/02/27 19:36:23 [info] 25#25: *4221 proxy 192.168.8.210:38940 connected to 192.168.1.200:389
2024-02-27T16:37:21-03:00 [1:ldapproxy1:ldapproxy] 2024/02/27 19:37:21 [info] 25#25: *4223 client 127.0.0.1:49570 connected to 127.0.0.1:20001
2024-02-27T16:37:21-03:00 [1:ldapproxy1:ldapproxy] 2024/02/27 19:37:21 [info] 25#25: *4225 client 127.0.0.1:49578 connected to 127.0.0.1:20001
2024-02-27T16:37:21-03:00 [1:ldapproxy1:ldapproxy] 2024/02/27 19:37:21 [info] 25#25: *4223 proxy 192.168.8.210:34056 connected to 192.168.1.200:389
2024-02-27T16:37:21-03:00 [1:ldapproxy1:ldapproxy] 2024/02/27 19:37:21 [info] 25#25: *4225 proxy 192.168.8.210:34058 connected to 192.168.1.200:389
2024-02-27T16:37:23-03:00 [1:ldapproxy1:ldapproxy] 2024/02/27 19:37:23 [info] 25#25: *4219 client disconnected, bytes from/to client:328/422, bytes from/to upstream:422/328
2024-02-27T16:37:23-03:00 [1:ldapproxy1:ldapproxy] 2024/02/27 19:37:23 [info] 25#25: *4221 client disconnected, bytes from/to client:66/22, bytes from/to upstream:22/66
2024-02-27T16:37:23-03:00 [1:ldapproxy1:ldapproxy] 2024/02/27 19:37:23 [info] 25#25: *4227 client 127.0.0.1:49580 connected to 127.0.0.1:20001
2024-02-27T16:37:23-03:00 [1:ldapproxy1:ldapproxy] 2024/02/27 19:37:23 [info] 25#25: *4229 client 127.0.0.1:49588 connected to 127.0.0.1:20001
2024-02-27T16:37:23-03:00 [1:ldapproxy1:ldapproxy] 2024/02/27 19:37:23 [info] 25#25: *4227 proxy 192.168.8.210:34080 connected to 192.168.1.200:389
2024-02-27T16:37:23-03:00 [1:ldapproxy1:ldapproxy] 2024/02/27 19:37:23 [info] 25#25: *4229 proxy 192.168.8.210:34094 connected to 192.168.1.200:389

Thank you