Learn more about these different git repos.
Other Git URLs
Issue My IPA clients are unable to perform public key/certificate based authentication of Active Directory users. The AD users are in the Default Trust View, where their PIV certificate is populated. Everything worked fine last week after I upgraded to CentOS 7.7 and sssd 1.16.4.21. The issue only appeared after a user's PIV certificate was updated (which was subsequently uploaded into the Default Trust View replacing their previous certificate). AD users ARE able to authenticate via certificate/PIV card to the IPA Servers; I can also kinit as an AD user from an IPA client, however the "id <ad_user>" command produces a "no such user" message (correctly resolves the AD user on the IPA servers, though).
Steps to Reproduce IPA Server setup in one-way trust with AD on Server 2016 AD user PIV certificate uploaded via IPA self-service page to Default Trust View (from macOS client not joined to the AD) >> ssh -I /usr/local/lib/opensc-pkcs11.so <ad_user>@ipa-client.ipa.ad.domain Actual behavior AD user cannot login to IPA client via ssh.
Expected behavior AD user successfully establishes an ssh login to the IPA client.
Version/Release/Distribution $ rpm -q freeipa-server freeipa-client ipa-server ipa-client 389-ds-base pki-ca krb5-server On Client: ipa-client-4.6.5-11.el7.centos.x86_64
On Server: ipa-server-4.6.5-11.el7.centos.x86_64 ipa-client-4.6.5-11.el7.centos.x86_64 389-ds-base-1.3.9.1-10.el7.x86_64 pki-ca-10.5.16-3.el7.noarch krb5-server-1.15.1-37.el7_7.2.x86_64
Additional info: Testing has shown that the sss_ssh_authorizedkeys function (when run on IPA clients) CAN identify IPA users that have keys or certificates associated with their account, but fails to match any users identified in the AD trust. The same function works correctly and identifies the AD users when run on the IPA servers.
id <ad_user> (on IPA server): [root@idm3 ~]# id michael.preissner uid=1556201124(michael.preissner@ahrq.cloud) gid=1556201124(michael.preissner@ahrq.cloud) groups=1556201124(michael.preissner@ahrq.cloud),1556201210(amc vulnerability managers@ahrq.cloud),1556201183(meps securecloud users@ahrq.cloud),1556201204(certauthority admins@ahrq.cloud),1556202128(amc_transit_admins@ahrq.cloud),1556201209(amc linux admins@ahrq.cloud),1556202122(aws_log_admins@ahrq.cloud),1556201190(zscaler_admins@ahrq.cloud),1556201330(amc_rdp_users@ahrq.cloud),1556200512(domain admins@ahrq.cloud),1556201192(zscaler users@ahrq.cloud),1556200513(domain users@ahrq.cloud),1556201189(aws_admins@ahrq.cloud),1556201232(duo admins@ahrq.cloud),1556201112(vpn_users@ahrq.cloud),1556202127(aws#transit#transit_admins#143822537711@ahrq.cloud),244800010(amc_admins@idm.ahrq.cloud),244800000(admins@idm.ahrq.cloud)
id <ad_user> on IPA Client: [root@ahrq-centos7 ~]# id michael.preissner id: michael.preissner: no such user
I've got debug cranked up to 9 in sssd.conf for the [sssd], [domain/IPA.domain], and [pam] sections. Just tell me which logs you want from both systems and I'll get those uploaded.
sssd version on client and server is sssd-1.16.4-21.el7.x86_64
Hi,
please send the sssd_domain.name.log from the client and sssd.conf from the client and the server.
bye, Sumit
Server sssd.conf:
[domain/idm.ahrq.cloud]
cache_credentials = True krb5_store_password_if_offline = True ipa_domain = idm.ahrq.cloud id_provider = ipa auth_provider = ipa access_provider = ipa ipa_hostname = idm3.idm.ahrq.cloud chpass_provider = ipa ipa_server = idm3.idm.ahrq.cloud ldap_tls_cacert = /etc/ipa/ca.crt
ipa_server_mode = True debug_level = 9
[sssd] domains = idm.ahrq.cloud services = ifp, nss, sudo, pam, ssh debug_level = 9
[nss] memcache_timeout = 600 homedir_substring = /home
[pam] pam_cert_auth = True debug_level = 9
[ssh]
[sudo]
[autofs]
[pac]
[ifp] allowed_uids = ipaapi, root user_attributes = +mail, +givenname, +sn, +displayname, +ou
[secrets]
[session_recording]
client sssd.conf
cache_credentials = True krb5_store_password_if_offline = True ipa_domain = idm.ahrq.cloud id_provider = ipa auth_provider = ipa access_provider = ipa ipa_hostname = ahrq-centos7.idm.ahrq.cloud chpass_provider = ipa dyndns_update = True ipa_server = srv, idm3.idm.ahrq.cloud dyndns_iface = eth0 ldap_tls_cacert = /etc/ipa/ca.crt debug_level = 9
[sssd] services = nss, sudo, pam, ssh domains = idm.ahrq.cloud debug_level = 9
[nss] homedir_substring = /home
[ifp]
Trying to figure out the best way to upload the log files...the server log has about 25k rows for the 1 second it took to run the id <ad_user> function...
The client log would be sufficient for a start.
Ok, it's about 1000 lines...
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [dp_get_account_info_handler] (0x0200): Got request for [0x1][BE_REQ_USER][name=michael.preissner@ahrq.cloud] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [dp_attach_req] (0x0400): DP Request [Account #24]: New request. Flags [0x0001]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_id_op_connect_step] (0x4000): reusing cached connection (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_id_op_connect_step] (0x4000): reusing cached connection (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_get_ad_override_connect_done] (0x4000): Searching for overrides in view [Default Trust View] with filter [(&(objectClass=ipaUserOverride)(uid=michael.preissner))]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_print_server] (0x2000): Searching 172.20.4.54:389 (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaUserOverride)(uid=michael.preissner))][cn=Default Trust View,cn=views,cn=accounts,dc=idm,dc=ahrq,dc=cloud]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 46 (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_op_add] (0x2000): New operation 46 timeout 6 (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_process_result] (0x2000): Trace: sh[0x55c70ca20550], connected[1], ops[0x55c70ca2e540], ldap[0x55c70ca1b2c0] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_RESULT] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_op_destructor] (0x2000): Operation 46 finished (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_get_ad_override_done] (0x4000): No override found with filter [(&(objectClass=ipaUserOverride)(uid=michael.preissner))]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_id_op_destroy] (0x4000): releasing operation connection (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_id_op_connect_step] (0x4000): reusing cached connection (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_get_acct_info_send] (0x0400): Sending request_type: [REQ_FULL_WITH_MEMBERS] for trust user [michael.preissner] to IPA server (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_exop_send] (0x0400): Executing extended operation (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_exop_send] (0x2000): ldap_extended_operation sent, msgid = 47 (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_op_add] (0x2000): New operation 47 timeout 6 (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_process_result] (0x2000): Trace: sh[0x55c70ca20550], connected[1], ops[0x55c70ca5bcb0], ldap[0x55c70ca1b2c0] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_process_result] (0x2000): Trace: end of ldap_result list (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_process_result] (0x2000): Trace: sh[0x55c70ca20550], connected[1], ops[0x55c70ca5bcb0], ldap[0x55c70ca1b2c0] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_EXTENDED] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_exop_done] (0x0400): ldap_extended_operation result: Success(0), (null). (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_op_destructor] (0x2000): Operation 47 finished (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [add_v1_user_data] (0x4000): BER tag is [48] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Found new sequence. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [objectSIDString]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalADname]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalADuidNumber]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalADgidNumber]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalADhomeDirectory]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalADgecos]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [userPrincipalName]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [defaultOverrideName]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [adAccountExpires]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [adUserAccountControl]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [userCertificate]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [mail]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalDN]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [mail]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_get_user_done] (0x0400): Received [18] groups in group list from IPA Server (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_get_user_done] (0x0400): [michael.preissner@ahrq.cloud]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_get_user_done] (0x0400): [amc vulnerability managers@ahrq.cloud]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_get_user_done] (0x0400): [meps securecloud users@ahrq.cloud]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_get_user_done] (0x0400): [certauthority admins@ahrq.cloud]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_get_user_done] (0x0400): [amc_transit_admins@ahrq.cloud]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_get_user_done] (0x0400): [amc linux admins@ahrq.cloud]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_get_user_done] (0x0400): [aws_log_admins@ahrq.cloud]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_get_user_done] (0x0400): [zscaler_admins@ahrq.cloud]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_get_user_done] (0x0400): [amc_rdp_users@ahrq.cloud]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_get_user_done] (0x0400): [domain admins@ahrq.cloud]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_get_user_done] (0x0400): [zscaler users@ahrq.cloud]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_get_user_done] (0x0400): [domain users@ahrq.cloud]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_get_user_done] (0x0400): [aws_admins@ahrq.cloud]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_get_user_done] (0x0400): [duo admins@ahrq.cloud]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_get_user_done] (0x0400): [vpn_users@ahrq.cloud]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_get_user_done] (0x0400): [aws#transit#transit_admins#143822537711@ahrq.cloud]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_get_user_done] (0x0400): [amc_admins@idm.ahrq.cloud]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_get_user_done] (0x0400): [admins@idm.ahrq.cloud]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70ca616a0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70ca61770
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70ca616a0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca61770 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca616a0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_search_by_name] (0x0400): No such entry (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70ca60fa0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70ca61070
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70ca60fa0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca61070 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca60fa0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_search_by_name] (0x0400): No such entry (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70ca61070
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70ca61140
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70ca61070 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca61140 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca61070 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_search_by_name] (0x0400): No such entry (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70ca61170
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70ca591e0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70ca61170 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca591e0 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca61170 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_search_by_name] (0x0400): No such entry (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70ca591e0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70ca592b0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70ca591e0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca592b0 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca591e0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_search_by_name] (0x0400): No such entry (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70ca592a0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70ca59370
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70ca592a0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca59370 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca592a0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_search_by_name] (0x0400): No such entry (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70ca59410
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70ca594e0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70ca59410 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca594e0 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca59410 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70ca5a9c0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70ca5aa90
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70ca5a9c0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca5aa90 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca5a9c0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70ca59950
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70ca59a20
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70ca59950 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca59a20 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca59950 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70ca5a2f0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70ca5a3c0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70ca5a2f0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca5a3c0 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca5a2f0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_search_by_name] (0x0400): No such entry (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70ca5a390
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70ca5a460
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70ca5a390 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca5a460 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca5a390 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70ca59890
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70ca59960
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70ca59890 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca59960 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca59890 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70ca5eb90
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70ca5ec60
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70ca5eb90 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca5ec60 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca5eb90 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_search_by_name] (0x0400): No such entry (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70ca59d10
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70ca59de0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70ca59d10 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca59de0 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca59d10 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_search_by_name] (0x0400): No such entry (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70ca59c70
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70ca59d40
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70ca59c70 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca59d40 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca59c70 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70ca616c0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70ca616c0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca616c0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70ca560b0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70ca5a900
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70ca560b0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca5a900 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca560b0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70da66a70
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70da66b40
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70da66a70 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da66b40 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da66a70 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_search_by_name] (0x0400): No such entry (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70ca55b00
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70da65f70
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70ca55b00 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da65f70 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca55b00 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_search_by_name] (0x0400): No such entry (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70ca567a0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70ca558a0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70ca567a0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca558a0 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca567a0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70ca55f50
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70ca56020
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70ca55f50 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca56020 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca55f50 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_get_list_step] (0x0400): Sending request_type: [REQ_FULL_WITH_MEMBERS] for object [michael.preissner@ahrq.cloud]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_exop_send] (0x0400): Executing extended operation (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_exop_send] (0x2000): ldap_extended_operation sent, msgid = 48 (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_op_add] (0x2000): New operation 48 timeout 6 (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_process_result] (0x2000): Trace: sh[0x55c70ca20550], connected[1], ops[0x55c70da66b40], ldap[0x55c70ca1b2c0] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_process_result] (0x2000): Trace: end of ldap_result list (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_process_result] (0x2000): Trace: sh[0x55c70ca20550], connected[1], ops[0x55c70da66b40], ldap[0x55c70ca1b2c0] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_EXTENDED] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_exop_done] (0x0400): ldap_extended_operation result: Success(0), (null). (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_op_destructor] (0x2000): Operation 48 finished (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [add_v1_user_data] (0x4000): BER tag is [48] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Found new sequence. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [objectSIDString]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalADname]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalADuidNumber]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalADgidNumber]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalADhomeDirectory]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalADgecos]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [userPrincipalName]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [defaultOverrideName]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [adAccountExpires]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [adUserAccountControl]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [userCertificate]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [mail]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalDN]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [get_extra_attrs] (0x4000): Extra attribute [mail]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_get_list_next] (0x0400): Received [michael.preissner@ahrq.cloud] attributes from IPA server. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_save_objects] (0x4000): Found original AD name [michael.preissner@ahrq.cloud]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_save_objects] (0x4000): Found original AD upn [Michael.Preissner@AHRQ.HHS.GOV]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): start ldb transaction (nesting: 0) (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): start ldb transaction (nesting: 1) (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70da69f90
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70da6a060
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70da69f90 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da6a060 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da69f90 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_search_by_name] (0x0400): No such entry (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_store_user] (0x1000): User michael.preissner@ahrq.cloud does not exist. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): start ldb transaction (nesting: 2) (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70da68dd0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70da6a520
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70da68dd0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da6a520 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da68dd0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_search_by_name] (0x0400): No such entry (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70da68810
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70da688e0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70da68810 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da688e0 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da68810 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_search_group_by_gid] (0x0400): No such entry (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70da68810
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_search_user_by_uid] (0x0400): No such entry (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): start ldb transaction (nesting: 3) (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70da77ef0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70da77fc0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70da77ef0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da77fc0 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da77ef0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): commit ldb transaction (nesting: 3) (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): start ldb transaction (nesting: 0) (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70da69e30
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70da69f00
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70da69e30 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da69f00 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da69e30 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): cancel ldb transaction (nesting: 0) (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_create_ts_entry] (0x0040): ldb_add failed: Entry already exists[Entry name=michael.preissner@ahrq.cloud,cn=users,cn=ahrq.cloud,cn=sysdb already exists] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_create_ts_entry] (0x0040): Error: 17 (File exists) (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_update_ts_cache] (0x0080): Cannot set ts attrs for group name=michael.preissner@ahrq.cloud,cn=users,cn=ahrq.cloud,cn=sysdb (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70da68170
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70da68240
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70da68170 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da68240 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da68170 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_ldb_msg_difference] (0x2000): Added attr [objectSIDString] to entry [name=michael.preissner@ahrq.cloud,cn=users,cn=ahrq.cloud,cn=sysdb] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): start ldb transaction (nesting: 3) (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70da816c0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70da81790
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70da816c0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da81790 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da816c0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): cancel ldb transaction (nesting: 3) (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_set_cache_entry_attr] (0x0080): ldb_modify failed: Attribute or value exists[attribute 'mail': value 'michael.preissner@ahrq.hhs.gov' on 'name=michael.preissner@ahrq.cloud,cn=users,cn=ahrq.cloud,cn=sysdb' provided more than once in REPLACE] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_set_cache_entry_attr] (0x0040): Error: 17 (File exists) (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_set_entry_attr] (0x0080): Cannot set attrs for name=michael.preissner@ahrq.cloud,cn=users,cn=ahrq.cloud,cn=sysdb, 17 [File exists] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_add_user] (0x0400): Error: 17 (File exists) (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): cancel ldb transaction (nesting: 2) (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70da81780
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70da6b080
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70da81780 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da6b080 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da81780 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70da82da0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70da801a0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70da82da0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da801a0 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da82da0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): start ldb transaction (nesting: 2) (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70da68ce0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70da68db0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70da68ce0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70da82b20
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70da82bf0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da68db0 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da68ce0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70da82b20 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da82bf0 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da82b20 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): commit ldb transaction (nesting: 2) (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): start ldb transaction (nesting: 0) (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70da827c0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70da6a800
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70da827c0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da6a800 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da827c0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): commit ldb transaction (nesting: 0) (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_store_new_user] (0x0400): A user with the same UID [1556201124] was removed from the cache (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): start ldb transaction (nesting: 2) (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70da68740
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70da82a60
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70da68740 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da82a60 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da68740 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_search_by_name] (0x0400): No such entry (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70da7c8b0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70da6a0c0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70da7c8b0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da6a0c0 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da7c8b0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_search_group_by_gid] (0x0400): No such entry (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70da7c8b0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_search_user_by_uid] (0x0400): No such entry (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): start ldb transaction (nesting: 3) (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70da6aec0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70da6af90
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70da6aec0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da6af90 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da6aec0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): commit ldb transaction (nesting: 3) (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): start ldb transaction (nesting: 0) (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70da6a3a0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70da6a470
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70da6a3a0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da6a470 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da6a3a0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): commit ldb transaction (nesting: 0) (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70da82a60
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70da82b30
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70da82a60 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da82b30 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da82a60 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_ldb_msg_difference] (0x2000): Added attr [objectSIDString] to entry [name=michael.preissner@ahrq.cloud,cn=users,cn=ahrq.cloud,cn=sysdb] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): start ldb transaction (nesting: 3) (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70da77590
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70da77660
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70da77590 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da77660 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da77590 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): cancel ldb transaction (nesting: 3) (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_set_cache_entry_attr] (0x0080): ldb_modify failed: Attribute or value exists[attribute 'mail': value 'michael.preissner@ahrq.hhs.gov' on 'name=michael.preissner@ahrq.cloud,cn=users,cn=ahrq.cloud,cn=sysdb' provided more than once in REPLACE] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_set_cache_entry_attr] (0x0040): Error: 17 (File exists) (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_set_entry_attr] (0x0080): Cannot set attrs for name=michael.preissner@ahrq.cloud,cn=users,cn=ahrq.cloud,cn=sysdb, 17 [File exists] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_add_user] (0x0400): Error: 17 (File exists) (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): cancel ldb transaction (nesting: 2) (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_store_new_user] (0x0040): sysdb_add_user failed (while renaming user) for: michael.preissner@ahrq.cloud [0]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_store_user] (0x0040): Cache update failed: 17 (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): cancel ldb transaction (nesting: 1) (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_store_user] (0x0400): Error: 17 (File exists) (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70da80260
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70da816c0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70da80260 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da816c0 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da80260 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70da81d40
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70da765c0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70da81d40 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da765c0 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da81d40 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70da762c0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70da81d40
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70da762c0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da81d40 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70da762c0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_search_group_by_gid] (0x0400): No such entry (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_delete_group] (0x0400): Error: 2 (No such file or directory) (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_save_objects] (0x0040): sysdb_delete_group failed for MPG group [2]: No such file or directory (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): cancel ldb transaction (nesting: 0) (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_get_list_save_step] (0x0040): ipa_s2n_save_objects failed. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_get_list_next] (0x0040): ipa_s2n_get_list_save_step failed. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_s2n_get_list_done] (0x0040): s2n get_fqlist request failed. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_id_op_done] (0x4000): releasing operation connection (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_id_op_destroy] (0x4000): releasing operation connection (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [dp_req_done] (0x0400): DP Request [Account #24]: Request handler finished [0]: Success (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [_dp_req_recv] (0x0400): DP Request [Account #24]: Receiving request data. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #24]: Finished. Success. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [dp_req_reply_std] (0x1000): DP Request [Account #24]: Returning [Success]: 0,0,Success (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:1::ahrq.cloud:name=michael.preissner@ahrq.cloud] from reply table (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [dp_req_destructor] (0x0400): DP Request [Account #24]: Request removed. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_process_result] (0x2000): Trace: sh[0x55c70ca20550], connected[1], ops[(nil)], ldap[0x55c70ca1b2c0] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_process_result] (0x2000): Trace: end of ldap_result list (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sbus_dispatch] (0x4000): dbus conn: 0x55c70ca14b10 (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sbus_dispatch] (0x4000): Dispatching. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sbus_message_handler] (0x2000): Received SBUS method org.freedesktop.sssd.dataprovider.getAccountInfo on path /org/freedesktop/sssd/dataprovider (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [dp_get_account_info_handler] (0x0200): Got request for [0x1][BE_REQ_USER][name=michael.preissner@idm.ahrq.cloud] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [dp_attach_req] (0x0400): DP Request [Account #25]: New request. Flags [0x0001]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sss_domain_get_state] (0x1000): Domain idm.ahrq.cloud is Active (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_id_op_connect_step] (0x4000): reusing cached connection (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_search_user_next_base] (0x0400): Searching for users with base [cn=accounts,dc=idm,dc=ahrq,dc=cloud] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_print_server] (0x2000): Searching 172.20.4.54:389 (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(uid=michael.preissner)(objectclass=posixAccount)(uid=)(&(uidNumber=)(!(uidNumber=0))))][cn=accounts,dc=idm,dc=ahrq,dc=cloud]. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uid] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uidNumber] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gecos] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [homeDirectory] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginShell] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbPrincipalName] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [memberOf] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUniqueID] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaNTSecurityIdentifier] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [entryUSN] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowLastChange] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowMin] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowMax] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowWarning] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowInactive] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowExpire] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowFlag] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbLastPwdChange] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbPasswordExpiration] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [pwdAttribute] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [authorizedService] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [accountExpires] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userAccountControl] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [nsAccountLock] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [host] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginDisabled] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginExpirationTime] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginAllowedTimeMap] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaSshPubKey] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUserAuthType] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userCertificate;binary] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [mail] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 49 (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_op_add] (0x2000): New operation 49 timeout 6 (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_process_result] (0x2000): Trace: sh[0x55c70ca20550], connected[1], ops[0x55c70ca1f7e0], ldap[0x55c70ca1b2c0] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_RESULT] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_op_destructor] (0x2000): Operation 49 finished (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_search_user_process] (0x0400): Search for users, returned 0 results. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_search_user_process] (0x2000): Retrieved total 0 users (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_id_op_done] (0x4000): releasing operation connection (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70ca418f0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70ca2cef0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70ca418f0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca2cef0 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca418f0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_search_by_name] (0x0400): No such entry (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_cache_search_groups] (0x2000): Search groups with filter: (&(objectCategory=group)(ghost=michael.preissner@idm.ahrq.cloud)) (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70ca30d50
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70ca22c90
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70ca30d50 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca22c90 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca30d50 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_cache_search_groups] (0x2000): No such entry (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_delete_user] (0x0400): Error: 2 (No such file or directory) (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55c70ca337c0
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55c70ca33890
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Running timer event 0x55c70ca337c0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca33890 "ltdb_timeout"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ldb] (0x4000): Destroying timer event 0x55c70ca337c0 "ltdb_callback"
(Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sysdb_search_by_name] (0x0400): No such entry (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [ipa_id_get_account_info_orig_done] (0x0080): Object not found, ending request (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [dp_req_done] (0x0400): DP Request [Account #25]: Request handler finished [0]: Success (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [_dp_req_recv] (0x0400): DP Request [Account #25]: Receiving request data. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #25]: Finished. Success. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [dp_req_reply_std] (0x1000): DP Request [Account #25]: Returning [Success]: 0,0,Success (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:1::idm.ahrq.cloud:name=michael.preissner@idm.ahrq.cloud] from reply table (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [dp_req_destructor] (0x0400): DP Request [Account #25]: Request removed. (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_process_result] (0x2000): Trace: sh[0x55c70ca20550], connected[1], ops[(nil)], ldap[0x55c70ca1b2c0] (Fri Oct 11 09:09:25 2019) [sssd[be[idm.ahrq.cloud]]] [sdap_process_result] (0x2000): Trace: end of ldap_result list (Fri Oct 11 09:09:30 2019) [sssd[be[idm.ahrq.cloud]]] [sbus_dispatch] (0x4000): dbus conn: 0x55c70c9e3a90 (Fri Oct 11 09:09:30 2019) [sssd[be[idm.ahrq.cloud]]] [sbus_dispatch] (0x4000): Dispatching. (Fri Oct 11 09:09:30 2019) [sssd[be[idm.ahrq.cloud]]] [sbus_message_handler] (0x2000): Received SBUS method org.freedesktop.sssd.service.resetOffline on path /org/freedesktop/sssd/service (Fri Oct 11 09:09:30 2019) [sssd[be[idm.ahrq.cloud]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Fri Oct 11 09:09:30 2019) [sssd[be[idm.ahrq.cloud]]] [check_if_online] (0x2000): Schedule check_if_online_delayed in 1s. (Fri Oct 11 09:09:31 2019) [sssd[be[idm.ahrq.cloud]]] [be_run_unconditional_online_cb] (0x4000): List of unconditional online callbacks is empty, nothing to do. (Fri Oct 11 09:09:31 2019) [sssd[be[idm.ahrq.cloud]]] [check_if_online_delayed] (0x2000): Backend is already online, nothing to do.
please try to remove +mail from user_attributes = +mail, +givenname, +sn, +displayname, +ou in sssd.conf on the IPA server. It is not needed since it is already in the default list of attributes read from LDAP and send to the IPA clients. Looks like SSSD on the client does not behave well if an attribute is send twice.
+mail
user_attributes = +mail, +givenname, +sn, +displayname, +ou
That did the trick! I had added those in while trying to get automatic organization assignment for AD users working with my Spacewalk server...never did get it working but apparently broke everything else in the process. Thanks!
Metadata Update from @mpreissner: - Issue close_status updated to: Fixed - Issue status updated to: Closed (was: Open)
thanks for the feedback, glad I could help.
I'd like to keep this ticket open to eventually make the IPA client more robust in this case.
Maybe the mail entry also was the reason why your Spacewalk integration didn't work? If you are running Spacewalk on an IPA client you have to add 'user_attributes = +givenname, +sn, +displayname, +ou' to the client's sssd.conf as well and must install sssd-dbus on this client.
mail
Metadata Update from @sbose: - Issue status updated to: Open (was: Closed)
Metadata Update from @thalman: - Custom field design_review adjusted to on - Custom field mark adjusted to on - Custom field patch adjusted to on - Custom field review adjusted to on - Custom field sensitive adjusted to on - Custom field testsupdated adjusted to on - Issue close_status updated to: Fixed - Issue status updated to: Closed (was: Open)
SSSD is moving from Pagure to Github. This means that new issues and pull requests will be accepted only in SSSD's github repository.
This issue has been cloned to Github and is available here: - https://github.com/SSSD/sssd/issues/5060
If you want to receive further updates on the issue, please navigate to the github issue and click on subscribe button.
subscribe
Thank you for understanding. We apologize for all inconvenience.
Login to comment on this ticket.