<div dir="ltr"><div><div><div><div><div><div> > Any change that it's running on a VM? If so, check your entropy: > cat /proc/sys/kernel/random/entropy_avail > If it's low (like < 1k), install haveged. </div>this indeed is vm , am running it on azure . However, I have a similar set up running on aws which works completely fine </div>The entropy was low, around 180, I installed haveged and now its above 3k cat /proc/sys/kernel/random/entropy_avail 3178 </div><div>The timing though is still the same around 19s </div><div> </div>@jakub, i am reattaching the logs. The dns resoltion seems fast when I check using dig below is my sssd.conf [domain/<a href="http://xyz.com">xyz.com</a>] selinux_provider=none krb5_auth_timeout = 20 cache_credentials = True krb5_store_password_if_offline = True ipa_domain = <a href="http://xyz.com">xyz.com</a> id_provider = ipa auth_provider = ipa access_provider = ipa ldap_tls_cacert = /etc/ipa/ca.crt ipa_hostname = 10.65.16.4 chpass_provider = ipa ipa_server = <a href="http://ipa-master-in.xyz.com">ipa-master-in.xyz.com</a> dns_discovery_domain = <a href="http://xyz.com">xyz.com</a> ignore_group_members=True ldap_purge_cache_timeout = 0 debug_level=8 [sssd] services = nss, sudo, pam, ssh config_file_version = 2 domains = <a href="http://xyz.com">xyz.com</a> [nss] homedir_substring = /home [pam] pam_id_timeout = 3 [sudo] [autofs] [ssh] [pac] [ifp] And here is the login times and logs [root@ipa-client-1 :~] date;ssh testuser@localhost Tue Jul 26 12:06:37 UTC 2016 testuser@localhost's password: Last login: Tue Jul 26 12:03:53 2016 from 127.0.0.1 [testuser@ipa-client-1 :~] date Tue Jul 26 12:06:55 UTC 2016 </div>sssd_domain logs (Tue Jul 26 12:06:40 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 (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_get_account_info] (0x0200): Got request for [0x3][1][name=testuser] (Tue Jul 26 12:06:40 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>] (Tue Jul 26 12:06:40 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)] (Tue Jul 26 12:06:40 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)] (Tue Jul 26 12:06:40 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] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Tue Jul 26 12:06:40 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]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uid] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uidNumber] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gecos] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [homeDirectory] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginShell] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbPrincipalName] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [memberOf] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUniqueID] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaNTSecurityIdentifier] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [entryUSN] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowLastChange] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowMin] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowMax] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowWarning] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowInactive] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowExpire] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowFlag] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbLastPwdChange] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbPasswordExpiration] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [pwdAttribute] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [authorizedService] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [accountExpires] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userAccountControl] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [nsAccountLock] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [host] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginDisabled] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginExpirationTime] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginAllowedTimeMap] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaSshPubKey] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUserAuthType] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userCertificate;binary] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 85 (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 85 timeout 6 (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d010af10], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:40 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]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [uid] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [uidNumber] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [gecos] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [homeDirectory] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [loginShell] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [krbPrincipalName] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [memberOf] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipaUniqueID] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [entryUSN] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [krbLastPwdChange] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [krbPasswordExpiration] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d010af10], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 85 finished (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_user] (0x0400): Save user (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_get_sid_str] (0x1000): No [objectSIDString] attribute. [0][Success] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_primary_name] (0x0400): Processing object testuser (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_user] (0x0400): Processing user testuser (Tue Jul 26 12:06:40 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)] (Tue Jul 26 12:06:40 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]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_user] (0x0400): Adding original memberOf attributes to [testuser]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding original mod-Timestamp [20160726120558Z] to attributes of [testuser]. (Tue Jul 26 12:06:40 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]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowLastChange is not available for [testuser]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowMin is not available for [testuser]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowMax is not available for [testuser]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowWarning is not available for [testuser]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowInactive is not available for [testuser]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowExpire is not available for [testuser]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowFlag is not available for [testuser]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding krbLastPwdChange [20160511120919Z] to attributes of [testuser]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding krbPasswordExpiration [20160809120919Z] to attributes of [testuser]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): pwdAttribute is not available for [testuser]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): authorizedService is not available for [testuser]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): adAccountExpires is not available for [testuser]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): adUserAccountControl is not available for [testuser]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): nsAccountLock is not available for [testuser]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): authorizedHost is not available for [testuser]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): ndsLoginDisabled is not available for [testuser]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): ndsLoginExpirationTime is not available for [testuser]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): ndsLoginAllowedTimeMap is not available for [testuser]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): sshPublicKey is not available for [testuser]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): authType is not available for [testuser]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): userCertificate is not available for [testuser]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_user] (0x0400): Storing info for user testuser (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [userPassword] from [testuser] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [objectSIDString] from [testuser] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowLastChange] from [testuser] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowMin] from [testuser] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowMax] from [testuser] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowWarning] from [testuser] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowInactive] from [testuser] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowExpire] from [testuser] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowFlag] from [testuser] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [pwdAttribute] from [testuser] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [authorizedService] from [testuser] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [adAccountExpires] from [testuser] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [adUserAccountControl] from [testuser] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [nsAccountLock] from [testuser] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [authorizedHost] from [testuser] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [ndsLoginDisabled] from [testuser] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [ndsLoginExpirationTime] from [testuser] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [ndsLoginAllowedTimeMap] from [testuser] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [sshPublicKey] from [testuser] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [authType] from [testuser] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [userCertificate] from [testuser] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_primary_name] (0x0400): Processing object testuser (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_has_deref_support] (0x0400): The server supports deref method OpenLDAP (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Tue Jul 26 12:06:40 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]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [posixGroup] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [member] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUniqueID] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaNTSecurityIdentifier] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [entryUSN] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 86 (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 86 timeout 6 (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d11378f0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d11378f0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:40 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]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [member] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipaUniqueID] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [entryUSN] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d11378f0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 86 finished (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Tue Jul 26 12:06:40 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]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [posixGroup] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [member] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUniqueID] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaNTSecurityIdentifier] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [entryUSN] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 87 (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 87 timeout 6 (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d11378f0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d11378f0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:40 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]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [member] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipaUniqueID] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [entryUSN] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d11378f0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 87 finished (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Tue Jul 26 12:06:40 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]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [posixGroup] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [member] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUniqueID] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaNTSecurityIdentifier] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [entryUSN] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 88 (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 88 timeout 6 (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d11378f0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d11378f0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 88 finished (Tue Jul 26 12:06:40 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 (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Tue Jul 26 12:06:40 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]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [posixGroup] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [member] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUniqueID] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaNTSecurityIdentifier] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [entryUSN] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 89 (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 89 timeout 6 (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d11378f0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d11378f0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:40 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]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [member] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipaUniqueID] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [entryUSN] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d11378f0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 89 finished (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_primary_name] (0x0400): Processing object ipausers (Tue Jul 26 12:06:40 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))] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_get_direct_parents] (0x1000): ipausers is a member of 0 sysdb groups (Tue Jul 26 12:06:40 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 (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_primary_name] (0x0400): Processing object spradmins (Tue Jul 26 12:06:40 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))] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_get_direct_parents] (0x1000): spradmins is a member of 0 sysdb groups (Tue Jul 26 12:06:40 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 (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_primary_name] (0x0400): Processing object spr-itops (Tue Jul 26 12:06:40 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))] (Tue Jul 26 12:06:40 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 (Tue Jul 26 12:06:40 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 (Tue Jul 26 12:06:40 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 (Tue Jul 26 12:06:40 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))] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_get_direct_parents] (0x1000): testuser is a member of 3 sysdb groups (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_initgr_store_user_memberships] (0x2000): Updating memberships for testuser (Tue Jul 26 12:06:40 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)] (Tue Jul 26 12:06:40 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] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Tue Jul 26 12:06:40 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]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [posixGroup] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUniqueID] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaNTSecurityIdentifier] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [entryUSN] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 90 (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 90 timeout 6 (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d11378f0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d11378f0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:40 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]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipaUniqueID] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [entryUSN] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d11378f0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 90 finished (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_has_deref_support] (0x0400): The server supports deref method OpenLDAP (Tue Jul 26 12:06:40 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)] (Tue Jul 26 12:06:40 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] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_nested_group_recv] (0x0400): 0 users found in the hash table (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_get_sid_str] (0x1000): No [objectSIDString] attribute. [0][Success] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_primary_name] (0x0400): Processing object spradmins (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_group] (0x0400): Processing group spradmins (Tue Jul 26 12:06:40 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)] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_group] (0x2000): This is a posix group (Tue Jul 26 12:06:40 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]. (Tue Jul 26 12:06:40 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]. (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_ghost_members] (0x0400): The group has 0 members (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_ghost_members] (0x0400): Group has 0 members (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_group] (0x0400): Storing info for group spradmins (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_primary_name] (0x0400): Processing object spradmins (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_grpmem] (0x0400): Processing group spradmins (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_grpmem] (0x0400): Failed to get group sid (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_grpmem] (0x0400): No members for group [spradmins] (Tue Jul 26 12:06:40 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_nested_done] (0x2000): No external members, done(Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_add_timeout] (0x2000): 0x7f88d0100830 (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[(nil)], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_remove_timeout] (0x2000): 0x7f88d0100830 (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [acctinfo_callback] (0x0100): Request processed. Returned 0,0,Success (Success) (Tue Jul 26 12:06:41 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 (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Tue Jul 26 12:06:41 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>] (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_pam_handler] (0x0100): Got request with the following data (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): command: SSS_PAM_PREAUTH (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): domain: <a href="http://xyz.com">xyz.com</a> (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): user: testuser (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): service: sshd (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): tty: ssh (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): ruser: (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): rhost: 127.0.0.1 (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): authtok type: 0 (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): newauthtok type: 0 (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): priv: 1 (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): cli_pid: 42266 (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): logon name: not set (Tue Jul 26 12:06:41 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 [0x7f88d1142ab0] immediately. (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [fo_resolve_service_send] (0x0100): Trying to resolve service 'IPA' (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [get_server_status] (0x1000): Status of server '<a href="http://ipa-master-in.xyz.com">ipa-master-in.xyz.com</a>' is 'working' (Tue Jul 26 12:06:41 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://ipa-master-in.xyz.com">ipa-master-in.xyz.com</a>' is 'working' (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [fo_resolve_service_activate_timeout] (0x2000): Resolve timeout set to 6 seconds (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [get_server_status] (0x1000): Status of server '<a href="http://ipa-master-in.xyz.com">ipa-master-in.xyz.com</a>' is 'working' (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_resolve_server_process] (0x1000): Saving the first resolved server (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_resolve_server_process] (0x0200): Found address for server <a href="http://ipa-master-in.xyz.com">ipa-master-in.xyz.com</a>: [10.65.16.4] TTL 127 (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [ipa_resolve_callback] (0x0400): Constructed uri 'ldap://<a href="http://ipa-master-in.xyz.com">ipa-master-in.xyz.com</a>' (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [child_handler_setup] (0x2000): Setting up signal handler up for pid [42276] (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [child_handler_setup] (0x2000): Signal handler set up for pid [42276] (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [write_pipe_handler] (0x0400): All data has been sent! (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [child_sig_handler] (0x1000): Waiting for child [42276]. (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [child_sig_handler] (0x0100): child [42276] finished successfully. (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [read_pipe_handler] (0x0400): EOF received, client finished (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [fo_set_port_status] (0x0100): Marking port 0 of server '<a href="http://ipa-master-in.xyz.com">ipa-master-in.xyz.com</a>' as 'working' (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [set_server_common_status] (0x0100): Marking server '<a href="http://ipa-master-in.xyz.com">ipa-master-in.xyz.com</a>' as 'working' (Tue Jul 26 12:06:41 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://ipa-master-in.xyz.com">ipa-master-in.xyz.com</a>' as 'working' (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [krb5_auth_store_creds] (0x0010): unsupported PAM command [249]. (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [krb5_auth_store_creds] (0x0010): password not available, offline auth may not work. (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [check_wait_queue] (0x1000): Wait queue for user [testuser] is empty. (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [krb5_auth_queue_done] (0x1000): krb5_auth_queue request [0x7f88d1142ab0] done. (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_pam_handler_callback] (0x0100): Backend returned: (0, 0, <NULL>) [Success (Success)] (Tue Jul 26 12:06:41 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>] (Tue Jul 26 12:06:41 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>] (Tue Jul 26 12:06:41 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 (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Tue Jul 26 12:06:41 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>] (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_pam_handler] (0x0100): Got request with the following data (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): command: PAM_AUTHENTICATE (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): domain: <a href="http://xyz.com">xyz.com</a> (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): user: testuser (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): service: sshd (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): tty: ssh (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): ruser: (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): rhost: 127.0.0.1 (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): authtok type: 1 (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): newauthtok type: 0 (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): priv: 1 (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): cli_pid: 42266 (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): logon name: not set (Tue Jul 26 12:06:41 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 [0x7f88d1142ab0] immediately. (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [fo_resolve_service_send] (0x0100): Trying to resolve service 'IPA' (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [get_server_status] (0x1000): Status of server '<a href="http://ipa-master-in.xyz.com">ipa-master-in.xyz.com</a>' is 'working' (Tue Jul 26 12:06:41 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://ipa-master-in.xyz.com">ipa-master-in.xyz.com</a>' is 'working' (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [fo_resolve_service_activate_timeout] (0x2000): Resolve timeout set to 6 seconds (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [get_server_status] (0x1000): Status of server '<a href="http://ipa-master-in.xyz.com">ipa-master-in.xyz.com</a>' is 'working' (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_resolve_server_process] (0x1000): Saving the first resolved server (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_resolve_server_process] (0x0200): Found address for server <a href="http://ipa-master-in.xyz.com">ipa-master-in.xyz.com</a>: [10.65.16.4] TTL 127 (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [ipa_resolve_callback] (0x0400): Constructed uri 'ldap://<a href="http://ipa-master-in.xyz.com">ipa-master-in.xyz.com</a>' (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [child_handler_setup] (0x2000): Setting up signal handler up for pid [42277] (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [child_handler_setup] (0x2000): Signal handler set up for pid [42277] (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [write_pipe_handler] (0x0400): All data has been sent! (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [child_sig_handler] (0x1000): Waiting for child [42277]. (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [child_sig_handler] (0x0100): child [42277] finished successfully. (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [read_pipe_handler] (0x0400): EOF received, client finished (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [parse_krb5_child_response] (0x1000): child response [0][3][35]. (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [parse_krb5_child_response] (0x1000): child response [0][-1073741822][28]. (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [parse_krb5_child_response] (0x1000): child response [0][-1073741823][32]. (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [parse_krb5_child_response] (0x1000): TGT times are [1469534801][1469534801][1469621201][0]. (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [parse_krb5_child_response] (0x1000): child response [0][6][8]. (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [fo_set_port_status] (0x0100): Marking port 0 of server '<a href="http://ipa-master-in.xyz.com">ipa-master-in.xyz.com</a>' as 'working' (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [set_server_common_status] (0x0100): Marking server '<a href="http://ipa-master-in.xyz.com">ipa-master-in.xyz.com</a>' as 'working' (Tue Jul 26 12:06:41 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://ipa-master-in.xyz.com">ipa-master-in.xyz.com</a>' as 'working' (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [check_wait_queue] (0x1000): Wait queue for user [testuser] is empty. (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [krb5_auth_queue_done] (0x1000): krb5_auth_queue request [0x7f88d1142ab0] done. (Tue Jul 26 12:06:41 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_pam_handler_callback] (0x0100): Backend returned: (0, 0, <NULL>) [Success (Success)] (Tue Jul 26 12:06:41 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>] (Tue Jul 26 12:06:41 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>] (Tue Jul 26 12:06:42 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 (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Tue Jul 26 12:06:42 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 (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Tue Jul 26 12:06:42 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>] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_pam_handler] (0x0100): Got request with the following data (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): command: PAM_ACCT_MGMT (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): domain: <a href="http://xyz.com">xyz.com</a> (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): user: testuser (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): service: sshd (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): tty: ssh (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): ruser: (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): rhost: 127.0.0.1 (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): authtok type: 0 (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): newauthtok type: 0 (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): priv: 1 (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): cli_pid: 42266 (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): logon name: not set (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_access_send] (0x0400): Performing access check for user [testuser] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_account_expired_rhds] (0x0400): Performing RHDS access check for user [testuser] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Tue Jul 26 12:06:42 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]. (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [fqdn] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [serverHostname] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [memberOf] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaSshPubKey] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUniqueID] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 91 (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 91 timeout 60 (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d01117e0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:42 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]. (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [fqdn] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [serverHostname] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipaSshPubKey] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipaUniqueID] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d01117e0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x2000): Total count [0] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 91 finished (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_has_deref_support] (0x0400): The server supports deref method OpenLDAP (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_deref_search_send] (0x2000): Server supports OpenLDAP deref (Tue Jul 26 12:06:42 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 (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Tue Jul 26 12:06:42 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]. (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [memberOf] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUniqueID] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 92 (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 92 timeout 60 (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d01117e0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d01117e0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_x_deref_parse_entry] (0x0400): Got deref control (Tue Jul 26 12:06:42 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 (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d01117e0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x2000): Total count [0] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 92 finished (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [ipa_hostgroup_info_done] (0x0200): No host groups were dereferenced (Tue Jul 26 12:06:42 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)] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Tue Jul 26 12:06:42 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]. (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectclass] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipauniqueid] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [member] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [memberOf] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 93 (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 93 timeout 60 (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d01117e0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d01117e0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:42 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]. (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d01117e0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:42 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]. (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [memberOf] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d01117e0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:42 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]. (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d01117e0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:42 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]. (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d01117e0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:42 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]. (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d01117e0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:42 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]. (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [memberOf] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d01117e0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:42 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]. (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [memberOf] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d01117e0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:42 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]. (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d01117e0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:42 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]. (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d01117e0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:42 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]. (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d01117e0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:42 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]. (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d01117e0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:42 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]. (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [memberOf] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d01117e0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:42 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]. (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [memberOf] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d01117e0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:42 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]. (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [memberOf] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d01117e0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:42 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]. (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [memberOf] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d01117e0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x2000): Total count [0] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 93 finished (Tue Jul 26 12:06:42 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)] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Tue Jul 26 12:06:42 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]. (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectclass] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipauniqueid] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [member] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [memberOf] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 94 (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 94 timeout 60 (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d01117e0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d01117e0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:42 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]. (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [member] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d01117e0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:42 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]. (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [member] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d01117e0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x2000): Total count [0] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 94 finished (Tue Jul 26 12:06:42 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)))] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Tue Jul 26 12:06:42 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]. (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectclass] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipauniqueid] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaenabledflag] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [accessRuleType] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [memberUser] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userCategory] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [memberService] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [serviceCategory] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sourceHost] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sourceHostCategory] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [externalHost] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [memberHost] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [hostCategory] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 95 (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 95 timeout 60 (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d01117e0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d01117e0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:42 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]. (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectclass] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipauniqueid] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipaenabledflag] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [accessRuleType] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [userCategory] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [serviceCategory] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [hostCategory] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d01117e0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x2000): Total count [0] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 95 finished (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [hbac_attrs_to_rule] (0x1000): Processing rule [allow_all] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [hbac_user_attrs_to_rule] (0x1000): Processing users for rule [allow_all] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [hbac_get_category] (0x0200): Category is set to 'all'. (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [hbac_service_attrs_to_rule] (0x1000): Processing PAM services for rule [allow_all] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [hbac_get_category] (0x0200): Category is set to 'all'. (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [hbac_thost_attrs_to_rule] (0x1000): Processing target hosts for rule [allow_all] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [hbac_get_category] (0x0200): Category is set to 'all'. (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [hbac_shost_attrs_to_rule] (0x0400): Processing source hosts for rule [allow_all] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [hbac_shost_attrs_to_rule] (0x2000): Source hosts disabled, setting ALL (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [hbac_eval_user_element] (0x1000): [4] groups for [testuser] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [hbac_eval_user_element] (0x1000): Added group [ipausers] for user [testuser] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [hbac_eval_user_element] (0x1000): Added group [spradmins] for user [testuser] (Tue Jul 26 12:06:42 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] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [hbac_eval_user_element] (0x1000): Added group [spr-itops] for user [testuser] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [ipa_hbac_evaluate_rules] (0x0080): Access granted by HBAC rule [allow_all] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_pam_handler_callback] (0x0100): Backend returned: (0, 0, <NULL>) [Success (Success)] (Tue Jul 26 12:06:42 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. (Tue Jul 26 12:06:42 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>] (Tue Jul 26 12:06:42 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>] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[(nil)], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Tue Jul 26 12:06:42 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 (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Tue Jul 26 12:06:42 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>] (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_pam_handler] (0x0100): Got request with the following data (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): command: PAM_SETCRED (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): domain: <a href="http://xyz.com">xyz.com</a> (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): user: testuser (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): service: sshd (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): tty: ssh (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): ruser: (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): rhost: 127.0.0.1 (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): authtok type: 0 (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): newauthtok type: 0 (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): priv: 1 (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): cli_pid: 42266 (Tue Jul 26 12:06:42 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): logon name: not set (Tue Jul 26 12:06:42 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>] (Tue Jul 26 12:06:52 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 (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Tue Jul 26 12:06:52 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 (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_get_account_info] (0x0200): Got request for [0x3][1][name=testuser] (Tue Jul 26 12:06:52 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>] (Tue Jul 26 12:06:52 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)] (Tue Jul 26 12:06:52 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)] (Tue Jul 26 12:06:52 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] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Tue Jul 26 12:06:52 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]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uid] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uidNumber] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gecos] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [homeDirectory] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginShell] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbPrincipalName] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [memberOf] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUniqueID] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaNTSecurityIdentifier] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [entryUSN] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowLastChange] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowMin] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowMax] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowWarning] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowInactive] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowExpire] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowFlag] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbLastPwdChange] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbPasswordExpiration] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [pwdAttribute] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [authorizedService] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [accountExpires] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userAccountControl] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [nsAccountLock] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [host] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginDisabled] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginExpirationTime] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginAllowedTimeMap] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaSshPubKey] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUserAuthType] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userCertificate;binary] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 96 (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 96 timeout 6 (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d010af10], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:52 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]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [uid] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [uidNumber] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [gecos] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [homeDirectory] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [loginShell] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [krbPrincipalName] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [memberOf] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipaUniqueID] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [entryUSN] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [krbLastPwdChange] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [krbPasswordExpiration] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d010af10], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 96 finished (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_user] (0x0400): Save user (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_get_sid_str] (0x1000): No [objectSIDString] attribute. [0][Success] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_primary_name] (0x0400): Processing object testuser (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_user] (0x0400): Processing user testuser (Tue Jul 26 12:06:52 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)] (Tue Jul 26 12:06:52 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]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_user] (0x0400): Adding original memberOf attributes to [testuser]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding original mod-Timestamp [20160726120640Z] to attributes of [testuser]. (Tue Jul 26 12:06:52 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]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowLastChange is not available for [testuser]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowMin is not available for [testuser]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowMax is not available for [testuser]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowWarning is not available for [testuser]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowInactive is not available for [testuser]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowExpire is not available for [testuser]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): shadowFlag is not available for [testuser]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding krbLastPwdChange [20160511120919Z] to attributes of [testuser]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): Adding krbPasswordExpiration [20160809120919Z] to attributes of [testuser]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): pwdAttribute is not available for [testuser]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): authorizedService is not available for [testuser]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): adAccountExpires is not available for [testuser]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): adUserAccountControl is not available for [testuser]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): nsAccountLock is not available for [testuser]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): authorizedHost is not available for [testuser]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): ndsLoginDisabled is not available for [testuser]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): ndsLoginExpirationTime is not available for [testuser]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): ndsLoginAllowedTimeMap is not available for [testuser]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): sshPublicKey is not available for [testuser]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): authType is not available for [testuser]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_add_ldap_attr] (0x2000): userCertificate is not available for [testuser]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_user] (0x0400): Storing info for user testuser (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [userPassword] from [testuser] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [objectSIDString] from [testuser] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowLastChange] from [testuser] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowMin] from [testuser] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowMax] from [testuser] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowWarning] from [testuser] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowInactive] from [testuser] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowExpire] from [testuser] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [shadowFlag] from [testuser] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [pwdAttribute] from [testuser] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [authorizedService] from [testuser] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [adAccountExpires] from [testuser] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [adUserAccountControl] from [testuser] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [nsAccountLock] from [testuser] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [authorizedHost] from [testuser] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [ndsLoginDisabled] from [testuser] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [ndsLoginExpirationTime] from [testuser] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [ndsLoginAllowedTimeMap] from [testuser] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [sshPublicKey] from [testuser] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [authType] from [testuser] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_remove_attrs] (0x2000): Removing attribute [userCertificate] from [testuser] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_primary_name] (0x0400): Processing object testuser (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_has_deref_support] (0x0400): The server supports deref method OpenLDAP (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Tue Jul 26 12:06:52 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]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [posixGroup] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [member] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUniqueID] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaNTSecurityIdentifier] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [entryUSN] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 97 (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 97 timeout 6 (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d115e2d0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d115e2d0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:52 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]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [member] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipaUniqueID] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [entryUSN] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d115e2d0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 97 finished (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Tue Jul 26 12:06:52 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]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [posixGroup] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [member] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUniqueID] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaNTSecurityIdentifier] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [entryUSN] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 98 (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 98 timeout 6 (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d115e2d0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d115e2d0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:52 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]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [member] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipaUniqueID] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [entryUSN] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d115e2d0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 98 finished (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Tue Jul 26 12:06:52 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]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [posixGroup] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [member] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUniqueID] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaNTSecurityIdentifier] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [entryUSN] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 99 (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 99 timeout 6 (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d115e2d0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d115e2d0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 99 finished (Tue Jul 26 12:06:52 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 (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Tue Jul 26 12:06:52 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]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [posixGroup] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [member] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUniqueID] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaNTSecurityIdentifier] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [entryUSN] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 100 (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 100 timeout 6 (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d115e2d0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d115e2d0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:52 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]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [member] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipaUniqueID] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [entryUSN] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d115e2d0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 100 finished (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_primary_name] (0x0400): Processing object ipausers (Tue Jul 26 12:06:52 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))] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_get_direct_parents] (0x1000): ipausers is a member of 0 sysdb groups (Tue Jul 26 12:06:52 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 (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_primary_name] (0x0400): Processing object spradmins (Tue Jul 26 12:06:52 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))] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_get_direct_parents] (0x1000): spradmins is a member of 0 sysdb groups (Tue Jul 26 12:06:52 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 (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_primary_name] (0x0400): Processing object spr-itops (Tue Jul 26 12:06:52 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))] (Tue Jul 26 12:06:52 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 (Tue Jul 26 12:06:52 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 (Tue Jul 26 12:06:52 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 (Tue Jul 26 12:06:52 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))] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sysdb_get_direct_parents] (0x1000): testuser is a member of 3 sysdb groups (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_initgr_store_user_memberships] (0x2000): Updating memberships for testuser (Tue Jul 26 12:06:52 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)] (Tue Jul 26 12:06:52 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] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_print_server] (0x2000): Searching 10.65.16.4 (Tue Jul 26 12:06:52 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]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [posixGroup] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUniqueID] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaNTSecurityIdentifier] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [entryUSN] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 101 (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_add] (0x2000): New operation 101 timeout 6 (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d115e2d0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d115e2d0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:52 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]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [objectClass] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [cn] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [gidNumber] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [ipaUniqueID] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [modifyTimestamp] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_parse_range] (0x2000): No sub-attributes for [entryUSN] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[0x7f88d115e2d0], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_op_destructor] (0x2000): Operation 101 finished (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_has_deref_support] (0x0400): The server supports deref method OpenLDAP (Tue Jul 26 12:06:52 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)] (Tue Jul 26 12:06:52 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] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_nested_group_recv] (0x0400): 0 users found in the hash table (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_attrs_get_sid_str] (0x1000): No [objectSIDString] attribute. [0][Success] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_primary_name] (0x0400): Processing object spradmins (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_group] (0x0400): Processing group spradmins (Tue Jul 26 12:06:52 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)] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_group] (0x2000): This is a posix group (Tue Jul 26 12:06:52 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]. (Tue Jul 26 12:06:52 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]. (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_ghost_members] (0x0400): The group has 0 members (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_ghost_members] (0x0400): Group has 0 members (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_group] (0x0400): Storing info for group spradmins (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_get_primary_name] (0x0400): Processing object spradmins (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_grpmem] (0x0400): Processing group spradmins (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_grpmem] (0x0400): Failed to get group sid (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_save_grpmem] (0x0400): No members for group [spradmins] (Tue Jul 26 12:06:52 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_nested_done] (0x2000): No external members, done(Tue Jul 26 12:06:53 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_add_timeout] (0x2000): 0x7f88d1178b40 (Tue Jul 26 12:06:53 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: sh[0x7f88d012a0e0], connected[1], ops[(nil)], ldap[0x7f88d00f9950] (Tue Jul 26 12:06:53 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing! (Tue Jul 26 12:06:53 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_remove_timeout] (0x2000): 0x7f88d1178b40 (Tue Jul 26 12:06:53 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [acctinfo_callback] (0x0100): Request processed. Returned 0,0,Success (Success) (Tue Jul 26 12:06:53 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 (Tue Jul 26 12:06:53 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Tue Jul 26 12:06:53 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>] (Tue Jul 26 12:06:53 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_pam_handler] (0x0100): Got request with the following data (Tue Jul 26 12:06:53 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION (Tue Jul 26 12:06:53 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): domain: <a href="http://xyz.com">xyz.com</a> (Tue Jul 26 12:06:53 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): user: testuser (Tue Jul 26 12:06:53 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): service: sshd (Tue Jul 26 12:06:53 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): tty: ssh (Tue Jul 26 12:06:53 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): ruser: (Tue Jul 26 12:06:53 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): rhost: 127.0.0.1 (Tue Jul 26 12:06:53 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): authtok type: 0 (Tue Jul 26 12:06:53 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): newauthtok type: 0 (Tue Jul 26 12:06:53 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): priv: 1 (Tue Jul 26 12:06:53 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): cli_pid: 42266 (Tue Jul 26 12:06:53 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): logon name: not set (Tue Jul 26 12:06:53 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>] (Tue Jul 26 12:06:53 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 (Tue Jul 26 12:06:53 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Tue Jul 26 12:06:53 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>] (Tue Jul 26 12:06:53 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [be_pam_handler] (0x0100): Got request with the following data (Tue Jul 26 12:06:53 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): command: PAM_SETCRED (Tue Jul 26 12:06:53 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): domain: <a href="http://xyz.com">xyz.com</a> (Tue Jul 26 12:06:53 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): user: testuser (Tue Jul 26 12:06:53 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): service: sshd (Tue Jul 26 12:06:53 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): tty: ssh (Tue Jul 26 12:06:53 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): ruser: (Tue Jul 26 12:06:53 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): rhost: 127.0.0.1 (Tue Jul 26 12:06:53 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): authtok type: 0 (Tue Jul 26 12:06:53 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): newauthtok type: 0 (Tue Jul 26 12:06:53 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): priv: 0 (Tue Jul 26 12:06:53 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): cli_pid: 42304 (Tue Jul 26 12:06:53 2016) [sssd[be[<a href="http://xyz.com">xyz.com</a>]]] [pam_print_data] (0x0100): logon name: not set (Tue Jul 26 12:06:53 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>Thanks, </div>Rakesh <div><div><div> </div></div></div></div><div class="gmail_extra"> <div class="gmail_quote">On Mon, Jul 25, 2016 at 11:12 PM, Jakub Hrozek <<a href="mailto:jhrozek@redhat.com" target="_blank">jhrozek@redhat.com</a>> wrote: <blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex">On Mon, Jul 25, 2016 at 09:23:19PM +0530, Rakesh Rajasekharan wrote: > Hi, > > 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 Are you sure the logs correspond to the login attempt? The stamps you posted are between 11:14:54 and 11:15:12 but the logs below are from a different time period. There is a 10 second period in the sssd logs when seemingly nothing happens. Does the same delay happen if you su from another non-root account (ruling out some DNS issues in SSH or similar) ? -- Manage your subscription for the Freeipa-users mailing list: <a href="https://www.redhat.com/mailman/listinfo/freeipa-users" rel="noreferrer" target="_blank">https://www.redhat.com/mailman/listinfo/freeipa-users</a> Go to <a href="http://freeipa.org" rel="noreferrer" target="_blank">http://freeipa.org</a> for more info on the project </blockquote></div> </div>