[Freeipa-users] Problems after updating to OL 8.3 (Ootpa)

[Ronald Wimmer]

After upgrading our IPA servers AD user resolution seems to have stopped 
working.

id myADUser says:
id: ‘myADUser’: no such user

Why? The log say:

==> /var/log/sssd/sssd_nss.log <==
(2020-11-18  9:09:59): [nss] [accept_fd_handler] (0x0400): Client 
[0x55b92cb403e0][26] connected!
(2020-11-18  9:09:59): [nss] [sss_cmd_get_version] (0x0200): Received 
client version [1].
(2020-11-18  9:09:59): [nss] [sss_cmd_get_version] (0x0200): Offered 
version [1].
(2020-11-18  9:09:59): [nss] [nss_getby_name] (0x0400): Input name: myADUser
(2020-11-18  9:09:59): [nss] [cache_req_send] (0x0400): CR #0: New 
request 'User by name'
(2020-11-18  9:09:59): [nss] [cache_req_process_input] (0x0400): CR #0: 
Parsing input name [myADUser]
(2020-11-18  9:09:59): [nss] [sss_parse_name_for_domains] (0x0200): name 
'myADUser' matched without domain, user is myADUser
(2020-11-18  9:09:59): [nss] [nss_get_object_send] (0x0400): Client 
[0x55b92cb403e0][26]: sent cache request #0
(2020-11-18  9:09:59): [nss] [cache_req_set_name] (0x0400): CR #0: 
Setting name [myADUser]
(2020-11-18  9:09:59): [nss] [cache_req_select_domains] (0x0400): CR #0: 
Performing a multi-domain search
(2020-11-18  9:09:59): [nss] [cache_req_search_domains] (0x0400): CR #0: 
Search will check the cache and check the data provider
(2020-11-18  9:09:59): [nss] [cache_req_set_domain] (0x0400): CR #0: 
Using domain [implicit_files]
(2020-11-18  9:09:59): [nss] [cache_req_prepare_domain_data] (0x0400): 
CR #0: Preparing input data for domain [implicit_files] rules
(2020-11-18  9:09:59): [nss] [cache_req_search_send] (0x0400): CR #0: 
Looking up myADUser@implicit_files
(2020-11-18  9:09:59): [nss] [cache_req_search_ncache] (0x0400): CR #0: 
Checking negative cache for [myADUser@implicit_files]
(2020-11-18  9:09:59): [nss] [cache_req_search_ncache] (0x0400): CR #0: 
[myADUser@implicit_files] is not present in negative cache
(2020-11-18  9:09:59): [nss] [cache_req_search_cache] (0x0400): CR #0: 
Looking up [myADUser@implicit_files] in cache
(2020-11-18  9:09:59): [nss] [cache_req_search_cache] (0x0400): CR #0: 
Object [myADUser@implicit_files] was not found in cache
(2020-11-18  9:09:59): [nss] [cache_req_search_dp] (0x0400): CR #0: 
Looking up [myADUser@implicit_files] in data provider
(2020-11-18  9:09:59): [nss] [cache_req_search_cache] (0x0400): CR #0: 
Looking up [myADUser@implicit_files] in cache
(2020-11-18  9:09:59): [nss] [cache_req_search_cache] (0x0400): CR #0: 
Object [myADUser@implicit_files] was not found in cache
(2020-11-18  9:09:59): [nss] [cache_req_search_ncache_add_to_domain] 
(0x0400): CR #0: Adding [myADUser@implicit_files] to negative cache
(2020-11-18  9:09:59): [nss] [sss_ncache_set_str] (0x0400): Adding 
[NCE/USER/implicit_files/myADUser@implicit_files] to negative cache
(2020-11-18  9:09:59): [nss] [cache_req_set_domain] (0x0400): CR #0: 
Using domain [org.mydomain.at]
(2020-11-18  9:09:59): [nss] [cache_req_prepare_domain_data] (0x0400): 
CR #0: Preparing input data for domain [org.mydomain.at] rules
(2020-11-18  9:09:59): [nss] [cache_req_search_send] (0x0400): CR #0: 
Looking up myADUser(a)org.mydomain.at
(2020-11-18  9:09:59): [nss] [cache_req_search_ncache] (0x0400): CR #0: 
Checking negative cache for [myADUser(a)org.mydomain.at]
(2020-11-18  9:09:59): [nss] [cache_req_search_ncache] (0x0400): CR #0: 
[myADUser(a)org.mydomain.at] is not present in negative cache
(2020-11-18  9:09:59): [nss] [cache_req_search_cache] (0x0400): CR #0: 
Looking up [myADUser(a)org.mydomain.at] in cache
(2020-11-18  9:09:59): [nss] [cache_req_search_cache] (0x0400): CR #0: 
Object [myADUser(a)org.mydomain.at] was not found in cache
(2020-11-18  9:09:59): [nss] [cache_req_search_dp] (0x0400): CR #0: 
Looking up [myADUser(a)org.mydomain.at] in data provider
(2020-11-18  9:09:59): [nss] [sss_dp_get_account_send] (0x0400): 
Creating request for 
[org.mydomain.at][0x1][BE_REQ_USER][name=[e-mail]:-]

==> /var/log/sssd/sssd_linux.mydomain.at.log <==
(2020-11-18  9:09:59): [be[linux.mydomain.at]] 
[dp_get_account_info_send] (0x0200): Got request for 
[0x1][BE_REQ_USER][name=myADUser(a)org.mydomain.at]
(2020-11-18  9:09:59): [be[linux.mydomain.at]] [dp_attach_req] (0x0400): 
DP Request [Account #1]: New request. Flags [0x0001].
(2020-11-18  9:09:59): [be[linux.mydomain.at]] [dp_attach_req] (0x0400): 
Number of active DP request: 1
(2020-11-18  9:09:59): [be[linux.mydomain.at]] 
[sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with 
[(&(objectClass=ipaUserOverride)(uid=myADUser))][cn=Default Trust 
View,cn=views,cn=accounts,dc=linux,dc=mydomain,dc=at].
(2020-11-18  9:09:59): [be[linux.mydomain.at]] 
[sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no 
errmsg set
(2020-11-18  9:09:59): [be[linux.mydomain.at]] 
[ipa_s2n_get_acct_info_send] (0x0400): Sending request_type: 
[REQ_FULL_WITH_MEMBERS] for trust user [myADUser] to IPA server
(2020-11-18  9:09:59): [be[linux.mydomain.at]] [ipa_s2n_exop_send] 
(0x0400): Executing extended operation
(2020-11-18  9:09:59): [be[linux.mydomain.at]] [ipa_s2n_exop_done] 
(0x0040): ldap_extended_operation result: No such object(32), (null).
(2020-11-18  9:09:59): [be[linux.mydomain.at]] [sysdb_search_by_name] 
(0x0400): No such entry
(2020-11-18  9:09:59): [be[linux.mydomain.at]] [sysdb_delete_user] 
(0x0400): Error: 2 (No such file or directory)
(2020-11-18  9:09:59): [be[linux.mydomain.at]] [dp_req_done] (0x0400): 
DP Request [Account #1]: Request handler finished [0]: Success
(2020-11-18  9:09:59): [be[linux.mydomain.at]] [_dp_req_recv] (0x0400): 
DP Request [Account #1]: Receiving request data.
(2020-11-18  9:09:59): [be[linux.mydomain.at]] [dp_req_destructor] 
(0x0400): DP Request [Account #1]: Request removed.
(2020-11-18  9:09:59): [be[linux.mydomain.at]] [dp_req_destructor] 
(0x0400): Number of active DP request: 0
(2020-11-18  9:09:59): [be[linux.mydomain.at]] [sbus_issue_request_done] 
(0x0400): sssd.dataprovider.getAccountInfo: Success

==> /var/log/sssd/sssd_nss.log <==
(2020-11-18  9:09:59): [nss] [cache_req_search_cache] (0x0400): CR #0: 
Looking up [myADUser(a)org.mydomain.at] in cache
(2020-11-18  9:09:59): [nss] [cache_req_search_cache] (0x0400): CR #0: 
Object [myADUser(a)org.mydomain.at] was not found in cache
(2020-11-18  9:09:59): [nss] [cache_req_search_ncache_add_to_domain] 
(0x0400): CR #0: Adding [myADUser(a)org.mydomain.at] to negative cache
(2020-11-18  9:09:59): [nss] [sss_ncache_set_str] (0x0400): Adding 
[NCE/USER/org.mydomain.at/myADUser(a)org.mydomain.at] to negative cache
(2020-11-18  9:09:59): [nss] [cache_req_set_domain] (0x0400): CR #0: 
Using domain [linux.mydomain.at]
(2020-11-18  9:09:59): [nss] [cache_req_prepare_domain_data] (0x0400): 
CR #0: Preparing input data for domain [linux.mydomain.at] rules
(2020-11-18  9:09:59): [nss] [cache_req_search_send] (0x0400): CR #0: 
Looking up myADUser(a)linux.mydomain.at
(2020-11-18  9:09:59): [nss] [cache_req_search_ncache] (0x0400): CR #0: 
Checking negative cache for [myADUser(a)linux.mydomain.at]
(2020-11-18  9:09:59): [nss] [cache_req_search_ncache] (0x0400): CR #0: 
[myADUser(a)linux.mydomain.at] is not present in negative cache
(2020-11-18  9:09:59): [nss] [cache_req_search_cache] (0x0400): CR #0: 
Looking up [myADUser(a)linux.mydomain.at] in cache
(2020-11-18  9:09:59): [nss] [cache_req_search_cache] (0x0400): CR #0: 
Object [myADUser(a)linux.mydomain.at] was not found in cache
(2020-11-18  9:09:59): [nss] [cache_req_search_dp] (0x0400): CR #0: 
Looking up [myADUser(a)linux.mydomain.at] in data provider
(2020-11-18  9:09:59): [nss] [sss_dp_get_account_send] (0x0400): 
Creating request for 
[linux.mydomain.at][0x1][BE_REQ_USER][name=[e-mail]:-]

==> /var/log/sssd/sssd_linux.mydomain.at.log <==
(2020-11-18  9:09:59): [be[linux.mydomain.at]] 
[dp_get_account_info_send] (0x0200): Got request for 
[0x1][BE_REQ_USER][name=myADUser(a)linux.mydomain.at]
(2020-11-18  9:09:59): [be[linux.mydomain.at]] [dp_attach_req] (0x0400): 
DP Request [Account #2]: New request. Flags [0x0001].
(2020-11-18  9:09:59): [be[linux.mydomain.at]] [dp_attach_req] (0x0400): 
Number of active DP request: 1
(2020-11-18  9:09:59): [be[linux.mydomain.at]] 
[sdap_search_user_next_base] (0x0400): Searching for users with base 
[cn=accounts,dc=linux,dc=mydomain,dc=at]
(2020-11-18  9:09:59): [be[linux.mydomain.at]] 
[sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with 
[(&(uid=myADUser)(objectclass=posixAccount)(uid=*)(&(uidNumber=*)(!(uidNumber=0))))][cn=accounts,dc=linux,dc=mydomain,dc=at].
(2020-11-18  9:09:59): [be[linux.mydomain.at]] 
[sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no 
errmsg set
(2020-11-18  9:09:59): [be[linux.mydomain.at]] 
[sdap_search_user_process] (0x0400): Search for users, returned 0 results.
(2020-11-18  9:09:59): [be[linux.mydomain.at]] [sysdb_search_by_name] 
(0x0400): No such entry
(2020-11-18  9:09:59): [be[linux.mydomain.at]] [sysdb_delete_user] 
(0x0400): Error: 2 (No such file or directory)
(2020-11-18  9:09:59): [be[linux.mydomain.at]] [sysdb_search_by_name] 
(0x0400): No such entry
(2020-11-18  9:09:59): [be[linux.mydomain.at]] 
[ipa_id_get_account_info_orig_done] (0x0080): Object not found, ending 
request
(2020-11-18  9:09:59): [be[linux.mydomain.at]] [dp_req_done] (0x0400): 
DP Request [Account #2]: Request handler finished [0]: Success
(2020-11-18  9:09:59): [be[linux.mydomain.at]] [_dp_req_recv] (0x0400): 
DP Request [Account #2]: Receiving request data.
(2020-11-18  9:09:59): [be[linux.mydomain.at]] [dp_req_destructor] 
(0x0400): DP Request [Account #2]: Request removed.
(2020-11-18  9:09:59): [be[linux.mydomain.at]] [dp_req_destructor] 
(0x0400): Number of active DP request: 0
(2020-11-18  9:09:59): [be[linux.mydomain.at]] [sbus_issue_request_done] 
(0x0400): sssd.dataprovider.getAccountInfo: Success

==> /var/log/sssd/sssd_nss.log <==
(2020-11-18  9:09:59): [nss] [cache_req_search_cache] (0x0400): CR #0: 
Looking up [myADUser(a)linux.mydomain.at] in cache
(2020-11-18  9:09:59): [nss] [cache_req_search_cache] (0x0400): CR #0: 
Object [myADUser(a)linux.mydomain.at] was not found in cache
(2020-11-18  9:09:59): [nss] [cache_req_search_ncache_add_to_domain] 
(0x0400): CR #0: Adding [myADUser(a)linux.mydomain.at] to negative cache
(2020-11-18  9:09:59): [nss] [sss_ncache_set_str] (0x0400): Adding 
[NCE/USER/linux.mydomain.at/myADUser(a)linux.mydomain.at] to negative cache
(2020-11-18  9:09:59): [nss] [cache_req_set_domain] (0x0400): CR #0: 
Using domain [buero.mydomain.at]
(2020-11-18  9:09:59): [nss] [cache_req_prepare_domain_data] (0x0400): 
CR #0: Preparing input data for domain [buero.mydomain.at] rules
(2020-11-18  9:09:59): [nss] [cache_req_search_send] (0x0400): CR #0: 
Looking up myADUser(a)buero.mydomain.at
(2020-11-18  9:09:59): [nss] [cache_req_search_ncache] (0x0400): CR #0: 
Checking negative cache for [myADUser(a)buero.mydomain.at]
(2020-11-18  9:09:59): [nss] [cache_req_search_ncache] (0x0400): CR #0: 
[myADUser(a)buero.mydomain.at] is not present in negative cache
(2020-11-18  9:09:59): [nss] [cache_req_search_cache] (0x0400): CR #0: 
Looking up [myADUser(a)buero.mydomain.at] in cache
(2020-11-18  9:09:59): [nss] [cache_req_search_cache] (0x0400): CR #0: 
Object [myADUser(a)buero.mydomain.at] was not found in cache
(2020-11-18  9:09:59): [nss] [cache_req_search_dp] (0x0400): CR #0: 
Looking up [myADUser(a)buero.mydomain.at] in data provider
(2020-11-18  9:09:59): [nss] [sss_dp_get_account_send] (0x0400): 
Creating request for 
[buero.mydomain.at][0x1][BE_REQ_USER][name=[e-mail]:-]

==> /var/log/sssd/sssd_linux.mydomain.at.log <==
(2020-11-18  9:09:59): [be[linux.mydomain.at]] 
[dp_get_account_info_send] (0x0200): Got request for 
[0x1][BE_REQ_USER][name=myADUser(a)buero.mydomain.at]
(2020-11-18  9:09:59): [be[linux.mydomain.at]] [dp_attach_req] (0x0400): 
DP Request [Account #3]: New request. Flags [0x0001].
(2020-11-18  9:09:59): [be[linux.mydomain.at]] [dp_attach_req] (0x0400): 
Number of active DP request: 1
(2020-11-18  9:09:59): [be[linux.mydomain.at]] 
[sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with 
[(&(objectClass=ipaUserOverride)(uid=myADUser))][cn=Default Trust 
View,cn=views,cn=accounts,dc=linux,dc=mydomain,dc=at].
(2020-11-18  9:09:59): [be[linux.mydomain.at]] 
[sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no 
errmsg set
(2020-11-18  9:09:59): [be[linux.mydomain.at]] 
[ipa_s2n_get_acct_info_send] (0x0400): Sending request_type: 
[REQ_FULL_WITH_MEMBERS] for trust user [myADUser] to IPA server
(2020-11-18  9:09:59): [be[linux.mydomain.at]] [ipa_s2n_exop_send] 
(0x0400): Executing extended operation
(2020-11-18  9:10:00): [be[linux.mydomain.at]] [ipa_s2n_exop_done] 
(0x0040): ldap_extended_operation result: No such object(32), (null).
(2020-11-18  9:10:00): [be[linux.mydomain.at]] [sysdb_search_by_name] 
(0x0400): No such entry
(2020-11-18  9:10:00): [be[linux.mydomain.at]] [sysdb_delete_user] 
(0x0400): Error: 2 (No such file or directory)
(2020-11-18  9:10:00): [be[linux.mydomain.at]] [dp_req_done] (0x0400): 
DP Request [Account #3]: Request handler finished [0]: Success
(2020-11-18  9:10:00): [be[linux.mydomain.at]] [_dp_req_recv] (0x0400): 
DP Request [Account #3]: Receiving request data.
(2020-11-18  9:10:00): [be[linux.mydomain.at]] [dp_req_destructor] 
(0x0400): DP Request [Account #3]: Request removed.
(2020-11-18  9:10:00): [be[linux.mydomain.at]] [dp_req_destructor] 
(0x0400): Number of active DP request: 0
(2020-11-18  9:10:00): [be[linux.mydomain.at]] [sbus_issue_request_done] 
(0x0400): sssd.dataprovider.getAccountInfo: Success

==> /var/log/sssd/sssd_nss.log <==
(2020-11-18  9:10:00): [nss] [cache_req_search_cache] (0x0400): CR #0: 
Looking up [myADUser(a)buero.mydomain.at] in cache
(2020-11-18  9:10:00): [nss] [cache_req_search_cache] (0x0400): CR #0: 
Object [myADUser(a)buero.mydomain.at] was not found in cache
(2020-11-18  9:10:00): [nss] [cache_req_search_ncache_add_to_domain] 
(0x0400): CR #0: Adding [myADUser(a)buero.mydomain.at] to negative cache
(2020-11-18  9:10:00): [nss] [sss_ncache_set_str] (0x0400): Adding 
[NCE/USER/buero.mydomain.at/myADUser(a)buero.mydomain.at] to negative cache
(2020-11-18  9:10:00): [nss] [cache_req_set_domain] (0x0400): CR #0: 
Using domain [mydomain.at]
(2020-11-18  9:10:00): [nss] [cache_req_prepare_domain_data] (0x0400): 
CR #0: Preparing input data for domain [mydomain.at] rules
(2020-11-18  9:10:00): [nss] [cache_req_search_send] (0x0400): CR #0: 
Looking up myADUser(a)mydomain.at
(2020-11-18  9:10:00): [nss] [cache_req_search_ncache] (0x0400): CR #0: 
Checking negative cache for [myADUser(a)mydomain.at]
(2020-11-18  9:10:00): [nss] [cache_req_search_ncache] (0x0400): CR #0: 
[myADUser(a)mydomain.at] is not present in negative cache
(2020-11-18  9:10:00): [nss] [cache_req_search_cache] (0x0400): CR #0: 
Looking up [myADUser(a)mydomain.at] in cache
(2020-11-18  9:10:00): [nss] [cache_req_search_cache] (0x0400): CR #0: 
Object [myADUser(a)mydomain.at] was not found in cache
(2020-11-18  9:10:00): [nss] [cache_req_search_dp] (0x0400): CR #0: 
Looking up [myADUser(a)mydomain.at] in data provider
(2020-11-18  9:10:00): [nss] [sss_dp_get_account_send] (0x0400): 
Creating request for 
[mydomain.at][0x1][BE_REQ_USER][name=[e-mail]:-]

==> /var/log/sssd/sssd_linux.mydomain.at.log <==
(2020-11-18  9:10:00): [be[linux.mydomain.at]] 
[dp_get_account_info_send] (0x0200): Got request for 
[0x1][BE_REQ_USER][name=myADUser(a)mydomain.at]
(2020-11-18  9:10:00): [be[linux.mydomain.at]] [dp_attach_req] (0x0400): 
DP Request [Account #4]: New request. Flags [0x0001].
(2020-11-18  9:10:00): [be[linux.mydomain.at]] [dp_attach_req] (0x0400): 
Number of active DP request: 1
(2020-11-18  9:10:00): [be[linux.mydomain.at]] 
[sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with 
[(&(objectClass=ipaUserOverride)(uid=myADUser))][cn=Default Trust 
View,cn=views,cn=accounts,dc=linux,dc=mydomain,dc=at].
(2020-11-18  9:10:00): [be[linux.mydomain.at]] 
[sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no 
errmsg set
(2020-11-18  9:10:00): [be[linux.mydomain.at]] 
[ipa_s2n_get_acct_info_send] (0x0400): Sending request_type: 
[REQ_FULL_WITH_MEMBERS] for trust user [myADUser] to IPA server
(2020-11-18  9:10:00): [be[linux.mydomain.at]] [ipa_s2n_exop_send] 
(0x0400): Executing extended operation
(2020-11-18  9:10:00): [be[linux.mydomain.at]] [ipa_s2n_exop_done] 
(0x0040): ldap_extended_operation result: No such object(32), (null).
(2020-11-18  9:10:00): [be[linux.mydomain.at]] [sysdb_search_by_name] 
(0x0400): No such entry
(2020-11-18  9:10:00): [be[linux.mydomain.at]] [sysdb_delete_user] 
(0x0400): Error: 2 (No such file or directory)
(2020-11-18  9:10:00): [be[linux.mydomain.at]] [dp_req_done] (0x0400): 
DP Request [Account #4]: Request handler finished [0]: Success
(2020-11-18  9:10:00): [be[linux.mydomain.at]] [_dp_req_recv] (0x0400): 
DP Request [Account #4]: Receiving request data.
(2020-11-18  9:10:00): [be[linux.mydomain.at]] [dp_req_destructor] 
(0x0400): DP Request [Account #4]: Request removed.
(2020-11-18  9:10:00): [be[linux.mydomain.at]] [dp_req_destructor] 
(0x0400): Number of active DP request: 0
(2020-11-18  9:10:00): [be[linux.mydomain.at]] [sbus_issue_request_done] 
(0x0400): sssd.dataprovider.getAccountInfo: Success

==> /var/log/sssd/sssd_nss.log <==
(2020-11-18  9:10:00): [nss] [cache_req_search_cache] (0x0400): CR #0: 
Looking up [myADUser(a)mydomain.at] in cache
(2020-11-18  9:10:00): [nss] [cache_req_search_cache] (0x0400): CR #0: 
Object [myADUser(a)mydomain.at] was not found in cache
(2020-11-18  9:10:00): [nss] [cache_req_search_ncache_add_to_domain] 
(0x0400): CR #0: Adding [myADUser(a)mydomain.at] to negative cache
(2020-11-18  9:10:00): [nss] [sss_ncache_set_str] (0x0400): Adding 
[NCE/USER/mydomain.at/myADUser(a)mydomain.at] to negative cache
(2020-11-18  9:10:00): [nss] [cache_req_set_domain] (0x0400): CR #0: 
Using domain [tk.mydomain.at]
(2020-11-18  9:10:00): [nss] [cache_req_prepare_domain_data] (0x0400): 
CR #0: Preparing input data for domain [tk.mydomain.at] rules
(2020-11-18  9:10:00): [nss] [cache_req_search_send] (0x0400): CR #0: 
Looking up myADUser(a)tk.mydomain.at
(2020-11-18  9:10:00): [nss] [cache_req_search_ncache] (0x0400): CR #0: 
Checking negative cache for [myADUser(a)tk.mydomain.at]
(2020-11-18  9:10:00): [nss] [cache_req_search_ncache] (0x0400): CR #0: 
[myADUser(a)tk.mydomain.at] is not present in negative cache
(2020-11-18  9:10:00): [nss] [cache_req_search_cache] (0x0400): CR #0: 
Looking up [myADUser(a)tk.mydomain.at] in cache
(2020-11-18  9:10:00): [nss] [cache_req_search_cache] (0x0400): CR #0: 
Object [myADUser(a)tk.mydomain.at] was not found in cache
(2020-11-18  9:10:00): [nss] [cache_req_search_dp] (0x0400): CR #0: 
Looking up [myADUser(a)tk.mydomain.at] in data provider
(2020-11-18  9:10:00): [nss] [sss_dp_get_account_send] (0x0400): 
Creating request for 
[tk.mydomain.at][0x1][BE_REQ_USER][name=[e-mail]:-]

==> /var/log/sssd/sssd_linux.mydomain.at.log <==
(2020-11-18  9:10:00): [be[linux.mydomain.at]] 
[dp_get_account_info_send] (0x0200): Got request for 
[0x1][BE_REQ_USER][name=myADUser(a)tk.mydomain.at]
(2020-11-18  9:10:00): [be[linux.mydomain.at]] [dp_attach_req] (0x0400): 
DP Request [Account #5]: New request. Flags [0x0001].
(2020-11-18  9:10:00): [be[linux.mydomain.at]] [dp_attach_req] (0x0400): 
Number of active DP request: 1
(2020-11-18  9:10:00): [be[linux.mydomain.at]] 
[sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with 
[(&(objectClass=ipaUserOverride)(uid=myADUser))][cn=Default Trust 
View,cn=views,cn=accounts,dc=linux,dc=mydomain,dc=at].
(2020-11-18  9:10:00): [be[linux.mydomain.at]] 
[sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no 
errmsg set
(2020-11-18  9:10:00): [be[linux.mydomain.at]] 
[ipa_s2n_get_acct_info_send] (0x0400): Sending request_type: 
[REQ_FULL_WITH_MEMBERS] for trust user [myADUser] to IPA server
(2020-11-18  9:10:00): [be[linux.mydomain.at]] [ipa_s2n_exop_send] 
(0x0400): Executing extended operation
(2020-11-18  9:10:00): [be[linux.mydomain.at]] [ipa_s2n_exop_done] 
(0x0040): ldap_extended_operation result: No such object(32), (null).
(2020-11-18  9:10:00): [be[linux.mydomain.at]] [sysdb_search_by_name] 
(0x0400): No such entry
(2020-11-18  9:10:00): [be[linux.mydomain.at]] [sysdb_delete_user] 
(0x0400): Error: 2 (No such file or directory)
(2020-11-18  9:10:00): [be[linux.mydomain.at]] [dp_req_done] (0x0400): 
DP Request [Account #5]: Request handler finished [0]: Success
(2020-11-18  9:10:00): [be[linux.mydomain.at]] [_dp_req_recv] (0x0400): 
DP Request [Account #5]: Receiving request data.
(2020-11-18  9:10:00): [be[linux.mydomain.at]] [dp_req_destructor] 
(0x0400): DP Request [Account #5]: Request removed.
(2020-11-18  9:10:00): [be[linux.mydomain.at]] [dp_req_destructor] 
(0x0400): Number of active DP request: 0
(2020-11-18  9:10:00): [be[linux.mydomain.at]] [sbus_issue_request_done] 
(0x0400): sssd.dataprovider.getAccountInfo: Success

==> /var/log/sssd/sssd_nss.log <==
(2020-11-18  9:10:00): [nss] [cache_req_search_cache] (0x0400): CR #0: 
Looking up [myADUser(a)tk.mydomain.at] in cache
(2020-11-18  9:10:00): [nss] [cache_req_search_cache] (0x0400): CR #0: 
Object [myADUser(a)tk.mydomain.at] was not found in cache
(2020-11-18  9:10:00): [nss] [cache_req_search_ncache_add_to_domain] 
(0x0400): CR #0: Adding [myADUser(a)tk.mydomain.at] to negative cache
(2020-11-18  9:10:00): [nss] [sss_ncache_set_str] (0x0400): Adding 
[NCE/USER/tk.mydomain.at/myADUser(a)tk.mydomain.at] to negative cache
(2020-11-18  9:10:00): [nss] [cache_req_process_result] (0x0400): CR #0: 
Finished: Not found
(2020-11-18  9:10:00): [nss] [client_recv] (0x0200): Client disconnected!