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