Server side SSSD logs:
(Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x1][BE_REQ_USER][name=<MY USERNAME>@<AD DOMAIN>] (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #1]: New request. Flags [0x0001]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaUserOverride)(uid=<MY USERNAME>))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_srv_ad_acct_lookup_step] (0x0400): Looking up AD account (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_resolve_service_send] (0x0100): Trying to resolve service 'sd_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [resolve_srv_send] (0x0200): The status of SRV lookup is neutral (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [ad_srv_plugin_send] (0x0400): About to find domain controllers (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [ad_get_dc_servers_send] (0x0400): Looking up domain controllers in domain <AD DOMAIN> and site GMA (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [resolv_discover_srv_next_domain] (0x0400): SRV resolution of service 'ldap'. Will use DNS discovery domain 'GMA._sites.<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [resolv_getsrv_send] (0x0100): Trying to resolve SRV record of '_ldap._tcp.GMA._sites.<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [request_watch_destructor] (0x0400): Deleting request watch (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_discover_srv_done] (0x0400): Got answer. Processing... (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_discover_srv_done] (0x0400): Got 8 servers (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [ad_get_dc_servers_done] (0x0400): Found 8 domain controllers in domain GMA._sites.<AD DOMAIN> (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [ad_srv_plugin_dcs_done] (0x0400): About to locate suitable site (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_connect_host_send] (0x0400): Resolving host <DC1>.<AD DOMAIN> (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [resolv_gethostbyname_files_send] (0x0100): Trying to resolve A record of '<DC1>.<AD DOMAIN>' in files (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [resolv_gethostbyname_files_send] (0x0100): Trying to resolve AAAA record of '<DC1>.<AD DOMAIN>' in files (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [resolv_gethostbyname_next] (0x0200): No more address families to retry (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [resolv_gethostbyname_dns_query] (0x0100): Trying to resolve A record of '<DC1>.<AD DOMAIN>' in DNS (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [request_watch_destructor] (0x0400): Deleting request watch (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_connect_host_resolv_done] (0x0400): Connecting to ldap://<DC1>.<AD DOMAIN>:389 (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sssd_async_socket_init_send] (0x0400): Setting 6 seconds timeout for connecting (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_connect_host_done] (0x0400): Successful connection to ldap://<DC1>.<AD DOMAIN>:389 (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(DnsDomain=<AD DOMAIN>)(NtVer=\14\00\00\00))][]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [ad_get_client_site_done] (0x0400): Found site: GMA (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [ad_get_client_site_done] (0x0400): Found forest: <AD DOMAIN> (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [ad_srv_plugin_site_done] (0x0400): About to discover primary and backup servers (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_discover_servers_send] (0x0400): Looking up primary servers (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [resolv_discover_srv_next_domain] (0x0400): SRV resolution of service 'ldap'. Will use DNS discovery domain 'GMA._sites.<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [resolv_getsrv_send] (0x0100): Trying to resolve SRV record of '_ldap._tcp.GMA._sites.<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [request_watch_destructor] (0x0400): Deleting request watch (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_discover_srv_done] (0x0400): Got answer. Processing... (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_discover_srv_done] (0x0400): Got 8 servers (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_discover_servers_primary_done] (0x0400): Looking up backup servers (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [resolv_discover_srv_next_domain] (0x0400): SRV resolution of service 'ldap'. Will use DNS discovery domain '<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [resolv_getsrv_send] (0x0100): Trying to resolve SRV record of '_ldap._tcp.<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [request_watch_destructor] (0x0400): Deleting request watch (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_discover_srv_done] (0x0400): Got answer. Processing... (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_discover_srv_done] (0x0400): Got 13 servers (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [ad_srv_plugin_servers_done] (0x0400): Got 8 primary and 13 backup servers (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted primary server '<DC2>.<AD DOMAIN>:389' to service 'sd_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted primary server '<DC3>.<AD DOMAIN>:389' to service 'sd_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted primary server '<DC4>.<AD DOMAIN>:389' to service 'sd_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted primary server '<DC5>.<AD DOMAIN>:389' to service 'sd_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted primary server '<DC6>.<AD DOMAIN>:389' to service 'sd_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted primary server '<DC1>.<AD DOMAIN>:389' to service 'sd_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted primary server '<DC7>.<AD DOMAIN>:389' to service 'sd_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted primary server '<DC8>.<AD DOMAIN>:389' to service 'sd_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted backup server '<DC9>.<AD DOMAIN>:389' to service 'sd_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Server '<DC1>.<AD DOMAIN>:389' for service 'sd_<AD DOMAIN>' is already present (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted backup server '<DC10>.<AD DOMAIN>:389' to service 'sd_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Server '<DC6>.<AD DOMAIN>:389' for service 'sd_<AD DOMAIN>' is already present (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted backup server '<DC11>.<AD DOMAIN>:389' to service 'sd_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted backup server '<DC12>.<AD DOMAIN>:389' to service 'sd_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted backup server '<DC13>.<AD DOMAIN>:389' to service 'sd_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted backup server '<DC14>.<AD DOMAIN>:389' to service 'sd_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Server '<DC4>.<AD DOMAIN>:389' for service 'sd_<AD DOMAIN>' is already present (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Server '<DC7>.<AD DOMAIN>:389' for service 'sd_<AD DOMAIN>' is already present (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Server '<DC5>.<AD DOMAIN>:389' for service 'sd_<AD DOMAIN>' is already present (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Server '<DC2>.<AD DOMAIN>:389' for service 'sd_<AD DOMAIN>' is already present (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted backup server '<DC15>.<AD DOMAIN>:389' to service 'sd_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [set_srv_data_status] (0x0100): Marking SRV lookup of service 'sd_<AD DOMAIN>' as 'resolved' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [resolv_gethostbyname_files_send] (0x0100): Trying to resolve A record of '<DC2>.<AD DOMAIN>' in files (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [set_server_common_status] (0x0100): Marking server '<DC2>.<AD DOMAIN>' as 'resolving name' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [resolv_gethostbyname_files_send] (0x0100): Trying to resolve AAAA record of '<DC2>.<AD DOMAIN>' in files (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [resolv_gethostbyname_next] (0x0200): No more address families to retry (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [resolv_gethostbyname_dns_query] (0x0100): Trying to resolve A record of '<DC2>.<AD DOMAIN>' in DNS (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [request_watch_destructor] (0x0400): Deleting request watch (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [set_server_common_status] (0x0100): Marking server '<DC2>.<AD DOMAIN>' as 'name resolved' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [be_resolve_server_process] (0x0200): Found address for server <DC2>.<AD DOMAIN>: [<IP>] TTL 3600 (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [ad_resolve_callback] (0x0100): Constructed uri 'ldap://<DC2>.<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [ad_resolve_callback] (0x0100): Constructed GC uri 'ldap://<DC2>.<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sssd_async_socket_init_send] (0x0400): Setting 6 seconds timeout for connecting (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=*)][]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [get_naming_context] (0x0200): Using value from [defaultNamingContext] as naming context. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_set_search_base] (0x0100): Setting option [ldap_host_search_base] to [DC=AD,DC=AD,DC=AD,DC=AD]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [common_parse_search_base] (0x0100): Search base added: [HOST][DC=AD,DC=AD,DC=AD,DC=AD][SUBTREE][] (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_server_opts_from_rootdse] (0x0100): Setting AD compatibility level to [4] (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_server_opts_from_rootdse] (0x0100): Will look for schema at [CN=Schema,CN=Configuration,DC=AD,DC=AD,DC=AD] (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_kinit_send] (0x0400): Attempting kinit (/var/lib/sss/keytabs/<AD DOMAIN>.keytab, SOC$, <AD DOMAIN>, 86400) (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_resolve_service_send] (0x0100): Trying to resolve service 'sd_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [resolve_srv_send] (0x0200): The status of SRV lookup is resolved (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [be_resolve_server_process] (0x0200): Found address for server <DC2>.<AD DOMAIN>: [<IP>] TTL 3600 (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [create_tgt_req_send_buffer] (0x0400): buffer size: 88 (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [set_tgt_child_timeout] (0x0400): Setting 6 seconds timeout for TGT child (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [write_pipe_handler] (0x0400): All data has been sent! (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [child_sig_handler] (0x0100): child [19594] finished successfully. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [read_pipe_handler] (0x0400): EOF received, client finished (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_tgt_recv] (0x0400): Child responded: 0 [FILE:/var/lib/sss/db/ccache_<AD DOMAIN>], expired on [1566340837] (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_cli_auth_step] (0x0100): expire timeout is 900 (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sasl_bind_send] (0x0100): Executing sasl bind mech: gssapi, user: SOC$ (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_cli_connect_recv] (0x0400): Connection established. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_set_port_status] (0x0100): Marking port 389 of server '<DC2>.<AD DOMAIN>' as 'working' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [set_server_common_status] (0x0100): Marking server '<DC2>.<AD DOMAIN>' as 'working' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_set_port_status] (0x0400): Marking port 389 of duplicate server '<DC2>.<AD DOMAIN>' as 'working' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_search_user_next_base] (0x0400): Searching for users with base [dc=AD,dc=AD,dc=AD,dc=AD] (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(sAMAccountName=<MY USERNAME>)(objectclass=user)(sAMAccountName=*)(objectSID=*))][dc=AD,dc=AD,dc=AD,dc=AD]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [be_run_online_cb] (0x0080): Going online. Running callbacks. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_search_user_process] (0x0400): Search for users, returned 1 results. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_user] (0x0400): Save user (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_primary_name] (0x0400): Processing object <MY USERNAME> (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_user] (0x0400): Processing user <MY USERNAME>@<AD DOMAIN> (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_user] (0x0400): Adding original memberOf attributes to [<MY USERNAME>@<AD DOMAIN>]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_user] (0x0400): Adding user principal [<MY USERNAME>@<AD DOMAIN>] to attributes of [<MY USERNAME>@<AD DOMAIN>]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_user] (0x0400): Storing info for user <MY USERNAME>@<AD DOMAIN> (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_set_entry_attr] (0x0200): Entry [name=<MY USERNAME>@<AD DOMAIN>,cn=users,cn=<AD DOMAIN>,cn=sysdb] has set [cache, ts_cache] attrs. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_set_entry_attr] (0x0200): Entry [name=<MY USERNAME>@<AD DOMAIN>,cn=users,cn=<AD DOMAIN>,cn=sysdb] has set [cache, ts_cache] attrs. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1361432))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1503411))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [ad_online_cb] (0x0400): The AD provider is online (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1266082))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1253067))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1140136))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1503412))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1366593))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1008666))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1518318))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1181689))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1443520))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-513))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1112640))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1598605))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1320769))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-926787))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1138107))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1366580))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1354727))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-988735))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-1144159806-1873613549-2446533747-77016))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-1144159806-1873613549-2446533747-77017))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_search_bases_ex_next_base] (0x0400): Issuing LDAP lookup with base [cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa] (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [objectClass=ipaexternalgroup][cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_search_bases_ex_done] (0x0400): Receiving data from base [cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa] (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_get_ext_groups_done] (0x0400): [1] external groups found. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_mod_group_member] (0x0080): ldb_modify failed: [Attribute or value exists](20)[attribute 'member': value #14 on 'name=<POSIX GROUP 1>@<IPA DOMAIN>,cn=groups,cn=<IPA DOMAIN>,cn=sysdb' already exists] (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_mod_group_member] (0x0400): Error: 17 (File exists) (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_set_entry_attr] (0x0200): Entry [name=<MY USERNAME>@<AD DOMAIN>,cn=users,cn=<AD DOMAIN>,cn=sysdb] has set [cache, ts_cache] attrs. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_mod_group_member] (0x0080): ldb_modify failed: [Attribute or value exists](20)[attribute 'member': value #14 on 'name=<POSIX GROUP 2>@<IPA DOMAIN>,cn=groups,cn=<IPA DOMAIN>,cn=sysdb' already exists] (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_mod_group_member] (0x0400): Error: 17 (File exists) (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_set_entry_attr] (0x0200): Entry [name=<MY USERNAME>@<AD DOMAIN>,cn=users,cn=<AD DOMAIN>,cn=sysdb] has set [cache, ts_cache] attrs. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa] (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(cn=98def1e4-bde5-11e9-813b-005056bf293e)(|(objectClass=ipaUserGroup)(objectClass=posixGroup))(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [be_ptask_online_cb] (0x0400): Back end is online (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [be_ptask_enable] (0x0080): Task [Subdomains Refresh]: already enabled (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 0 results. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_add_ad_memberships_get_next] (0x0020): There are unresolved external group memberships even after all groups have been looked up on the LDAP server. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #1]: Request handler finished [0]: Success (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #1]: Receiving request data. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #1]: Finished. Success. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:1::<AD DOMAIN>:name=<MY USERNAME>@<AD DOMAIN>] from reply table (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #1]: Request removed. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [be_ptask_online_cb] (0x0400): Back end is online (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [be_ptask_enable] (0x0080): Task [SUDO Smart Refresh]: already enabled (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [be_ptask_online_cb] (0x0400): Back end is online (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [be_ptask_enable] (0x0080): Task [SUDO Full Refresh]: already enabled (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x3][BE_REQ_INITGROUPS][name=<MY USERNAME>@<AD DOMAIN>] (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Initgroups #2]: New request. Flags [0x0001]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaUserOverride)(uid=<MY USERNAME>))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_srv_ad_acct_lookup_step] (0x0400): Looking up AD account (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_resolve_service_send] (0x0100): Trying to resolve service 'sd_gc_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [resolve_srv_send] (0x0200): The status of SRV lookup is neutral (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [ad_srv_plugin_send] (0x0400): About to find domain controllers (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [ad_get_dc_servers_send] (0x0400): Looking up domain controllers in domain <AD DOMAIN> and site GMA (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [resolv_discover_srv_next_domain] (0x0400): SRV resolution of service 'ldap'. Will use DNS discovery domain 'GMA._sites.<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [resolv_getsrv_send] (0x0100): Trying to resolve SRV record of '_ldap._tcp.GMA._sites.<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [request_watch_destructor] (0x0400): Deleting request watch (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_discover_srv_done] (0x0400): Got answer. Processing... (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_discover_srv_done] (0x0400): Got 8 servers (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [ad_get_dc_servers_done] (0x0400): Found 8 domain controllers in domain GMA._sites.<AD DOMAIN> (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [ad_srv_plugin_dcs_done] (0x0400): About to locate suitable site (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_connect_host_send] (0x0400): Resolving host <DC2>.<AD DOMAIN> (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [resolv_gethostbyname_files_send] (0x0100): Trying to resolve A record of '<DC2>.<AD DOMAIN>' in files (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [resolv_gethostbyname_files_send] (0x0100): Trying to resolve AAAA record of '<DC2>.<AD DOMAIN>' in files (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [resolv_gethostbyname_next] (0x0200): No more address families to retry (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [resolv_gethostbyname_dns_query] (0x0100): Trying to resolve A record of '<DC2>.<AD DOMAIN>' in DNS (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [request_watch_destructor] (0x0400): Deleting request watch (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_connect_host_resolv_done] (0x0400): Connecting to ldap://<DC2>.<AD DOMAIN>:389 (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sssd_async_socket_init_send] (0x0400): Setting 6 seconds timeout for connecting (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_connect_host_done] (0x0400): Successful connection to ldap://<DC2>.<AD DOMAIN>:389 (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(DnsDomain=<AD DOMAIN>)(NtVer=\14\00\00\00))][]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [ad_get_client_site_done] (0x0400): Found site: GMA (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [ad_get_client_site_done] (0x0400): Found forest: <AD DOMAIN> (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [ad_srv_plugin_site_done] (0x0400): About to discover primary and backup servers (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_discover_servers_send] (0x0400): Looking up primary servers (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [resolv_discover_srv_next_domain] (0x0400): SRV resolution of service 'gc'. Will use DNS discovery domain 'GMA._sites.<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [resolv_getsrv_send] (0x0100): Trying to resolve SRV record of '_gc._tcp.GMA._sites.<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [request_watch_destructor] (0x0400): Deleting request watch (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_discover_srv_done] (0x0400): Got answer. Processing... (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_discover_srv_done] (0x0400): Got 11 servers (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_discover_servers_primary_done] (0x0400): Looking up backup servers (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [resolv_discover_srv_next_domain] (0x0400): SRV resolution of service 'gc'. Will use DNS discovery domain '<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [resolv_getsrv_send] (0x0100): Trying to resolve SRV record of '_gc._tcp.<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [request_watch_destructor] (0x0400): Deleting request watch (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_discover_srv_done] (0x0400): Got answer. Processing... (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_discover_srv_done] (0x0400): Got 18 servers (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [ad_srv_plugin_servers_done] (0x0400): Got 11 primary and 18 backup servers (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted primary server '<DC2>.<AD DOMAIN>:3268' to service 'sd_gc_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted primary server '<DC4>.<AD DOMAIN>:3268' to service 'sd_gc_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted primary server '<DC8>.<AD DOMAIN>:3268' to service 'sd_gc_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted primary server '<DC7>.<AD DOMAIN>:3268' to service 'sd_gc_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted primary server '<DC6>.<AD DOMAIN>:3268' to service 'sd_gc_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted primary server '<DC5>.<AD DOMAIN>:3268' to service 'sd_gc_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted primary server '<DC1>.<AD DOMAIN>:3268' to service 'sd_gc_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted primary server '<DC3>.<AD DOMAIN>:3268' to service 'sd_gc_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted primary server '<DC16>.<AD DOMAIN>:3268' to service 'sd_gc_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted primary server '<DC17>.<AD DOMAIN>:3268' to service 'sd_gc_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted primary server '<DC18>.<AD DOMAIN>:3268' to service 'sd_gc_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Server '<DC7>.<AD DOMAIN>:3268' for service 'sd_gc_<AD DOMAIN>' is already present (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted backup server '<DC9>.<AD DOMAIN>:3268' to service 'sd_gc_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Server '<DC1>.<AD DOMAIN>:3268' for service 'sd_gc_<AD DOMAIN>' is already present (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Server '<DC16>.<AD DOMAIN>:3268' for service 'sd_gc_<AD DOMAIN>' is already present (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted backup server '<DC10>.<AD DOMAIN>:3268' to service 'sd_gc_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Server '<DC5>.<AD DOMAIN>:3268' for service 'sd_gc_<AD DOMAIN>' is already present (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Server '<DC4>.<AD DOMAIN>:3268' for service 'sd_gc_<AD DOMAIN>' is already present (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted backup server '<DC15>.<AD DOMAIN>:3268' to service 'sd_gc_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted backup server '<DC14>.<AD DOMAIN>:3268' to service 'sd_gc_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted backup server '<DC13>.<AD DOMAIN>:3268' to service 'sd_gc_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted backup server '<DC11>.<AD DOMAIN>:3268' to service 'sd_gc_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted backup server '<DC12>.<AD DOMAIN>:3268' to service 'sd_gc_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Server '<DC2>.<AD DOMAIN>:3268' for service 'sd_gc_<AD DOMAIN>' is already present (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Server '<DC6>.<AD DOMAIN>:3268' for service 'sd_gc_<AD DOMAIN>' is already present (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted backup server '<DC19>.<AD DOMAIN>:3268' to service 'sd_gc_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Server '<DC17>.<AD DOMAIN>:3268' for service 'sd_gc_<AD DOMAIN>' is already present (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted backup server '<DC20>.<AD DOMAIN>:3268' to service 'sd_gc_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_add_server_to_list] (0x0400): Inserted backup server '<DC21>.<AD DOMAIN>:3268' to service 'sd_gc_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [set_srv_data_status] (0x0100): Marking SRV lookup of service 'sd_gc_<AD DOMAIN>' as 'resolved' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [be_resolve_server_process] (0x0200): Found address for server <DC2>.<AD DOMAIN>: [<IP>] TTL 3600 (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [ad_resolve_callback] (0x0100): Constructed uri 'ldap://<DC2>.<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [ad_resolve_callback] (0x0100): Constructed GC uri 'ldap://<DC2>.<AD DOMAIN>:3268' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sssd_async_socket_init_send] (0x0400): Setting 6 seconds timeout for connecting (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=*)][]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_server_opts_from_rootdse] (0x0100): Setting AD compatibility level to [4] (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_server_opts_from_rootdse] (0x0100): Will look for schema at [CN=Schema,CN=Configuration,DC=AD,DC=AD,DC=AD] (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_kinit_send] (0x0400): Attempting kinit (/var/lib/sss/keytabs/<AD DOMAIN>.keytab, SOC$, <AD DOMAIN>, 86400) (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_resolve_service_send] (0x0100): Trying to resolve service 'sd_<AD DOMAIN>' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [resolve_srv_send] (0x0200): The status of SRV lookup is resolved (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [be_resolve_server_process] (0x0200): Found address for server <DC2>.<AD DOMAIN>: [<IP>] TTL 3600 (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [create_tgt_req_send_buffer] (0x0400): buffer size: 88 (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [set_tgt_child_timeout] (0x0400): Setting 6 seconds timeout for TGT child (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [delayed_online_authentication_callback] (0x0200): Backend is online, starting delayed online authentication. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [write_pipe_handler] (0x0400): All data has been sent! (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [child_sig_handler] (0x0100): child [19596] finished successfully. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [read_pipe_handler] (0x0400): EOF received, client finished (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_tgt_recv] (0x0400): Child responded: 0 [FILE:/var/lib/sss/db/ccache_<AD DOMAIN>], expired on [1566340837] (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_cli_auth_step] (0x0100): expire timeout is 900 (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sasl_bind_send] (0x0100): Executing sasl bind mech: gssapi, user: SOC$ (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_cli_connect_recv] (0x0400): Connection established. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_set_port_status] (0x0100): Marking port 3268 of server '<DC2>.<AD DOMAIN>' as 'working' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [set_server_common_status] (0x0100): Marking server '<DC2>.<AD DOMAIN>' as 'working' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [fo_set_port_status] (0x0400): Marking port 3268 of duplicate server '<DC2>.<AD DOMAIN>' as 'working' (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_initgr_next_base] (0x0400): Searching for users with base [dc=AD,dc=AD,dc=AD,dc=AD] (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(sAMAccountName=<MY USERNAME>)(objectclass=user)(objectSID=*))][dc=AD,dc=AD,dc=AD,dc=AD]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_user] (0x0400): Save user (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_primary_name] (0x0400): Processing object <MY USERNAME> (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_user] (0x0400): Processing user <MY USERNAME>@<AD DOMAIN> (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_user] (0x0400): Adding original memberOf attributes to [<MY USERNAME>@<AD DOMAIN>]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_user] (0x0400): Adding user principal [<MY USERNAME>@<AD DOMAIN>] to attributes of [<MY USERNAME>@<AD DOMAIN>]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_user] (0x0400): Storing info for user <MY USERNAME>@<AD DOMAIN> (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_set_entry_attr] (0x0200): Entry [name=<MY USERNAME>@<AD DOMAIN>,cn=users,cn=<AD DOMAIN>,cn=sysdb] has set [cache, ts_cache] attrs. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [no filter][CN=<MY USERNAME>,OU=<USERS>,OU=<COMPANY>,OU=Business Units,DC=AD,DC=AD,DC=AD,DC=AD]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_ad_tokengroups_get_posix_members] (0x0080): Domain not found for SID S-1-5-21-1851355360-2027500884-774919444-153082 (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_initgr_done] (0x0400): Primary group already cached, nothing to do. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_set_entry_attr] (0x0200): Entry [name=<MY USERNAME>@<AD DOMAIN>,cn=users,cn=<AD DOMAIN>,cn=sysdb] has set [cache, ts_cache] attrs. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1361432))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1503411))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1266082))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1253067))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1140136))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1503412))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1366593))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1008666))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1518318))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1181689))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1443520))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-513))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1112640))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1598605))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1320769))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-926787))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1138107))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1366580))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1354727))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-988735))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-1144159806-1873613549-2446533747-77016))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_get_ad_memberships_send] (0x0400): External group information still valid. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_set_entry_attr] (0x0200): Entry [name=<MY USERNAME>@<AD DOMAIN>,cn=users,cn=<AD DOMAIN>,cn=sysdb] has set [cache, ts_cache] attrs. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_set_entry_attr] (0x0200): Entry [name=<MY USERNAME>@<AD DOMAIN>,cn=users,cn=<AD DOMAIN>,cn=sysdb] has set [cache, ts_cache] attrs. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa] (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(cn=98def1e4-bde5-11e9-813b-005056bf293e)(|(objectClass=ipaUserGroup)(objectClass=posixGroup))(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 0 results. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_add_ad_memberships_get_next] (0x0020): There are unresolved external group memberships even after all groups have been looked up on the LDAP server. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Initgroups #2]: Request handler finished [0]: Success (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Initgroups #2]: Receiving request data. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_set_entry_attr] (0x0200): Entry [name=<MY USERNAME>@<AD DOMAIN>,cn=users,cn=<AD DOMAIN>,cn=sysdb] has set [ts_cache] attrs. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_initgr_pp_nss_notify] (0x0400): Ordering NSS responder to update memory cache (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Initgroups #2]: Finished. Success. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:3::<AD DOMAIN>:name=<MY USERNAME>@<AD DOMAIN>] from reply table (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Initgroups #2]: Request removed. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [AccountDomain #3]: New request. Flags [0000]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [AccountDomain #3]: Request handler finished [0]: Success (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [AccountDomain #3]: Receiving request data. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [AccountDomain #3]: Finished. Success. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:10:0000:0x55be877fa480] from reply table (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [AccountDomain #3]: Request removed. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=3361432] (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #4]: New request. Flags [0x0001]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa] (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(gidNumber=3361432)(|(objectClass=ipaUserGroup)(objectClass=posixGroup))(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 0 results. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_search_group_by_gid] (0x0400): No such entry (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_delete_group] (0x0400): Error: 2 (No such file or directory) (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_id_get_account_info_orig_done] (0x0080): Object not found, ending request (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #4]: Request handler finished [0]: Success (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #4]: Receiving request data. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #4]: Finished. Success. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<IPA DOMAIN>:idnumber=3361432] from reply table (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #4]: Request removed. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=3503411] (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #5]: New request. Flags [0x0001]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa] (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(gidNumber=3503411)(|(objectClass=ipaUserGroup)(objectClass=posixGroup))(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 0 results. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_search_group_by_gid] (0x0400): No such entry (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_delete_group] (0x0400): Error: 2 (No such file or directory) (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_id_get_account_info_orig_done] (0x0080): Object not found, ending request (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #5]: Request handler finished [0]: Success (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #5]: Receiving request data. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #5]: Finished. Success. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<IPA DOMAIN>:idnumber=3503411] from reply table (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #5]: Request removed. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=3503411] (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #6]: New request. Flags [0x0001]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaGroupOverride)(gidNumber=3503411))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_srv_ad_acct_lookup_step] (0x0400): Looking up AD account (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=AD,dc=AD,dc=AD,dc=AD] (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-2129867641-1448237841-168566570-1503411)(objectClass=group)(sAMAccountName=*))][dc=AD,dc=AD,dc=AD,dc=AD]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 0 users found in the hash table (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_primary_name] (0x0400): Processing object <AD GROUP> (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Processing group <AD GROUP>@<AD DOMAIN> (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): The group has 0 members (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): Group has 0 members (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Storing info for group <AD GROUP>@<AD DOMAIN> (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_set_entry_attr] (0x0200): Entry [name=<AD GROUP>@<AD DOMAIN>,cn=groups,cn=<AD DOMAIN>,cn=sysdb] has set [ts_cache] attrs. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_grpmem] (0x0020): Group members are ignored, nothing to do. If you see this message it might indicate an error in the group processing logic. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [apply_subdomain_homedir] (0x0040): UID for user [<AD GROUP>@<AD DOMAIN>] is not known. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1503411))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #6]: Request handler finished [0]: Success (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #6]: Receiving request data. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #6]: Finished. Success. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<AD DOMAIN>:idnumber=3503411] from reply table (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #6]: Request removed. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=3266082] (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #7]: New request. Flags [0x0001]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa] (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(gidNumber=3266082)(|(objectClass=ipaUserGroup)(objectClass=posixGroup))(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 0 results. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_search_group_by_gid] (0x0400): No such entry (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_delete_group] (0x0400): Error: 2 (No such file or directory) (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_id_get_account_info_orig_done] (0x0080): Object not found, ending request (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #7]: Request handler finished [0]: Success (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #7]: Receiving request data. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #7]: Finished. Success. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<IPA DOMAIN>:idnumber=3266082] from reply table (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #7]: Request removed. (Tue Aug 20 08:40:37 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=3266082] (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #8]: New request. Flags [0x0001]. (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaGroupOverride)(gidNumber=3266082))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_srv_ad_acct_lookup_step] (0x0400): Looking up AD account (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=AD,dc=AD,dc=AD,dc=AD] (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-2129867641-1448237841-168566570-1266082)(objectClass=group)(sAMAccountName=*))][dc=AD,dc=AD,dc=AD,dc=AD]. (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results. (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 0 users found in the hash table (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_primary_name] (0x0400): Processing object <AD GROUP 2> (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Processing group <AD GROUP 2>@<AD DOMAIN> (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): The group has 0 members (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): Group has 0 members (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Storing info for group <AD GROUP 2>@<AD DOMAIN> (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_set_entry_attr] (0x0200): Entry [name=<AD GROUP 2>@<AD DOMAIN>,cn=groups,cn=<AD DOMAIN>,cn=sysdb] has set [ts_cache] attrs. (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_grpmem] (0x0020): Group members are ignored, nothing to do. If you see this message it might indicate an error in the group processing logic. (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [apply_subdomain_homedir] (0x0040): UID for user [<AD GROUP 2>@<AD DOMAIN>] is not known. (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1266082))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #8]: Request handler finished [0]: Success (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #8]: Receiving request data. (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #8]: Finished. Success. (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<AD DOMAIN>:idnumber=3266082] from reply table (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #8]: Request removed. (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=3253067] (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #9]: New request. Flags [0x0001]. (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa] (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(gidNumber=3253067)(|(objectClass=ipaUserGroup)(objectClass=posixGroup))(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 0 results. (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_search_group_by_gid] (0x0400): No such entry (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_delete_group] (0x0400): Error: 2 (No such file or directory) (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_id_get_account_info_orig_done] (0x0080): Object not found, ending request (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #9]: Request handler finished [0]: Success (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #9]: Receiving request data. (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #9]: Finished. Success. (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<IPA DOMAIN>:idnumber=3253067] from reply table (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #9]: Request removed. (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=3253067] (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #10]: New request. Flags [0x0001]. (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaGroupOverride)(gidNumber=3253067))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_srv_ad_acct_lookup_step] (0x0400): Looking up AD account (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=AD,dc=AD,dc=AD,dc=AD] (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-2129867641-1448237841-168566570-1253067)(objectClass=group)(sAMAccountName=*))][dc=AD,dc=AD,dc=AD,dc=AD]. (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results. (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 0 users found in the hash table (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_primary_name] (0x0400): Processing object <AD GROUP 3> (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Processing group <AD GROUP 3>@<AD DOMAIN> (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): The group has 0 members (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): Group has 0 members (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Storing info for group <AD GROUP 3>@<AD DOMAIN> (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_set_entry_attr] (0x0200): Entry [name=<AD GROUP 3>@<AD DOMAIN>,cn=groups,cn=<AD DOMAIN>,cn=sysdb] has set [ts_cache] attrs. (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_grpmem] (0x0020): Group members are ignored, nothing to do. If you see this message it might indicate an error in the group processing logic. (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [apply_subdomain_homedir] (0x0040): UID for user [<AD GROUP 3>@<AD DOMAIN>] is not known. (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1253067))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #10]: Request handler finished [0]: Success (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #10]: Receiving request data. (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #10]: Finished. Success. (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<AD DOMAIN>:idnumber=3253067] from reply table (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #10]: Request removed. (Tue Aug 20 08:40:38 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=3140136] (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #11]: New request. Flags [0x0001]. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa] (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(gidNumber=3140136)(|(objectClass=ipaUserGroup)(objectClass=posixGroup))(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 0 results. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_search_group_by_gid] (0x0400): No such entry (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_delete_group] (0x0400): Error: 2 (No such file or directory) (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_id_get_account_info_orig_done] (0x0080): Object not found, ending request (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #11]: Request handler finished [0]: Success (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #11]: Receiving request data. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #11]: Finished. Success. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<IPA DOMAIN>:idnumber=3140136] from reply table (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #11]: Request removed. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=3140136] (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #12]: New request. Flags [0x0001]. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaGroupOverride)(gidNumber=3140136))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_srv_ad_acct_lookup_step] (0x0400): Looking up AD account (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=AD,dc=AD,dc=AD,dc=AD] (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-2129867641-1448237841-168566570-1140136)(objectClass=group)(sAMAccountName=*))][dc=AD,dc=AD,dc=AD,dc=AD]. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 0 users found in the hash table (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_primary_name] (0x0400): Processing object <AD GROUP 4> (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Processing group <AD GROUP 4>@<AD DOMAIN> (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): The group has 0 members (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): Group has 0 members (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Storing info for group <AD GROUP 4>@<AD DOMAIN> (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_set_entry_attr] (0x0200): Entry [name=<AD GROUP 4>@<AD DOMAIN>,cn=groups,cn=<AD DOMAIN>,cn=sysdb] has set [ts_cache] attrs. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_grpmem] (0x0020): Group members are ignored, nothing to do. If you see this message it might indicate an error in the group processing logic. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [apply_subdomain_homedir] (0x0040): UID for user [<AD GROUP 4>@<AD DOMAIN>] is not known. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1140136))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #12]: Request handler finished [0]: Success (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #12]: Receiving request data. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #12]: Finished. Success. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<AD DOMAIN>:idnumber=3140136] from reply table (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #12]: Request removed. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=3503412] (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #13]: New request. Flags [0x0001]. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa] (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(gidNumber=3503412)(|(objectClass=ipaUserGroup)(objectClass=posixGroup))(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 0 results. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_search_group_by_gid] (0x0400): No such entry (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_delete_group] (0x0400): Error: 2 (No such file or directory) (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_id_get_account_info_orig_done] (0x0080): Object not found, ending request (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #13]: Request handler finished [0]: Success (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #13]: Receiving request data. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #13]: Finished. Success. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<IPA DOMAIN>:idnumber=3503412] from reply table (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #13]: Request removed. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=3503412] (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #14]: New request. Flags [0x0001]. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaGroupOverride)(gidNumber=3503412))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_srv_ad_acct_lookup_step] (0x0400): Looking up AD account (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=AD,dc=AD,dc=AD,dc=AD] (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-2129867641-1448237841-168566570-1503412)(objectClass=group)(sAMAccountName=*))][dc=AD,dc=AD,dc=AD,dc=AD]. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 0 users found in the hash table (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_primary_name] (0x0400): Processing object <AD GROUP 5> (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Processing group <AD GROUP 5>@<AD DOMAIN> (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): The group has 0 members (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): Group has 0 members (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Storing info for group <AD GROUP 5>@<AD DOMAIN> (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_set_entry_attr] (0x0200): Entry [name=<AD GROUP 5>@<AD DOMAIN>,cn=groups,cn=<AD DOMAIN>,cn=sysdb] has set [ts_cache] attrs. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_grpmem] (0x0020): Group members are ignored, nothing to do. If you see this message it might indicate an error in the group processing logic. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [apply_subdomain_homedir] (0x0040): UID for user [<AD GROUP 5>@<AD DOMAIN>] is not known. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1503412))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #14]: Request handler finished [0]: Success (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #14]: Receiving request data. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #14]: Finished. Success. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<AD DOMAIN>:idnumber=3503412] from reply table (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #14]: Request removed. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=3366593] (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #15]: New request. Flags [0x0001]. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa] (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(gidNumber=3366593)(|(objectClass=ipaUserGroup)(objectClass=posixGroup))(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 0 results. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_search_group_by_gid] (0x0400): No such entry (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_delete_group] (0x0400): Error: 2 (No such file or directory) (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_id_get_account_info_orig_done] (0x0080): Object not found, ending request (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #15]: Request handler finished [0]: Success (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #15]: Receiving request data. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #15]: Finished. Success. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<IPA DOMAIN>:idnumber=3366593] from reply table (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #15]: Request removed. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=3366593] (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #16]: New request. Flags [0x0001]. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaGroupOverride)(gidNumber=3366593))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_srv_ad_acct_lookup_step] (0x0400): Looking up AD account (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=AD,dc=AD,dc=AD,dc=AD] (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-2129867641-1448237841-168566570-1366593)(objectClass=group)(sAMAccountName=*))][dc=AD,dc=AD,dc=AD,dc=AD]. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 0 users found in the hash table (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_primary_name] (0x0400): Processing object <AD GROUP 6> (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Processing group <AD GROUP 6>@<AD DOMAIN> (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): The group has 0 members (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): Group has 0 members (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Storing info for group <AD GROUP 6>@<AD DOMAIN> (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_set_entry_attr] (0x0200): Entry [name=<AD GROUP 6>@<AD DOMAIN>,cn=groups,cn=<AD DOMAIN>,cn=sysdb] has set [ts_cache] attrs. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_grpmem] (0x0020): Group members are ignored, nothing to do. If you see this message it might indicate an error in the group processing logic. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [apply_subdomain_homedir] (0x0040): UID for user [<AD GROUP 6>@<AD DOMAIN>] is not known. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1366593))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #16]: Request handler finished [0]: Success (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #16]: Receiving request data. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #16]: Finished. Success. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<AD DOMAIN>:idnumber=3366593] from reply table (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #16]: Request removed. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=3008666] (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #17]: New request. Flags [0x0001]. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa] (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(gidNumber=3008666)(|(objectClass=ipaUserGroup)(objectClass=posixGroup))(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 0 results. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_search_group_by_gid] (0x0400): No such entry (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_delete_group] (0x0400): Error: 2 (No such file or directory) (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_id_get_account_info_orig_done] (0x0080): Object not found, ending request (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #17]: Request handler finished [0]: Success (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #17]: Receiving request data. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #17]: Finished. Success. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<IPA DOMAIN>:idnumber=3008666] from reply table (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #17]: Request removed. (Tue Aug 20 08:40:39 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:41 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=3008666] (Tue Aug 20 08:40:41 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #18]: New request. Flags [0x0001]. (Tue Aug 20 08:40:41 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:41 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaGroupOverride)(gidNumber=3008666))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:41 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:41 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_srv_ad_acct_lookup_step] (0x0400): Looking up AD account (Tue Aug 20 08:40:41 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=AD,dc=AD,dc=AD,dc=AD] (Tue Aug 20 08:40:41 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-2129867641-1448237841-168566570-1008666)(objectClass=group)(sAMAccountName=*))][dc=AD,dc=AD,dc=AD,dc=AD]. (Tue Aug 20 08:40:41 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:41 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results. (Tue Aug 20 08:40:41 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ (Tue Aug 20 08:40:41 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 0 users found in the hash table (Tue Aug 20 08:40:41 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table (Tue Aug 20 08:40:41 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_primary_name] (0x0400): Processing object <AD GROUP 7> (Tue Aug 20 08:40:41 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Processing group <AD GROUP 7>@<AD DOMAIN> (Tue Aug 20 08:40:41 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): The group has 0 members (Tue Aug 20 08:40:41 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): Group has 0 members (Tue Aug 20 08:40:41 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Storing info for group <AD GROUP 7>@<AD DOMAIN> (Tue Aug 20 08:40:41 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_set_entry_attr] (0x0200): Entry [name=<AD GROUP 7>@<AD DOMAIN>,cn=groups,cn=<AD DOMAIN>,cn=sysdb] has set [ts_cache] attrs. (Tue Aug 20 08:40:41 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_grpmem] (0x0020): Group members are ignored, nothing to do. If you see this message it might indicate an error in the group processing logic. (Tue Aug 20 08:40:41 2019) [sssd[be[<IPA DOMAIN>]]] [apply_subdomain_homedir] (0x0040): UID for user [<AD GROUP 7>@<AD DOMAIN>] is not known. (Tue Aug 20 08:40:41 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1008666))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:41 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:41 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #18]: Request handler finished [0]: Success (Tue Aug 20 08:40:41 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #18]: Receiving request data. (Tue Aug 20 08:40:41 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #18]: Finished. Success. (Tue Aug 20 08:40:41 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<AD DOMAIN>:idnumber=3008666] from reply table (Tue Aug 20 08:40:41 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #18]: Request removed. (Tue Aug 20 08:40:41 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=3518318] (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #19]: New request. Flags [0x0001]. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa] (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(gidNumber=3518318)(|(objectClass=ipaUserGroup)(objectClass=posixGroup))(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 0 results. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_search_group_by_gid] (0x0400): No such entry (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_delete_group] (0x0400): Error: 2 (No such file or directory) (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_id_get_account_info_orig_done] (0x0080): Object not found, ending request (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #19]: Request handler finished [0]: Success (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #19]: Receiving request data. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #19]: Finished. Success. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<IPA DOMAIN>:idnumber=3518318] from reply table (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #19]: Request removed. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=3518318] (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #20]: New request. Flags [0x0001]. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaGroupOverride)(gidNumber=3518318))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_srv_ad_acct_lookup_step] (0x0400): Looking up AD account (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=AD,dc=AD,dc=AD,dc=AD] (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-2129867641-1448237841-168566570-1518318)(objectClass=group)(sAMAccountName=*))][dc=AD,dc=AD,dc=AD,dc=AD]. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 0 users found in the hash table (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_primary_name] (0x0400): Processing object <AD GROUP 8> (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Processing group <AD GROUP 8>@<AD DOMAIN> (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): The group has 0 members (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): Group has 0 members (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Storing info for group <AD GROUP 8>@<AD DOMAIN> (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_set_entry_attr] (0x0200): Entry [name=<AD GROUP 8>@<AD DOMAIN>,cn=groups,cn=<AD DOMAIN>,cn=sysdb] has set [ts_cache] attrs. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_grpmem] (0x0020): Group members are ignored, nothing to do. If you see this message it might indicate an error in the group processing logic. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [apply_subdomain_homedir] (0x0040): UID for user [<AD GROUP 8>@<AD DOMAIN>] is not known. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1518318))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #20]: Request handler finished [0]: Success (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #20]: Receiving request data. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #20]: Finished. Success. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<AD DOMAIN>:idnumber=3518318] from reply table (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #20]: Request removed. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=3181689] (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #21]: New request. Flags [0x0001]. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa] (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(gidNumber=3181689)(|(objectClass=ipaUserGroup)(objectClass=posixGroup))(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 0 results. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_search_group_by_gid] (0x0400): No such entry (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_delete_group] (0x0400): Error: 2 (No such file or directory) (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_id_get_account_info_orig_done] (0x0080): Object not found, ending request (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #21]: Request handler finished [0]: Success (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #21]: Receiving request data. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #21]: Finished. Success. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<IPA DOMAIN>:idnumber=3181689] from reply table (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #21]: Request removed. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=3181689] (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #22]: New request. Flags [0x0001]. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaGroupOverride)(gidNumber=3181689))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_srv_ad_acct_lookup_step] (0x0400): Looking up AD account (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=AD,dc=AD,dc=AD,dc=AD] (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-2129867641-1448237841-168566570-1181689)(objectClass=group)(sAMAccountName=*))][dc=AD,dc=AD,dc=AD,dc=AD]. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 0 users found in the hash table (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_primary_name] (0x0400): Processing object <AD GROUP 9> (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Processing group <AD GROUP 9>@<AD DOMAIN> (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): The group has 0 members (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): Group has 0 members (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Storing info for group <AD GROUP 9>@<AD DOMAIN> (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_set_entry_attr] (0x0200): Entry [name=<AD GROUP 9>@<AD DOMAIN>,cn=groups,cn=<AD DOMAIN>,cn=sysdb] has set [ts_cache] attrs. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_grpmem] (0x0020): Group members are ignored, nothing to do. If you see this message it might indicate an error in the group processing logic. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [apply_subdomain_homedir] (0x0040): UID for user [<AD GROUP 9>@<AD DOMAIN>] is not known. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1181689))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #22]: Request handler finished [0]: Success (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #22]: Receiving request data. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #22]: Finished. Success. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<AD DOMAIN>:idnumber=3181689] from reply table (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #22]: Request removed. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=3443520] (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #23]: New request. Flags [0x0001]. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa] (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(gidNumber=3443520)(|(objectClass=ipaUserGroup)(objectClass=posixGroup))(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 0 results. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_search_group_by_gid] (0x0400): No such entry (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_delete_group] (0x0400): Error: 2 (No such file or directory) (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_id_get_account_info_orig_done] (0x0080): Object not found, ending request (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #23]: Request handler finished [0]: Success (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #23]: Receiving request data. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #23]: Finished. Success. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<IPA DOMAIN>:idnumber=3443520] from reply table (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #23]: Request removed. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=3443520] (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #24]: New request. Flags [0x0001]. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaGroupOverride)(gidNumber=3443520))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_srv_ad_acct_lookup_step] (0x0400): Looking up AD account (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=AD,dc=AD,dc=AD,dc=AD] (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-2129867641-1448237841-168566570-1443520)(objectClass=group)(sAMAccountName=*))][dc=AD,dc=AD,dc=AD,dc=AD]. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 0 users found in the hash table (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_primary_name] (0x0400): Processing object <AD GROUP 10> (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Processing group <AD GROUP 10>@<AD DOMAIN> (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): The group has 0 members (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): Group has 0 members (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Storing info for group <AD GROUP 10>@<AD DOMAIN> (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_set_entry_attr] (0x0200): Entry [name=<AD GROUP 10>@<AD DOMAIN>,cn=groups,cn=<AD DOMAIN>,cn=sysdb] has set [ts_cache] attrs. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_grpmem] (0x0020): Group members are ignored, nothing to do. If you see this message it might indicate an error in the group processing logic. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [apply_subdomain_homedir] (0x0040): UID for user [<AD GROUP 10>@<AD DOMAIN>] is not known. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1443520))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #24]: Request handler finished [0]: Success (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #24]: Receiving request data. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #24]: Finished. Success. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<AD DOMAIN>:idnumber=3443520] from reply table (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #24]: Request removed. (Tue Aug 20 08:40:42 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=2000513] (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #25]: New request. Flags [0x0001]. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa] (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(gidNumber=2000513)(|(objectClass=ipaUserGroup)(objectClass=posixGroup))(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 0 results. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_search_group_by_gid] (0x0400): No such entry (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_delete_group] (0x0400): Error: 2 (No such file or directory) (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_id_get_account_info_orig_done] (0x0080): Object not found, ending request (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #25]: Request handler finished [0]: Success (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #25]: Receiving request data. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #25]: Finished. Success. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<IPA DOMAIN>:idnumber=2000513] from reply table (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #25]: Request removed. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=2000513] (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #26]: New request. Flags [0x0001]. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaGroupOverride)(gidNumber=2000513))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_srv_ad_acct_lookup_step] (0x0400): Looking up AD account (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=AD,dc=AD,dc=AD,dc=AD] (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-2129867641-1448237841-168566570-513)(objectClass=group)(sAMAccountName=*))][dc=AD,dc=AD,dc=AD,dc=AD]. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 0 users found in the hash table (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_primary_name] (0x0400): Processing object Domain Users (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Processing group Domain Users@<AD DOMAIN> (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): The group has 0 members (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): Group has 0 members (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Storing info for group Domain Users@<AD DOMAIN> (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_set_entry_attr] (0x0200): Entry [name=Domain Users@<AD DOMAIN>,cn=groups,cn=<AD DOMAIN>,cn=sysdb] has set [ts_cache] attrs. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_grpmem] (0x0020): Group members are ignored, nothing to do. If you see this message it might indicate an error in the group processing logic. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [apply_subdomain_homedir] (0x0040): UID for user [Domain Users@<AD DOMAIN>] is not known. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-513))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #26]: Request handler finished [0]: Success (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #26]: Receiving request data. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #26]: Finished. Success. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<AD DOMAIN>:idnumber=2000513] from reply table (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #26]: Request removed. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=3112640] (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #27]: New request. Flags [0x0001]. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa] (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(gidNumber=3112640)(|(objectClass=ipaUserGroup)(objectClass=posixGroup))(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 0 results. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_search_group_by_gid] (0x0400): No such entry (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_delete_group] (0x0400): Error: 2 (No such file or directory) (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_id_get_account_info_orig_done] (0x0080): Object not found, ending request (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #27]: Request handler finished [0]: Success (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #27]: Receiving request data. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #27]: Finished. Success. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<IPA DOMAIN>:idnumber=3112640] from reply table (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #27]: Request removed. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=3112640] (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #28]: New request. Flags [0x0001]. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaGroupOverride)(gidNumber=3112640))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_srv_ad_acct_lookup_step] (0x0400): Looking up AD account (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=AD,dc=AD,dc=AD,dc=AD] (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-2129867641-1448237841-168566570-1112640)(objectClass=group)(sAMAccountName=*))][dc=AD,dc=AD,dc=AD,dc=AD]. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 0 users found in the hash table (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_primary_name] (0x0400): Processing object <AD GROUP 11> (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Processing group <AD GROUP 11>@<AD DOMAIN> (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): The group has 0 members (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): Group has 0 members (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Storing info for group <AD GROUP 11>@<AD DOMAIN> (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_set_entry_attr] (0x0200): Entry [name=<AD GROUP 11>@<AD DOMAIN>,cn=groups,cn=<AD DOMAIN>,cn=sysdb] has set [ts_cache] attrs. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_grpmem] (0x0020): Group members are ignored, nothing to do. If you see this message it might indicate an error in the group processing logic. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [apply_subdomain_homedir] (0x0040): UID for user [<AD GROUP 11>@<AD DOMAIN>] is not known. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1112640))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #28]: Request handler finished [0]: Success (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #28]: Receiving request data. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #28]: Finished. Success. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<AD DOMAIN>:idnumber=3112640] from reply table (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #28]: Request removed. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=3598605] (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #29]: New request. Flags [0x0001]. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa] (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(gidNumber=3598605)(|(objectClass=ipaUserGroup)(objectClass=posixGroup))(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 0 results. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_search_group_by_gid] (0x0400): No such entry (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_delete_group] (0x0400): Error: 2 (No such file or directory) (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_id_get_account_info_orig_done] (0x0080): Object not found, ending request (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #29]: Request handler finished [0]: Success (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #29]: Receiving request data. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #29]: Finished. Success. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<IPA DOMAIN>:idnumber=3598605] from reply table (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #29]: Request removed. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=3598605] (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #30]: New request. Flags [0x0001]. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaGroupOverride)(gidNumber=3598605))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_srv_ad_acct_lookup_step] (0x0400): Looking up AD account (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=AD,dc=AD,dc=AD,dc=AD] (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-2129867641-1448237841-168566570-1598605)(objectClass=group)(sAMAccountName=*))][dc=AD,dc=AD,dc=AD,dc=AD]. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 0 users found in the hash table (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_primary_name] (0x0400): Processing object <AD GROUP 12> (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Processing group <AD GROUP 12>@<AD DOMAIN> (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): The group has 0 members (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): Group has 0 members (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Storing info for group <AD GROUP 12>@<AD DOMAIN> (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_set_entry_attr] (0x0200): Entry [name=<AD GROUP 12>@<AD DOMAIN>,cn=groups,cn=<AD DOMAIN>,cn=sysdb] has set [ts_cache] attrs. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_grpmem] (0x0020): Group members are ignored, nothing to do. If you see this message it might indicate an error in the group processing logic. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [apply_subdomain_homedir] (0x0040): UID for user [<AD GROUP 12>@<AD DOMAIN>] is not known. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1598605))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #30]: Request handler finished [0]: Success (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #30]: Receiving request data. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #30]: Finished. Success. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<AD DOMAIN>:idnumber=3598605] from reply table (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #30]: Request removed. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=3320769] (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #31]: New request. Flags [0x0001]. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa] (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(gidNumber=3320769)(|(objectClass=ipaUserGroup)(objectClass=posixGroup))(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 0 results. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_search_group_by_gid] (0x0400): No such entry (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_delete_group] (0x0400): Error: 2 (No such file or directory) (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_id_get_account_info_orig_done] (0x0080): Object not found, ending request (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #31]: Request handler finished [0]: Success (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #31]: Receiving request data. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #31]: Finished. Success. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<IPA DOMAIN>:idnumber=3320769] from reply table (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #31]: Request removed. (Tue Aug 20 08:40:43 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=3320769] (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #32]: New request. Flags [0x0001]. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaGroupOverride)(gidNumber=3320769))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_srv_ad_acct_lookup_step] (0x0400): Looking up AD account (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=AD,dc=AD,dc=AD,dc=AD] (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-2129867641-1448237841-168566570-1320769)(objectClass=group)(sAMAccountName=*))][dc=AD,dc=AD,dc=AD,dc=AD]. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 0 users found in the hash table (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_primary_name] (0x0400): Processing object <AD GROUP 13> (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Processing group <AD GROUP 13>@<AD DOMAIN> (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): The group has 0 members (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): Group has 0 members (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Storing info for group <AD GROUP 13>@<AD DOMAIN> (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_set_entry_attr] (0x0200): Entry [name=<AD GROUP 13>@<AD DOMAIN>,cn=groups,cn=<AD DOMAIN>,cn=sysdb] has set [ts_cache] attrs. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_grpmem] (0x0020): Group members are ignored, nothing to do. If you see this message it might indicate an error in the group processing logic. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [apply_subdomain_homedir] (0x0040): UID for user [<AD GROUP 13>@<AD DOMAIN>] is not known. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1320769))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #32]: Request handler finished [0]: Success (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #32]: Receiving request data. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #32]: Finished. Success. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<AD DOMAIN>:idnumber=3320769] from reply table (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #32]: Request removed. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=2926787] (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #33]: New request. Flags [0x0001]. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa] (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(gidNumber=2926787)(|(objectClass=ipaUserGroup)(objectClass=posixGroup))(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 0 results. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_search_group_by_gid] (0x0400): No such entry (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_delete_group] (0x0400): Error: 2 (No such file or directory) (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_id_get_account_info_orig_done] (0x0080): Object not found, ending request (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #33]: Request handler finished [0]: Success (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #33]: Receiving request data. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #33]: Finished. Success. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<IPA DOMAIN>:idnumber=2926787] from reply table (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #33]: Request removed. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=2926787] (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #34]: New request. Flags [0x0001]. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaGroupOverride)(gidNumber=2926787))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_srv_ad_acct_lookup_step] (0x0400): Looking up AD account (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=AD,dc=AD,dc=AD,dc=AD] (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-2129867641-1448237841-168566570-926787)(objectClass=group)(sAMAccountName=*))][dc=AD,dc=AD,dc=AD,dc=AD]. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 0 users found in the hash table (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_primary_name] (0x0400): Processing object <AD GROUP 14> (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Processing group <AD GROUP 14>@<AD DOMAIN> (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): The group has 0 members (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): Group has 0 members (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Storing info for group <AD GROUP 14>@<AD DOMAIN> (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_set_entry_attr] (0x0200): Entry [name=<AD GROUP 14>@<AD DOMAIN>,cn=groups,cn=<AD DOMAIN>,cn=sysdb] has set [ts_cache] attrs. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_grpmem] (0x0020): Group members are ignored, nothing to do. If you see this message it might indicate an error in the group processing logic. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [apply_subdomain_homedir] (0x0040): UID for user [<AD GROUP 14>@<AD DOMAIN>] is not known. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-926787))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #34]: Request handler finished [0]: Success (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #34]: Receiving request data. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #34]: Finished. Success. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<AD DOMAIN>:idnumber=2926787] from reply table (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #34]: Request removed. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=3138107] (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #35]: New request. Flags [0x0001]. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa] (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(gidNumber=3138107)(|(objectClass=ipaUserGroup)(objectClass=posixGroup))(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 0 results. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_search_group_by_gid] (0x0400): No such entry (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_delete_group] (0x0400): Error: 2 (No such file or directory) (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_id_get_account_info_orig_done] (0x0080): Object not found, ending request (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #35]: Request handler finished [0]: Success (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #35]: Receiving request data. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #35]: Finished. Success. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<IPA DOMAIN>:idnumber=3138107] from reply table (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #35]: Request removed. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=3138107] (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #36]: New request. Flags [0x0001]. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaGroupOverride)(gidNumber=3138107))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_srv_ad_acct_lookup_step] (0x0400): Looking up AD account (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=AD,dc=AD,dc=AD,dc=AD] (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-2129867641-1448237841-168566570-1138107)(objectClass=group)(sAMAccountName=*))][dc=AD,dc=AD,dc=AD,dc=AD]. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 0 users found in the hash table (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_primary_name] (0x0400): Processing object <AD GROUP 15> (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Processing group <AD GROUP 15>@<AD DOMAIN> (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): The group has 0 members (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): Group has 0 members (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Storing info for group <AD GROUP 15>@<AD DOMAIN> (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_set_entry_attr] (0x0200): Entry [name=<AD GROUP 15>@<AD DOMAIN>,cn=groups,cn=<AD DOMAIN>,cn=sysdb] has set [ts_cache] attrs. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_grpmem] (0x0020): Group members are ignored, nothing to do. If you see this message it might indicate an error in the group processing logic. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [apply_subdomain_homedir] (0x0040): UID for user [<AD GROUP 15>@<AD DOMAIN>] is not known. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1138107))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #36]: Request handler finished [0]: Success (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #36]: Receiving request data. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #36]: Finished. Success. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<AD DOMAIN>:idnumber=3138107] from reply table (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #36]: Request removed. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=3366580] (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #37]: New request. Flags [0x0001]. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa] (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(gidNumber=3366580)(|(objectClass=ipaUserGroup)(objectClass=posixGroup))(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 0 results. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_search_group_by_gid] (0x0400): No such entry (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_delete_group] (0x0400): Error: 2 (No such file or directory) (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_id_get_account_info_orig_done] (0x0080): Object not found, ending request (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #37]: Request handler finished [0]: Success (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #37]: Receiving request data. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #37]: Finished. Success. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<IPA DOMAIN>:idnumber=3366580] from reply table (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #37]: Request removed. (Tue Aug 20 08:40:44 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:50 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=3366580] (Tue Aug 20 08:40:50 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #38]: New request. Flags [0x0001]. (Tue Aug 20 08:40:50 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:50 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaGroupOverride)(gidNumber=3366580))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:50 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:50 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_srv_ad_acct_lookup_step] (0x0400): Looking up AD account (Tue Aug 20 08:40:50 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=AD,dc=AD,dc=AD,dc=AD] (Tue Aug 20 08:40:50 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-2129867641-1448237841-168566570-1366580)(objectClass=group)(sAMAccountName=*))][dc=AD,dc=AD,dc=AD,dc=AD]. (Tue Aug 20 08:40:50 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:50 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results. (Tue Aug 20 08:40:50 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ (Tue Aug 20 08:40:50 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 0 users found in the hash table (Tue Aug 20 08:40:50 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table (Tue Aug 20 08:40:50 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_primary_name] (0x0400): Processing object <AD GROUP 16> (Tue Aug 20 08:40:50 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Processing group <AD GROUP 16>@<AD DOMAIN> (Tue Aug 20 08:40:50 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): The group has 0 members (Tue Aug 20 08:40:50 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): Group has 0 members (Tue Aug 20 08:40:50 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Storing info for group <AD GROUP 16>@<AD DOMAIN> (Tue Aug 20 08:40:50 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_set_entry_attr] (0x0200): Entry [name=<AD GROUP 16>@<AD DOMAIN>,cn=groups,cn=<AD DOMAIN>,cn=sysdb] has set [ts_cache] attrs. (Tue Aug 20 08:40:50 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_grpmem] (0x0020): Group members are ignored, nothing to do. If you see this message it might indicate an error in the group processing logic. (Tue Aug 20 08:40:50 2019) [sssd[be[<IPA DOMAIN>]]] [apply_subdomain_homedir] (0x0040): UID for user [<AD GROUP 16>@<AD DOMAIN>] is not known. (Tue Aug 20 08:40:50 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1366580))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:50 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:50 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #38]: Request handler finished [0]: Success (Tue Aug 20 08:40:50 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #38]: Receiving request data. (Tue Aug 20 08:40:50 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #38]: Finished. Success. (Tue Aug 20 08:40:50 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<AD DOMAIN>:idnumber=3366580] from reply table (Tue Aug 20 08:40:50 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #38]: Request removed. (Tue Aug 20 08:40:50 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:40:55 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=3354727] (Tue Aug 20 08:40:55 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #39]: New request. Flags [0x0001]. (Tue Aug 20 08:40:55 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:40:55 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa] (Tue Aug 20 08:40:55 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(gidNumber=3354727)(|(objectClass=ipaUserGroup)(objectClass=posixGroup))(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:40:55 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:40:55 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 0 results. (Tue Aug 20 08:40:55 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_search_group_by_gid] (0x0400): No such entry (Tue Aug 20 08:40:55 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_delete_group] (0x0400): Error: 2 (No such file or directory) (Tue Aug 20 08:40:55 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_id_get_account_info_orig_done] (0x0080): Object not found, ending request (Tue Aug 20 08:40:55 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #39]: Request handler finished [0]: Success (Tue Aug 20 08:40:55 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #39]: Receiving request data. (Tue Aug 20 08:40:55 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #39]: Finished. Success. (Tue Aug 20 08:40:55 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<IPA DOMAIN>:idnumber=3354727] from reply table (Tue Aug 20 08:40:55 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #39]: Request removed. (Tue Aug 20 08:40:55 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Tue Aug 20 08:41:00 2019) [sssd[be[<IPA DOMAIN>]]] [dp_get_account_info_handler] (0x0200): Got request for [0x2][BE_REQ_GROUP][idnumber=3354727] (Tue Aug 20 08:41:00 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): DP Request [Account #40]: New request. Flags [0x0001]. (Tue Aug 20 08:41:00 2019) [sssd[be[<IPA DOMAIN>]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Tue Aug 20 08:41:00 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaGroupOverride)(gidNumber=3354727))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:41:00 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:41:00 2019) [sssd[be[<IPA DOMAIN>]]] [ipa_srv_ad_acct_lookup_step] (0x0400): Looking up AD account (Tue Aug 20 08:41:00 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=AD,dc=AD,dc=AD,dc=AD] (Tue Aug 20 08:41:00 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-2129867641-1448237841-168566570-1354727)(objectClass=group)(sAMAccountName=*))][dc=AD,dc=AD,dc=AD,dc=AD]. (Tue Aug 20 08:41:00 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:41:00 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results. (Tue Aug 20 08:41:00 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ (Tue Aug 20 08:41:00 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 0 users found in the hash table (Tue Aug 20 08:41:00 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table (Tue Aug 20 08:41:00 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_primary_name] (0x0400): Processing object <AD GROUP 17> (Tue Aug 20 08:41:00 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Processing group <AD GROUP 17>@<AD DOMAIN> (Tue Aug 20 08:41:00 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): The group has 0 members (Tue Aug 20 08:41:00 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_process_ghost_members] (0x0400): Group has 0 members (Tue Aug 20 08:41:00 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_group] (0x0400): Storing info for group <AD GROUP 17>@<AD DOMAIN> (Tue Aug 20 08:41:00 2019) [sssd[be[<IPA DOMAIN>]]] [sysdb_set_entry_attr] (0x0200): Entry [name=<AD GROUP 17>@<AD DOMAIN>,cn=groups,cn=<AD DOMAIN>,cn=sysdb] has set [ts_cache] attrs. (Tue Aug 20 08:41:00 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_save_grpmem] (0x0020): Group members are ignored, nothing to do. If you see this message it might indicate an error in the group processing logic. (Tue Aug 20 08:41:01 2019) [sssd[be[<IPA DOMAIN>]]] [apply_subdomain_homedir] (0x0040): UID for user [<AD GROUP 17>@<AD DOMAIN>] is not known. (Tue Aug 20 08:41:01 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-2129867641-1448237841-168566570-1354727))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=ipa,dc=ipa,dc=ipa]. (Tue Aug 20 08:41:01 2019) [sssd[be[<IPA DOMAIN>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Aug 20 08:41:01 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_done] (0x0400): DP Request [Account #40]: Request handler finished [0]: Success (Tue Aug 20 08:41:01 2019) [sssd[be[<IPA DOMAIN>]]] [_dp_req_recv] (0x0400): DP Request [Account #40]: Receiving request data. (Tue Aug 20 08:41:01 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #40]: Finished. Success. (Tue Aug 20 08:41:01 2019) [sssd[be[<IPA DOMAIN>]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:2::<AD DOMAIN>:idnumber=3354727] from reply table (Tue Aug 20 08:41:01 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): DP Request [Account #40]: Request removed. (Tue Aug 20 08:41:01 2019) [sssd[be[<IPA DOMAIN>]]] [dp_req_destructor] (0x0400): Number of active DP request: 0