sssd cache not working, cant login when connection to AD is disabled

Question

sssd cache not working, cant login when connection to AD is disabled

Opened this issue 3 months ago · 10 comments

Hello,

I have a Host, which is able to use my AD to authenticate the users.

But when I use a vpn to a customer system, the connection to the AD is no longer available, and after some inactivity, the screensaver activates and prompts me for a password.

The problem now is, that my sssd_cache wont let me authenticate on the Host, and I need to restart my Host.

in the /etc/sssd/sssd.conf the option "cache_credentials" is enabled.

I saw through the logs, and at some line it told me "offline credentials expiration is [0] days"

where can I change this value, or does [0] mean forever?

(2024-07-17 14:24:55): [be[domain.lan]] [sysdb_cache_auth] (0x4000): [RID#23] Offline credentials expiration is [0] days.
(2024-07-17 14:24:55): [be[domain.lan]] [check_failed_login_attempts] (0x4000): [RID#23] Failed login attempts [0], allowed
failed login attempts [0], failed login delay [5].
(2024-07-17 14:24:55): [be[domain.lan]] [sysdb_cache_auth] (0x0100): [RID#23] Cached credentials not available.
(2024-07-17 14:24:55): [be[domain.lan]] [krb5_auth_cache_creds] (0x0020): [RID#23] Offline authentication failed

Thanks in Advance

Answer 1 · 2024-07-17T12:48:14.000Z

I saw through the logs, and at some line it told me "offline credentials expiration is [0] days"

where can I change this value?

This is offline_credentials_expiration option (see 'man sssd.conf') but this is not a problem you face ('0' means 'no limit')

[be[domain.lan]] [sysdb_cache_auth] (0x0100): [RID#23] Cached credentials not available.

This ^^ is the problem. Looks like password hash isn't cached.
Inspect your user object (using 'ldbsearch') in the SSSD cache (/var/lib/sss/db/...) - see if cachedPassword attribute is set.

Answer 2 · 2024-07-17T12:50:03.000Z

thanks I will take a look

Answer 3 · 2024-07-17T13:02:59.000Z

there is no "cachedPassword" line in the ldb files, how can I change thís, so the password will be cached again?

Answer 4 · 2024-07-17T13:32:28.000Z

If cache_credentials = true (in the proper domain section) then it should be cached during successful online login.

Answer 5 · 2024-07-17T13:53:32.000Z

ad_domain = domain.lan
auto_private_groups = True
cache_credentials = True
default_shell = /bin/bash
dyndns_update = False
enumerate = True
fallback_homedir = /home/%u
id_provider = ad
krb5_realm = DOMAIN.LAN
krb5_store_password_if_offline = True
ldap_access_order = expire
ldap_account_expire_policy = ad
ldap_force_upper_case_realm = True
ldap_group_gid_number = gidNumber
ldap_group_name = sAMAccountName
ldap_group_object_class = group
ldap_id_mapping = False
ldap_user_fullname = displayName
ldap_user_gecos = displayName
ldap_user_home_directory = unixHomeDirectory
ldap_user_name = sAMAccountName
ldap_user_object_class = user
ldap_user_principal = userPrincipalName
ldap_user_shell = loginShell
override_homedir = /home/%u
realmd_tags = manages-system joined-with-adcli
use_fully_qualified_names = False

Yes it is enabled, maybe any other parameters, or config files they could hinder that?

edit: selinux is disabled, apparmor is active

Answer 6 · 2024-07-17T14:02:18.000Z

Check logs during online login: is there anything like "password not available, offline auth may not work" or "Failed to cache password, offline auth may not work" in the log?

Answer 7 · 2024-07-17T14:19:25.000Z

no such things found in my sssd logfile
I activated debug level 9
after logging in with a AD user, I captured all logs
********************** PREVIOUS MESSAGE WAS TRIGGERED BY THE FOLLOWING BACKTRACE:

(2024-07-17 16:08:10): [be[domain.lan]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): Additional References: ldap://domain.lan/CN=Configuration,DC=domain,DC=lan
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b301e50], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_RESULT]
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_get_generic_op_finished] (0x2000): Total count [0]
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_op_destructor] (0x2000): Operation 20 finished
(2024-07-17 16:08:10): [be[domain.lan]] [generic_ext_search_handler] (0x4000): Request included referrals which were ignored.
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_get_iphost_process] (0x0400): Search for IP hosts returned 0 results.
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_id_op_done] (0x4000): releasing operation connection
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_id_op_connect_step] (0x4000): reusing cached connection
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_get_ipnetwork_next_base] (0x0400): Searching for IP network with base [DC=domain,DC=lan]
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_print_server] (0x2000): Searching 192.168.135.3:389
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectclass=ipNetwork)(cn=)(ipNetworkNumber=))][DC=domain,DC=lan].
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass]
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn]
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipNetworkNumber]
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uSNChanged]
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 22
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_op_add] (0x2000): New operation 22 timeout 6
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b2645d0], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: end of ldap_result list
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b2645d0], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): Additional References: ldap://ForestDnsZones.domain.lan/DC=ForestDnsZones,DC=domain,DC=lan
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b2645d0], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): Additional References: ldap://DomainDnsZones.domain.lan/DC=DomainDnsZones,DC=domain,DC=lan
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b2645d0], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): Additional References: ldap://domain.lan/CN=Configuration,DC=domain,DC=lan
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b2645d0], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_RESULT]
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_get_generic_op_finished] (0x2000): Total count [0]
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_op_destructor] (0x2000): Operation 21 finished
(2024-07-17 16:08:10): [be[domain.lan]] [generic_ext_search_handler] (0x4000): Request included referrals which were ignored.
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_get_services_process] (0x0400): Search for services, returned 0 results.
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_id_op_done] (0x4000): releasing operation connection
(2024-07-17 16:08:10): [be[domain.lan]] [cleanup_users] (0x4000): Cache expiration is set to 0 days
(2024-07-17 16:08:10): [be[domain.lan]] [sysdb_cache_search_users] (0x2000): Search users with filter: (&(objectCategory=user)(&(!(dataExpireTimestamp=0))(dataExpireTimestamp<=1721225290)(!(lastLogin=))))
(2024-07-17 16:08:10): [be[domain.lan]] [sysdb_cache_search_users] (0x2000): No such entry
(2024-07-17 16:08:10): [be[domain.lan]] [cleanup_users] (0x0200): Found 0 expired user entries!
(2024-07-17 16:08:10): [be[domain.lan]] [sysdb_cache_search_groups] (0x2000): Search groups with filter: (&(objectCategory=group)(&(!(dataExpireTimestamp=0))(dataExpireTimestamp<=1721225290)))
(2024-07-17 16:08:10): [be[domain.lan]] [cleanup_groups] (0x0200): Found 13 expired group entries!
(2024-07-17 16:08:10): [be[domain.lan]] [cleanup_groups] (0x1000): Searching with: (&(objectCategory=user)(|(memberOf=name=jira-software-users@domain.lan,cn=groups,cn=domain.lan,cn=sysdb)(gidNumber=0)))
(2024-07-17 16:08:10): [be[domain.lan]] [cleanup_groups] (0x1000): Searching with: (&(objectCategory=user)(|(memberOf=name=jira-servicedesk@domain.lan,cn=groups,cn=domain.lan,cn=sysdb)(gidNumber=0)))
(2024-07-17 16:08:10): [be[domain.lan]] [cleanup_groups] (0x1000): Searching with: (&(objectCategory=user)(|(memberOf=name=grp_rocketchat@domain.lan,cn=groups,cn=domain.lan,cn=sysdb)(gidNumber=0)))
(2024-07-17 16:08:10): [be[domain.lan]] [cleanup_groups] (0x1000): Searching with: (&(objectCategory=user)(|(memberOf=name=grp_teamviewer@domain.lan,cn=groups,cn=domain.lan,cn=sysdb)(gidNumber=0)))
(2024-07-17 16:08:10): [be[domain.lan]] [cleanup_groups] (0x1000): Searching with: (&(objectCategory=user)(|(memberOf=name=Domain[20Users@domain.lan](mailto:20Users@domain.lan),cn=groups,cn=domain.lan,cn=sysdb)(gidNumber=0)))
(2024-07-17 16:08:10): [be[domain.lan]] [cleanup_groups] (0x1000): Searching with: (&(objectCategory=user)(|(memberOf=name=grp_vmware@domain.lan,cn=groups,cn=domain.lan,cn=sysdb)(gidNumber=0)))
(2024-07-17 16:08:10): [be[domain.lan]] [cleanup_groups] (0x1000): Searching with: (&(objectCategory=user)(|(memberOf=name=grp_mail@domain.lan,cn=groups,cn=domain.lan,cn=sysdb)(gidNumber=0)))
(2024-07-17 16:08:10): [be[domain.lan]] [cleanup_groups] (0x1000): Searching with: (&(objectCategory=user)(|(memberOf=name=grp_wlan@domain.lan,cn=groups,cn=domain.lan,cn=sysdb)(gidNumber=0)))
(2024-07-17 16:08:10): [be[domain.lan]] [cleanup_groups] (0x1000): Searching with: (&(objectCategory=user)(|(memberOf=name=grp_awx@domain.lan,cn=groups,cn=domain.lan,cn=sysdb)(gidNumber=0)))
(2024-07-17 16:08:10): [be[domain.lan]] [cleanup_groups] (0x1000): Searching with: (&(objectCategory=user)(|(memberOf=name=grp_git@domain.lan,cn=groups,cn=domain.lan,cn=sysdb)(gidNumber=0)))
(2024-07-17 16:08:10): [be[domain.lan]] [cleanup_groups] (0x1000): Searching with: (&(objectCategory=user)(|(memberOf=name=grp_pbx@domain.lan,cn=groups,cn=domain.lan,cn=sysdb)(gidNumber=0)))
(2024-07-17 16:08:10): [be[domain.lan]] [cleanup_groups] (0x1000): Searching with: (&(objectCategory=user)(|(memberOf=name=grp_pwm@domain.lan,cn=groups,cn=domain.lan,cn=sysdb)(gidNumber=0)))
(2024-07-17 16:08:10): [be[domain.lan]] [cleanup_groups] (0x1000): Searching with: (&(objectCategory=user)(|(memberOf=name=grp_vpn@domain.lan,cn=groups,cn=domain.lan,cn=sysdb)(gidNumber=0)))
(2024-07-17 16:08:10): [be[domain.lan]] [sysdb_cache_search_groups] (0x2000): Search groups with filter: (&(objectCategory=group)(name=))
(2024-07-17 16:08:10): [be[domain.lan]] [ad_group_extra_members] (0x0400): Group grp_git@domain.lan has no original members
(2024-07-17 16:08:10): [be[domain.lan]] [ad_enum_cross_dom_members] (0x2000): No extra members
(2024-07-17 16:08:10): [be[domain.lan]] [ad_group_extra_members] (0x0400): Group grp_vmware@domain.lan has no original members
(2024-07-17 16:08:10): [be[domain.lan]] [ad_enum_cross_dom_members] (0x2000): No extra members
(2024-07-17 16:08:10): [be[domain.lan]] [ad_group_extra_members] (0x0400): Group Domain Users@domain.lan has no original members
(2024-07-17 16:08:10): [be[domain.lan]] [ad_enum_cross_dom_members] (0x2000): No extra members
(2024-07-17 16:08:10): [be[domain.lan]] [ad_enum_cross_dom_members] (0x2000): No extra members
(2024-07-17 16:08:10): [be[domain.lan]] [ad_group_extra_members] (0x0400): Group grp_rocketchat@domain.lan has no original members
(2024-07-17 16:08:10): [be[domain.lan]] [ad_enum_cross_dom_members] (0x2000): No extra members
(2024-07-17 16:08:10): [be[domain.lan]] [ad_enum_cross_dom_members] (0x2000): No extra members
(2024-07-17 16:08:10): [be[domain.lan]] [ad_group_extra_members] (0x0400): Group grp_mail@domain.lan has no original members
(2024-07-17 16:08:10): [be[domain.lan]] [ad_enum_cross_dom_members] (0x2000): No extra members
(2024-07-17 16:08:10): [be[domain.lan]] [ad_group_extra_members] (0x0400): Group grp_vpn@domain.lan has no original members
(2024-07-17 16:08:10): [be[domain.lan]] [ad_enum_cross_dom_members] (0x2000): No extra members
(2024-07-17 16:08:10): [be[domain.lan]] [ad_group_extra_members] (0x0400): Group grp_wlan@domain.lan has no original members
(2024-07-17 16:08:10): [be[domain.lan]] [ad_enum_cross_dom_members] (0x2000): No extra members
(2024-07-17 16:08:10): [be[domain.lan]] [ad_group_extra_members] (0x0400): Group grp_awx@domain.lan has no original members
(2024-07-17 16:08:10): [be[domain.lan]] [ad_enum_cross_dom_members] (0x2000): No extra members
(2024-07-17 16:08:10): [be[domain.lan]] [ad_group_extra_members] (0x0400): Group grp_pwm@domain.lan has no original members
(2024-07-17 16:08:10): [be[domain.lan]] [ad_enum_cross_dom_members] (0x2000): No extra members
(2024-07-17 16:08:10): [be[domain.lan]] [ad_group_extra_members] (0x0400): Group jira-software-users@domain.lan has no original members
(2024-07-17 16:08:10): [be[domain.lan]] [ad_enum_cross_dom_members] (0x2000): No extra members
(2024-07-17 16:08:10): [be[domain.lan]] [ad_group_extra_members] (0x0400): Group jira-servicedesk@domain.lan has no original members
(2024-07-17 16:08:10): [be[domain.lan]] [ad_enum_cross_dom_members] (0x2000): No extra members
(2024-07-17 16:08:10): [be[domain.lan]] [ad_group_extra_members] (0x0400): Group grp_pbx@domain.lan has no original members
(2024-07-17 16:08:10): [be[domain.lan]] [ad_enum_cross_dom_members] (0x2000): No extra members
(2024-07-17 16:08:10): [be[domain.lan]] [ad_enum_cross_dom_members] (0x2000): No extra members
(2024-07-17 16:08:10): [be[domain.lan]] [ad_group_extra_members] (0x0400): Group grp_teamviewer@domain.lan has no original members
(2024-07-17 16:08:10): [be[domain.lan]] [ad_enum_cross_dom_members] (0x2000): No extra members
(2024-07-17 16:08:10): [be[domain.lan]] [ad_enum_cross_dom_members] (0x2000): No extra members
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_id_op_destroy] (0x4000): releasing operation connection
(2024-07-17 16:08:10): [be[domain.lan]] [be_ptask_done] (0x0400): Task [Enumeration [id] of domain.lan]: finished successfully
(2024-07-17 16:08:10): [be[domain.lan]] [be_ptask_schedule] (0x0400): Task [Enumeration [id] of domain.lan]: scheduling task 300 seconds from last execution time [1721225590]
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b2645d0], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: end of ldap_result list
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b2645d0], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): Additional References: ldap://ForestDnsZones.domain.lan/DC=ForestDnsZones,DC=domain,DC=lan
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b2645d0], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): Additional References: ldap://DomainDnsZones.domain.lan/DC=DomainDnsZones,DC=domain,DC=lan
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b2645d0], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): Additional References: ldap://domain.lan/CN=Configuration,DC=domain,DC=lan
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b2645d0], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_RESULT]
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_get_generic_op_finished] (0x2000): Total count [0]
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_op_destructor] (0x2000): Operation 22 finished
(2024-07-17 16:08:10): [be[domain.lan]] [generic_ext_search_handler] (0x4000): Request included referrals which were ignored.
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_get_ipnetwork_process] (0x0400): Search for IP networks returned 0 results.
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_id_op_done] (0x4000): releasing operation connection
(2024-07-17 16:08:10): [be[domain.lan]] [sysdb_search_hosts] (0x0400): Searching hosts with subfilter [(&(!(dataExpireTimestamp=0))(dataExpireTimestamp<=1721225290))] in domain [domain.lan]
(2024-07-17 16:08:10): [be[domain.lan]] [sysdb_search_hosts] (0x2000): Searching hosts with filter [(&(objectclass=host)(&(!(dataExpireTimestamp=0))(dataExpireTimestamp<=1721225290)))] in domain [domain.lan]
(2024-07-17 16:08:10): [be[domain.lan]] [sysdb_search_hosts] (0x2000): No such entry
(2024-07-17 16:08:10): [be[domain.lan]] [cleanup_iphosts] (0x0200): Found 0 expired ip host entries!
(2024-07-17 16:08:10): [be[domain.lan]] [sysdb_search_ipnetworks] (0x0400): Searching networks with subfilter [(&(!(dataExpireTimestamp=0))(dataExpireTimestamp<=1721225290))] in domain [domain.lan]
(2024-07-17 16:08:10): [be[domain.lan]] [sysdb_search_ipnetworks] (0x2000): Searching networks with filter [(&(objectclass=network)(&(!(dataExpireTimestamp=0))(dataExpireTimestamp<=1721225290)))] in domain [domain.lan]
(2024-07-17 16:08:10): [be[domain.lan]] [sysdb_search_ipnetworks] (0x2000): No such entry
(2024-07-17 16:08:10): [be[domain.lan]] [cleanup_ipnetworks] (0x0200): Found 0 expired IP network entries!
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_id_op_destroy] (0x4000): releasing operation connection
(2024-07-17 16:08:10): [be[domain.lan]] [be_ptask_done] (0x0400): Task [Enumeration [resolver] of domain.lan]: finished successfully
(2024-07-17 16:08:10): [be[domain.lan]] [be_ptask_schedule] (0x0400): Task [Enumeration [resolver] of domain.lan]: scheduling task 300 seconds from last execution time [1721225590]
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[(nil)], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:10): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: end of ldap_result list
(2024-07-17 16:08:11): [be[domain.lan]] [dp_get_account_info_send] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=201]
(2024-07-17 16:08:11): [be[domain.lan]] [dp_attach_req] (0x0400): [RID#25] DP Request [Account https://github.com//pull/25]: REQ_TRACE: New request. [sssd.nss CID https://github.com//pull/20] Flags [0x0001].
(2024-07-17 16:08:11): [be[domain.lan]] [dp_attach_req] (0x0400): [RID#25] Number of active DP request: 1
(2024-07-17 16:08:11): [be[domain.lan]] [sss_domain_get_state] (0x1000): [RID#25] Domain domain.lan is Active
(2024-07-17 16:08:11): [be[domain.lan]] [sss_domain_get_state] (0x1000): [RID#25] Domain domain.lan is Active
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_id_op_connect_step] (0x4000): [RID#25] reusing cached connection
(2024-07-17 16:08:11): [be[domain.lan]] [get_ldap_conn_from_sdom_pvt] (0x4000): [RID#25] Returning LDAP connection for user lookup.
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_id_op_connect_step] (0x4000): [RID#25] reusing cached connection
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_groups_next_base] (0x0400): [RID#25] Searching for groups with base [DC=domain,DC=lan]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_print_server] (0x2000): [RID#25] Searching 192.168.135.3:389
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x0400): [RID#25] calling ldap_search_ext with [(&(gidNumber=201)(objectClass=group)(sAMAccountName=)(&(gidNumber=)(!(gidNumber=0))))][DC=domain,DC=lan].
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#25] Requesting attrs: [objectClass]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#25] Requesting attrs: [sAMAccountName]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#25] Requesting attrs: [gidNumber]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#25] Requesting attrs: [member]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#25] Requesting attrs: [objectGUID]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#25] Requesting attrs: [objectSID]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#25] Requesting attrs: [whenChanged]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#25] Requesting attrs: [uSNChanged]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#25] Requesting attrs: [groupType]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x2000): [RID#25] ldap_search_ext called, msgid = 23
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_op_add] (0x2000): [RID#25] New operation 23 timeout 6
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd2d0], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#25] Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): [RID#25] Additional References: ldap://ForestDnsZones.domain.lan/DC=ForestDnsZones,DC=domain,DC=lan
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd2d0], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#25] Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): [RID#25] Additional References: ldap://DomainDnsZones.domain.lan/DC=DomainDnsZones,DC=domain,DC=lan
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd2d0], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#25] Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): [RID#25] Additional References: ldap://domain.lan/CN=Configuration,DC=domain,DC=lan
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd2d0], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#25] Message type: [LDAP_RES_SEARCH_RESULT]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_op_finished] (0x0400): [RID#25] Search result: Success(0), no errmsg set
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_op_destructor] (0x2000): [RID#25] Operation 23 finished
(2024-07-17 16:08:11): [be[domain.lan]] [generic_ext_search_handler] (0x4000): [RID#25] Request included referrals which were ignored.
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_groups_process] (0x0400): [RID#25] Search for groups, returned 0 results.
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_id_op_destroy] (0x4000): [RID#25] releasing operation connection
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_id_op_done] (0x4000): [RID#25] releasing operation connection
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_id_op_connect_step] (0x4000): [RID#25] reusing cached connection
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_search_user_next_base] (0x0400): [RID#25] Searching for users with base [DC=domain,DC=lan]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_print_server] (0x2000): [RID#25] Searching 192.168.135.3:389
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x0400): [RID#25] calling ldap_search_ext with [(&(uidNumber=201)(objectclass=user)(sAMAccountName=)(&(uidNumber=)(!(uidNumber=0))))][DC=domain,DC=lan].
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#25] Requesting attrs: [objectClass]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#25] Requesting attrs: [sAMAccountName]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#25] Requesting attrs: [unixUserPassword]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#25] Requesting attrs: [uidNumber]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#25] Requesting attrs: [gidNumber]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#25] Requesting attrs: [displayName]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#25] Requesting attrs: [unixHomeDirectory]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#25] Requesting attrs: [loginShell]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#25] Requesting attrs: [userPrincipalName]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#25] Requesting attrs: [displayName]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#25] Requesting attrs: [memberOf]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#25] Requesting attrs: [objectGUID]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#25] Requesting attrs: [objectSID]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#25] Requesting attrs: [primaryGroupID]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#25] Requesting attrs: [whenChanged]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#25] Requesting attrs: [uSNChanged]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#25] Requesting attrs: [accountExpires]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#25] Requesting attrs: [userAccountControl]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#25] Requesting attrs: [userCertificate;binary]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#25] Requesting attrs: [mail]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x2000): [RID#25] ldap_search_ext called, msgid = 24
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_op_add] (0x2000): [RID#25] New operation 24 timeout 6
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd2d0], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: end of ldap_result list
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd2d0], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#25] Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): [RID#25] Additional References: ldap://ForestDnsZones.domain.lan/DC=ForestDnsZones,DC=domain,DC=lan
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd2d0], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#25] Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): [RID#25] Additional References: ldap://DomainDnsZones.domain.lan/DC=DomainDnsZones,DC=domain,DC=lan
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd2d0], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#25] Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): [RID#25] Additional References: ldap://domain.lan/CN=Configuration,DC=domain,DC=lan
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd2d0], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#25] Message type: [LDAP_RES_SEARCH_RESULT]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_op_finished] (0x0400): [RID#25] Search result: Success(0), no errmsg set
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_op_destructor] (0x2000): [RID#25] Operation 24 finished
(2024-07-17 16:08:11): [be[domain.lan]] [generic_ext_search_handler] (0x4000): [RID#25] Request included referrals which were ignored.
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_search_user_process] (0x0400): [RID#25] Search for users, returned 0 results.
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_search_user_process] (0x2000): [RID#25] Retrieved total 0 users
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_id_op_done] (0x4000): [RID#25] releasing operation connection
(2024-07-17 16:08:11): [be[domain.lan]] [sysdb_search_user_by_uid] (0x0400): [RID#25] No such entry
(2024-07-17 16:08:11): [be[domain.lan]] [sysdb_delete_user] (0x0400): [RID#25] Error: 2 (No such file or directory)
(2024-07-17 16:08:11): [be[domain.lan]] [sysdb_search_group_by_id] (0x0400): [RID#25] No such entry
(2024-07-17 16:08:11): [be[domain.lan]] [sysdb_delete_group] (0x0400): [RID#25] Error: 2 (No such file or directory)
(2024-07-17 16:08:11): [be[domain.lan]] [dp_req_done] (0x0400): [RID#25] DP Request [Account https://github.com//pull/25]: Request handler finished [0]: Success
(2024-07-17 16:08:11): [be[domain.lan]] [_dp_req_recv] (0x0400): [RID#25] DP Request [Account https://github.com//pull/25]: Receiving request data.
(2024-07-17 16:08:11): [be[domain.lan]] [dp_req_destructor] (0x0400): [RID#25] DP Request [Account https://github.com//pull/25]: Request removed.
(2024-07-17 16:08:11): [be[domain.lan]] [dp_req_destructor] (0x0400): [RID#25] Number of active DP request: 0
(2024-07-17 16:08:11): [be[domain.lan]] [dp_req_reply_std] (0x1000): [RID#25] DP Request [Account https://github.com//pull/25]: Returning [Success]: 0,0,Success
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[(nil)], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: end of ldap_result list
(2024-07-17 16:08:11): [be[domain.lan]] [dp_get_account_info_send] (0x0200): Got request for [0x3][BE_REQ_INITGROUPS][name=geoclue@domain.lan]
(2024-07-17 16:08:11): [be[domain.lan]] [sss_domain_get_state] (0x1000): Domain domain.lan is Active
(2024-07-17 16:08:11): [be[domain.lan]] [dp_attach_req] (0x0400): [RID#26] DP Request [Initgroups https://github.com//pull/26]: REQ_TRACE: New request. [sssd.nss CID https://github.com//pull/21] Flags [0x0001].
(2024-07-17 16:08:11): [be[domain.lan]] [dp_attach_req] (0x0400): [RID#26] Number of active DP request: 1
(2024-07-17 16:08:11): [be[domain.lan]] [sss_domain_get_state] (0x1000): [RID#26] Domain domain.lan is Active
(2024-07-17 16:08:11): [be[domain.lan]] [sss_domain_get_state] (0x1000): [RID#26] Domain domain.lan is Active
(2024-07-17 16:08:11): [be[domain.lan]] [sysdb_search_by_name] (0x0400): [RID#26] No such entry
(2024-07-17 16:08:11): [be[domain.lan]] [find_user_entry] (0x4000): [RID#26] No user found with filter [geoclue@domain.lan].
(2024-07-17 16:08:11): [be[domain.lan]] [check_if_pac_is_available] (0x0200): [RID#26] find_user_entry didn't find user entry.
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_id_op_connect_step] (0x4000): [RID#26] reusing cached connection
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_initgr_send] (0x4000): [RID#26] Retrieving info for initgroups call
(2024-07-17 16:08:11): [be[domain.lan]] [get_ldap_conn_from_sdom_pvt] (0x4000): [RID#26] Returning LDAP connection for user lookup.
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_id_op_connect_step] (0x4000): [RID#26] reusing cached connection
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_initgr_next_base] (0x0400): [RID#26] Searching for users with base [DC=domain,DC=lan]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_print_server] (0x2000): [RID#26] Searching 192.168.135.3:389
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x0400): [RID#26] calling ldap_search_ext with [(&(sAMAccountName=geoclue)(objectclass=user)(&(uidNumber=)(!(uidNumber=0))))][DC=domain,DC=lan].
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#26] Requesting attrs: [objectClass]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#26] Requesting attrs: [sAMAccountName]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#26] Requesting attrs: [unixUserPassword]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#26] Requesting attrs: [uidNumber]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#26] Requesting attrs: [gidNumber]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#26] Requesting attrs: [displayName]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#26] Requesting attrs: [unixHomeDirectory]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#26] Requesting attrs: [loginShell]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#26] Requesting attrs: [userPrincipalName]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#26] Requesting attrs: [displayName]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#26] Requesting attrs: [memberOf]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#26] Requesting attrs: [objectGUID]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#26] Requesting attrs: [objectSID]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#26] Requesting attrs: [primaryGroupID]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#26] Requesting attrs: [whenChanged]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#26] Requesting attrs: [uSNChanged]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#26] Requesting attrs: [accountExpires]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#26] Requesting attrs: [userAccountControl]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#26] Requesting attrs: [userCertificate;binary]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#26] Requesting attrs: [mail]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_step] (0x2000): [RID#26] ldap_search_ext called, msgid = 25
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_op_add] (0x2000): [RID#26] New operation 25 timeout 6
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd420], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#26] Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): [RID#26] Additional References: ldap://ForestDnsZones.domain.lan/DC=ForestDnsZones,DC=domain,DC=lan
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd420], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#26] Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): [RID#26] Additional References: ldap://DomainDnsZones.domain.lan/DC=DomainDnsZones,DC=domain,DC=lan
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd420], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#26] Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): [RID#26] Additional References: ldap://domain.lan/CN=Configuration,DC=domain,DC=lan
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd420], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#26] Message type: [LDAP_RES_SEARCH_RESULT]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_generic_op_finished] (0x0400): [RID#26] Search result: Success(0), no errmsg set
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_op_destructor] (0x2000): [RID#26] Operation 25 finished
(2024-07-17 16:08:11): [be[domain.lan]] [generic_ext_search_handler] (0x4000): [RID#26] Request included referrals which were ignored.
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_get_initgr_user] (0x4000): [RID#26] Receiving info for the user
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_id_op_destroy] (0x4000): [RID#26] releasing operation connection
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_id_op_done] (0x4000): [RID#26] releasing operation connection
(2024-07-17 16:08:11): [be[domain.lan]] [sysdb_search_user_by_upn] (0x0400): [RID#26] No entry with upn [geoclue@domain.lan] found.
(2024-07-17 16:08:11): [be[domain.lan]] [sysdb_search_entry_by_sid_str] (0x0400): [RID#26] No such entry
(2024-07-17 16:08:11): [be[domain.lan]] [sysdb_search_object_attr] (0x0400): [RID#26] No such entry.
(2024-07-17 16:08:11): [be[domain.lan]] [sysdb_get_real_name] (0x0400): [RID#26] User [geoclue@domain.lan] is missing in cache
(2024-07-17 16:08:11): [be[domain.lan]] [groups_by_user_done] (0x2000): [RID#26] Failed to canonicalize name, using [geoclue@domain.lan] [2]: No such file or directory.
(2024-07-17 16:08:11): [be[domain.lan]] [sysdb_search_by_name] (0x0400): [RID#26] No such entry
(2024-07-17 16:08:11): [be[domain.lan]] [sysdb_cache_search_groups] (0x2000): [RID#26] Search groups with filter: (&(objectCategory=group)(ghost=geoclue@domain.lan))
(2024-07-17 16:08:11): [be[domain.lan]] [sysdb_cache_search_groups] (0x2000): [RID#26] No such entry
(2024-07-17 16:08:11): [be[domain.lan]] [sysdb_delete_user] (0x0400): [RID#26] Error: 2 (No such file or directory)
(2024-07-17 16:08:11): [be[domain.lan]] [dp_req_done] (0x0400): [RID#26] DP Request [Initgroups https://github.com//pull/26]: Request handler finished [0]: Success
(2024-07-17 16:08:11): [be[domain.lan]] [_dp_req_recv] (0x0400): [RID#26] DP Request [Initgroups https://github.com//pull/26]: Receiving request data.
(2024-07-17 16:08:11): [be[domain.lan]] [dp_req_destructor] (0x0400): [RID#26] DP Request [Initgroups https://github.com//pull/26]: Request removed.
(2024-07-17 16:08:11): [be[domain.lan]] [dp_req_destructor] (0x0400): [RID#26] Number of active DP request: 0
(2024-07-17 16:08:11): [be[domain.lan]] [sysdb_search_user_by_upn] (0x0400): [RID#26] No entry with upn [geoclue@domain.lan] found.
(2024-07-17 16:08:11): [be[domain.lan]] [sysdb_search_entry_by_sid_str] (0x0400): [RID#26] No such entry
(2024-07-17 16:08:11): [be[domain.lan]] [sysdb_search_object_attr] (0x0400): [RID#26] No such entry.
(2024-07-17 16:08:11): [be[domain.lan]] [sysdb_get_real_name] (0x0400): [RID#26] User [geoclue@domain.lan] is missing in cache
(2024-07-17 16:08:11): [be[domain.lan]] [dp_req_reply_std] (0x1000): [RID#26] DP Request [Initgroups https://github.com//pull/26]: Returning [Success]: 0,0,Success
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[(nil)], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:11): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: end of ldap_result list
(2024-07-17 16:08:12): [be[domain.lan]] [dp_get_account_info_send] (0x0200): Got request for [0x3][BE_REQ_INITGROUPS][name=colord@domain.lan]
(2024-07-17 16:08:12): [be[domain.lan]] [sss_domain_get_state] (0x1000): Domain domain.lan is Active
(2024-07-17 16:08:12): [be[domain.lan]] [dp_attach_req] (0x0400): [RID#27] DP Request [Initgroups https://github.com//pull/27]: REQ_TRACE: New request. [sssd.nss CID https://github.com//pull/22] Flags [0x0001].
(2024-07-17 16:08:12): [be[domain.lan]] [dp_attach_req] (0x0400): [RID#27] Number of active DP request: 1
(2024-07-17 16:08:12): [be[domain.lan]] [sss_domain_get_state] (0x1000): [RID#27] Domain domain.lan is Active
(2024-07-17 16:08:12): [be[domain.lan]] [sss_domain_get_state] (0x1000): [RID#27] Domain domain.lan is Active
(2024-07-17 16:08:12): [be[domain.lan]] [sysdb_search_by_name] (0x0400): [RID#27] No such entry
(2024-07-17 16:08:12): [be[domain.lan]] [find_user_entry] (0x4000): [RID#27] No user found with filter [colord@domain.lan].
(2024-07-17 16:08:12): [be[domain.lan]] [check_if_pac_is_available] (0x0200): [RID#27] find_user_entry didn't find user entry.
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_id_op_connect_step] (0x4000): [RID#27] reusing cached connection
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_get_initgr_send] (0x4000): [RID#27] Retrieving info for initgroups call
(2024-07-17 16:08:12): [be[domain.lan]] [get_ldap_conn_from_sdom_pvt] (0x4000): [RID#27] Returning LDAP connection for user lookup.
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_id_op_connect_step] (0x4000): [RID#27] reusing cached connection
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_get_initgr_next_base] (0x0400): [RID#27] Searching for users with base [DC=domain,DC=lan]
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_print_server] (0x2000): [RID#27] Searching 192.168.135.3:389
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_get_generic_ext_step] (0x0400): [RID#27] calling ldap_search_ext with [(&(sAMAccountName=colord)(objectclass=user)(&(uidNumber=)(!(uidNumber=0))))][DC=domain,DC=lan].
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#27] Requesting attrs: [objectClass]
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#27] Requesting attrs: [sAMAccountName]
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#27] Requesting attrs: [unixUserPassword]
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#27] Requesting attrs: [uidNumber]
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#27] Requesting attrs: [gidNumber]
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#27] Requesting attrs: [displayName]
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#27] Requesting attrs: [unixHomeDirectory]
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#27] Requesting attrs: [loginShell]
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#27] Requesting attrs: [userPrincipalName]
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#27] Requesting attrs: [displayName]
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#27] Requesting attrs: [memberOf]
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#27] Requesting attrs: [objectGUID]
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#27] Requesting attrs: [objectSID]
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#27] Requesting attrs: [primaryGroupID]
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#27] Requesting attrs: [whenChanged]
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#27] Requesting attrs: [uSNChanged]
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#27] Requesting attrs: [accountExpires]
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#27] Requesting attrs: [userAccountControl]
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#27] Requesting attrs: [userCertificate;binary]
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#27] Requesting attrs: [mail]
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_get_generic_ext_step] (0x2000): [RID#27] ldap_search_ext called, msgid = 26
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_op_add] (0x2000): [RID#27] New operation 26 timeout 6
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd420], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#27] Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): [RID#27] Additional References: ldap://ForestDnsZones.domain.lan/DC=ForestDnsZones,DC=domain,DC=lan
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd420], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#27] Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): [RID#27] Additional References: ldap://DomainDnsZones.domain.lan/DC=DomainDnsZones,DC=domain,DC=lan
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd420], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#27] Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): [RID#27] Additional References: ldap://domain.lan/CN=Configuration,DC=domain,DC=lan
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd420], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#27] Message type: [LDAP_RES_SEARCH_RESULT]
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_get_generic_op_finished] (0x0400): [RID#27] Search result: Success(0), no errmsg set
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_op_destructor] (0x2000): [RID#27] Operation 26 finished
(2024-07-17 16:08:12): [be[domain.lan]] [generic_ext_search_handler] (0x4000): [RID#27] Request included referrals which were ignored.
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_get_initgr_user] (0x4000): [RID#27] Receiving info for the user
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_id_op_destroy] (0x4000): [RID#27] releasing operation connection
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_id_op_done] (0x4000): [RID#27] releasing operation connection
(2024-07-17 16:08:12): [be[domain.lan]] [sysdb_search_user_by_upn] (0x0400): [RID#27] No entry with upn [colord@domain.lan] found.
(2024-07-17 16:08:12): [be[domain.lan]] [sysdb_search_entry_by_sid_str] (0x0400): [RID#27] No such entry
(2024-07-17 16:08:12): [be[domain.lan]] [sysdb_search_object_attr] (0x0400): [RID#27] No such entry.
(2024-07-17 16:08:12): [be[domain.lan]] [sysdb_get_real_name] (0x0400): [RID#27] User [colord@domain.lan] is missing in cache
(2024-07-17 16:08:12): [be[domain.lan]] [groups_by_user_done] (0x2000): [RID#27] Failed to canonicalize name, using [colord@domain.lan] [2]: No such file or directory.
(2024-07-17 16:08:12): [be[domain.lan]] [sysdb_search_by_name] (0x0400): [RID#27] No such entry
(2024-07-17 16:08:12): [be[domain.lan]] [sysdb_cache_search_groups] (0x2000): [RID#27] Search groups with filter: (&(objectCategory=group)(ghost=colord@domain.lan))
(2024-07-17 16:08:12): [be[domain.lan]] [sysdb_cache_search_groups] (0x2000): [RID#27] No such entry
(2024-07-17 16:08:12): [be[domain.lan]] [sysdb_delete_user] (0x0400): [RID#27] Error: 2 (No such file or directory)
(2024-07-17 16:08:12): [be[domain.lan]] [dp_req_done] (0x0400): [RID#27] DP Request [Initgroups https://github.com//pull/27]: Request handler finished [0]: Success
(2024-07-17 16:08:12): [be[domain.lan]] [_dp_req_recv] (0x0400): [RID#27] DP Request [Initgroups https://github.com//pull/27]: Receiving request data.
(2024-07-17 16:08:12): [be[domain.lan]] [dp_req_destructor] (0x0400): [RID#27] DP Request [Initgroups https://github.com//pull/27]: Request removed.
(2024-07-17 16:08:12): [be[domain.lan]] [dp_req_destructor] (0x0400): [RID#27] Number of active DP request: 0
(2024-07-17 16:08:12): [be[domain.lan]] [sysdb_search_user_by_upn] (0x0400): [RID#27] No entry with upn [colord@domain.lan] found.
(2024-07-17 16:08:12): [be[domain.lan]] [sysdb_search_entry_by_sid_str] (0x0400): [RID#27] No such entry
(2024-07-17 16:08:12): [be[domain.lan]] [sysdb_search_object_attr] (0x0400): [RID#27] No such entry.
(2024-07-17 16:08:12): [be[domain.lan]] [sysdb_get_real_name] (0x0400): [RID#27] User [colord@domain.lan] is missing in cache
(2024-07-17 16:08:12): [be[domain.lan]] [dp_req_reply_std] (0x1000): [RID#27] DP Request [Initgroups https://github.com//pull/27]: Returning [Success]: 0,0,Success
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[(nil)], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:12): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: end of ldap_result list
(2024-07-17 16:08:23): [be[domain.lan]] [dp_get_account_info_send] (0x0200): Got request for [0x3][BE_REQ_INITGROUPS][name=domain@domain.lan]
(2024-07-17 16:08:23): [be[domain.lan]] [sss_domain_get_state] (0x1000): Domain domain.lan is Active
(2024-07-17 16:08:23): [be[domain.lan]] [dp_attach_req] (0x0400): [RID#28] DP Request [Initgroups https://github.com//pull/28]: REQ_TRACE: New request. [sssd.nss CID https://github.com//pull/23] Flags [0x0001].
(2024-07-17 16:08:23): [be[domain.lan]] [dp_attach_req] (0x0400): [RID#28] Number of active DP request: 1
(2024-07-17 16:08:23): [be[domain.lan]] [sss_domain_get_state] (0x1000): [RID#28] Domain domain.lan is Active
(2024-07-17 16:08:23): [be[domain.lan]] [sss_domain_get_state] (0x1000): [RID#28] Domain domain.lan is Active
(2024-07-17 16:08:23): [be[domain.lan]] [sysdb_search_by_name] (0x0400): [RID#28] No such entry
(2024-07-17 16:08:23): [be[domain.lan]] [find_user_entry] (0x4000): [RID#28] No user found with filter [domain@domain.lan].
(2024-07-17 16:08:23): [be[domain.lan]] [check_if_pac_is_available] (0x0200): [RID#28] find_user_entry didn't find user entry.
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_id_op_connect_step] (0x4000): [RID#28] reusing cached connection
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_initgr_send] (0x4000): [RID#28] Retrieving info for initgroups call
(2024-07-17 16:08:23): [be[domain.lan]] [get_ldap_conn_from_sdom_pvt] (0x4000): [RID#28] Returning LDAP connection for user lookup.
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_id_op_connect_step] (0x4000): [RID#28] reusing cached connection
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_initgr_next_base] (0x0400): [RID#28] Searching for users with base [DC=domain,DC=lan]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_print_server] (0x2000): [RID#28] Searching 192.168.135.3:389
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x0400): [RID#28] calling ldap_search_ext with [(&(sAMAccountName=domain)(objectclass=user)(&(uidNumber=)(!(uidNumber=0))))][DC=domain,DC=lan].
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#28] Requesting attrs: [objectClass]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#28] Requesting attrs: [sAMAccountName]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#28] Requesting attrs: [unixUserPassword]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#28] Requesting attrs: [uidNumber]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#28] Requesting attrs: [gidNumber]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#28] Requesting attrs: [displayName]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#28] Requesting attrs: [unixHomeDirectory]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#28] Requesting attrs: [loginShell]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#28] Requesting attrs: [userPrincipalName]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#28] Requesting attrs: [displayName]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#28] Requesting attrs: [memberOf]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#28] Requesting attrs: [objectGUID]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#28] Requesting attrs: [objectSID]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#28] Requesting attrs: [primaryGroupID]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#28] Requesting attrs: [whenChanged]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#28] Requesting attrs: [uSNChanged]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#28] Requesting attrs: [accountExpires]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#28] Requesting attrs: [userAccountControl]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#28] Requesting attrs: [userCertificate;binary]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#28] Requesting attrs: [mail]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x2000): [RID#28] ldap_search_ext called, msgid = 27
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_op_add] (0x2000): [RID#28] New operation 27 timeout 6
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd420], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#28] Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): [RID#28] Additional References: ldap://ForestDnsZones.domain.lan/DC=ForestDnsZones,DC=domain,DC=lan
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd420], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#28] Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): [RID#28] Additional References: ldap://DomainDnsZones.domain.lan/DC=DomainDnsZones,DC=domain,DC=lan
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd420], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#28] Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): [RID#28] Additional References: ldap://domain.lan/CN=Configuration,DC=domain,DC=lan
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd420], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#28] Message type: [LDAP_RES_SEARCH_RESULT]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_op_finished] (0x0400): [RID#28] Search result: Success(0), no errmsg set
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_op_destructor] (0x2000): [RID#28] Operation 27 finished
(2024-07-17 16:08:23): [be[domain.lan]] [generic_ext_search_handler] (0x4000): [RID#28] Request included referrals which were ignored.
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_initgr_user] (0x4000): [RID#28] Receiving info for the user
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_id_op_destroy] (0x4000): [RID#28] releasing operation connection
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_id_op_done] (0x4000): [RID#28] releasing operation connection
(2024-07-17 16:08:23): [be[domain.lan]] [sysdb_search_user_by_upn] (0x0400): [RID#28] No entry with upn [domain@domain.lan] found.
(2024-07-17 16:08:23): [be[domain.lan]] [sysdb_search_entry_by_sid_str] (0x0400): [RID#28] No such entry
(2024-07-17 16:08:23): [be[domain.lan]] [sysdb_search_object_attr] (0x0400): [RID#28] No such entry.
(2024-07-17 16:08:23): [be[domain.lan]] [sysdb_get_real_name] (0x0400): [RID#28] User [domain@domain.lan] is missing in cache
(2024-07-17 16:08:23): [be[domain.lan]] [groups_by_user_done] (0x2000): [RID#28] Failed to canonicalize name, using [domain@domain.lan] [2]: No such file or directory.
(2024-07-17 16:08:23): [be[domain.lan]] [sysdb_search_by_name] (0x0400): [RID#28] No such entry
(2024-07-17 16:08:23): [be[domain.lan]] [sysdb_cache_search_groups] (0x2000): [RID#28] Search groups with filter: (&(objectCategory=group)(ghost=domain@domain.lan))
(2024-07-17 16:08:23): [be[domain.lan]] [sysdb_cache_search_groups] (0x2000): [RID#28] No such entry
(2024-07-17 16:08:23): [be[domain.lan]] [sysdb_delete_user] (0x0400): [RID#28] Error: 2 (No such file or directory)
(2024-07-17 16:08:23): [be[domain.lan]] [dp_req_done] (0x0400): [RID#28] DP Request [Initgroups https://github.com//pull/28]: Request handler finished [0]: Success
(2024-07-17 16:08:23): [be[domain.lan]] [_dp_req_recv] (0x0400): [RID#28] DP Request [Initgroups https://github.com//pull/28]: Receiving request data.
(2024-07-17 16:08:23): [be[domain.lan]] [dp_req_destructor] (0x0400): [RID#28] DP Request [Initgroups https://github.com//pull/28]: Request removed.
(2024-07-17 16:08:23): [be[domain.lan]] [dp_req_destructor] (0x0400): [RID#28] Number of active DP request: 0
(2024-07-17 16:08:23): [be[domain.lan]] [sysdb_search_user_by_upn] (0x0400): [RID#28] No entry with upn [domain@domain.lan] found.
(2024-07-17 16:08:23): [be[domain.lan]] [sysdb_search_entry_by_sid_str] (0x0400): [RID#28] No such entry
(2024-07-17 16:08:23): [be[domain.lan]] [sysdb_search_object_attr] (0x0400): [RID#28] No such entry.
(2024-07-17 16:08:23): [be[domain.lan]] [sysdb_get_real_name] (0x0400): [RID#28] User [domain@domain.lan] is missing in cache
(2024-07-17 16:08:23): [be[domain.lan]] [dp_req_reply_std] (0x1000): [RID#28] DP Request [Initgroups https://github.com//pull/28]: Returning [Success]: 0,0,Success
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[(nil)], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: end of ldap_result list
(2024-07-17 16:08:23): [be[domain.lan]] [dp_client_init] (0x0100): Set-up Backend ID timeout [0x560c8b25b100]
(2024-07-17 16:08:23): [be[domain.lan]] [dp_client_register] (0x0100): Added Frontend client [pac]
(2024-07-17 16:08:23): [be[domain.lan]] [dp_client_register] (0x0100): Cancel DP ID timeout [0x560c8b25b100]
(2024-07-17 16:08:23): [be[domain.lan]] [dp_attach_req] (0x0400): [RID#29] DP Request [Subdomains https://github.com//pull/29]: REQ_TRACE: New request. Flags [0000].
(2024-07-17 16:08:23): [be[domain.lan]] [dp_attach_req] (0x0400): [RID#29] Number of active DP request: 1
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_id_op_connect_step] (0x4000): [RID#29] reusing cached connection
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_print_server] (0x2000): [RID#29] Searching 192.168.135.3:389
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x0400): [RID#29] calling ldap_search_ext with [objectclass=domain][DC=domain,DC=lan].
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#29] Requesting attrs: [objectSID]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x2000): [RID#29] ldap_search_ext called, msgid = 28
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_op_add] (0x2000): [RID#29] New operation 28 timeout 6
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd2d0], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#29] Message type: [LDAP_RES_SEARCH_ENTRY]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_parse_entry] (0x1000): [RID#29] OriginalDN: [DC=domain,DC=lan].
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_parse_range] (0x2000): [RID#29] No sub-attributes for [objectSid]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd2d0], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#29] Message type: [LDAP_RES_SEARCH_RESULT]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_op_finished] (0x0400): [RID#29] Search result: Success(0), no errmsg set
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_op_destructor] (0x2000): [RID#29] Operation 28 finished
(2024-07-17 16:08:23): [be[domain.lan]] [ad_domain_info_next_done] (0x0400): [RID#29] Found SID [S-1-5-21-4005887970-6952358-270221094].
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_print_server] (0x2000): [RID#29] Searching 192.168.135.3:389
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x0400): [RID#29] calling ldap_search_ext with [(&(DnsDomain=domain.lan)(NtVer=\14\00\00\00))][].
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#29] Requesting attrs: [netlogon]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x2000): [RID#29] ldap_search_ext called, msgid = 29
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_op_add] (0x2000): [RID#29] New operation 29 timeout 6
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd2d0], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: end of ldap_result list
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd2d0], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#29] Message type: [LDAP_RES_SEARCH_ENTRY]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_parse_entry] (0x1000): [RID#29] OriginalDN: [].
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_parse_range] (0x2000): [RID#29] No sub-attributes for [netlogon]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd2d0], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#29] Message type: [LDAP_RES_SEARCH_RESULT]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_op_finished] (0x0400): [RID#29] Search result: Success(0), no errmsg set
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_op_destructor] (0x2000): [RID#29] Operation 29 finished
(2024-07-17 16:08:23): [be[domain.lan]] [ad_domain_info_netlogon_done] (0x0400): [RID#29] Found flat name [DOMAIN].
(2024-07-17 16:08:23): [be[domain.lan]] [ad_domain_info_netlogon_done] (0x0400): [RID#29] Found site [Default-First-Site-Name].
(2024-07-17 16:08:23): [be[domain.lan]] [ad_domain_info_netlogon_done] (0x0400): [RID#29] Found forest [domain.lan].
(2024-07-17 16:08:23): [be[domain.lan]] [ad_check_gc_usability_send] (0x0400): [RID#29] GC explicitly disabled
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_id_op_done] (0x4000): [RID#29] releasing operation connection
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_id_op_connect_step] (0x4000): [RID#29] reusing cached connection
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_search_bases_ex_next_base] (0x0400): [RID#29] Issuing LDAP lookup with base [DC=domain,DC=lan]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_print_server] (0x2000): [RID#29] Searching 192.168.135.3:389
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x0400): [RID#29] calling ldap_search_ext with [(&(objectclass=trustedDomain)(trustType=2)(!(msDS-TrustForestTrustInfo=)))][DC=domain,DC=lan].
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#29] Requesting attrs: [flatName]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#29] Requesting attrs: [trustPartner]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#29] Requesting attrs: [securityIdentifier]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#29] Requesting attrs: [trustType]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#29] Requesting attrs: [trustAttributes]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#29] Requesting attrs: [trustDirection]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_step] (0x2000): [RID#29] ldap_search_ext called, msgid = 30
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_op_add] (0x2000): [RID#29] New operation 30 timeout 6
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b2103e0], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: end of ldap_result list
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b2103e0], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#29] Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): [RID#29] Additional References: ldap://ForestDnsZones.domain.lan/DC=ForestDnsZones,DC=domain,DC=lan
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b2103e0], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#29] Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): [RID#29] Additional References: ldap://DomainDnsZones.domain.lan/DC=DomainDnsZones,DC=domain,DC=lan
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b2103e0], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#29] Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): [RID#29] Additional References: ldap://domain.lan/CN=Configuration,DC=domain,DC=lan
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b2103e0], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#29] Message type: [LDAP_RES_SEARCH_RESULT]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_get_generic_op_finished] (0x0400): [RID#29] Search result: Success(0), no errmsg set
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_op_destructor] (0x2000): [RID#29] Operation 30 finished
(2024-07-17 16:08:23): [be[domain.lan]] [generic_ext_search_handler] (0x4000): [RID#29] Request included referrals which were ignored.
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_search_bases_ex_done] (0x0400): [RID#29] Receiving data from base [DC=domain,DC=lan]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_id_op_done] (0x4000): [RID#29] releasing operation connection
(2024-07-17 16:08:23): [be[domain.lan]] [ad_get_slave_domain_done] (0x1000): [RID#29] There are no changes
(2024-07-17 16:08:23): [be[domain.lan]] [ad_subdomains_refresh_done] (0x0400): [RID#29] Subdomains refreshed.
(2024-07-17 16:08:23): [be[domain.lan]] [dp_req_done] (0x0400): [RID#29] DP Request [Subdomains https://github.com//pull/29]: Request handler finished [0]: Success
(2024-07-17 16:08:23): [be[domain.lan]] [_dp_req_recv] (0x0400): [RID#29] DP Request [Subdomains https://github.com//pull/29]: Receiving request data.
(2024-07-17 16:08:23): [be[domain.lan]] [dp_req_destructor] (0x0400): [RID#29] DP Request [Subdomains https://github.com//pull/29]: Request removed.
(2024-07-17 16:08:23): [be[domain.lan]] [dp_req_destructor] (0x0400): [RID#29] Number of active DP request: 0
(2024-07-17 16:08:23): [be[domain.lan]] [dp_req_reply_std] (0x1000): [RID#29] DP Request [Subdomains https://github.com//pull/29]: Returning [Success]: 0,0,Success
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[(nil)], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:23): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: end of ldap_result list
(2024-07-17 16:08:23): [be[domain.lan]] [dp_attach_req] (0x0400): [RID#30] DP Request [Subdomains https://github.com//pull/30]: REQ_TRACE: New request. Flags [0000].
(2024-07-17 16:08:23): [be[domain.lan]] [dp_attach_req] (0x0400): [RID#30] Number of active DP request: 1
(2024-07-17 16:08:23): [be[domain.lan]] [ad_subdomains_handler_send] (0x0400): [RID#30] Subdomains were recently refreshed, nothing to do
(2024-07-17 16:08:23): [be[domain.lan]] [dp_req_done] (0x0400): [RID#30] DP Request [Subdomains https://github.com//pull/30]: Request handler finished [0]: Success
(2024-07-17 16:08:23): [be[domain.lan]] [_dp_req_recv] (0x0400): [RID#30] DP Request [Subdomains https://github.com//pull/30]: Receiving request data.
(2024-07-17 16:08:23): [be[domain.lan]] [dp_req_destructor] (0x0400): [RID#30] DP Request [Subdomains https://github.com//pull/30]: Request removed.
(2024-07-17 16:08:23): [be[domain.lan]] [dp_req_destructor] (0x0400): [RID#30] Number of active DP request: 0
(2024-07-17 16:08:23): [be[domain.lan]] [dp_req_reply_std] (0x1000): [RID#30] DP Request [Subdomains https://github.com//pull/30]: Returning [Success]: 0,0,Success
(2024-07-17 16:08:28): [be[domain.lan]] [dp_get_account_info_send] (0x0200): Got request for [0x3][BE_REQ_INITGROUPS][name=domain@domain.lan]
(2024-07-17 16:08:28): [be[domain.lan]] [sss_domain_get_state] (0x1000): Domain domain.lan is Active
(2024-07-17 16:08:28): [be[domain.lan]] [dp_attach_req] (0x0400): [RID#31] DP Request [Initgroups https://github.com//pull/31]: REQ_TRACE: New request. [sssd.pam CID https://github.com//pull/2] Flags [0x0001].
(2024-07-17 16:08:28): [be[domain.lan]] [dp_attach_req] (0x0400): [RID#31] Number of active DP request: 1
(2024-07-17 16:08:28): [be[domain.lan]] [sss_domain_get_state] (0x1000): [RID#31] Domain domain.lan is Active
(2024-07-17 16:08:28): [be[domain.lan]] [sss_domain_get_state] (0x1000): [RID#31] Domain domain.lan is Active
(2024-07-17 16:08:28): [be[domain.lan]] [sysdb_search_by_name] (0x0400): [RID#31] No such entry
(2024-07-17 16:08:28): [be[domain.lan]] [find_user_entry] (0x4000): [RID#31] No user found with filter [domain@domain.lan].
(2024-07-17 16:08:28): [be[domain.lan]] [check_if_pac_is_available] (0x0200): [RID#31] find_user_entry didn't find user entry.
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_id_op_connect_step] (0x4000): [RID#31] reusing cached connection
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_initgr_send] (0x4000): [RID#31] Retrieving info for initgroups call
(2024-07-17 16:08:28): [be[domain.lan]] [get_ldap_conn_from_sdom_pvt] (0x4000): [RID#31] Returning LDAP connection for user lookup.
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_id_op_connect_step] (0x4000): [RID#31] reusing cached connection
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_initgr_next_base] (0x0400): [RID#31] Searching for users with base [DC=domain,DC=lan]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_print_server] (0x2000): [RID#31] Searching 192.168.135.3:389
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x0400): [RID#31] calling ldap_search_ext with [(&(sAMAccountName=domain)(objectclass=user)(&(uidNumber=*)(!(uidNumber=0))))][DC=domain,DC=lan].
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#31] Requesting attrs: [objectClass]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#31] Requesting attrs: [sAMAccountName]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#31] Requesting attrs: [unixUserPassword]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#31] Requesting attrs: [uidNumber]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#31] Requesting attrs: [gidNumber]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#31] Requesting attrs: [displayName]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#31] Requesting attrs: [unixHomeDirectory]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#31] Requesting attrs: [loginShell]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#31] Requesting attrs: [userPrincipalName]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#31] Requesting attrs: [displayName]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#31] Requesting attrs: [memberOf]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#31] Requesting attrs: [objectGUID]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#31] Requesting attrs: [objectSID]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#31] Requesting attrs: [primaryGroupID]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#31] Requesting attrs: [whenChanged]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#31] Requesting attrs: [uSNChanged]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#31] Requesting attrs: [accountExpires]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#31] Requesting attrs: [userAccountControl]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#31] Requesting attrs: [userCertificate;binary]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#31] Requesting attrs: [mail]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x2000): [RID#31] ldap_search_ext called, msgid = 31
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_op_add] (0x2000): [RID#31] New operation 31 timeout 6
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b2f6170], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#31] Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): [RID#31] Additional References: ldap://ForestDnsZones.domain.lan/DC=ForestDnsZones,DC=domain,DC=lan
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b2f6170], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#31] Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): [RID#31] Additional References: ldap://DomainDnsZones.domain.lan/DC=DomainDnsZones,DC=domain,DC=lan
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b2f6170], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#31] Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): [RID#31] Additional References: ldap://domain.lan/CN=Configuration,DC=domain,DC=lan
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b2f6170], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#31] Message type: [LDAP_RES_SEARCH_RESULT]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_op_finished] (0x0400): [RID#31] Search result: Success(0), no errmsg set
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_op_destructor] (0x2000): [RID#31] Operation 31 finished
(2024-07-17 16:08:28): [be[domain.lan]] [generic_ext_search_handler] (0x4000): [RID#31] Request included referrals which were ignored.
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_initgr_user] (0x4000): [RID#31] Receiving info for the user
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_id_op_destroy] (0x4000): [RID#31] releasing operation connection
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_id_op_done] (0x4000): [RID#31] releasing operation connection
(2024-07-17 16:08:28): [be[domain.lan]] [sysdb_search_user_by_upn] (0x0400): [RID#31] No entry with upn [domain@domain.lan] found.
(2024-07-17 16:08:28): [be[domain.lan]] [sysdb_search_entry_by_sid_str] (0x0400): [RID#31] No such entry
(2024-07-17 16:08:28): [be[domain.lan]] [sysdb_search_object_attr] (0x0400): [RID#31] No such entry.
(2024-07-17 16:08:28): [be[domain.lan]] [sysdb_get_real_name] (0x0400): [RID#31] User [domain@domain.lan] is missing in cache
(2024-07-17 16:08:28): [be[domain.lan]] [groups_by_user_done] (0x2000): [RID#31] Failed to canonicalize name, using [domain@domain.lan] [2]: No such file or directory.
(2024-07-17 16:08:28): [be[domain.lan]] [sysdb_search_by_name] (0x0400): [RID#31] No such entry
(2024-07-17 16:08:28): [be[domain.lan]] [sysdb_cache_search_groups] (0x2000): [RID#31] Search groups with filter: (&(objectCategory=group)(ghost=domain@domain.lan))
(2024-07-17 16:08:28): [be[domain.lan]] [sysdb_cache_search_groups] (0x2000): [RID#31] No such entry
(2024-07-17 16:08:28): [be[domain.lan]] [sysdb_delete_user] (0x0400): [RID#31] Error: 2 (No such file or directory)
(2024-07-17 16:08:28): [be[domain.lan]] [dp_req_done] (0x0400): [RID#31] DP Request [Initgroups https://github.com//pull/31]: Request handler finished [0]: Success
(2024-07-17 16:08:28): [be[domain.lan]] [_dp_req_recv] (0x0400): [RID#31] DP Request [Initgroups https://github.com//pull/31]: Receiving request data.
(2024-07-17 16:08:28): [be[domain.lan]] [dp_req_destructor] (0x0400): [RID#31] DP Request [Initgroups https://github.com//pull/31]: Request removed.
(2024-07-17 16:08:28): [be[domain.lan]] [dp_req_destructor] (0x0400): [RID#31] Number of active DP request: 0
(2024-07-17 16:08:28): [be[domain.lan]] [sysdb_search_user_by_upn] (0x0400): [RID#31] No entry with upn [domain@domain.lan] found.
(2024-07-17 16:08:28): [be[domain.lan]] [sysdb_search_entry_by_sid_str] (0x0400): [RID#31] No such entry
(2024-07-17 16:08:28): [be[domain.lan]] [sysdb_search_object_attr] (0x0400): [RID#31] No such entry.
(2024-07-17 16:08:28): [be[domain.lan]] [sysdb_get_real_name] (0x0400): [RID#31] User [domain@domain.lan] is missing in cache
(2024-07-17 16:08:28): [be[domain.lan]] [dp_req_reply_std] (0x1000): [RID#31] DP Request [Initgroups https://github.com//pull/31]: Returning [Success]: 0,0,Success
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[(nil)], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: end of ldap_result list
(2024-07-17 16:08:28): [be[domain.lan]] [dp_get_account_info_send] (0x0200): Got request for [0x1][BE_REQ_USER][name=storage@domain.lan]
(2024-07-17 16:08:28): [be[domain.lan]] [dp_attach_req] (0x0400): [RID#32] DP Request [Account https://github.com//pull/32]: REQ_TRACE: New request. [sssd.nss CID https://github.com//pull/27] Flags [0x0001].
(2024-07-17 16:08:28): [be[domain.lan]] [dp_attach_req] (0x0400): [RID#32] Number of active DP request: 1
(2024-07-17 16:08:28): [be[domain.lan]] [sss_domain_get_state] (0x1000): [RID#32] Domain domain.lan is Active
(2024-07-17 16:08:28): [be[domain.lan]] [sss_domain_get_state] (0x1000): [RID#32] Domain domain.lan is Active
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_id_op_connect_step] (0x4000): [RID#32] reusing cached connection
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_search_user_next_base] (0x0400): [RID#32] Searching for users with base [DC=domain,DC=lan]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_print_server] (0x2000): [RID#32] Searching 192.168.135.3:389
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x0400): [RID#32] calling ldap_search_ext with [(&(sAMAccountName=storage)(objectclass=user)(sAMAccountName=)(&(uidNumber=)(!(uidNumber=0))))][DC=domain,DC=lan].
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#32] Requesting attrs: [objectClass]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#32] Requesting attrs: [sAMAccountName]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#32] Requesting attrs: [unixUserPassword]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#32] Requesting attrs: [uidNumber]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#32] Requesting attrs: [gidNumber]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#32] Requesting attrs: [displayName]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#32] Requesting attrs: [unixHomeDirectory]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#32] Requesting attrs: [loginShell]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#32] Requesting attrs: [userPrincipalName]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#32] Requesting attrs: [displayName]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#32] Requesting attrs: [memberOf]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#32] Requesting attrs: [objectGUID]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#32] Requesting attrs: [objectSID]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#32] Requesting attrs: [primaryGroupID]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#32] Requesting attrs: [whenChanged]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#32] Requesting attrs: [uSNChanged]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#32] Requesting attrs: [accountExpires]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#32] Requesting attrs: [userAccountControl]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#32] Requesting attrs: [userCertificate;binary]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#32] Requesting attrs: [mail]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x2000): [RID#32] ldap_search_ext called, msgid = 32
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_op_add] (0x2000): [RID#32] New operation 32 timeout 6
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd420], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#32] Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): [RID#32] Additional References: ldap://ForestDnsZones.domain.lan/DC=ForestDnsZones,DC=domain,DC=lan
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd420], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#32] Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): [RID#32] Additional References: ldap://DomainDnsZones.domain.lan/DC=DomainDnsZones,DC=domain,DC=lan
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd420], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#32] Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): [RID#32] Additional References: ldap://domain.lan/CN=Configuration,DC=domain,DC=lan
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd420], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#32] Message type: [LDAP_RES_SEARCH_RESULT]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_op_finished] (0x0400): [RID#32] Search result: Success(0), no errmsg set
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_op_destructor] (0x2000): [RID#32] Operation 32 finished
(2024-07-17 16:08:28): [be[domain.lan]] [generic_ext_search_handler] (0x4000): [RID#32] Request included referrals which were ignored.
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_search_user_process] (0x0400): [RID#32] Search for users, returned 0 results.
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_search_user_process] (0x2000): [RID#32] Retrieved total 0 users
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_id_op_done] (0x4000): [RID#32] releasing operation connection
(2024-07-17 16:08:28): [be[domain.lan]] [sysdb_search_by_name] (0x0400): [RID#32] No such entry
(2024-07-17 16:08:28): [be[domain.lan]] [sysdb_cache_search_groups] (0x2000): [RID#32] Search groups with filter: (&(objectCategory=group)(ghost=storage@domain.lan))
(2024-07-17 16:08:28): [be[domain.lan]] [sysdb_cache_search_groups] (0x2000): [RID#32] No such entry
(2024-07-17 16:08:28): [be[domain.lan]] [sysdb_delete_user] (0x0400): [RID#32] Error: 2 (No such file or directory)
(2024-07-17 16:08:28): [be[domain.lan]] [dp_req_done] (0x0400): [RID#32] DP Request [Account https://github.com//pull/32]: Request handler finished [0]: Success
(2024-07-17 16:08:28): [be[domain.lan]] [_dp_req_recv] (0x0400): [RID#32] DP Request [Account https://github.com//pull/32]: Receiving request data.
(2024-07-17 16:08:28): [be[domain.lan]] [dp_req_destructor] (0x0400): [RID#32] DP Request [Account https://github.com//pull/32]: Request removed.
(2024-07-17 16:08:28): [be[domain.lan]] [dp_req_destructor] (0x0400): [RID#32] Number of active DP request: 0
(2024-07-17 16:08:28): [be[domain.lan]] [dp_req_reply_std] (0x1000): [RID#32] DP Request [Account https://github.com//pull/32]: Returning [Success]: 0,0,Success
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[(nil)], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: end of ldap_result list
(2024-07-17 16:08:28): [be[domain.lan]] [dp_get_account_info_send] (0x0200): Got request for [0x1][BE_REQ_USER][name=buildah@domain.lan]
(2024-07-17 16:08:28): [be[domain.lan]] [dp_attach_req] (0x0400): [RID#33] DP Request [Account https://github.com//pull/33]: REQ_TRACE: New request. [sssd.nss CID https://github.com//pull/27] Flags [0x0001].
(2024-07-17 16:08:28): [be[domain.lan]] [dp_attach_req] (0x0400): [RID#33] Number of active DP request: 1
(2024-07-17 16:08:28): [be[domain.lan]] [sss_domain_get_state] (0x1000): [RID#33] Domain domain.lan is Active
(2024-07-17 16:08:28): [be[domain.lan]] [sss_domain_get_state] (0x1000): [RID#33] Domain domain.lan is Active
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_id_op_connect_step] (0x4000): [RID#33] reusing cached connection
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_search_user_next_base] (0x0400): [RID#33] Searching for users with base [DC=domain,DC=lan]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_print_server] (0x2000): [RID#33] Searching 192.168.135.3:389
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x0400): [RID#33] calling ldap_search_ext with [(&(sAMAccountName=buildah)(objectclass=user)(sAMAccountName=)(&(uidNumber=)(!(uidNumber=0))))][DC=domain,DC=lan].
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#33] Requesting attrs: [objectClass]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#33] Requesting attrs: [sAMAccountName]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#33] Requesting attrs: [unixUserPassword]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#33] Requesting attrs: [uidNumber]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#33] Requesting attrs: [gidNumber]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#33] Requesting attrs: [displayName]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#33] Requesting attrs: [unixHomeDirectory]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#33] Requesting attrs: [loginShell]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#33] Requesting attrs: [userPrincipalName]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#33] Requesting attrs: [displayName]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#33] Requesting attrs: [memberOf]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#33] Requesting attrs: [objectGUID]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#33] Requesting attrs: [objectSID]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#33] Requesting attrs: [primaryGroupID]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#33] Requesting attrs: [whenChanged]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#33] Requesting attrs: [uSNChanged]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#33] Requesting attrs: [accountExpires]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#33] Requesting attrs: [userAccountControl]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#33] Requesting attrs: [userCertificate;binary]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x1000): [RID#33] Requesting attrs: [mail]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_step] (0x2000): [RID#33] ldap_search_ext called, msgid = 33
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_op_add] (0x2000): [RID#33] New operation 33 timeout 6
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd420], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#33] Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): [RID#33] Additional References: ldap://ForestDnsZones.domain.lan/DC=ForestDnsZones,DC=domain,DC=lan
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd420], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#33] Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): [RID#33] Additional References: ldap://DomainDnsZones.domain.lan/DC=DomainDnsZones,DC=domain,DC=lan
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd420], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#33] Message type: [LDAP_RES_SEARCH_REFERENCE]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_ext_add_references] (0x1000): [RID#33] Additional References: ldap://domain.lan/CN=Configuration,DC=domain,DC=lan
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[0x560c8b1dd420], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_process_message] (0x4000): [RID#33] Message type: [LDAP_RES_SEARCH_RESULT]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_get_generic_op_finished] (0x0400): [RID#33] Search result: Success(0), no errmsg set
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_op_destructor] (0x2000): [RID#33] Operation 33 finished
(2024-07-17 16:08:28): [be[domain.lan]] [generic_ext_search_handler] (0x4000): [RID#33] Request included referrals which were ignored.
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_search_user_process] (0x0400): [RID#33] Search for users, returned 0 results.
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_search_user_process] (0x2000): [RID#33] Retrieved total 0 users
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_id_op_done] (0x4000): [RID#33] releasing operation connection
(2024-07-17 16:08:28): [be[domain.lan]] [sysdb_search_by_name] (0x0400): [RID#33] No such entry
(2024-07-17 16:08:28): [be[domain.lan]] [sysdb_cache_search_groups] (0x2000): [RID#33] Search groups with filter: (&(objectCategory=group)(ghost=buildah@domain.lan))
(2024-07-17 16:08:28): [be[domain.lan]] [sysdb_cache_search_groups] (0x2000): [RID#33] No such entry
(2024-07-17 16:08:28): [be[domain.lan]] [sysdb_delete_user] (0x0400): [RID#33] Error: 2 (No such file or directory)
(2024-07-17 16:08:28): [be[domain.lan]] [dp_req_done] (0x0400): [RID#33] DP Request [Account https://github.com//pull/33]: Request handler finished [0]: Success
(2024-07-17 16:08:28): [be[domain.lan]] [_dp_req_recv] (0x0400): [RID#33] DP Request [Account https://github.com//pull/33]: Receiving request data.
(2024-07-17 16:08:28): [be[domain.lan]] [dp_req_destructor] (0x0400): [RID#33] DP Request [Account https://github.com//pull/33]: Request removed.
(2024-07-17 16:08:28): [be[domain.lan]] [dp_req_destructor] (0x0400): [RID#33] Number of active DP request: 0
(2024-07-17 16:08:28): [be[domain.lan]] [dp_req_reply_std] (0x1000): [RID#33] DP Request [Account https://github.com//pull/33]: Returning [Success]: 0,0,Success
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: sh[0x560c8b2603e0], connected[1], ops[(nil)], ldap[0x560c8b1de2d0]
(2024-07-17 16:08:28): [be[domain.lan]] [sdap_process_result] (0x2000): Trace: end of ldap_result list
(2024-07-17 16:08:49): [be[domain.lan]] [dp_get_account_info_send] (0x0200): Got request for [0x3][BE_REQ_INITGROUPS][name=mri@domain.lan]
(2024-07-17 16:08:49): [be[domain.lan]] [sss_domain_get_state] (0x1000): Domain domain.lan is Active
(2024-07-17 16:08:49): [be[domain.lan]] [dp_attach_req] (0x0400): [RID#34] DP Request [Initgroups https://github.com//pull/34]: REQ_TRACE: New request. [sssd.pam CID https://github.com//pull/3] Flags [0x0001].
(2024-07-17 16:08:49): [be[domain.lan]] [dp_attach_req] (0x0400): [RID#34] Number of active DP request: 1
(2024-07-17 16:08:49): [be[domain.lan]] [sss_domain_get_state] (0x1000): [RID#34] Domain domain.lan is Active
(2024-07-17 16:08:49): [be[domain.lan]] [sss_domain_get_state] (0x1000): [RID#34] Domain domain.lan is Active
(2024-07-17 16:08:49): [be[domain.lan]] [ad_handle_pac_initgr_send] (0x4000): [RID#34] Running PAC processing with external IDs.
(2024-07-17 16:08:49): [be[domain.lan]] [sdap_ad_tokengroups_get_posix_members] (0x1000): [RID#34] Processing membership SID [S-1-5-21-4005887970-6952358-270221094-1153]
(2024-07-17 16:08:49): [be[domain.lan]] [sss_domain_get_state] (0x1000): [RID#34] Domain domain.lan is Active
(2024-07-17 16:08:49): [be[domain.lan]] [sdap_ad_tokengroups_get_posix_members] (0x1000): [RID#34] Processing membership SID [S-1-5-21-4005887970-6952358-270221094-12620]
(2024-07-17 16:08:49): [be[domain.lan]] [sss_domain_get_state] (0x1000): [RID#34] Domain domain.lan is Active
(2024-07-17 16:08:49): [be[domain.lan]] [sdap_ad_tokengroups_get_posix_members] (0x1000): [RID#34] Processing membership SID [S-1-5-21-4005887970-6952358-270221094-12142]
(2024-07-17 16:08:49): [be[domain.lan]] [sss_domain_get_state] (0x1000): [RID#34] Domain domain.lan is Active
(2024-07-17 16:08:49): [be[domain.lan]] [sdap_ad_tokengroups_get_posix_members] (0x1000): [RID#34] Processing membership SID [S-1-5-21-4005887970-6952358-270221094-12131]
(2024-07-17 16:08:49): [be[domain.lan]] [sss_domain_get_state] (0x1000): [RID#34] Domain domain.lan is Active
(2024-07-17 16:08:49): [be[domain.lan]] [sdap_ad_tokengroups_get_posix_members] (0x1000): [RID#34] Processing membership SID [S-1-5-21-4005887970-6952358-270221094-513]
(2024-07-17 16:08:49): [be[domain.lan]] [sss_domain_get_state] (0x1000): [RID#34] Domain domain.lan is Active
(2024-07-17 16:08:49): [be[domain.lan]] [sdap_ad_tokengroups_get_posix_members] (0x1000): [RID#34] Processing membership SID [S-1-5-21-4005887970-6952358-270221094-1150]
(2024-07-17 16:08:49): [be[domain.lan]] [sss_domain_get_state] (0x1000): [RID#34] Domain domain.lan is Active
(2024-07-17 16:08:49): [be[domain.lan]] [sdap_ad_tokengroups_get_posix_members] (0x1000): [RID#34] Processing membership SID [S-1-5-21-4005887970-6952358-270221094-1142]
(2024-07-17 16:08:49): [be[domain.lan]] [sss_domain_get_state] (0x1000): [RID#34] Domain domain.lan is Active
(2024-07-17 16:08:49): [be[domain.lan]] [sdap_ad_tokengroups_get_posix_members] (0x1000): [RID#34] Processing membership SID [S-1-18-1]
(2024-07-17 16:08:49): [be[domain.lan]] [sss_domain_get_state] (0x1000): [RID#34] Domain domain.lan is Active
(2024-07-17 16:08:49): [be[domain.lan]] [well_known_sid_to_name] (0x0040): [RID#34] handle_special_sids failed.
********************** BACKTRACE DUMP ENDS HERE *********************************

Answer 8 · 2024-07-18T08:53:48.000Z

any other ideas?

Answer 9 · 2024-07-18T11:34:05.000Z

handle_special_sids failed looks weird.
What SSSD version is this?

Answer 10 · 2024-07-18T11:37:11.000Z

im using a ubuntu 22.04 LTS btw.

its the 2.6.3

i have a second environment, where the password caching is working, in this however, its a oracle Linux 8.10 Host with a sssd version of 2.2.0