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

[ultimatebash]

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

[alexey-tikhonov]

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.

[ultimatebash]

thanks I will take a look

[ultimatebash]

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

[alexey-tikhonov]

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

[ultimatebash]

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

[alexey-tikhonov]

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?

[ultimatebash]

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/SSSD/sssd/pull/25]: REQ_TRACE: New request. [sssd.nss CID https://github.com/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/pull/26]: REQ_TRACE: New request. [sssd.nss CID https://github.com/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/pull/27]: REQ_TRACE: New request. [sssd.nss CID https://github.com/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/pull/28]: REQ_TRACE: New request. [sssd.nss CID https://github.com/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/pull/31]: REQ_TRACE: New request. [sssd.pam CID https://github.com/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/pull/32]: REQ_TRACE: New request. [sssd.nss CID https://github.com/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/pull/33]: REQ_TRACE: New request. [sssd.nss CID https://github.com/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/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/SSSD/sssd/pull/34]: REQ_TRACE: New request. [sssd.pam CID https://github.com/SSSD/sssd/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 *****

[ultimatebash]

any other ideas?

[alexey-tikhonov]

handle_special_sids failed looks weird. What SSSD version is this?

[ultimatebash]

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