transient, intermittent "No user exists" errors

[Gary Miguel]

I'm using SSSD and Google LDAP. My users can log (via tailscale SSH, if that matters)
in but after being logged in for a while they get errors like:
No user exists for uid 61270005

If they log out and log back in things work. Some users have reported that just waiting
without logging out / in also resolves the issue.


Any suggestions as to how to debug or fix?

Here's my sssd.conf:
[sssd]
services = nss, pam
domains = example.org

[domain/example.org]
create_homedir = true
auto_private_groups = true
cache_credentials = true
ldap_tls_cert = /etc/ldap/ldap-client.crt
ldap_tls_key = /etc/ldap/ldap-client.key
ldap_uri = ldaps://ldap.google.com
ldap_search_base = dc=example,dc=org
id_provider = ldap
auth_provider = ldap
ldap_schema = rfc2307bis
ldap_user_uuid = entryUUID
ldap_groups_use_matching_rule_in_chain = true
ldap_initgroups_use_matching_rule_in_chain = true

[pam]
offline_credentials_expiration = 1

Here's nsswitch.conf:
passwd:         files systemd sss
group:          files systemd sss
shadow:         files sss
gshadow:        files

hosts:          files dns
networks:       files

protocols:      db files
services:       db files
ethers:         db files
rpc:            db files

netgroup:       files sss

[Gary Miguel]

I'm seeing things in sssd_example.org.log that indicate problems connecting to the
LDAP server. There are several different errors that happened at different times. 
I've pasted logs from 2 different instances. However I expected the offline
credentials to be usable when this sort of issue happens. Is there something wrong with
the offline / caching?

Errors in sssd_example.org.log below:
Error #1:
(2024-04-09 19:45:40): [be[example.org]] [fo_resolve_service_send] (0x0020): [RID#25761]
No available servers for service 'LDAP'
********************** PREVIOUS MESSAGE WAS TRIGGERED BY THE FOLLOWING BACKTRACE:
   *  (2024-04-09 19:45:40): [be[example.org]] [sdap_id_op_done] (0x0200): [RID#25761]
communication error on cached connection, moving to next server
   *  (2024-04-09 19:45:40): [be[example.org]] [sdap_id_op_done] (0x4000): [RID#25761]
advising for connection retry #1
   *  (2024-04-09 19:45:40): [be[example.org]] [sdap_id_op_done] (0x4000): [RID#25761]
releasing operation connection
   *  (2024-04-09 19:45:40): [be[example.org]] [sdap_id_release_conn_data] (0x4000):
[RID#25761] Releasing unused connection with fd [22]
   *  (2024-04-09 19:45:40): [be[example.org]] [sdap_handle_release] (0x2000): [RID#25761]
Trace: sh[0x56453e6f9b50], connected[1], ops[(nil)], ldap[0x56453ea50650],
destructor_lock[0], release_memory[0]
   *  (2024-04-09 19:45:40): [be[example.org]] [remove_connection_callback] (0x4000):
[RID#25761] Successfully removed connection callback.
   *  (2024-04-09 19:45:40): [be[example.org]] [sdap_id_op_connect_step] (0x4000):
[RID#25761] beginning to connect
   *  (2024-04-09 19:45:40): [be[example.org]] [fo_resolve_service_send] (0x0100):
[RID#25761] Trying to resolve service 'LDAP'
   *  (2024-04-09 19:45:40): [be[example.org]] [get_server_status] (0x1000): [RID#25761]
Status of server 'ldap.google.com' is 'working'
   *  (2024-04-09 19:45:40): [be[example.org]] [get_port_status] (0x1000): [RID#25761]
Port status of port 636 for server 'ldap.google.com' is 'not working'
   *  (2024-04-09 19:45:40): [be[example.org]] [get_port_status] (0x0080): [RID#25761]
SSSD is unable to complete the full connection request, this internal status does not
necessarily indicate network port issues.
   *  (2024-04-09 19:45:40): [be[example.org]] [fo_resolve_service_send] (0x0020):
[RID#25761] No available servers for service 'LDAP'
********************** BACKTRACE DUMP ENDS HERE *********************************

(2024-04-09 19:45:40): [be[example.org]] [sdap_id_op_connect_done] (0x0040): [RID#25761]
Failed to connect, going offline (5 [Input/output error])
********************** PREVIOUS MESSAGE WAS TRIGGERED BY THE FOLLOWING BACKTRACE:
   *  (2024-04-09 19:45:40): [be[example.org]] [be_resolve_server_done] (0x1000):
[RID#25761] Server [NULL] resolution failed: [5]: Input/output error
   *  (2024-04-09 19:45:40): [be[example.org]] [sdap_id_op_connect_done] (0x0040):
[RID#25761] Failed to connect, going offline (5 [Input/output error])
********************** BACKTRACE DUMP ENDS HERE *********************************

(2024-04-09 19:46:01): [be[example.org]] [sbus_issue_request_done] (0x0040):
sssd.dataprovider.getAccountInfo: Error [1432158212]: SSSD is offline
(2024-04-09 19:46:01): [be[example.org]] [sbus_issue_request_done] (0x0040):
sssd.dataprovider.getAccountInfo: Error [1432158212]: SSSD is offline
(2024-04-09 19:46:01): [be[example.org]] [sbus_issue_request_done] (0x0040):
sssd.dataprovider.getAccountInfo: Error [1432158212]: SSSD is offline
(2024-04-09 19:46:01): [be[example.org]] [sbus_issue_request_done] (0x0040):
sssd.dataprovider.getAccountInfo: Error [1432158212]: SSSD is offline
(2024-04-09 19:46:01): [be[example.org]] [sbus_issue_request_done] (0x0040):
sssd.dataprovider.getAccountInfo: Error [1432158212]: SSSD is offline
(2024-04-09 19:46:22): [be[example.org]] [sbus_issue_request_done] (0x0040):
sssd.dataprovider.getAccountInfo: Error [1432158212]: SSSD is offline
(2024-04-09 19:46:22): [be[example.org]] [sbus_issue_request_done] (0x0040):
sssd.dataprovider.getAccountInfo: Error [1432158212]: SSSD is offline
(2024-04-09 19:46:22): [be[example.org]] [sbus_issue_request_done] (0x0040):
sssd.dataprovider.getAccountInfo: Error [1432158212]: SSSD is offline
(2024-04-09 19:46:22): [be[example.org]] [sbus_issue_request_done] (0x0040):
sssd.dataprovider.getAccountInfo: Error [1432158212]: SSSD is offline
(2024-04-09 19:46:22): [be[example.org]] [sbus_issue_request_done] (0x0040):
sssd.dataprovider.getAccountInfo: Error [1432158212]: SSSD is offline
(2024-04-09 19:46:44): [be[example.org]] [sbus_issue_request_done] (0x0040):
sssd.dataprovider.getAccountInfo: Error [1432158212]: SSSD is offline
(2024-04-09 19:46:44): [be[example.org]] [sbus_issue_request_done] (0x0040):
sssd.dataprovider.getAccountInfo: Error [1432158212]: SSSD is offline
(2024-04-09 19:46:44): [be[example.org]] [sbus_issue_request_done] (0x0040):
sssd.dataprovider.getAccountInfo: Error [1432158212]: SSSD is offline
(2024-04-09 19:46:44): [be[example.org]] [sbus_issue_request_done] (0x0040):
sssd.dataprovider.getAccountInfo: Error [1432158212]: SSSD is offline
(2024-04-09 19:46:44): [be[example.org]] [sbus_issue_request_done] (0x0040):
sssd.dataprovider.getAccountInfo: Error [1432158212]: SSSD is offline
(2024-04-09 19:46:48): [be[example.org]] [resolv_gethostbyname_done] (0x0040): [RID#25777]
querying hosts database failed [5]: Input/output error
********************** PREVIOUS MESSAGE WAS TRIGGERED BY THE FOLLOWING BACKTRACE:
   *  (2024-04-09 19:45:40): [be[example.org]] [be_mark_offline] (0x2000): [RID#25761]
Going offline!
   *  (2024-04-09 19:45:40): [be[example.org]] [be_mark_offline] (0x2000): [RID#25761]
Enable check_if_online_ptask.
   *  (2024-04-09 19:45:40): [be[example.org]] [be_ptask_enable] (0x0400): [RID#25761]
Task [Check if online (periodic)]: enabling task
   *  (2024-04-09 19:45:40): [be[example.org]] [be_ptask_schedule] (0x0400): [RID#25761]
Task [Check if online (periodic)]: scheduling task 67 seconds from now [1712692007]
   *  (2024-04-09 19:45:40): [be[example.org]] [be_run_offline_cb] (0x0080): [RID#25761]
Going offline. Running callbacks.
   *  (2024-04-09 19:45:40): [be[example.org]] [sdap_id_op_connect_done] (0x4000):
[RID#25761] notify offline to op #1
   *  (2024-04-09 19:45:40): [be[example.org]] [dp_req_done] (0x0400): [RID#25761] DP
Request [Account #25761]: Request handler finished [0]: Success
   *  (2024-04-09 19:45:40): [be[example.org]] [_dp_req_recv] (0x0400): [RID#25761] DP
Request [Account #25761]: Receiving request data.
   *  (2024-04-09 19:45:40): [be[example.org]] [dp_req_destructor] (0x0400): [RID#25761]
DP Request [Account #25761]: Request removed.
   *  (2024-04-09 19:45:40): [be[example.org]] [dp_req_destructor] (0x0400): [RID#25761]
Number of active DP request: 0
   *  (2024-04-09 19:45:40): [be[example.org]] [dp_req_reply_std] (0x1000): [RID#25761] DP
Request [Account #25761]: Returning [Provider is Offline]: 1,11,User lookup failed
   *  (2024-04-09 19:45:40): [be[example.org]] [sdap_id_release_conn_data] (0x4000):
[RID#25761] Releasing unused connection with fd [-1]
   *  (2024-04-09 19:45:40): [be[example.org]] [be_ptask_offline_cb] (0x0400): [RID#25761]
Back end is offline
   *  (2024-04-09 19:45:40): [be[example.org]] [be_ptask_disable] (0x0400): [RID#25761]
Task [SUDO Smart Refresh]: disabling task
   *  (2024-04-09 19:45:40): [be[example.org]] [be_ptask_offline_cb] (0x0400): [RID#25761]
Back end is offline
   *  (2024-04-09 19:45:40): [be[example.org]] [be_ptask_disable] (0x0400): [RID#25761]
Task [SUDO Full Refresh]: disabling task
   *  (2024-04-09 19:46:01): [be[example.org]] [dp_get_account_info_send] (0x0200): Got
request for [0x1][BE_REQ_USER][idnumber=1473209301]
   *  (2024-04-09 19:46:01): [be[example.org]] [dp_attach_req] (0x0400): [RID#25762] DP
Request [Account #25762]: REQ_TRACE: New request. [sssd.nss CID #24862] Flags [0x0001].
   *  (2024-04-09 19:46:01): [be[example.org]] [dp_attach_req] (0x0400): [RID#25762] [CID
#24862] Backend is offline! Using cached data if available
   *  (2024-04-09 19:46:01): [be[example.org]] [dp_attach_req] (0x0400): [RID#25762]
Number of active DP request: 1
   *  (2024-04-09 19:46:01): [be[example.org]] [sss_domain_get_state] (0x1000):
[RID#25762] Domain example.org is Active
   *  (2024-04-09 19:46:01): [be[example.org]] [_dp_req_recv] (0x0400): DP Request
[Account #25762]: Receiving request data.
   *  (2024-04-09 19:46:01): [be[example.org]] [dp_req_destructor] (0x0400): DP Request
[Account #25762]: Request removed.
   *  (2024-04-09 19:46:01): [be[example.org]] [dp_req_destructor] (0x0400): Number of
active DP request: 0
   *  (2024-04-09 19:46:01): [be[example.org]] [dp_get_account_info_send] (0x0200): Got
request for [0x2][BE_REQ_GROUP][idnumber=1473209301]
   *  (2024-04-09 19:46:01): [be[example.org]] [dp_attach_req] (0x0400): [RID#25763] DP
Request [Account #25763]: REQ_TRACE: New request. [sssd.nss CID #24862] Flags [0x0001].
   *  (2024-04-09 19:46:01): [be[example.org]] [dp_attach_req] (0x0400): [RID#25763] [CID
#24862] Backend is offline! Using cached data if available
   *  (2024-04-09 19:46:01): [be[example.org]] [dp_attach_req] (0x0400): [RID#25763]
Number of active DP request: 1
   *  (2024-04-09 19:46:01): [be[example.org]] [sss_domain_get_state] (0x1000):
[RID#25763] Domain example.org is Active
   *  (2024-04-09 19:46:01): [be[example.org]] [_dp_req_recv] (0x0400): DP Request
[Account #25763]: Receiving request data.
   *  (2024-04-09 19:46:01): [be[example.org]] [dp_req_destructor] (0x0400): DP Request
[Account #25763]: Request removed.
   *  (2024-04-09 19:46:01): [be[example.org]] [dp_req_destructor] (0x0400): Number of
active DP request: 0
   *  (2024-04-09 19:46:01): [be[example.org]] [dp_get_account_info_send] (0x0200): Got
request for [0x2][BE_REQ_GROUP][idnumber=1473209301]
   *  (2024-04-09 19:46:01): [be[example.org]] [dp_attach_req] (0x0400): [RID#25764] DP
Request [Account #25764]: REQ_TRACE: New request. [sssd.nss CID #24862] Flags [0x0001].
   *  (2024-04-09 19:46:01): [be[example.org]] [dp_attach_req] (0x0400): [RID#25764] [CID
#24862] Backend is offline! Using cached data if available
   *  (2024-04-09 19:46:01): [be[example.org]] [dp_attach_req] (0x0400): [RID#25764]
Number of active DP request: 1
   *  (2024-04-09 19:46:01): [be[example.org]] [sss_domain_get_state] (0x1000):
[RID#25764] Domain example.org is Active
   *  (2024-04-09 19:46:01): [be[example.org]] [_dp_req_recv] (0x0400): DP Request
[Account #25764]: Receiving request data.
   *  (2024-04-09 19:46:01): [be[example.org]] [dp_req_destructor] (0x0400): DP Request
[Account #25764]: Request removed.
   *  (2024-04-09 19:46:01): [be[example.org]] [dp_req_destructor] (0x0400): Number of
active DP request: 0
   *  (2024-04-09 19:46:01): [be[example.org]] [dp_get_account_info_send] (0x0200): Got
request for [0x1][BE_REQ_USER][idnumber=1220213587]
   *  (2024-04-09 19:46:01): [be[example.org]] [dp_attach_req] (0x0400): [RID#25765] DP
Request [Account #25765]: REQ_TRACE: New request. [sssd.nss CID #24863] Flags [0x0001].
   *  (2024-04-09 19:46:01): [be[example.org]] [dp_attach_req] (0x0400): [RID#25765] [CID
#24863] Backend is offline! Using cached data if available
   *  (2024-04-09 19:46:01): [be[example.org]] [dp_attach_req] (0x0400): [RID#25765]
Number of active DP request: 1
   *  (2024-04-09 19:46:01): [be[example.org]] [sss_domain_get_state] (0x1000):
[RID#25765] Domain example.org is Active
   *  (2024-04-09 19:46:01): [be[example.org]] [_dp_req_recv] (0x0400): DP Request
[Account #25765]: Receiving request data.
   *  (2024-04-09 19:46:01): [be[example.org]] [dp_req_destructor] (0x0400): DP Request
[Account #25765]: Request removed.
   *  (2024-04-09 19:46:01): [be[example.org]] [dp_req_destructor] (0x0400): Number of
active DP request: 0
   *  (2024-04-09 19:46:01): [be[example.org]] [dp_get_account_info_send] (0x0200): Got
request for [0x1][BE_REQ_USER][idnumber=1473209301]
   *  (2024-04-09 19:46:01): [be[example.org]] [dp_attach_req] (0x0400): [RID#25766] DP
Request [Account #25766]: REQ_TRACE: New request. [sssd.nss CID #24863] Flags [0x0001].
   *  (2024-04-09 19:46:01): [be[example.org]] [dp_attach_req] (0x0400): [RID#25766] [CID
#24863] Backend is offline! Using cached data if available
   *  (2024-04-09 19:46:01): [be[example.org]] [dp_attach_req] (0x0400): [RID#25766]
Number of active DP request: 1
   *  (2024-04-09 19:46:01): [be[example.org]] [sss_domain_get_state] (0x1000):
[RID#25766] Domain example.org is Active
   *  (2024-04-09 19:46:01): [be[example.org]] [_dp_req_recv] (0x0400): DP Request
[Account #25766]: Receiving request data.
   *  (2024-04-09 19:46:01): [be[example.org]] [dp_req_destructor] (0x0400): DP Request
[Account #25766]: Request removed.
   *  (2024-04-09 19:46:01): [be[example.org]] [dp_req_destructor] (0x0400): Number of
active DP request: 0
   *  (2024-04-09 19:46:22): [be[example.org]] [dp_get_account_info_send] (0x0200): Got
request for [0x1][BE_REQ_USER][idnumber=1473209301]
   *  (2024-04-09 19:46:22): [be[example.org]] [dp_attach_req] (0x0400): [RID#25767] DP
Request [Account #25767]: REQ_TRACE: New request. [sssd.nss CID #24864] Flags [0x0001].
   *  (2024-04-09 19:46:22): [be[example.org]] [dp_attach_req] (0x0400): [RID#25767] [CID
#24864] Backend is offline! Using cached data if available
   *  (2024-04-09 19:46:22): [be[example.org]] [dp_attach_req] (0x0400): [RID#25767]
Number of active DP request: 1
   *  (2024-04-09 19:46:22): [be[example.org]] [sss_domain_get_state] (0x1000):
[RID#25767] Domain example.org is Active
   *  (2024-04-09 19:46:22): [be[example.org]] [_dp_req_recv] (0x0400): DP Request
[Account #25767]: Receiving request data.
   *  (2024-04-09 19:46:22): [be[example.org]] [dp_req_destructor] (0x0400): DP Request
[Account #25767]: Request removed.
   *  (2024-04-09 19:46:22): [be[example.org]] [dp_req_destructor] (0x0400): Number of
active DP request: 0
   *  (2024-04-09 19:46:22): [be[example.org]] [dp_get_account_info_send] (0x0200): Got
request for [0x2][BE_REQ_GROUP][idnumber=1473209301]
   *  (2024-04-09 19:46:22): [be[example.org]] [dp_attach_req] (0x0400): [RID#25768] DP
Request [Account #25768]: REQ_TRACE: New request. [sssd.nss CID #24864] Flags [0x0001].
   *  (2024-04-09 19:46:22): [be[example.org]] [dp_attach_req] (0x0400): [RID#25768] [CID
#24864] Backend is offline! Using cached data if available
   *  (2024-04-09 19:46:22): [be[example.org]] [dp_attach_req] (0x0400): [RID#25768]
Number of active DP request: 1
   *  (2024-04-09 19:46:22): [be[example.org]] [sss_domain_get_state] (0x1000):
[RID#25768] Domain example.org is Active
   *  (2024-04-09 19:46:22): [be[example.org]] [_dp_req_recv] (0x0400): DP Request
[Account #25768]: Receiving request data.
   *  (2024-04-09 19:46:22): [be[example.org]] [dp_req_destructor] (0x0400): DP Request
[Account #25768]: Request removed.
   *  (2024-04-09 19:46:22): [be[example.org]] [dp_req_destructor] (0x0400): Number of
active DP request: 0
   *  (2024-04-09 19:46:22): [be[example.org]] [dp_get_account_info_send] (0x0200): Got
request for [0x2][BE_REQ_GROUP][idnumber=1473209301]
   *  (2024-04-09 19:46:22): [be[example.org]] [dp_attach_req] (0x0400): [RID#25769] DP
Request [Account #25769]: REQ_TRACE: New request. [sssd.nss CID #24864] Flags [0x0001].
   *  (2024-04-09 19:46:22): [be[example.org]] [dp_attach_req] (0x0400): [RID#25769] [CID
#24864] Backend is offline! Using cached data if available
   *  (2024-04-09 19:46:22): [be[example.org]] [dp_attach_req] (0x0400): [RID#25769]
Number of active DP request: 1
   *  (2024-04-09 19:46:22): [be[example.org]] [sss_domain_get_state] (0x1000):
[RID#25769] Domain example.org is Active
   *  (2024-04-09 19:46:22): [be[example.org]] [_dp_req_recv] (0x0400): DP Request
[Account #25769]: Receiving request data.
   *  (2024-04-09 19:46:22): [be[example.org]] [dp_req_destructor] (0x0400): DP Request
[Account #25769]: Request removed.
   *  (2024-04-09 19:46:22): [be[example.org]] [dp_req_destructor] (0x0400): Number of
active DP request: 0
   *  (2024-04-09 19:46:22): [be[example.org]] [dp_get_account_info_send] (0x0200): Got
request for [0x1][BE_REQ_USER][idnumber=1220213587]
   *  (2024-04-09 19:46:22): [be[example.org]] [dp_attach_req] (0x0400): [RID#25770] DP
Request [Account #25770]: REQ_TRACE: New request. [sssd.nss CID #24865] Flags [0x0001].
   *  (2024-04-09 19:46:22): [be[example.org]] [dp_attach_req] (0x0400): [RID#25770] [CID
#24865] Backend is offline! Using cached data if available
   *  (2024-04-09 19:46:22): [be[example.org]] [dp_attach_req] (0x0400): [RID#25770]
Number of active DP request: 1
   *  (2024-04-09 19:46:22): [be[example.org]] [sss_domain_get_state] (0x1000):
[RID#25770] Domain example.org is Active
   *  (2024-04-09 19:46:22): [be[example.org]] [_dp_req_recv] (0x0400): DP Request
[Account #25770]: Receiving request data.
   *  (2024-04-09 19:46:22): [be[example.org]] [dp_req_destructor] (0x0400): DP Request
[Account #25770]: Request removed.
   *  (2024-04-09 19:46:22): [be[example.org]] [dp_req_destructor] (0x0400): Number of
active DP request: 0
   *  (2024-04-09 19:46:22): [be[example.org]] [dp_get_account_info_send] (0x0200): Got
request for [0x1][BE_REQ_USER][idnumber=1473209301]
   *  (2024-04-09 19:46:22): [be[example.org]] [dp_attach_req] (0x0400): [RID#25771] DP
Request [Account #25771]: REQ_TRACE: New request. [sssd.nss CID #24865] Flags [0x0001].
   *  (2024-04-09 19:46:22): [be[example.org]] [dp_attach_req] (0x0400): [RID#25771] [CID
#24865] Backend is offline! Using cached data if available
   *  (2024-04-09 19:46:22): [be[example.org]] [dp_attach_req] (0x0400): [RID#25771]
Number of active DP request: 1
   *  (2024-04-09 19:46:22): [be[example.org]] [sss_domain_get_state] (0x1000):
[RID#25771] Domain example.org is Active
   *  (2024-04-09 19:46:22): [be[example.org]] [_dp_req_recv] (0x0400): DP Request
[Account #25771]: Receiving request data.
   *  (2024-04-09 19:46:22): [be[example.org]] [dp_req_destructor] (0x0400): DP Request
[Account #25771]: Request removed.
   *  (2024-04-09 19:46:22): [be[example.org]] [dp_req_destructor] (0x0400): Number of
active DP request: 0
   *  (2024-04-09 19:46:44): [be[example.org]] [dp_get_account_info_send] (0x0200): Got
request for [0x1][BE_REQ_USER][idnumber=1473209301]
   *  (2024-04-09 19:46:44): [be[example.org]] [dp_attach_req] (0x0400): [RID#25772] DP
Request [Account #25772]: REQ_TRACE: New request. [sssd.nss CID #24866] Flags [0x0001].
   *  (2024-04-09 19:46:44): [be[example.org]] [dp_attach_req] (0x0400): [RID#25772] [CID
#24866] Backend is offline! Using cached data if available
   *  (2024-04-09 19:46:44): [be[example.org]] [dp_attach_req] (0x0400): [RID#25772]
Number of active DP request: 1
   *  (2024-04-09 19:46:44): [be[example.org]] [sss_domain_get_state] (0x1000):
[RID#25772] Domain example.org is Active
   *  (2024-04-09 19:46:44): [be[example.org]] [_dp_req_recv] (0x0400): DP Request
[Account #25772]: Receiving request data.
   *  (2024-04-09 19:46:44): [be[example.org]] [dp_req_destructor] (0x0400): DP Request
[Account #25772]: Request removed.
   *  (2024-04-09 19:46:44): [be[example.org]] [dp_req_destructor] (0x0400): Number of
active DP request: 0
   *  (2024-04-09 19:46:44): [be[example.org]] [dp_get_account_info_send] (0x0200): Got
request for [0x2][BE_REQ_GROUP][idnumber=1473209301]
   *  (2024-04-09 19:46:44): [be[example.org]] [dp_attach_req] (0x0400): [RID#25773] DP
Request [Account #25773]: REQ_TRACE: New request. [sssd.nss CID #24866] Flags [0x0001].
   *  (2024-04-09 19:46:44): [be[example.org]] [dp_attach_req] (0x0400): [RID#25773] [CID
#24866] Backend is offline! Using cached data if available
   *  (2024-04-09 19:46:44): [be[example.org]] [dp_attach_req] (0x0400): [RID#25773]
Number of active DP request: 1
   *  (2024-04-09 19:46:44): [be[example.org]] [sss_domain_get_state] (0x1000):
[RID#25773] Domain example.org is Active
   *  (2024-04-09 19:46:44): [be[example.org]] [_dp_req_recv] (0x0400): DP Request
[Account #25773]: Receiving request data.
   *  (2024-04-09 19:46:44): [be[example.org]] [dp_req_destructor] (0x0400): DP Request
[Account #25773]: Request removed.
   *  (2024-04-09 19:46:44): [be[example.org]] [dp_req_destructor] (0x0400): Number of
active DP request: 0
   *  (2024-04-09 19:46:44): [be[example.org]] [dp_get_account_info_send] (0x0200): Got
request for [0x2][BE_REQ_GROUP][idnumber=1473209301]
   *  (2024-04-09 19:46:44): [be[example.org]] [dp_attach_req] (0x0400): [RID#25774] DP
Request [Account #25774]: REQ_TRACE: New request. [sssd.nss CID #24866] Flags [0x0001].
   *  (2024-04-09 19:46:44): [be[example.org]] [dp_attach_req] (0x0400): [RID#25774] [CID
#24866] Backend is offline! Using cached data if available
   *  (2024-04-09 19:46:44): [be[example.org]] [dp_attach_req] (0x0400): [RID#25774]
Number of active DP request: 1
   *  (2024-04-09 19:46:44): [be[example.org]] [sss_domain_get_state] (0x1000):
[RID#25774] Domain example.org is Active
   *  (2024-04-09 19:46:44): [be[example.org]] [_dp_req_recv] (0x0400): DP Request
[Account #25774]: Receiving request data.
   *  (2024-04-09 19:46:44): [be[example.org]] [dp_req_destructor] (0x0400): DP Request
[Account #25774]: Request removed.
   *  (2024-04-09 19:46:44): [be[example.org]] [dp_req_destructor] (0x0400): Number of
active DP request: 0
   *  (2024-04-09 19:46:44): [be[example.org]] [dp_get_account_info_send] (0x0200): Got
request for [0x1][BE_REQ_USER][idnumber=1220213587]
   *  (2024-04-09 19:46:44): [be[example.org]] [dp_attach_req] (0x0400): [RID#25775] DP
Request [Account #25775]: REQ_TRACE: New request. [sssd.nss CID #24867] Flags [0x0001].
   *  (2024-04-09 19:46:44): [be[example.org]] [dp_attach_req] (0x0400): [RID#25775] [CID
#24867] Backend is offline! Using cached data if available
   *  (2024-04-09 19:46:44): [be[example.org]] [dp_attach_req] (0x0400): [RID#25775]
Number of active DP request: 1
   *  (2024-04-09 19:46:44): [be[example.org]] [sss_domain_get_state] (0x1000):
[RID#25775] Domain example.org is Active
   *  (2024-04-09 19:46:44): [be[example.org]] [_dp_req_recv] (0x0400): DP Request
[Account #25775]: Receiving request data.
   *  (2024-04-09 19:46:44): [be[example.org]] [dp_req_destructor] (0x0400): DP Request
[Account #25775]: Request removed.
   *  (2024-04-09 19:46:44): [be[example.org]] [dp_req_destructor] (0x0400): Number of
active DP request: 0
   *  (2024-04-09 19:46:44): [be[example.org]] [dp_get_account_info_send] (0x0200): Got
request for [0x1][BE_REQ_USER][idnumber=1473209301]
   *  (2024-04-09 19:46:44): [be[example.org]] [dp_attach_req] (0x0400): [RID#25776] DP
Request [Account #25776]: REQ_TRACE: New request. [sssd.nss CID #24867] Flags [0x0001].
   *  (2024-04-09 19:46:44): [be[example.org]] [dp_attach_req] (0x0400): [RID#25776] [CID
#24867] Backend is offline! Using cached data if available
   *  (2024-04-09 19:46:44): [be[example.org]] [dp_attach_req] (0x0400): [RID#25776]
Number of active DP request: 1
   *  (2024-04-09 19:46:44): [be[example.org]] [sss_domain_get_state] (0x1000):
[RID#25776] Domain example.org is Active
   *  (2024-04-09 19:46:44): [be[example.org]] [_dp_req_recv] (0x0400): DP Request
[Account #25776]: Receiving request data.
   *  (2024-04-09 19:46:44): [be[example.org]] [dp_req_destructor] (0x0400): DP Request
[Account #25776]: Request removed.
   *  (2024-04-09 19:46:44): [be[example.org]] [dp_req_destructor] (0x0400): Number of
active DP request: 0
   *  (2024-04-09 19:46:47): [be[example.org]] [be_ptask_execute] (0x0400): [RID#25761]
Back end is offline
   *  (2024-04-09 19:46:47): [be[example.org]] [be_ptask_execute] (0x0400): [RID#25761]
Task [Check if online (periodic)]: executing task, timeout 60 seconds
   *  (2024-04-09 19:46:47): [be[example.org]] [check_if_online] (0x2000): [RID#25761]
Schedule check_if_online_delayed in 0s.
   *  (2024-04-09 19:46:47): [be[example.org]] [be_ptask_done] (0x0400): [RID#25761] Task
[Check if online (periodic)]: finished successfully
   *  (2024-04-09 19:46:47): [be[example.org]] [be_ptask_schedule] (0x0400): [RID#25761]
Task [Check if online (periodic)]: scheduling task 145 seconds from last execution time
[1712692152]
   *  (2024-04-09 19:46:47): [be[example.org]] [be_run_unconditional_online_cb] (0x4000):
[RID#25761] List of unconditional online callbacks is empty, nothing to do.
   *  (2024-04-09 19:46:47): [be[example.org]] [check_if_online_delayed] (0x2000):
[RID#25761] Trying to go back online!
   *  (2024-04-09 19:46:47): [be[example.org]] [fo_reset_services] (0x1000): [RID#25761]
Resetting all servers in all services
   *  (2024-04-09 19:46:47): [be[example.org]] [set_server_common_status] (0x0100):
[RID#25761] Marking server 'ldap.google.com' as 'name not resolved'
   *  (2024-04-09 19:46:47): [be[example.org]] [fo_set_port_status] (0x0100): [RID#25761]
Marking port 636 of server 'ldap.google.com' as 'neutral'
   *  (2024-04-09 19:46:47): [be[example.org]] [fo_set_port_status] (0x0400): [RID#25761]
Marking port 636 of duplicate server 'ldap.google.com' as 'neutral'
   *  (2024-04-09 19:46:47): [be[example.org]] [dp_attach_req] (0x0400): [RID#25777] DP
Request [Online Check #25777]: REQ_TRACE: New request. Flags [0000].
   *  (2024-04-09 19:46:47): [be[example.org]] [dp_attach_req] (0x0400): [RID#25777]
Number of active DP request: 1
   *  (2024-04-09 19:46:47): [be[example.org]] [fo_resolve_service_send] (0x0100):
[RID#25777] Trying to resolve service 'LDAP'
   *  (2024-04-09 19:46:47): [be[example.org]] [get_server_status] (0x1000): [RID#25777]
Status of server 'ldap.google.com' is 'name not resolved'
   *  (2024-04-09 19:46:47): [be[example.org]] [get_port_status] (0x1000): [RID#25777]
Port status of port 636 for server 'ldap.google.com' is 'neutral'
   *  (2024-04-09 19:46:47): [be[example.org]] [fo_resolve_service_activate_timeout]
(0x2000): [RID#25777] Resolve timeout [dns_resolver_timeout] set to 6 seconds
   *  (2024-04-09 19:46:47): [be[example.org]] [get_server_status] (0x1000): [RID#25777]
Status of server 'ldap.google.com' is 'name not resolved'
   *  (2024-04-09 19:46:47): [be[example.org]] [resolv_is_address] (0x4000): [RID#25777]
[ldap.google.com] does not look like an IP address
   *  (2024-04-09 19:46:47): [be[example.org]] [resolv_gethostbyname_step] (0x2000):
[RID#25777] Querying files
   *  (2024-04-09 19:46:47): [be[example.org]] [resolv_gethostbyname_files_send] (0x0100):
[RID#25777] Trying to resolve A record of 'ldap.google.com' in files
   *  (2024-04-09 19:46:47): [be[example.org]] [set_server_common_status] (0x0100):
[RID#25777] Marking server 'ldap.google.com' as 'resolving name'
   *  (2024-04-09 19:46:47): [be[example.org]] [check_if_online_delayed] (0x2000):
[RID#25761] Check online req created.
   *  (2024-04-09 19:46:47): [be[example.org]] [resolv_gethostbyname_step] (0x2000):
[RID#25777] Querying files
   *  (2024-04-09 19:46:47): [be[example.org]] [resolv_gethostbyname_files_send] (0x0100):
[RID#25777] Trying to resolve AAAA record of 'ldap.google.com' in files
   *  (2024-04-09 19:46:47): [be[example.org]] [resolv_gethostbyname_next] (0x0200):
[RID#25777] No more address families to retry
   *  (2024-04-09 19:46:47): [be[example.org]] [resolv_gethostbyname_step] (0x2000):
[RID#25777] Querying DNS
   *  (2024-04-09 19:46:47): [be[example.org]] [resolv_gethostbyname_dns_query] (0x0100):
[RID#25777] Trying to resolve A record of 'ldap.google.com' in DNS
   *  (2024-04-09 19:46:47): [be[example.org]] [schedule_request_timeout] (0x2000):
[RID#25777] Scheduling a timeout of 3 seconds
   *  (2024-04-09 19:46:47): [be[example.org]] [schedule_timeout_watcher] (0x2000):
[RID#25777] Scheduling DNS timeout watcher
   *  (2024-04-09 19:46:48): [be[example.org]] [check_fd_timeouts] (0x4000): [RID#25777]
Checking for DNS timeouts
   *  (2024-04-09 19:46:48): [be[example.org]] [unschedule_timeout_watcher] (0x4000):
[RID#25777] Unscheduling DNS timeout watcher
   *  (2024-04-09 19:46:48): [be[example.org]] [request_watch_destructor] (0x0400):
[RID#25777] Deleting request watch
   *  (2024-04-09 19:46:48): [be[example.org]] [resolv_gethostbyname_done] (0x0040):
[RID#25777] querying hosts database failed [5]: Input/output error
********************** BACKTRACE DUMP ENDS HERE *********************************

Error #2:

(2024-04-11 20:29:19): [be[example.org]] [sdap_process_result] (0x0040): ldap_result
error: [Can't contact LDAP server]
********************** PREVIOUS MESSAGE WAS TRIGGERED BY THE FOLLOWING BACKTRACE:
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_print_server] (0x2000): [RID#37292]
Searching 216.239.32.58:636
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x0400):
[RID#37292] calling ldap_search_ext with
[(&(uidNumber=1473209301)(objectclass=posixAccount)(uid=*)(&(uidNumber=*)(!(uidNumber=0))))][dc=example,dc=org].
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [objectClass]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [uid]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [userPassword]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [uidNumber]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [gidNumber]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [gecos]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [homeDirectory]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [loginShell]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [krbPrincipalName]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [cn]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [memberOf]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [entryUUID]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [modifyTimestamp]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [modifyTimestamp]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [shadowLastChange]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [shadowMin]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [shadowMax]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [shadowWarning]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [shadowInactive]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [shadowExpire]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [shadowFlag]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [krbLastPwdChange]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [krbPasswordExpiration]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [pwdAttribute]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [authorizedService]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [accountExpires]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [userAccountControl]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [nsAccountLock]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [host]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [rhost]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [loginDisabled]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [loginExpirationTime]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [loginAllowedTimeMap]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [sshPublicKey]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [userCertificate;binary]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37292] Requesting attrs: [mail]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x2000):
[RID#37292] ldap_search_ext called, msgid = 186
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_op_add] (0x2000): [RID#37292] New
operation 186 timeout 6
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_process_result] (0x2000): Trace:
sh[0x56453e6c4af0], connected[1], ops[0x56453ea31d30], ldap[0x56453e7074a0]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_process_message] (0x4000):
[RID#37292] Message type: [LDAP_RES_SEARCH_RESULT]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_op_finished] (0x0400):
[RID#37292] Search result: Success(0), no errmsg set
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_op_destructor] (0x2000): [RID#37292]
Operation 186 finished
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_search_user_process] (0x0400):
[RID#37292] Search for users, returned 0 results.
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_search_user_process] (0x2000):
[RID#37292] Retrieved total 0 users
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_id_op_done] (0x4000): [RID#37292]
releasing operation connection
   *  (2024-04-11 20:28:32): [be[example.org]] [sysdb_search_user_by_uid] (0x0400):
[RID#37292] No such entry
   *  (2024-04-11 20:28:32): [be[example.org]] [sysdb_delete_user] (0x0400): [RID#37292]
Error: 2 (No such file or directory)
   *  (2024-04-11 20:28:32): [be[example.org]] [dp_req_done] (0x0400): [RID#37292] DP
Request [Account #37292]: Request handler finished [0]: Success
   *  (2024-04-11 20:28:32): [be[example.org]] [_dp_req_recv] (0x0400): [RID#37292] DP
Request [Account #37292]: Receiving request data.
   *  (2024-04-11 20:28:32): [be[example.org]] [dp_req_destructor] (0x0400): [RID#37292]
DP Request [Account #37292]: Request removed.
   *  (2024-04-11 20:28:32): [be[example.org]] [dp_req_destructor] (0x0400): [RID#37292]
Number of active DP request: 0
   *  (2024-04-11 20:28:32): [be[example.org]] [dp_req_reply_std] (0x1000): [RID#37292] DP
Request [Account #37292]: Returning [Success]: 0,0,Success
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_process_result] (0x2000): Trace:
sh[0x56453e6c4af0], connected[1], ops[(nil)], ldap[0x56453e7074a0]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_process_result] (0x2000): Trace: end
of ldap_result list
   *  (2024-04-11 20:28:32): [be[example.org]] [dp_get_account_info_send] (0x0200): Got
request for [0x2][BE_REQ_GROUP][idnumber=1473209301]
   *  (2024-04-11 20:28:32): [be[example.org]] [dp_attach_req] (0x0400): [RID#37293] DP
Request [Account #37293]: REQ_TRACE: New request. [sssd.nss CID #37609] Flags [0x0001].
   *  (2024-04-11 20:28:32): [be[example.org]] [dp_attach_req] (0x0400): [RID#37293]
Number of active DP request: 1
   *  (2024-04-11 20:28:32): [be[example.org]] [sss_domain_get_state] (0x1000):
[RID#37293] Domain example.org is Active
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_id_op_connect_step] (0x4000):
[RID#37293] reusing cached connection
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_groups_next_base] (0x0400):
[RID#37293] Searching for groups with base [dc=example,dc=org]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_print_server] (0x2000): [RID#37293]
Searching 216.239.32.58:636
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x0400):
[RID#37293] calling ldap_search_ext with
[(&(gidNumber=1473209301)(objectClass=posixGroup)(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][dc=example,dc=org].
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [objectClass]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [cn]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [userPassword]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [gidNumber]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [member]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [modifyTimestamp]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [modifyTimestamp]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x2000):
[RID#37293] ldap_search_ext called, msgid = 187
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_op_add] (0x2000): [RID#37293] New
operation 187 timeout 6
   *  (2024-04-11 20:28:32): [be[example.org]] [dp_get_account_info_send] (0x0200): Got
request for [0x1][BE_REQ_USER][idnumber=61270005]
   *  (2024-04-11 20:28:32): [be[example.org]] [dp_attach_req] (0x0400): [RID#37294] DP
Request [Account #37294]: REQ_TRACE: New request. [sssd.nss CID #37610] Flags [0x0001].
   *  (2024-04-11 20:28:32): [be[example.org]] [dp_attach_req] (0x0400): [RID#37294]
Number of active DP request: 2
   *  (2024-04-11 20:28:32): [be[example.org]] [sss_domain_get_state] (0x1000):
[RID#37294] Domain example.org is Active
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_id_op_connect_step] (0x4000):
[RID#37294] reusing cached connection
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_search_user_next_base] (0x0400):
[RID#37294] Searching for users with base [dc=example,dc=org]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_print_server] (0x2000): [RID#37294]
Searching 216.239.32.58:636
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x0400):
[RID#37294] calling ldap_search_ext with
[(&(uidNumber=61270005)(objectclass=posixAccount)(uid=*)(&(uidNumber=*)(!(uidNumber=0))))][dc=example,dc=org].
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [objectClass]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [uid]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [userPassword]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [uidNumber]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [gidNumber]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [gecos]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [homeDirectory]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [loginShell]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [krbPrincipalName]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [cn]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [memberOf]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [entryUUID]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [modifyTimestamp]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [modifyTimestamp]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [shadowLastChange]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [shadowMin]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [shadowMax]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [shadowWarning]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [shadowInactive]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [shadowExpire]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [shadowFlag]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [krbLastPwdChange]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [krbPasswordExpiration]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [pwdAttribute]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [authorizedService]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [accountExpires]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [userAccountControl]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [nsAccountLock]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [host]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [rhost]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [loginDisabled]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [loginExpirationTime]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [loginAllowedTimeMap]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [sshPublicKey]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [userCertificate;binary]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37294] Requesting attrs: [mail]
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_get_generic_ext_step] (0x2000):
[RID#37294] ldap_search_ext called, msgid = 188
   *  (2024-04-11 20:28:32): [be[example.org]] [sdap_op_add] (0x2000): [RID#37294] New
operation 188 timeout 6
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_process_result] (0x2000): Trace:
sh[0x56453e6c4af0], connected[1], ops[0x56453ea56f20], ldap[0x56453e7074a0]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_process_message] (0x4000):
[RID#37294] Message type: [LDAP_RES_SEARCH_RESULT]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_op_finished] (0x0400):
[RID#37294] Search result: Success(0), no errmsg set
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_op_destructor] (0x2000): [RID#37294]
Operation 188 finished
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_search_user_process] (0x0400):
[RID#37294] Search for users, returned 0 results.
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_search_user_process] (0x2000):
[RID#37294] Retrieved total 0 users
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_id_op_done] (0x4000): [RID#37294]
releasing operation connection
   *  (2024-04-11 20:28:33): [be[example.org]] [sysdb_search_user_by_uid] (0x0400):
[RID#37294] No such entry
   *  (2024-04-11 20:28:33): [be[example.org]] [sysdb_delete_user] (0x0400): [RID#37294]
Error: 2 (No such file or directory)
   *  (2024-04-11 20:28:33): [be[example.org]] [dp_req_done] (0x0400): [RID#37294] DP
Request [Account #37294]: Request handler finished [0]: Success
   *  (2024-04-11 20:28:33): [be[example.org]] [_dp_req_recv] (0x0400): [RID#37294] DP
Request [Account #37294]: Receiving request data.
   *  (2024-04-11 20:28:33): [be[example.org]] [dp_req_destructor] (0x0400): [RID#37294]
DP Request [Account #37294]: Request removed.
   *  (2024-04-11 20:28:33): [be[example.org]] [dp_req_destructor] (0x0400): [RID#37294]
Number of active DP request: 1
   *  (2024-04-11 20:28:33): [be[example.org]] [dp_req_reply_std] (0x1000): [RID#37294] DP
Request [Account #37294]: Returning [Success]: 0,0,Success
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_process_result] (0x2000): Trace:
sh[0x56453e6c4af0], connected[1], ops[0x56453ea4ef90], ldap[0x56453e7074a0]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_process_result] (0x2000): Trace: end
of ldap_result list
   *  (2024-04-11 20:28:33): [be[example.org]] [dp_get_account_info_send] (0x0200): Got
request for [0x2][BE_REQ_GROUP][idnumber=61270005]
   *  (2024-04-11 20:28:33): [be[example.org]] [dp_attach_req] (0x0400): [RID#37295] DP
Request [Account #37295]: REQ_TRACE: New request. [sssd.nss CID #37610] Flags [0x0001].
   *  (2024-04-11 20:28:33): [be[example.org]] [dp_attach_req] (0x0400): [RID#37295]
Number of active DP request: 2
   *  (2024-04-11 20:28:33): [be[example.org]] [sss_domain_get_state] (0x1000):
[RID#37295] Domain example.org is Active
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_id_op_connect_step] (0x4000):
[RID#37295] reusing cached connection
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_groups_next_base] (0x0400):
[RID#37295] Searching for groups with base [dc=example,dc=org]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_print_server] (0x2000): [RID#37295]
Searching 216.239.32.58:636
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x0400):
[RID#37295] calling ldap_search_ext with
[(&(gidNumber=61270005)(objectClass=posixGroup)(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][dc=example,dc=org].
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [objectClass]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [cn]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [userPassword]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [gidNumber]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [member]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [modifyTimestamp]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [modifyTimestamp]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x2000):
[RID#37295] ldap_search_ext called, msgid = 189
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_op_add] (0x2000): [RID#37295] New
operation 189 timeout 6
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_process_result] (0x2000): Trace:
sh[0x56453e6c4af0], connected[1], ops[0x56453e70fd80], ldap[0x56453e7074a0]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_process_message] (0x4000):
[RID#37293] Message type: [LDAP_RES_SEARCH_RESULT]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_op_finished] (0x0400):
[RID#37293] Search result: Success(0), no errmsg set
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_op_destructor] (0x2000): [RID#37293]
Operation 187 finished
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_groups_process] (0x0400):
[RID#37293] Search for groups, returned 0 results.
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_id_op_done] (0x4000): [RID#37293]
releasing operation connection
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_id_op_connect_step] (0x4000):
[RID#37293] reusing cached connection
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_search_user_next_base] (0x0400):
[RID#37293] Searching for users with base [dc=example,dc=org]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_print_server] (0x2000): [RID#37293]
Searching 216.239.32.58:636
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x0400):
[RID#37293] calling ldap_search_ext with
[(&(uidNumber=1473209301)(objectclass=posixAccount)(uid=*)(&(uidNumber=*)(!(uidNumber=0))))][dc=example,dc=org].
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [objectClass]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [uid]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [userPassword]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [uidNumber]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [gidNumber]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [gecos]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [homeDirectory]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [loginShell]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [krbPrincipalName]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [cn]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [memberOf]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [entryUUID]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [modifyTimestamp]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [modifyTimestamp]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [shadowLastChange]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [shadowMin]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [shadowMax]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [shadowWarning]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [shadowInactive]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [shadowExpire]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [shadowFlag]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [krbLastPwdChange]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [krbPasswordExpiration]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [pwdAttribute]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [authorizedService]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [accountExpires]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [userAccountControl]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [nsAccountLock]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [host]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [rhost]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [loginDisabled]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [loginExpirationTime]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [loginAllowedTimeMap]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [sshPublicKey]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [userCertificate;binary]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37293] Requesting attrs: [mail]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x2000):
[RID#37293] ldap_search_ext called, msgid = 190
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_op_add] (0x2000): [RID#37293] New
operation 190 timeout 6
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_process_result] (0x2000): Trace:
sh[0x56453e6c4af0], connected[1], ops[0x56453ea4ef90], ldap[0x56453e7074a0]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_process_result] (0x2000): Trace: end
of ldap_result list
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_process_result] (0x2000): Trace:
sh[0x56453e6c4af0], connected[1], ops[0x56453ea4ef90], ldap[0x56453e7074a0]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_process_message] (0x4000):
[RID#37293] Message type: [LDAP_RES_SEARCH_RESULT]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_op_finished] (0x0400):
[RID#37293] Search result: Success(0), no errmsg set
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_op_destructor] (0x2000): [RID#37293]
Operation 190 finished
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_search_user_process] (0x0400):
[RID#37293] Search for users, returned 0 results.
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_search_user_process] (0x2000):
[RID#37293] Retrieved total 0 users
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_id_op_done] (0x4000): [RID#37293]
releasing operation connection
   *  (2024-04-11 20:28:33): [be[example.org]] [sysdb_search_user_by_uid] (0x0400):
[RID#37293] No such entry
   *  (2024-04-11 20:28:33): [be[example.org]] [sysdb_delete_user] (0x0400): [RID#37293]
Error: 2 (No such file or directory)
   *  (2024-04-11 20:28:33): [be[example.org]] [sysdb_search_group_by_id] (0x0400):
[RID#37293] No such entry
   *  (2024-04-11 20:28:33): [be[example.org]] [sysdb_delete_group] (0x0400): [RID#37293]
Error: 2 (No such file or directory)
   *  (2024-04-11 20:28:33): [be[example.org]] [dp_req_done] (0x0400): [RID#37293] DP
Request [Account #37293]: Request handler finished [0]: Success
   *  (2024-04-11 20:28:33): [be[example.org]] [_dp_req_recv] (0x0400): [RID#37293] DP
Request [Account #37293]: Receiving request data.
   *  (2024-04-11 20:28:33): [be[example.org]] [dp_req_destructor] (0x0400): [RID#37293]
DP Request [Account #37293]: Request removed.
   *  (2024-04-11 20:28:33): [be[example.org]] [dp_req_destructor] (0x0400): [RID#37293]
Number of active DP request: 1
   *  (2024-04-11 20:28:33): [be[example.org]] [dp_req_reply_std] (0x1000): [RID#37293] DP
Request [Account #37293]: Returning [Success]: 0,0,Success
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_process_result] (0x2000): Trace:
sh[0x56453e6c4af0], connected[1], ops[0x56453e70fd80], ldap[0x56453e7074a0]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_process_result] (0x2000): Trace: end
of ldap_result list
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_process_result] (0x2000): Trace:
sh[0x56453e6c4af0], connected[1], ops[0x56453e70fd80], ldap[0x56453e7074a0]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_process_message] (0x4000):
[RID#37295] Message type: [LDAP_RES_SEARCH_RESULT]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_op_finished] (0x0400):
[RID#37295] Search result: Success(0), no errmsg set
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_op_destructor] (0x2000): [RID#37295]
Operation 189 finished
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_groups_process] (0x0400):
[RID#37295] Search for groups, returned 0 results.
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_id_op_done] (0x4000): [RID#37295]
releasing operation connection
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_id_op_connect_step] (0x4000):
[RID#37295] reusing cached connection
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_search_user_next_base] (0x0400):
[RID#37295] Searching for users with base [dc=example,dc=org]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_print_server] (0x2000): [RID#37295]
Searching 216.239.32.58:636
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x0400):
[RID#37295] calling ldap_search_ext with
[(&(uidNumber=61270005)(objectclass=posixAccount)(uid=*)(&(uidNumber=*)(!(uidNumber=0))))][dc=example,dc=org].
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [objectClass]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [uid]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [userPassword]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [uidNumber]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [gidNumber]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [gecos]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [homeDirectory]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [loginShell]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [krbPrincipalName]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [cn]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [memberOf]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [entryUUID]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [modifyTimestamp]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [modifyTimestamp]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [shadowLastChange]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [shadowMin]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [shadowMax]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [shadowWarning]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [shadowInactive]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [shadowExpire]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [shadowFlag]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [krbLastPwdChange]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [krbPasswordExpiration]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [pwdAttribute]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [authorizedService]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [accountExpires]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [userAccountControl]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [nsAccountLock]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [host]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [rhost]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [loginDisabled]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [loginExpirationTime]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [loginAllowedTimeMap]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [sshPublicKey]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [userCertificate;binary]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37295] Requesting attrs: [mail]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_get_generic_ext_step] (0x2000):
[RID#37295] ldap_search_ext called, msgid = 191
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_op_add] (0x2000): [RID#37295] New
operation 191 timeout 6
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_process_result] (0x2000): Trace:
sh[0x56453e6c4af0], connected[1], ops[0x56453e6e3930], ldap[0x56453e7074a0]
   *  (2024-04-11 20:28:33): [be[example.org]] [sdap_process_result] (0x2000): Trace: end
of ldap_result list
   *  (2024-04-11 20:28:34): [be[example.org]] [dp_get_account_info_send] (0x0200): Got
request for [0x1][BE_REQ_USER][idnumber=1220213587]
   *  (2024-04-11 20:28:34): [be[example.org]] [dp_attach_req] (0x0400): [RID#37296] DP
Request [Account #37296]: REQ_TRACE: New request. [sssd.nss CID #37611] Flags [0x0001].
   *  (2024-04-11 20:28:34): [be[example.org]] [dp_attach_req] (0x0400): [RID#37296]
Number of active DP request: 2
   *  (2024-04-11 20:28:34): [be[example.org]] [sss_domain_get_state] (0x1000):
[RID#37296] Domain example.org is Active
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_id_op_connect_step] (0x4000):
[RID#37296] reusing cached connection
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_search_user_next_base] (0x0400):
[RID#37296] Searching for users with base [dc=example,dc=org]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_print_server] (0x2000): [RID#37296]
Searching 216.239.32.58:636
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x0400):
[RID#37296] calling ldap_search_ext with
[(&(uidNumber=1220213587)(objectclass=posixAccount)(uid=*)(&(uidNumber=*)(!(uidNumber=0))))][dc=example,dc=org].
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [objectClass]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [uid]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [userPassword]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [uidNumber]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [gidNumber]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [gecos]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [homeDirectory]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [loginShell]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [krbPrincipalName]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [cn]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [memberOf]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [entryUUID]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [modifyTimestamp]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [modifyTimestamp]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [shadowLastChange]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [shadowMin]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [shadowMax]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [shadowWarning]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [shadowInactive]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [shadowExpire]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [shadowFlag]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [krbLastPwdChange]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [krbPasswordExpiration]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [pwdAttribute]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [authorizedService]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [accountExpires]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [userAccountControl]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [nsAccountLock]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [host]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [rhost]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [loginDisabled]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [loginExpirationTime]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [loginAllowedTimeMap]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [sshPublicKey]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [userCertificate;binary]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37296] Requesting attrs: [mail]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_ext_step] (0x2000):
[RID#37296] ldap_search_ext called, msgid = 192
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_op_add] (0x2000): [RID#37296] New
operation 192 timeout 6
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_process_result] (0x2000): Trace:
sh[0x56453e6c4af0], connected[1], ops[0x56453ea4ef90], ldap[0x56453e7074a0]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_process_message] (0x4000):
[RID#37295] Message type: [LDAP_RES_SEARCH_RESULT]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_op_finished] (0x0400):
[RID#37295] Search result: Success(0), no errmsg set
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_op_destructor] (0x2000): [RID#37295]
Operation 191 finished
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_search_user_process] (0x0400):
[RID#37295] Search for users, returned 0 results.
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_search_user_process] (0x2000):
[RID#37295] Retrieved total 0 users
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_id_op_done] (0x4000): [RID#37295]
releasing operation connection
   *  (2024-04-11 20:28:34): [be[example.org]] [sysdb_search_user_by_uid] (0x0400):
[RID#37295] No such entry
   *  (2024-04-11 20:28:34): [be[example.org]] [sysdb_delete_user] (0x0400): [RID#37295]
Error: 2 (No such file or directory)
   *  (2024-04-11 20:28:34): [be[example.org]] [sysdb_search_group_by_id] (0x0400):
[RID#37295] No such entry
   *  (2024-04-11 20:28:34): [be[example.org]] [sysdb_delete_group] (0x0400): [RID#37295]
Error: 2 (No such file or directory)
   *  (2024-04-11 20:28:34): [be[example.org]] [dp_req_done] (0x0400): [RID#37295] DP
Request [Account #37295]: Request handler finished [0]: Success
   *  (2024-04-11 20:28:34): [be[example.org]] [_dp_req_recv] (0x0400): [RID#37295] DP
Request [Account #37295]: Receiving request data.
   *  (2024-04-11 20:28:34): [be[example.org]] [dp_req_destructor] (0x0400): [RID#37295]
DP Request [Account #37295]: Request removed.
   *  (2024-04-11 20:28:34): [be[example.org]] [dp_req_destructor] (0x0400): [RID#37295]
Number of active DP request: 1
   *  (2024-04-11 20:28:34): [be[example.org]] [dp_req_reply_std] (0x1000): [RID#37295] DP
Request [Account #37295]: Returning [Success]: 0,0,Success
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_process_result] (0x2000): Trace:
sh[0x56453e6c4af0], connected[1], ops[0x56453ea4ef90], ldap[0x56453e7074a0]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_process_result] (0x2000): Trace: end
of ldap_result list
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_process_result] (0x2000): Trace:
sh[0x56453e6c4af0], connected[1], ops[0x56453ea4ef90], ldap[0x56453e7074a0]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_process_message] (0x4000):
[RID#37296] Message type: [LDAP_RES_SEARCH_RESULT]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_get_generic_op_finished] (0x0400):
[RID#37296] Search result: Success(0), no errmsg set
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_op_destructor] (0x2000): [RID#37296]
Operation 192 finished
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_search_user_process] (0x0400):
[RID#37296] Search for users, returned 0 results.
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_search_user_process] (0x2000):
[RID#37296] Retrieved total 0 users
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_id_op_done] (0x4000): [RID#37296]
releasing operation connection
   *  (2024-04-11 20:28:34): [be[example.org]] [sysdb_search_user_by_uid] (0x0400):
[RID#37296] No such entry
   *  (2024-04-11 20:28:34): [be[example.org]] [sysdb_delete_user] (0x0400): [RID#37296]
Error: 2 (No such file or directory)
   *  (2024-04-11 20:28:34): [be[example.org]] [dp_req_done] (0x0400): [RID#37296] DP
Request [Account #37296]: Request handler finished [0]: Success
   *  (2024-04-11 20:28:34): [be[example.org]] [_dp_req_recv] (0x0400): [RID#37296] DP
Request [Account #37296]: Receiving request data.
   *  (2024-04-11 20:28:34): [be[example.org]] [dp_req_destructor] (0x0400): [RID#37296]
DP Request [Account #37296]: Request removed.
   *  (2024-04-11 20:28:34): [be[example.org]] [dp_req_destructor] (0x0400): [RID#37296]
Number of active DP request: 0
   *  (2024-04-11 20:28:34): [be[example.org]] [dp_req_reply_std] (0x1000): [RID#37296] DP
Request [Account #37296]: Returning [Success]: 0,0,Success
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_process_result] (0x2000): Trace:
sh[0x56453e6c4af0], connected[1], ops[(nil)], ldap[0x56453e7074a0]
   *  (2024-04-11 20:28:34): [be[example.org]] [sdap_process_result] (0x2000): Trace: end
of ldap_result list
   *  (2024-04-11 20:29:02): [be[example.org]] [dp_get_account_info_send] (0x0200): Got
request for [0x1][BE_REQ_USER][idnumber=1473209301]
   *  (2024-04-11 20:29:02): [be[example.org]] [dp_attach_req] (0x0400): [RID#37297] DP
Request [Account #37297]: REQ_TRACE: New request. [sssd.nss CID #37613] Flags [0x0001].
   *  (2024-04-11 20:29:02): [be[example.org]] [dp_attach_req] (0x0400): [RID#37297]
Number of active DP request: 1
   *  (2024-04-11 20:29:02): [be[example.org]] [sss_domain_get_state] (0x1000):
[RID#37297] Domain example.org is Active
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_id_op_connect_step] (0x4000):
[RID#37297] reusing cached connection
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_search_user_next_base] (0x0400):
[RID#37297] Searching for users with base [dc=example,dc=org]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_print_server] (0x2000): [RID#37297]
Searching 216.239.32.58:636
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x0400):
[RID#37297] calling ldap_search_ext with
[(&(uidNumber=1473209301)(objectclass=posixAccount)(uid=*)(&(uidNumber=*)(!(uidNumber=0))))][dc=example,dc=org].
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [objectClass]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [uid]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [userPassword]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [uidNumber]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [gidNumber]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [gecos]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [homeDirectory]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [loginShell]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [krbPrincipalName]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [cn]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [memberOf]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [entryUUID]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [modifyTimestamp]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [modifyTimestamp]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [shadowLastChange]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [shadowMin]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [shadowMax]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [shadowWarning]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [shadowInactive]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [shadowExpire]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [shadowFlag]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [krbLastPwdChange]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [krbPasswordExpiration]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [pwdAttribute]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [authorizedService]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [accountExpires]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [userAccountControl]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [nsAccountLock]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [host]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [rhost]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [loginDisabled]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [loginExpirationTime]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [loginAllowedTimeMap]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [sshPublicKey]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [userCertificate;binary]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37297] Requesting attrs: [mail]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x2000):
[RID#37297] ldap_search_ext called, msgid = 193
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_op_add] (0x2000): [RID#37297] New
operation 193 timeout 6
   *  (2024-04-11 20:29:02): [be[example.org]] [dp_get_account_info_send] (0x0200): Got
request for [0x1][BE_REQ_USER][idnumber=61270005]
   *  (2024-04-11 20:29:02): [be[example.org]] [dp_attach_req] (0x0400): [RID#37298] DP
Request [Account #37298]: REQ_TRACE: New request. [sssd.nss CID #37614] Flags [0x0001].
   *  (2024-04-11 20:29:02): [be[example.org]] [dp_attach_req] (0x0400): [RID#37298]
Number of active DP request: 2
   *  (2024-04-11 20:29:02): [be[example.org]] [sss_domain_get_state] (0x1000):
[RID#37298] Domain example.org is Active
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_id_op_connect_step] (0x4000):
[RID#37298] reusing cached connection
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_search_user_next_base] (0x0400):
[RID#37298] Searching for users with base [dc=example,dc=org]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_print_server] (0x2000): [RID#37298]
Searching 216.239.32.58:636
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x0400):
[RID#37298] calling ldap_search_ext with
[(&(uidNumber=61270005)(objectclass=posixAccount)(uid=*)(&(uidNumber=*)(!(uidNumber=0))))][dc=example,dc=org].
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [objectClass]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [uid]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [userPassword]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [uidNumber]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [gidNumber]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [gecos]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [homeDirectory]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [loginShell]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [krbPrincipalName]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [cn]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [memberOf]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [entryUUID]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [modifyTimestamp]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [modifyTimestamp]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [shadowLastChange]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [shadowMin]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [shadowMax]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [shadowWarning]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [shadowInactive]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [shadowExpire]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [shadowFlag]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [krbLastPwdChange]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [krbPasswordExpiration]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [pwdAttribute]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [authorizedService]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [accountExpires]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [userAccountControl]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [nsAccountLock]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [host]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [rhost]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [loginDisabled]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [loginExpirationTime]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [loginAllowedTimeMap]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [sshPublicKey]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [userCertificate;binary]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37298] Requesting attrs: [mail]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x2000):
[RID#37298] ldap_search_ext called, msgid = 194
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_op_add] (0x2000): [RID#37298] New
operation 194 timeout 6
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_process_result] (0x2000): Trace:
sh[0x56453e6c4af0], connected[1], ops[0x56453e70fd80], ldap[0x56453e7074a0]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_process_message] (0x4000):
[RID#37297] Message type: [LDAP_RES_SEARCH_RESULT]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_op_finished] (0x0400):
[RID#37297] Search result: Success(0), no errmsg set
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_op_destructor] (0x2000): [RID#37297]
Operation 193 finished
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_search_user_process] (0x0400):
[RID#37297] Search for users, returned 0 results.
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_search_user_process] (0x2000):
[RID#37297] Retrieved total 0 users
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_id_op_done] (0x4000): [RID#37297]
releasing operation connection
   *  (2024-04-11 20:29:02): [be[example.org]] [sysdb_search_user_by_uid] (0x0400):
[RID#37297] No such entry
   *  (2024-04-11 20:29:02): [be[example.org]] [sysdb_delete_user] (0x0400): [RID#37297]
Error: 2 (No such file or directory)
   *  (2024-04-11 20:29:02): [be[example.org]] [dp_req_done] (0x0400): [RID#37297] DP
Request [Account #37297]: Request handler finished [0]: Success
   *  (2024-04-11 20:29:02): [be[example.org]] [_dp_req_recv] (0x0400): [RID#37297] DP
Request [Account #37297]: Receiving request data.
   *  (2024-04-11 20:29:02): [be[example.org]] [dp_req_destructor] (0x0400): [RID#37297]
DP Request [Account #37297]: Request removed.
   *  (2024-04-11 20:29:02): [be[example.org]] [dp_req_destructor] (0x0400): [RID#37297]
Number of active DP request: 1
   *  (2024-04-11 20:29:02): [be[example.org]] [dp_req_reply_std] (0x1000): [RID#37297] DP
Request [Account #37297]: Returning [Success]: 0,0,Success
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_process_result] (0x2000): Trace:
sh[0x56453e6c4af0], connected[1], ops[0x56453e70fd80], ldap[0x56453e7074a0]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_process_result] (0x2000): Trace: end
of ldap_result list
   *  (2024-04-11 20:29:02): [be[example.org]] [dp_get_account_info_send] (0x0200): Got
request for [0x2][BE_REQ_GROUP][idnumber=1473209301]
   *  (2024-04-11 20:29:02): [be[example.org]] [dp_attach_req] (0x0400): [RID#37299] DP
Request [Account #37299]: REQ_TRACE: New request. [sssd.nss CID #37614] Flags [0x0001].
   *  (2024-04-11 20:29:02): [be[example.org]] [dp_attach_req] (0x0400): [RID#37299]
Number of active DP request: 2
   *  (2024-04-11 20:29:02): [be[example.org]] [sss_domain_get_state] (0x1000):
[RID#37299] Domain example.org is Active
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_id_op_connect_step] (0x4000):
[RID#37299] reusing cached connection
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_groups_next_base] (0x0400):
[RID#37299] Searching for groups with base [dc=example,dc=org]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_print_server] (0x2000): [RID#37299]
Searching 216.239.32.58:636
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x0400):
[RID#37299] calling ldap_search_ext with
[(&(gidNumber=1473209301)(objectClass=posixGroup)(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][dc=example,dc=org].
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [objectClass]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [cn]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [userPassword]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [gidNumber]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [member]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [modifyTimestamp]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [modifyTimestamp]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x2000):
[RID#37299] ldap_search_ext called, msgid = 195
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_op_add] (0x2000): [RID#37299] New
operation 195 timeout 6
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_process_result] (0x2000): Trace:
sh[0x56453e6c4af0], connected[1], ops[0x56453ea31d30], ldap[0x56453e7074a0]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_process_message] (0x4000):
[RID#37298] Message type: [LDAP_RES_SEARCH_RESULT]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_op_finished] (0x0400):
[RID#37298] Search result: Success(0), no errmsg set
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_op_destructor] (0x2000): [RID#37298]
Operation 194 finished
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_search_user_process] (0x0400):
[RID#37298] Search for users, returned 0 results.
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_search_user_process] (0x2000):
[RID#37298] Retrieved total 0 users
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_id_op_done] (0x4000): [RID#37298]
releasing operation connection
   *  (2024-04-11 20:29:02): [be[example.org]] [sysdb_search_user_by_uid] (0x0400):
[RID#37298] No such entry
   *  (2024-04-11 20:29:02): [be[example.org]] [sysdb_delete_user] (0x0400): [RID#37298]
Error: 2 (No such file or directory)
   *  (2024-04-11 20:29:02): [be[example.org]] [dp_req_done] (0x0400): [RID#37298] DP
Request [Account #37298]: Request handler finished [0]: Success
   *  (2024-04-11 20:29:02): [be[example.org]] [_dp_req_recv] (0x0400): [RID#37298] DP
Request [Account #37298]: Receiving request data.
   *  (2024-04-11 20:29:02): [be[example.org]] [dp_req_destructor] (0x0400): [RID#37298]
DP Request [Account #37298]: Request removed.
   *  (2024-04-11 20:29:02): [be[example.org]] [dp_req_destructor] (0x0400): [RID#37298]
Number of active DP request: 1
   *  (2024-04-11 20:29:02): [be[example.org]] [dp_req_reply_std] (0x1000): [RID#37298] DP
Request [Account #37298]: Returning [Success]: 0,0,Success
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_process_result] (0x2000): Trace:
sh[0x56453e6c4af0], connected[1], ops[0x56453ea31d30], ldap[0x56453e7074a0]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_process_result] (0x2000): Trace: end
of ldap_result list
   *  (2024-04-11 20:29:02): [be[example.org]] [dp_get_account_info_send] (0x0200): Got
request for [0x2][BE_REQ_GROUP][idnumber=61270005]
   *  (2024-04-11 20:29:02): [be[example.org]] [dp_attach_req] (0x0400): [RID#37300] DP
Request [Account #37300]: REQ_TRACE: New request. [sssd.nss CID #37614] Flags [0x0001].
   *  (2024-04-11 20:29:02): [be[example.org]] [dp_attach_req] (0x0400): [RID#37300]
Number of active DP request: 2
   *  (2024-04-11 20:29:02): [be[example.org]] [sss_domain_get_state] (0x1000):
[RID#37300] Domain example.org is Active
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_id_op_connect_step] (0x4000):
[RID#37300] reusing cached connection
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_groups_next_base] (0x0400):
[RID#37300] Searching for groups with base [dc=example,dc=org]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_print_server] (0x2000): [RID#37300]
Searching 216.239.32.58:636
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x0400):
[RID#37300] calling ldap_search_ext with
[(&(gidNumber=61270005)(objectClass=posixGroup)(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][dc=example,dc=org].
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [objectClass]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [cn]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [userPassword]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [gidNumber]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [member]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [modifyTimestamp]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [modifyTimestamp]
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_get_generic_ext_step] (0x2000):
[RID#37300] ldap_search_ext called, msgid = 196
   *  (2024-04-11 20:29:02): [be[example.org]] [sdap_op_add] (0x2000): [RID#37300] New
operation 196 timeout 6
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_process_result] (0x2000): Trace:
sh[0x56453e6c4af0], connected[1], ops[0x56453e720b60], ldap[0x56453e7074a0]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_process_message] (0x4000):
[RID#37299] Message type: [LDAP_RES_SEARCH_RESULT]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_op_finished] (0x0400):
[RID#37299] Search result: Success(0), no errmsg set
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_op_destructor] (0x2000): [RID#37299]
Operation 195 finished
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_groups_process] (0x0400):
[RID#37299] Search for groups, returned 0 results.
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_id_op_done] (0x4000): [RID#37299]
releasing operation connection
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_id_op_connect_step] (0x4000):
[RID#37299] reusing cached connection
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_search_user_next_base] (0x0400):
[RID#37299] Searching for users with base [dc=example,dc=org]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_print_server] (0x2000): [RID#37299]
Searching 216.239.32.58:636
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x0400):
[RID#37299] calling ldap_search_ext with
[(&(uidNumber=1473209301)(objectclass=posixAccount)(uid=*)(&(uidNumber=*)(!(uidNumber=0))))][dc=example,dc=org].
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [objectClass]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [uid]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [userPassword]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [uidNumber]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [gidNumber]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [gecos]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [homeDirectory]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [loginShell]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [krbPrincipalName]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [cn]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [memberOf]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [entryUUID]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [modifyTimestamp]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [modifyTimestamp]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [shadowLastChange]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [shadowMin]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [shadowMax]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [shadowWarning]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [shadowInactive]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [shadowExpire]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [shadowFlag]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [krbLastPwdChange]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [krbPasswordExpiration]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [pwdAttribute]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [authorizedService]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [accountExpires]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [userAccountControl]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [nsAccountLock]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [host]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [rhost]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [loginDisabled]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [loginExpirationTime]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [loginAllowedTimeMap]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [sshPublicKey]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [userCertificate;binary]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37299] Requesting attrs: [mail]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x2000):
[RID#37299] ldap_search_ext called, msgid = 197
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_op_add] (0x2000): [RID#37299] New
operation 197 timeout 6
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_process_result] (0x2000): Trace:
sh[0x56453e6c4af0], connected[1], ops[0x56453ea31d30], ldap[0x56453e7074a0]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_process_result] (0x2000): Trace: end
of ldap_result list
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_process_result] (0x2000): Trace:
sh[0x56453e6c4af0], connected[1], ops[0x56453ea31d30], ldap[0x56453e7074a0]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_process_message] (0x4000):
[RID#37300] Message type: [LDAP_RES_SEARCH_RESULT]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_op_finished] (0x0400):
[RID#37300] Search result: Success(0), no errmsg set
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_op_destructor] (0x2000): [RID#37300]
Operation 196 finished
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_groups_process] (0x0400):
[RID#37300] Search for groups, returned 0 results.
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_id_op_done] (0x4000): [RID#37300]
releasing operation connection
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_id_op_connect_step] (0x4000):
[RID#37300] reusing cached connection
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_search_user_next_base] (0x0400):
[RID#37300] Searching for users with base [dc=example,dc=org]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_print_server] (0x2000): [RID#37300]
Searching 216.239.32.58:636
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x0400):
[RID#37300] calling ldap_search_ext with
[(&(uidNumber=61270005)(objectclass=posixAccount)(uid=*)(&(uidNumber=*)(!(uidNumber=0))))][dc=example,dc=org].
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [objectClass]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [uid]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [userPassword]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [uidNumber]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [gidNumber]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [gecos]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [homeDirectory]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [loginShell]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [krbPrincipalName]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [cn]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [memberOf]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [entryUUID]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [modifyTimestamp]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [modifyTimestamp]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [shadowLastChange]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [shadowMin]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [shadowMax]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [shadowWarning]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [shadowInactive]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [shadowExpire]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [shadowFlag]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [krbLastPwdChange]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [krbPasswordExpiration]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [pwdAttribute]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [authorizedService]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [accountExpires]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [userAccountControl]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [nsAccountLock]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [host]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [rhost]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [loginDisabled]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [loginExpirationTime]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [loginAllowedTimeMap]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [sshPublicKey]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [userCertificate;binary]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37300] Requesting attrs: [mail]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x2000):
[RID#37300] ldap_search_ext called, msgid = 198
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_op_add] (0x2000): [RID#37300] New
operation 198 timeout 6
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_process_result] (0x2000): Trace:
sh[0x56453e6c4af0], connected[1], ops[0x56453e720b60], ldap[0x56453e7074a0]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_process_result] (0x2000): Trace: end
of ldap_result list
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_process_result] (0x2000): Trace:
sh[0x56453e6c4af0], connected[1], ops[0x56453e720b60], ldap[0x56453e7074a0]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_process_message] (0x4000):
[RID#37299] Message type: [LDAP_RES_SEARCH_RESULT]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_op_finished] (0x0400):
[RID#37299] Search result: Success(0), no errmsg set
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_op_destructor] (0x2000): [RID#37299]
Operation 197 finished
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_search_user_process] (0x0400):
[RID#37299] Search for users, returned 0 results.
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_search_user_process] (0x2000):
[RID#37299] Retrieved total 0 users
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_id_op_done] (0x4000): [RID#37299]
releasing operation connection
   *  (2024-04-11 20:29:03): [be[example.org]] [sysdb_search_user_by_uid] (0x0400):
[RID#37299] No such entry
   *  (2024-04-11 20:29:03): [be[example.org]] [sysdb_delete_user] (0x0400): [RID#37299]
Error: 2 (No such file or directory)
   *  (2024-04-11 20:29:03): [be[example.org]] [sysdb_search_group_by_id] (0x0400):
[RID#37299] No such entry
   *  (2024-04-11 20:29:03): [be[example.org]] [sysdb_delete_group] (0x0400): [RID#37299]
Error: 2 (No such file or directory)
   *  (2024-04-11 20:29:03): [be[example.org]] [dp_req_done] (0x0400): [RID#37299] DP
Request [Account #37299]: Request handler finished [0]: Success
   *  (2024-04-11 20:29:03): [be[example.org]] [_dp_req_recv] (0x0400): [RID#37299] DP
Request [Account #37299]: Receiving request data.
   *  (2024-04-11 20:29:03): [be[example.org]] [dp_req_destructor] (0x0400): [RID#37299]
DP Request [Account #37299]: Request removed.
   *  (2024-04-11 20:29:03): [be[example.org]] [dp_req_destructor] (0x0400): [RID#37299]
Number of active DP request: 1
   *  (2024-04-11 20:29:03): [be[example.org]] [dp_req_reply_std] (0x1000): [RID#37299] DP
Request [Account #37299]: Returning [Success]: 0,0,Success
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_process_result] (0x2000): Trace:
sh[0x56453e6c4af0], connected[1], ops[0x56453e720b60], ldap[0x56453e7074a0]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_process_result] (0x2000): Trace: end
of ldap_result list
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_process_result] (0x2000): Trace:
sh[0x56453e6c4af0], connected[1], ops[0x56453e720b60], ldap[0x56453e7074a0]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_process_message] (0x4000):
[RID#37300] Message type: [LDAP_RES_SEARCH_RESULT]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_op_finished] (0x0400):
[RID#37300] Search result: Success(0), no errmsg set
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_op_destructor] (0x2000): [RID#37300]
Operation 198 finished
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_search_user_process] (0x0400):
[RID#37300] Search for users, returned 0 results.
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_search_user_process] (0x2000):
[RID#37300] Retrieved total 0 users
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_id_op_done] (0x4000): [RID#37300]
releasing operation connection
   *  (2024-04-11 20:29:03): [be[example.org]] [sysdb_search_user_by_uid] (0x0400):
[RID#37300] No such entry
   *  (2024-04-11 20:29:03): [be[example.org]] [sysdb_delete_user] (0x0400): [RID#37300]
Error: 2 (No such file or directory)
   *  (2024-04-11 20:29:03): [be[example.org]] [sysdb_search_group_by_id] (0x0400):
[RID#37300] No such entry
   *  (2024-04-11 20:29:03): [be[example.org]] [sysdb_delete_group] (0x0400): [RID#37300]
Error: 2 (No such file or directory)
   *  (2024-04-11 20:29:03): [be[example.org]] [dp_req_done] (0x0400): [RID#37300] DP
Request [Account #37300]: Request handler finished [0]: Success
   *  (2024-04-11 20:29:03): [be[example.org]] [_dp_req_recv] (0x0400): [RID#37300] DP
Request [Account #37300]: Receiving request data.
   *  (2024-04-11 20:29:03): [be[example.org]] [dp_req_destructor] (0x0400): [RID#37300]
DP Request [Account #37300]: Request removed.
   *  (2024-04-11 20:29:03): [be[example.org]] [dp_req_destructor] (0x0400): [RID#37300]
Number of active DP request: 0
   *  (2024-04-11 20:29:03): [be[example.org]] [dp_req_reply_std] (0x1000): [RID#37300] DP
Request [Account #37300]: Returning [Success]: 0,0,Success
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_process_result] (0x2000): Trace:
sh[0x56453e6c4af0], connected[1], ops[(nil)], ldap[0x56453e7074a0]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_process_result] (0x2000): Trace: end
of ldap_result list
   *  (2024-04-11 20:29:03): [be[example.org]] [dp_get_account_info_send] (0x0200): Got
request for [0x1][BE_REQ_USER][idnumber=1220213587]
   *  (2024-04-11 20:29:03): [be[example.org]] [dp_attach_req] (0x0400): [RID#37301] DP
Request [Account #37301]: REQ_TRACE: New request. [sssd.nss CID #37615] Flags [0x0001].
   *  (2024-04-11 20:29:03): [be[example.org]] [dp_attach_req] (0x0400): [RID#37301]
Number of active DP request: 1
   *  (2024-04-11 20:29:03): [be[example.org]] [sss_domain_get_state] (0x1000):
[RID#37301] Domain example.org is Active
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_id_op_connect_step] (0x4000):
[RID#37301] reusing cached connection
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_search_user_next_base] (0x0400):
[RID#37301] Searching for users with base [dc=example,dc=org]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_print_server] (0x2000): [RID#37301]
Searching 216.239.32.58:636
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x0400):
[RID#37301] calling ldap_search_ext with
[(&(uidNumber=1220213587)(objectclass=posixAccount)(uid=*)(&(uidNumber=*)(!(uidNumber=0))))][dc=example,dc=org].
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [objectClass]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [uid]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [userPassword]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [uidNumber]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [gidNumber]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [gecos]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [homeDirectory]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [loginShell]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [krbPrincipalName]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [cn]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [memberOf]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [entryUUID]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [modifyTimestamp]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [modifyTimestamp]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [shadowLastChange]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [shadowMin]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [shadowMax]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [shadowWarning]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [shadowInactive]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [shadowExpire]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [shadowFlag]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [krbLastPwdChange]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [krbPasswordExpiration]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [pwdAttribute]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [authorizedService]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [accountExpires]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [userAccountControl]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [nsAccountLock]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [host]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [rhost]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [loginDisabled]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [loginExpirationTime]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [loginAllowedTimeMap]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [sshPublicKey]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [userCertificate;binary]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37301] Requesting attrs: [mail]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x2000):
[RID#37301] ldap_search_ext called, msgid = 199
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_op_add] (0x2000): [RID#37301] New
operation 199 timeout 6
   *  (2024-04-11 20:29:03): [be[example.org]] [dp_get_account_info_send] (0x0200): Got
request for [0x1][BE_REQ_USER][idnumber=1220213587]
   *  (2024-04-11 20:29:03): [be[example.org]] [dp_attach_req] (0x0400): [RID#37302] DP
Request [Account #37302]: REQ_TRACE: New request. [sssd.nss CID #37616] Flags [0x0001].
   *  (2024-04-11 20:29:03): [be[example.org]] [dp_attach_req] (0x0400): [RID#37302]
Number of active DP request: 2
   *  (2024-04-11 20:29:03): [be[example.org]] [sss_domain_get_state] (0x1000):
[RID#37302] Domain example.org is Active
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_id_op_connect_step] (0x4000):
[RID#37302] reusing cached connection
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_search_user_next_base] (0x0400):
[RID#37302] Searching for users with base [dc=example,dc=org]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_print_server] (0x2000): [RID#37302]
Searching 216.239.32.58:636
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x0400):
[RID#37302] calling ldap_search_ext with
[(&(uidNumber=1220213587)(objectclass=posixAccount)(uid=*)(&(uidNumber=*)(!(uidNumber=0))))][dc=example,dc=org].
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [objectClass]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [uid]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [userPassword]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [uidNumber]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [gidNumber]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [gecos]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [homeDirectory]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [loginShell]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [krbPrincipalName]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [cn]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [memberOf]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [entryUUID]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [modifyTimestamp]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [modifyTimestamp]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [shadowLastChange]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [shadowMin]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [shadowMax]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [shadowWarning]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [shadowInactive]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [shadowExpire]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [shadowFlag]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [krbLastPwdChange]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [krbPasswordExpiration]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [pwdAttribute]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [authorizedService]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [accountExpires]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [userAccountControl]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [nsAccountLock]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [host]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [rhost]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [loginDisabled]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [loginExpirationTime]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [loginAllowedTimeMap]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [sshPublicKey]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [userCertificate;binary]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x1000):
[RID#37302] Requesting attrs: [mail]
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_get_generic_ext_step] (0x2000):
[RID#37302] ldap_search_ext called, msgid = 200
   *  (2024-04-11 20:29:03): [be[example.org]] [sdap_op_add] (0x2000): [RID#37302] New
operation 200 timeout 6
   *  (2024-04-11 20:29:04): [be[example.org]] [sdap_process_result] (0x2000): Trace:
sh[0x56453e6c4af0], connected[1], ops[0x56453ea50620], ldap[0x56453e7074a0]
   *  (2024-04-11 20:29:04): [be[example.org]] [sdap_process_message] (0x4000):
[RID#37301] Message type: [LDAP_RES_SEARCH_RESULT]
   *  (2024-04-11 20:29:04): [be[example.org]] [sdap_get_generic_op_finished] (0x0400):
[RID#37301] Search result: Success(0), no errmsg set
   *  (2024-04-11 20:29:04): [be[example.org]] [sdap_op_destructor] (0x2000): [RID#37301]
Operation 199 finished
   *  (2024-04-11 20:29:04): [be[example.org]] [sdap_search_user_process] (0x0400):
[RID#37301] Search for users, returned 0 results.
   *  (2024-04-11 20:29:04): [be[example.org]] [sdap_search_user_process] (0x2000):
[RID#37301] Retrieved total 0 users
   *  (2024-04-11 20:29:04): [be[example.org]] [sdap_id_op_done] (0x4000): [RID#37301]
releasing operation connection
   *  (2024-04-11 20:29:04): [be[example.org]] [sysdb_search_user_by_uid] (0x0400):
[RID#37301] No such entry
   *  (2024-04-11 20:29:04): [be[example.org]] [sysdb_delete_user] (0x0400): [RID#37301]
Error: 2 (No such file or directory)
   *  (2024-04-11 20:29:04): [be[example.org]] [dp_req_done] (0x0400): [RID#37301] DP
Request [Account #37301]: Request handler finished [0]: Success
   *  (2024-04-11 20:29:04): [be[example.org]] [_dp_req_recv] (0x0400): [RID#37301] DP
Request [Account #37301]: Receiving request data.
   *  (2024-04-11 20:29:04): [be[example.org]] [dp_req_destructor] (0x0400): [RID#37301]
DP Request [Account #37301]: Request removed.
   *  (2024-04-11 20:29:04): [be[example.org]] [dp_req_destructor] (0x0400): [RID#37301]
Number of active DP request: 1
   *  (2024-04-11 20:29:04): [be[example.org]] [dp_req_reply_std] (0x1000): [RID#37301] DP
Request [Account #37301]: Returning [Success]: 0,0,Success
   *  (2024-04-11 20:29:04): [be[example.org]] [sdap_process_result] (0x2000): Trace:
sh[0x56453e6c4af0], connected[1], ops[0x56453ea50620], ldap[0x56453e7074a0]
   *  (2024-04-11 20:29:04): [be[example.org]] [sdap_process_result] (0x2000): Trace: end
of ldap_result list
   *  (2024-04-11 20:29:04): [be[example.org]] [sdap_process_result] (0x2000): Trace:
sh[0x56453e6c4af0], connected[1], ops[0x56453ea50620], ldap[0x56453e7074a0]
   *  (2024-04-11 20:29:04): [be[example.org]] [sdap_process_message] (0x4000):
[RID#37302] Message type: [LDAP_RES_SEARCH_RESULT]
   *  (2024-04-11 20:29:04): [be[example.org]] [sdap_get_generic_op_finished] (0x0400):
[RID#37302] Search result: Success(0), no errmsg set
   *  (2024-04-11 20:29:04): [be[example.org]] [sdap_op_destructor] (0x2000): [RID#37302]
Operation 200 finished
   *  (2024-04-11 20:29:04): [be[example.org]] [sdap_search_user_process] (0x0400):
[RID#37302] Search for users, returned 0 results.
   *  (2024-04-11 20:29:04): [be[example.org]] [sdap_search_user_process] (0x2000):
[RID#37302] Retrieved total 0 users
   *  (2024-04-11 20:29:04): [be[example.org]] [sdap_id_op_done] (0x4000): [RID#37302]
releasing operation connection
   *  (2024-04-11 20:29:04): [be[example.org]] [sysdb_search_user_by_uid] (0x0400):
[RID#37302] No such entry
   *  (2024-04-11 20:29:04): [be[example.org]] [sysdb_delete_user] (0x0400): [RID#37302]
Error: 2 (No such file or directory)
   *  (2024-04-11 20:29:04): [be[example.org]] [dp_req_done] (0x0400): [RID#37302] DP
Request [Account #37302]: Request handler finished [0]: Success
   *  (2024-04-11 20:29:04): [be[example.org]] [_dp_req_recv] (0x0400): [RID#37302] DP
Request [Account #37302]: Receiving request data.
   *  (2024-04-11 20:29:04): [be[example.org]] [dp_req_destructor] (0x0400): [RID#37302]
DP Request [Account #37302]: Request removed.
   *  (2024-04-11 20:29:04): [be[example.org]] [dp_req_destructor] (0x0400): [RID#37302]
Number of active DP request: 0
   *  (2024-04-11 20:29:04): [be[example.org]] [dp_req_reply_std] (0x1000): [RID#37302] DP
Request [Account #37302]: Returning [Success]: 0,0,Success
   *  (2024-04-11 20:29:04): [be[example.org]] [sdap_process_result] (0x2000): Trace:
sh[0x56453e6c4af0], connected[1], ops[(nil)], ldap[0x56453e7074a0]
   *  (2024-04-11 20:29:04): [be[example.org]] [sdap_process_result] (0x2000): Trace: end
of ldap_result list
   *  (2024-04-11 20:29:19): [be[example.org]] [sdap_process_result] (0x2000): Trace:
sh[0x56453e6c4af0], connected[1], ops[(nil)], ldap[0x56453e7074a0]
   *  (2024-04-11 20:29:19): [be[example.org]] [sdap_ldap_connect_callback_del] (0x4000):
Closing LDAP connection with fd [22].
   *  (2024-04-11 20:29:19): [be[example.org]] [sdap_process_result] (0x0040): ldap_result
error: [Can't contact LDAP server]
********************** BACKTRACE DUMP ENDS HERE *********************************

[Gary Miguel]

Hmm is it possible that caching of users is not working at all?
I tried searching the cache for users and didn't find any. Not sure if I'm doing
it wrong.

ldbsearch -H /var/lib/sss/db/cache_example.org.ldb '(objectClass=user)'
# returned 0 records
# 0 entries
# 0 referrals