<div dir="ltr"><div><div>Hi, </div>I am facing slow login issue with IPA 4.2.0 version. The login takes around 18-19s date;ssh <a href="mailto:testuser@10.16.32.4">testuser@10.16.32.4</a> Mon Jul 25 11:14:54 UTC 2016 <a href="mailto:testuser@10.65.32.4">testuser@10.65.32.4</a>'s password: Last login: Mon Jul 25 11:10:35 2016 from 10.65.16.4 [testuser@ipa-client-1 :~] date Mon Jul 25 11:15:12 UTC 2016 </div><div>I have tried most of the settings like "selinux_provider=none" as well as followed <a href="https://jhrozek.wordpress.com/2015/08/19/performance-tuning-sssd-for-large-ipa-ad-trust-deployments/">https://jhrozek.wordpress.com/2015/08/19/performance-tuning-sssd-for-large-ipa-ad-trust-deployments/</a> </div><div>However, still the slowness does not seem to go away. </div><div>Below are my sssd_domain logs (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_message_handler] (0x2000): Received SBUS method org.freedesktop.sssd.service.ping on path /org/freedesktop/sssd/service (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_message_handler] (0x2000): Received SBUS method org.freedesktop.sssd.dataprovider.getAccountInfo on path /org/freedesktop/sssd/dataprovider (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_get_account_info] (0x0200): Got request for [0x3][1][name=testuser] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_req_set_domain] (0x0400): Changing request domain from [<a href="http://xyz.com">xyz.com</a>] to [<a href="http://xyz.com">xyz.com</a>] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_idmap_domain_has_algorithmic_mapping] (0x0080): Could not parse domain SID from [(null)] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_idmap_domain_has_algorithmic_mapping] (0x0080): Could not parse domain SID from [(null)] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_initgr_next_base] (0x0400): Searching for users with base [cn=accounts,dc=xyz,dc=com] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(uid=testuser)(objectclass=posixAccount)(&(uidNumber=*)(!(uidNumber=0))))][cn=accounts,dc=xyz,dc=com]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uid] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uidNumber] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gecos] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [homeDirectory] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginShell] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbPrincipalName] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [memberOf] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUniqueID] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaNTSecurityIdentifier] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [entryUSN] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowLastChange] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowMin] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowMax] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowWarning] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowInactive] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowExpire] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowFlag] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbLastPwdChange] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbPasswordExpiration] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [pwdAttribute] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [authorizedService] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [accountExpires] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userAccountControl] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [nsAccountLock] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [host] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginDisabled] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginExpirationTime] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginAllowedTimeMap] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaSshPubKey] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUserAuthType] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userCertificate;binary] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 120 (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 120 timeout 6 (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d00eb6b0], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_entry] (0x1000): OriginalDN: [uid=testuser,cn=users,cn=accounts,dc=xyz,dc=com]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [uid] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [uidNumber] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [gecos] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [homeDirectory] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [loginShell] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [krbPrincipalName] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [memberOf] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipaUniqueID] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [entryUSN] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [krbLastPwdChange] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [krbPasswordExpiration] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d00eb6b0], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 120 finished (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_user] (0x0400): Save user (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_get_sid_str] (0x1000): No [objectSIDString] attribute. [0][Success] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_primary_name] (0x0400): Processing object testuser (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_user] (0x0400): Processing user testuser (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_idmap_domain_has_algorithmic_mapping] (0x0080): Could not parse domain SID from [(null)] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_user] (0x2000): Adding originalDN [uid=testuser,cn=users,cn=accounts,dc=xyz,dc=com] to attributes of [testuser]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_user] (0x0400): Adding original memberOf attributes to [testuser]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding original mod-Timestamp [20160725110654Z] to attributes of [testuser]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_user] (0x0400): Adding user principal [testuser@xyz.COM] to attributes of [testuser]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowLastChange is not available for [testuser]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowMin is not available for [testuser]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowMax is not available for [testuser]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowWarning is not available for [testuser]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowInactive is not available for [testuser]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowExpire is not available for [testuser]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowFlag is not available for [testuser]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding krbLastPwdChange [20160511120919Z] to attributes of [testuser]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding krbPasswordExpiration [20160809120919Z] to attributes of [testuser]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): pwdAttribute is not available for [testuser]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): authorizedService is not available for [testuser]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): adAccountExpires is not available for [testuser]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): adUserAccountControl is not available for [testuser]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): nsAccountLock is not available for [testuser]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): authorizedHost is not available for [testuser]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): ndsLoginDisabled is not available for [testuser]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): ndsLoginExpirationTime is not available for [testuser]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): ndsLoginAllowedTimeMap is not available for [testuser]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): sshPublicKey is not available for [testuser]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): authType is not available for [testuser]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): userCertificate is not available for [testuser]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_user] (0x0400): Storing info for user testuser (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [userPassword] from [testuser] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [objectSIDString] from [testuser] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowLastChange] from [testuser] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowMin] from [testuser] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowMax] from [testuser] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowWarning] from [testuser] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowInactive] from [testuser] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowExpire] from [testuser] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowFlag] from [testuser] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [pwdAttribute] from [testuser] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [authorizedService] from [testuser] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [adAccountExpires] from [testuser] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [adUserAccountControl] from [testuser] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [nsAccountLock] from [testuser] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [authorizedHost] from [testuser] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [ndsLoginDisabled] from [testuser] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [ndsLoginExpirationTime] from [testuser] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [ndsLoginAllowedTimeMap] from [testuser] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [sshPublicKey] from [testuser] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [authType] from [testuser] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [userCertificate] from [testuser] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_primary_name] (0x0400): Processing object testuser (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_has_deref_support] (0x0400): The server supports deref method OpenLDAP (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(|(objectClass=ipaUserGroup)(objectClass=posixGroup))(cn=*))][cn=ipausers,cn=groups,cn=accounts,dc=xyz,dc=com]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [posixGroup] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [member] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUniqueID] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaNTSecurityIdentifier] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [entryUSN] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 121 (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 121 timeout 6 (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d012c820], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d012c820], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_entry] (0x1000): OriginalDN: [cn=ipausers,cn=groups,cn=accounts,dc=xyz,dc=com]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [member] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipaUniqueID] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [entryUSN] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d012c820], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 121 finished (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(|(objectClass=ipaUserGroup)(objectClass=posixGroup))(cn=*))][cn=spradmins,cn=groups,cn=accounts,dc=xyz,dc=com]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [posixGroup] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [member] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUniqueID] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaNTSecurityIdentifier] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [entryUSN] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 122 (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 122 timeout 6 (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d012c820], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d012c820], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_entry] (0x1000): OriginalDN: [cn=spradmins,cn=groups,cn=accounts,dc=xyz,dc=com]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [member] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipaUniqueID] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [entryUSN] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d012c820], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 122 finished (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(|(objectClass=ipaUserGroup)(objectClass=posixGroup))(cn=*))][ipaUniqueID=f990b3fc-1770-11e6-b561-000d3a01891b,cn=sudorules,cn=sudo,dc=xyz,dc=com]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [posixGroup] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [member] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUniqueID] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaNTSecurityIdentifier] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [entryUSN] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 123 (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 123 timeout 6 (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d013c3d0], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d013c3d0], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 123 finished (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_initgr_nested_search] (0x0040): Search for group ipaUniqueID=f990b3fc-1770-11e6-b561-000d3a01891b,cn=sudorules,cn=sudo,dc=xyz,dc=com, returned 0 results. Skipping (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(|(objectClass=ipaUserGroup)(objectClass=posixGroup))(cn=*))][cn=spr-itops,cn=groups,cn=accounts,dc=xyz,dc=com]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [posixGroup] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [member] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUniqueID] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaNTSecurityIdentifier] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [entryUSN] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 124 (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 124 timeout 6 (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d012c820], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d012c820], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_entry] (0x1000): OriginalDN: [cn=spr-itops,cn=groups,cn=accounts,dc=xyz,dc=com]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [member] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipaUniqueID] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [entryUSN] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d012c820], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 124 finished (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_primary_name] (0x0400): Processing object ipausers (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_get_direct_parents] (0x2000): searching sysdb with filter [(&(objectClass=group)(member=name=ipausers,cn=groups,cn=<a href="http://xyz.com">xyz.com</a>,cn=sysdb))] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_get_direct_parents] (0x1000): ipausers is a member of 0 sysdb groups (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_initgr_nested_get_membership_diff] (0x1000): The group ipausers is a direct member of 0 LDAP groups (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_primary_name] (0x0400): Processing object spradmins (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_get_direct_parents] (0x2000): searching sysdb with filter [(&(objectClass=group)(member=name=spradmins,cn=groups,cn=<a href="http://xyz.com">xyz.com</a>,cn=sysdb))] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_get_direct_parents] (0x1000): spradmins is a member of 0 sysdb groups (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_initgr_nested_get_membership_diff] (0x1000): The group spradmins is a direct member of 0 LDAP groups (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_primary_name] (0x0400): Processing object spr-itops (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_get_direct_parents] (0x2000): searching sysdb with filter [(&(objectClass=group)(member=name=spr-itops,cn=groups,cn=<a href="http://xyz.com">xyz.com</a>,cn=sysdb))] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_get_direct_parents] (0x1000): spr-itops is a member of 0 sysdb groups (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_initgr_nested_get_membership_diff] (0x1000): The group spr-itops is a direct member of 0 LDAP groups (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_initgr_store_user_memberships] (0x1000): The user testuser is a direct member of 3 LDAP groups (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_get_direct_parents] (0x2000): searching sysdb with filter [(&(objectClass=group)(member=name=testuser,cn=users,cn=<a href="http://xyz.com">xyz.com</a>,cn=sysdb))] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_get_direct_parents] (0x1000): testuser is a member of 3 sysdb groups (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_initgr_store_user_memberships] (0x2000): Updating memberships for testuser (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_idmap_domain_has_algorithmic_mapping] (0x0080): Could not parse domain SID from [(null)] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [cn=accounts,dc=xyz,dc=com] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(gidNumber=703)(|(objectClass=ipaUserGroup)(objectClass=posixGroup))(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][cn=accounts,dc=xyz,dc=com]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [posixGroup] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUniqueID] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaNTSecurityIdentifier] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [entryUSN] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 125 (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 125 timeout 6 (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d012c820], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d012c820], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_entry] (0x1000): OriginalDN: [cn=spradmins,cn=groups,cn=accounts,dc=xyz,dc=com]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipaUniqueID] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [entryUSN] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d012c820], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 125 finished (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_has_deref_support] (0x0400): The server supports deref method OpenLDAP (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_idmap_domain_has_algorithmic_mapping] (0x0080): Could not parse domain SID from [(null)] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_nested_group_process_send] (0x2000): About to process group [cn=spradmins,cn=groups,cn=accounts,dc=xyz,dc=com] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_nested_group_recv] (0x0400): 0 users found in the hash table (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_get_sid_str] (0x1000): No [objectSIDString] attribute. [0][Success] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_primary_name] (0x0400): Processing object spradmins (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_group] (0x0400): Processing group spradmins (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_idmap_domain_has_algorithmic_mapping] (0x0080): Could not parse domain SID from [(null)] (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_group] (0x2000): This is a posix group (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding original DN [cn=spradmins,cn=groups,cn=accounts,dc=xyz,dc=com] to attributes of [spradmins]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding original mod-Timestamp [20160722213052Z] to attributes of [spradmins]. (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_ghost_members] (0x0400): The group has 0 members (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_ghost_members] (0x0400): Group has 0 members (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_group] (0x0400): Storing info for group spradmins (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_primary_name] (0x0400): Processing object spradmins (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_grpmem] (0x0400): Processing group spradmins (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_grpmem] (0x0400): Failed to get group sid (Mon Jul 25 11:10:22 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_grpmem] (0x0400): No members for group [spradmins] (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_nested_done] (0x2000): No external members, done(Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_add_timeout] (0x2000): 0x7f88d00f83a0 (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[(nil)], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_remove_timeout] (0x2000): 0x7f88d00f83a0 (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [acctinfo_callback] (0x0100): Request processed. Returned 0,0,Success (Success) (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_message_handler] (0x2000): Received SBUS method org.freedesktop.sssd.dataprovider.pamHandler on path /org/freedesktop/sssd/dataprovider (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_req_set_domain] (0x0400): Changing request domain from [<a href="http://xyz.com">xyz.com</a>] to [<a href="http://xyz.com">xyz.com</a>] (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_pam_handler] (0x0100): Got request with the following data (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): command: SSS_PAM_PREAUTH (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): domain: <a href="http://xyz.com">xyz.com</a> (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): user: testuser (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): service: sshd (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): tty: ssh (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): ruser: (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): rhost: 10.65.16.4 (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): authtok type: 0 (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): newauthtok type: 0 (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): priv: 1 (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): cli_pid: 36265 (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): logon name: not set (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [krb5_auth_queue_send] (0x1000): Wait queue of user [testuser] is empty, running request [0x7f88d0128bd0] immediately. (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [fo_resolve_service_send] (0x0100): Trying to resolve service 'IPA' (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [get_server_status] (0x1000): Status of server '<a href="http://prod1-ipa-master-int.xyz.com">prod1-ipa-master-int.xyz.com</a>' is 'working' (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [get_server_status] (0x0100): Hostname resolution expired, resetting the server status of '<a href="http://prod1-ipa-master-int.xyz.com">prod1-ipa-master-int.xyz.com</a>' (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [set_server_common_status] (0x0100): Marking server '<a href="http://prod1-ipa-master-int.xyz.com">prod1-ipa-master-int.xyz.com</a>' as 'name not resolved' (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [get_port_status] (0x1000): Port status of port 0 for server '<a href="http://prod1-ipa-master-int.xyz.com">prod1-ipa-master-int.xyz.com</a>' is 'working' (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [fo_resolve_service_activate_timeout] (0x2000): Resolve timeout set to 6 seconds (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [get_server_status] (0x1000): Status of server '<a href="http://prod1-ipa-master-int.xyz.com">prod1-ipa-master-int.xyz.com</a>' is 'name not resolved' (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [resolv_gethostbyname_step] (0x2000): Querying files (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [resolv_gethostbyname_files_send] (0x0100): Trying to resolve A record of '<a href="http://prod1-ipa-master-int.xyz.com">prod1-ipa-master-int.xyz.com</a>' in files (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [set_server_common_status] (0x0100): Marking server '<a href="http://prod1-ipa-master-int.xyz.com">prod1-ipa-master-int.xyz.com</a>' as 'resolving name' (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [resolv_gethostbyname_step] (0x2000): Querying files (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [resolv_gethostbyname_files_send] (0x0100): Trying to resolve AAAA record of '<a href="http://prod1-ipa-master-int.xyz.com">prod1-ipa-master-int.xyz.com</a>' in files (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [resolv_gethostbyname_next] (0x0200): No more address families to retry (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [resolv_gethostbyname_step] (0x2000): Querying DNS (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [resolv_gethostbyname_dns_query] (0x0100): Trying to resolve A record of '<a href="http://prod1-ipa-master-int.xyz.com">prod1-ipa-master-int.xyz.com</a>' in DNS (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [schedule_request_timeout] (0x2000): Scheduling a timeout of 6 seconds (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [schedule_timeout_watcher] (0x2000): Scheduling DNS timeout watcher (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [resolv_gethostbyname_dns_parse] (0x1000): Parsing an A reply (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [request_watch_destructor] (0x0400): Deleting request watch (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [set_server_common_status] (0x0100): Marking server '<a href="http://prod1-ipa-master-int.xyz.com">prod1-ipa-master-int.xyz.com</a>' as 'name resolved' (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_resolve_server_process] (0x1000): Saving the first resolved server (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_resolve_server_process] (0x0200): Found address for server <a href="http://prod1-ipa-master-int.xyz.com">prod1-ipa-master-int.xyz.com</a>: [10.65.16.4] TTL 300 (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [ipa_resolve_callback] (0x0400): Constructed uri 'ldap://<a href="http://prod1-ipa-master-int.xyz.com">prod1-ipa-master-int.xyz.com</a>' (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [child_handler_setup] (0x2000): Setting up signal handler up for pid [36271] (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [child_handler_setup] (0x2000): Signal handler set up for pid [36271] (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [write_pipe_handler] (0x0400): All data has been sent! (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [read_pipe_handler] (0x0400): EOF received, client finished (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [fo_set_port_status] (0x0100): Marking port 0 of server '<a href="http://prod1-ipa-master-int.xyz.com">prod1-ipa-master-int.xyz.com</a>' as 'working' (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [set_server_common_status] (0x0100): Marking server '<a href="http://prod1-ipa-master-int.xyz.com">prod1-ipa-master-int.xyz.com</a>' as 'working' (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [fo_set_port_status] (0x0400): Marking port 0 of duplicate server '<a href="http://prod1-ipa-master-int.xyz.com">prod1-ipa-master-int.xyz.com</a>' as 'working' (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [krb5_auth_store_creds] (0x0010): unsupported PAM command [249]. (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [krb5_auth_store_creds] (0x0010): password not available, offline auth may not work. (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [check_wait_queue] (0x1000): Wait queue for user [testuser] is empty. (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [krb5_auth_queue_done] (0x1000): krb5_auth_queue request [0x7f88d0128bd0] done. (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_pam_handler_callback] (0x0100): Backend returned: (0, 0, <NULL>) [Success (Success)] (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_pam_handler_callback] (0x0100): Sending result [0][<a href="http://xyz.com">xyz.com</a>] (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_pam_handler_callback] (0x0100): Sent result [0][<a href="http://xyz.com">xyz.com</a>] (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [child_sig_handler] (0x1000): Waiting for child [36271]. (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [child_sig_handler] (0x0100): child [36271] finished successfully. (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_message_handler] (0x2000): Received SBUS method org.freedesktop.sssd.dataprovider.pamHandler on path /org/freedesktop/sssd/dataprovider (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_req_set_domain] (0x0400): Changing request domain from [<a href="http://xyz.com">xyz.com</a>] to [<a href="http://xyz.com">xyz.com</a>] (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_pam_handler] (0x0100): Got request with the following data (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): command: PAM_AUTHENTICATE (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): domain: <a href="http://xyz.com">xyz.com</a> (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): user: testuser (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): service: sshd (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): tty: ssh (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): ruser: (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): rhost: 10.65.16.4 (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): authtok type: 1 (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): newauthtok type: 0 (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): priv: 1 (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): cli_pid: 36265 (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): logon name: not set (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [krb5_auth_queue_send] (0x1000): Wait queue of user [testuser] is empty, running request [0x7f88d0128bd0] immediately. (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [fo_resolve_service_send] (0x0100): Trying to resolve service 'IPA' (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [get_server_status] (0x1000): Status of server '<a href="http://prod1-ipa-master-int.xyz.com">prod1-ipa-master-int.xyz.com</a>' is 'working' (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [get_port_status] (0x1000): Port status of port 0 for server '<a href="http://prod1-ipa-master-int.xyz.com">prod1-ipa-master-int.xyz.com</a>' is 'working' (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [fo_resolve_service_activate_timeout] (0x2000): Resolve timeout set to 6 seconds (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [get_server_status] (0x1000): Status of server '<a href="http://prod1-ipa-master-int.xyz.com">prod1-ipa-master-int.xyz.com</a>' is 'working' (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_resolve_server_process] (0x1000): Saving the first resolved server (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_resolve_server_process] (0x0200): Found address for server <a href="http://prod1-ipa-master-int.xyz.com">prod1-ipa-master-int.xyz.com</a>: [10.65.16.4] TTL 300 (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [child_handler_setup] (0x2000): Setting up signal handler up for pid [36272] (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [child_handler_setup] (0x2000): Signal handler set up for pid [36272] (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [write_pipe_handler] (0x0400): All data has been sent! (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [child_sig_handler] (0x1000): Waiting for child [36272]. (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [child_sig_handler] (0x0100): child [36272] finished successfully. (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [read_pipe_handler] (0x0400): EOF received, client finished (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [parse_krb5_child_response] (0x1000): child response [0][3][35]. (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [parse_krb5_child_response] (0x1000): child response [0][-1073741822][28]. (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [parse_krb5_child_response] (0x1000): child response [0][-1073741823][32]. (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [parse_krb5_child_response] (0x1000): TGT times are [1469445023][1469445023][1469531423][0]. (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [parse_krb5_child_response] (0x1000): child response [0][6][8]. (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [fo_set_port_status] (0x0100): Marking port 0 of server '<a href="http://prod1-ipa-master-int.xyz.com">prod1-ipa-master-int.xyz.com</a>' as 'working' (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [set_server_common_status] (0x0100): Marking server '<a href="http://prod1-ipa-master-int.xyz.com">prod1-ipa-master-int.xyz.com</a>' as 'working' (Mon Jul 25 11:10:23 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [fo_set_port_status] (0x0400): Marking port 0 of duplicate server '<a href="http://prod1-ipa-master-int.xyz.com">prod1-ipa-master-int.xyz.com</a>' as 'working' (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [check_wait_queue] (0x1000): Wait queue for user [testuser] is empty. (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [krb5_auth_queue_done] (0x1000): krb5_auth_queue request [0x7f88d0128bd0] done. (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_pam_handler_callback] (0x0100): Backend returned: (0, 0, <NULL>) [Success (Success)] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_pam_handler_callback] (0x0100): Sending result [0][<a href="http://xyz.com">xyz.com</a>] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_pam_handler_callback] (0x0100): Sent result [0][<a href="http://xyz.com">xyz.com</a>] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_message_handler] (0x2000): Received SBUS method org.freedesktop.sssd.dataprovider.pamHandler on path /org/freedesktop/sssd/dataprovider (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_req_set_domain] (0x0400): Changing request domain from [<a href="http://xyz.com">xyz.com</a>] to [<a href="http://xyz.com">xyz.com</a>] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_pam_handler] (0x0100): Got request with the following data (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): command: PAM_ACCT_MGMT (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): domain: <a href="http://xyz.com">xyz.com</a> (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): user: testuser (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): service: sshd (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): tty: ssh (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): ruser: (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): rhost: 10.65.16.4 (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): authtok type: 0 (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): newauthtok type: 0 (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): priv: 1 (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): cli_pid: 36265 (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): logon name: not set (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_access_send] (0x0400): Performing access check for user [testuser] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_account_expired_rhds] (0x0400): Performing RHDS access check for user [testuser] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaHost)(fqdn=10.65.32.4))][cn=accounts,dc=xyz,dc=com]. (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [fqdn] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [serverHostname] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [memberOf] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaSshPubKey] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUniqueID] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 126 (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 126 timeout 60 (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d00f8b70], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_entry] (0x1000): OriginalDN: [fqdn=10.65.32.4,cn=computers,cn=accounts,dc=xyz,dc=com]. (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [fqdn] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [serverHostname] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipaSshPubKey] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipaUniqueID] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d00f8b70], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x2000): Total count [0] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 126 finished (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_has_deref_support] (0x0400): The server supports deref method OpenLDAP (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_deref_search_send] (0x2000): Server supports OpenLDAP deref (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_x_deref_search_send] (0x0400): Dereferencing entry [fqdn=10.65.32.4,cn=computers,cn=accounts,dc=xyz,dc=com] using OpenLDAP deref (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [no filter][fqdn=10.65.32.4,cn=computers,cn=accounts,dc=xyz,dc=com]. (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [memberOf] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUniqueID] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 127 (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 127 timeout 60 (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d00f8b70], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d00f8b70], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_x_deref_parse_entry] (0x0400): Got deref control (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_x_deref_parse_entry] (0x0400): All deref results from a single control parsed (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d00f8b70], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x2000): Total count [0] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 127 finished (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [ipa_hostgroup_info_done] (0x0200): No host groups were dereferenced (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [ipa_hbac_service_info_next] (0x0400): Sending request for next search base: [cn=hbac,dc=xyz,dc=com][2][(objectClass=ipaHBACService)] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectClass=ipaHBACService)][cn=hbac,dc=xyz,dc=com]. (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectclass] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipauniqueid] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [member] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [memberOf] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 128 (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 128 timeout 60 (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d00f8b70], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d00f8b70], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_entry] (0x1000): OriginalDN: [cn=sshd,cn=hbacservices,cn=hbac,dc=xyz,dc=com]. (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d00f8b70], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_entry] (0x1000): OriginalDN: [cn=ftp,cn=hbacservices,cn=hbac,dc=xyz,dc=com]. (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [memberOf] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d00f8b70], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_entry] (0x1000): OriginalDN: [cn=su,cn=hbacservices,cn=hbac,dc=xyz,dc=com]. (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d00f8b70], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_entry] (0x1000): OriginalDN: [cn=login,cn=hbacservices,cn=hbac,dc=xyz,dc=com]. (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d00f8b70], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_entry] (0x1000): OriginalDN: [cn=su-l,cn=hbacservices,cn=hbac,dc=xyz,dc=com]. (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d00f8b70], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_entry] (0x1000): OriginalDN: [cn=sudo,cn=hbacservices,cn=hbac,dc=xyz,dc=com]. (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [memberOf] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d00f8b70], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_entry] (0x1000): OriginalDN: [cn=sudo-i,cn=hbacservices,cn=hbac,dc=xyz,dc=com]. (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [memberOf] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d00f8b70], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_entry] (0x1000): OriginalDN: [cn=gdm,cn=hbacservices,cn=hbac,dc=xyz,dc=com]. (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d00f8b70], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_entry] (0x1000): OriginalDN: [cn=gdm-password,cn=hbacservices,cn=hbac,dc=xyz,dc=com]. (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d00f8b70], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_entry] (0x1000): OriginalDN: [cn=kdm,cn=hbacservices,cn=hbac,dc=xyz,dc=com]. (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d00f8b70], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_entry] (0x1000): OriginalDN: [cn=crond,cn=hbacservices,cn=hbac,dc=xyz,dc=com]. (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d00f8b70], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_entry] (0x1000): OriginalDN: [cn=vsftpd,cn=hbacservices,cn=hbac,dc=xyz,dc=com]. (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [memberOf] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d00f8b70], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_entry] (0x1000): OriginalDN: [cn=proftpd,cn=hbacservices,cn=hbac,dc=xyz,dc=com]. (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [memberOf] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d00f8b70], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_entry] (0x1000): OriginalDN: [cn=pure-ftpd,cn=hbacservices,cn=hbac,dc=xyz,dc=com]. (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [memberOf] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d00f8b70], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_entry] (0x1000): OriginalDN: [cn=gssftp,cn=hbacservices,cn=hbac,dc=xyz,dc=com]. (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [memberOf] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d00f8b70], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x2000): Total count [0] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 128 finished (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [ipa_hbac_servicegroup_info_next] (0x0400): Sending request for next search base: [cn=hbac,dc=xyz,dc=com][2][(objectClass=ipaHBACServiceGroup)] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectClass=ipaHBACServiceGroup)][cn=hbac,dc=xyz,dc=com]. (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectclass] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipauniqueid] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [member] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [memberOf] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 129 (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 129 timeout 60 (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d00f8b70], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d00f8b70], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_entry] (0x1000): OriginalDN: [cn=Sudo,cn=hbacservicegroups,cn=hbac,dc=xyz,dc=com]. (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [member] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d00f8b70], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_entry] (0x1000): OriginalDN: [cn=ftp,cn=hbacservicegroups,cn=hbac,dc=xyz,dc=com]. (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [member] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d00f8b70], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x2000): Total count [0] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 129 finished (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [ipa_hbac_rule_info_next] (0x0400): Sending request for next search base: [cn=hbac,dc=xyz,dc=com][2][(&(objectclass=ipaHBACRule)(ipaenabledflag=TRUE)(accessRuleType=allow)(|(hostCategory=all)(memberHost=fqdn=10.65.32.4,cn=computers,cn=accounts,dc=xyz,dc=com)))] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectclass=ipaHBACRule)(ipaenabledflag=TRUE)(accessRuleType=allow)(|(hostCategory=all)(memberHost=fqdn=10.65.32.4,cn=computers,cn=accounts,dc=xyz,dc=com)))][cn=hbac,dc=xyz,dc=com]. (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectclass] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipauniqueid] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaenabledflag] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [accessRuleType] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [memberUser] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userCategory] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [memberService] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [serviceCategory] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sourceHost] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sourceHostCategory] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [externalHost] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [memberHost] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [hostCategory] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 130 (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 130 timeout 60 (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d00f8b70], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d00f8b70], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_entry] (0x1000): OriginalDN: [ipaUniqueID=7f4abeae-176f-11e6-9090-000d3a01891b,cn=hbac,dc=xyz,dc=com]. (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipaenabledflag] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [accessRuleType] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [userCategory] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [serviceCategory] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [hostCategory] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d00f8b70], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x2000): Total count [0] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 130 finished (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [hbac_attrs_to_rule] (0x1000): Processing rule [allow_all] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [hbac_user_attrs_to_rule] (0x1000): Processing users for rule [allow_all] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [hbac_get_category] (0x0200): Category is set to 'all'. (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [hbac_service_attrs_to_rule] (0x1000): Processing PAM services for rule [allow_all] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [hbac_get_category] (0x0200): Category is set to 'all'. (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [hbac_thost_attrs_to_rule] (0x1000): Processing target hosts for rule [allow_all] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [hbac_get_category] (0x0200): Category is set to 'all'. (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [hbac_shost_attrs_to_rule] (0x0400): Processing source hosts for rule [allow_all] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [hbac_shost_attrs_to_rule] (0x2000): Source hosts disabled, setting ALL (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [hbac_eval_user_element] (0x1000): [4] groups for [testuser] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [hbac_eval_user_element] (0x1000): Added group [ipausers] for user [testuser] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [hbac_eval_user_element] (0x1000): Added group [spradmins] for user [testuser] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [hbac_eval_user_element] (0x2000): Skipping non-group memberOf [ipaUniqueID=f990b3fc-1770-11e6-b561-000d3a01891b,cn=sudorules,cn=sudo,dc=xyz,dc=com] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [hbac_eval_user_element] (0x1000): Added group [spr-itops] for user [testuser] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [ipa_hbac_evaluate_rules] (0x0080): Access granted by HBAC rule [allow_all] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_pam_handler_callback] (0x0100): Backend returned: (0, 0, <NULL>) [Success (Success)] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_pam_handler_callback] (0x0400): SELinux provider doesn't exist, not sending the request to it. (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_pam_handler_callback] (0x0100): Sending result [0][<a href="http://xyz.com">xyz.com</a>] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_pam_handler_callback] (0x0100): Sent result [0][<a href="http://xyz.com">xyz.com</a>] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[(nil)], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_message_handler] (0x2000): Received SBUS method org.freedesktop.sssd.dataprovider.pamHandler on path /org/freedesktop/sssd/dataprovider (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_req_set_domain] (0x0400): Changing request domain from [<a href="http://xyz.com">xyz.com</a>] to [<a href="http://xyz.com">xyz.com</a>] (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_pam_handler] (0x0100): Got request with the following data (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): command: PAM_SETCRED (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): domain: <a href="http://xyz.com">xyz.com</a> (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): user: testuser (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): service: sshd (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): tty: ssh (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): ruser: (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): rhost: 10.65.16.4 (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): authtok type: 0 (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): newauthtok type: 0 (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): priv: 1 (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): cli_pid: 36265 (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): logon name: not set (Mon Jul 25 11:10:24 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_pam_handler] (0x0100): Sending result [0][<a href="http://xyz.com">xyz.com</a>] (Mon Jul 25 11:10:32 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_message_handler] (0x2000): Received SBUS method org.freedesktop.sssd.service.ping on path /org/freedesktop/sssd/service (Mon Jul 25 11:10:32 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_message_handler] (0x2000): Received SBUS method org.freedesktop.sssd.dataprovider.getAccountInfo on path /org/freedesktop/sssd/dataprovider (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_get_account_info] (0x0200): Got request for [0x3][1][name=testuser] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_req_set_domain] (0x0400): Changing request domain from [<a href="http://xyz.com">xyz.com</a>] to [<a href="http://xyz.com">xyz.com</a>] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_idmap_domain_has_algorithmic_mapping] (0x0080): Could not parse domain SID from [(null)] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_idmap_domain_has_algorithmic_mapping] (0x0080): Could not parse domain SID from [(null)] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_initgr_next_base] (0x0400): Searching for users with base [cn=accounts,dc=xyz,dc=com] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(uid=testuser)(objectclass=posixAccount)(&(uidNumber=*)(!(uidNumber=0))))][cn=accounts,dc=xyz,dc=com]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uid] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uidNumber] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gecos] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [homeDirectory] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginShell] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbPrincipalName] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [memberOf] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUniqueID] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaNTSecurityIdentifier] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [entryUSN] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowLastChange] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowMin] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowMax] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowWarning] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowInactive] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowExpire] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowFlag] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbLastPwdChange] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbPasswordExpiration] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [pwdAttribute] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [authorizedService] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [accountExpires] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userAccountControl] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [nsAccountLock] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [host] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginDisabled] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginExpirationTime] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginAllowedTimeMap] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaSshPubKey] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUserAuthType] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userCertificate;binary] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 131 (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 131 timeout 6 (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d00f8b70], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_entry] (0x1000): OriginalDN: [uid=testuser,cn=users,cn=accounts,dc=xyz,dc=com]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [uid] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [uidNumber] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [gecos] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [homeDirectory] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [loginShell] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [krbPrincipalName] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [memberOf] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipaUniqueID] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [entryUSN] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [krbLastPwdChange] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [krbPasswordExpiration] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d00f8b70], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 131 finished (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_user] (0x0400): Save user (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_get_sid_str] (0x1000): No [objectSIDString] attribute. [0][Success] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_primary_name] (0x0400): Processing object testuser (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_user] (0x0400): Processing user testuser (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_idmap_domain_has_algorithmic_mapping] (0x0080): Could not parse domain SID from [(null)] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_user] (0x2000): Adding originalDN [uid=testuser,cn=users,cn=accounts,dc=xyz,dc=com] to attributes of [testuser]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_user] (0x0400): Adding original memberOf attributes to [testuser]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding original mod-Timestamp [20160725111022Z] to attributes of [testuser]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_user] (0x0400): Adding user principal [testuser@xyz.COM] to attributes of [testuser]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowLastChange is not available for [testuser]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowMin is not available for [testuser]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowMax is not available for [testuser]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowWarning is not available for [testuser]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowInactive is not available for [testuser]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowExpire is not available for [testuser]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowFlag is not available for [testuser]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding krbLastPwdChange [20160511120919Z] to attributes of [testuser]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding krbPasswordExpiration [20160809120919Z] to attributes of [testuser]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): pwdAttribute is not available for [testuser]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): authorizedService is not available for [testuser]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): adAccountExpires is not available for [testuser]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): adUserAccountControl is not available for [testuser]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): nsAccountLock is not available for [testuser]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): authorizedHost is not available for [testuser]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): ndsLoginDisabled is not available for [testuser]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): ndsLoginExpirationTime is not available for [testuser]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): ndsLoginAllowedTimeMap is not available for [testuser]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): sshPublicKey is not available for [testuser]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): authType is not available for [testuser]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): userCertificate is not available for [testuser]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_user] (0x0400): Storing info for user testuser (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [userPassword] from [testuser] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [objectSIDString] from [testuser] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowLastChange] from [testuser] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowMin] from [testuser] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowMax] from [testuser] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowWarning] from [testuser] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowInactive] from [testuser] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowExpire] from [testuser] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowFlag] from [testuser] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [pwdAttribute] from [testuser] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [authorizedService] from [testuser] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [adAccountExpires] from [testuser] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [adUserAccountControl] from [testuser] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [nsAccountLock] from [testuser] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [authorizedHost] from [testuser] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [ndsLoginDisabled] from [testuser] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [ndsLoginExpirationTime] from [testuser] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [ndsLoginAllowedTimeMap] from [testuser] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [sshPublicKey] from [testuser] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [authType] from [testuser] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [userCertificate] from [testuser] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_primary_name] (0x0400): Processing object testuser (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_has_deref_support] (0x0400): The server supports deref method OpenLDAP (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(|(objectClass=ipaUserGroup)(objectClass=posixGroup))(cn=*))][cn=ipausers,cn=groups,cn=accounts,dc=xyz,dc=com]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [posixGroup] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [member] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUniqueID] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaNTSecurityIdentifier] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [entryUSN] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 132 (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 132 timeout 6 (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d012ba00], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d012ba00], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_entry] (0x1000): OriginalDN: [cn=ipausers,cn=groups,cn=accounts,dc=xyz,dc=com]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [member] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipaUniqueID] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [entryUSN] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d012ba00], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 132 finished (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(|(objectClass=ipaUserGroup)(objectClass=posixGroup))(cn=*))][cn=spradmins,cn=groups,cn=accounts,dc=xyz,dc=com]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [posixGroup] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [member] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUniqueID] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaNTSecurityIdentifier] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [entryUSN] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 133 (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 133 timeout 6 (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d012ba00], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d012ba00], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_entry] (0x1000): OriginalDN: [cn=spradmins,cn=groups,cn=accounts,dc=xyz,dc=com]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [member] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipaUniqueID] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [entryUSN] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d012ba00], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 133 finished (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(|(objectClass=ipaUserGroup)(objectClass=posixGroup))(cn=*))][ipaUniqueID=f990b3fc-1770-11e6-b561-000d3a01891b,cn=sudorules,cn=sudo,dc=xyz,dc=com]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [posixGroup] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [member] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUniqueID] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaNTSecurityIdentifier] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [entryUSN] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 134 (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 134 timeout 6 (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d012ba00], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d012ba00], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 134 finished (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_initgr_nested_search] (0x0040): Search for group ipaUniqueID=f990b3fc-1770-11e6-b561-000d3a01891b,cn=sudorules,cn=sudo,dc=xyz,dc=com, returned 0 results. Skipping (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(|(objectClass=ipaUserGroup)(objectClass=posixGroup))(cn=*))][cn=spr-itops,cn=groups,cn=accounts,dc=xyz,dc=com]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [posixGroup] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [member] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUniqueID] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaNTSecurityIdentifier] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [entryUSN] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 135 (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 135 timeout 6 (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d012ba00], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d012ba00], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_entry] (0x1000): OriginalDN: [cn=spr-itops,cn=groups,cn=accounts,dc=xyz,dc=com]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [member] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipaUniqueID] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [entryUSN] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d012ba00], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 135 finished (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_primary_name] (0x0400): Processing object ipausers (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_get_direct_parents] (0x2000): searching sysdb with filter [(&(objectClass=group)(member=name=ipausers,cn=groups,cn=<a href="http://xyz.com">xyz.com</a>,cn=sysdb))] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_get_direct_parents] (0x1000): ipausers is a member of 0 sysdb groups (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_initgr_nested_get_membership_diff] (0x1000): The group ipausers is a direct member of 0 LDAP groups (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_primary_name] (0x0400): Processing object spradmins (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_get_direct_parents] (0x2000): searching sysdb with filter [(&(objectClass=group)(member=name=spradmins,cn=groups,cn=<a href="http://xyz.com">xyz.com</a>,cn=sysdb))] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_get_direct_parents] (0x1000): spradmins is a member of 0 sysdb groups (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_initgr_nested_get_membership_diff] (0x1000): The group spradmins is a direct member of 0 LDAP groups (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_primary_name] (0x0400): Processing object spr-itops (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_get_direct_parents] (0x2000): searching sysdb with filter [(&(objectClass=group)(member=name=spr-itops,cn=groups,cn=<a href="http://xyz.com">xyz.com</a>,cn=sysdb))] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_get_direct_parents] (0x1000): spr-itops is a member of 0 sysdb groups (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_initgr_nested_get_membership_diff] (0x1000): The group spr-itops is a direct member of 0 LDAP groups (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_initgr_store_user_memberships] (0x1000): The user testuser is a direct member of 3 LDAP groups (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_get_direct_parents] (0x2000): searching sysdb with filter [(&(objectClass=group)(member=name=testuser,cn=users,cn=<a href="http://xyz.com">xyz.com</a>,cn=sysdb))] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_get_direct_parents] (0x1000): testuser is a member of 3 sysdb groups (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_initgr_store_user_memberships] (0x2000): Updating memberships for testuser (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_idmap_domain_has_algorithmic_mapping] (0x0080): Could not parse domain SID from [(null)] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [cn=accounts,dc=xyz,dc=com] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(gidNumber=703)(|(objectClass=ipaUserGroup)(objectClass=posixGroup))(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][cn=accounts,dc=xyz,dc=com]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [posixGroup] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUniqueID] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaNTSecurityIdentifier] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [entryUSN] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 136 (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 136 timeout 6 (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d012ba00], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d012ba00], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_entry] (0x1000): OriginalDN: [cn=spradmins,cn=groups,cn=accounts,dc=xyz,dc=com]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipaUniqueID] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [entryUSN] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[0x7f88d012ba00], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 136 finished (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_has_deref_support] (0x0400): The server supports deref method OpenLDAP (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_idmap_domain_has_algorithmic_mapping] (0x0080): Could not parse domain SID from [(null)] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_nested_group_process_send] (0x2000): About to process group [cn=spradmins,cn=groups,cn=accounts,dc=xyz,dc=com] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_nested_group_recv] (0x0400): 0 users found in the hash table (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_get_sid_str] (0x1000): No [objectSIDString] attribute. [0][Success] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_primary_name] (0x0400): Processing object spradmins (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_group] (0x0400): Processing group spradmins (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_idmap_domain_has_algorithmic_mapping] (0x0080): Could not parse domain SID from [(null)] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_group] (0x2000): This is a posix group (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding original DN [cn=spradmins,cn=groups,cn=accounts,dc=xyz,dc=com] to attributes of [spradmins]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding original mod-Timestamp [20160722213052Z] to attributes of [spradmins]. (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_ghost_members] (0x0400): The group has 0 members (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_ghost_members] (0x0400): Group has 0 members (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_group] (0x0400): Storing info for group spradmins (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_primary_name] (0x0400): Processing object spradmins (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_grpmem] (0x0400): Processing group spradmins (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_grpmem] (0x0400): Failed to get group sid (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_grpmem] (0x0400): No members for group [spradmins] (Mon Jul 25 11:10:34 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_nested_done] (0x2000): No external members, done(Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_add_timeout] (0x2000): 0x7f88d00f83c0 (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d00eb060], connected[1], ops[(nil)], ldap[0x7f88d00dca80] (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_remove_timeout] (0x2000): 0x7f88d00f83c0 (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [acctinfo_callback] (0x0100): Request processed. Returned 0,0,Success (Success) (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_message_handler] (0x2000): Received SBUS method org.freedesktop.sssd.dataprovider.pamHandler on path /org/freedesktop/sssd/dataprovider (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_req_set_domain] (0x0400): Changing request domain from [<a href="http://xyz.com">xyz.com</a>] to [<a href="http://xyz.com">xyz.com</a>] (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_pam_handler] (0x0100): Got request with the following data (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): domain: <a href="http://xyz.com">xyz.com</a> (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): user: testuser (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): service: sshd (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): tty: ssh (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): ruser: (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): rhost: 10.65.16.4 (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): authtok type: 0 (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): newauthtok type: 0 (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): priv: 1 (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): cli_pid: 36265 (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): logon name: not set (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_pam_handler] (0x0100): Sending result [0][<a href="http://xyz.com">xyz.com</a>] (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_message_handler] (0x2000): Received SBUS method org.freedesktop.sssd.dataprovider.pamHandler on path /org/freedesktop/sssd/dataprovider (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_req_set_domain] (0x0400): Changing request domain from [<a href="http://xyz.com">xyz.com</a>] to [<a href="http://xyz.com">xyz.com</a>] (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_pam_handler] (0x0100): Got request with the following data (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): command: PAM_SETCRED (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): domain: <a href="http://xyz.com">xyz.com</a> (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): user: testuser (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): service: sshd (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): tty: ssh (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): ruser: (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): rhost: 10.65.16.4 (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): authtok type: 0 (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): newauthtok type: 0 (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): priv: 0 (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): cli_pid: 36274 (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): logon name: not set (Mon Jul 25 11:10:35 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_pam_handler] (0x0100): Sending result [0][<a href="http://xyz.com">xyz.com</a>] </div><div>any pointers here to how can i solve this issue </div><div>Thanks, </div><div>Rakesh </div><div> </div></div>