This is the output from both IPA server and client:
From IPA Server:
# id mspezie@example.org uid=1070607073(mspezie@example.org) gid=1070607073(mspezie@example.org) groups=1070607073(mspezie@example.org) 1070603934(linux power users@example.org) 1070600512(domain admins@example.org) 1535800006(ad_admins) 1535800000(admins) .... 1070600513(domain users@example.org)
# id freeipa@example.org uid=1070607388(freeipa@example.org) gid=1070607388(freeipa@example.org) groups=1070607388(freeipa@example.org) 1070600513(domain users@example.org) 1535800006(ad_admins) 1535800000(admins)
From IPA Client:
# id mspezie@example.org id: mspezie@example.org: no such user
# id freeipa@example.org uid=1070607388(freeipa@example.org) gid=1070607388(freeipa@example.org) groups=1070607388(freeipa@example.org) 1070600513(domain users@example.org) 1535800006(ad_admins) 1535800000(admins)
The only difference from these two accounts is that freeipa(a)example.org is present in cn=Users and mspezie(a)example.org not. All the AD groups associated to mspezie have a name
On Thu, Aug 09, 2018 at 10:34:57AM +0000, Mirko Spezie via FreeIPA-users wrote:
This is the output from both IPA server and client:
From IPA Server:
# id mspezie@example.org uid=1070607073(mspezie@example.org) gid=1070607073(mspezie@example.org) groups=1070607073(mspezie@example.org) 1070603934(linux power users@example.org) 1070600512(domain admins@example.org) 1535800006(ad_admins) 1535800000(admins) .... 1070600513(domain users@example.org)
# id freeipa@example.org uid=1070607388(freeipa@example.org) gid=1070607388(freeipa@example.org) groups=1070607388(freeipa@example.org) 1070600513(domain users@example.org) 1535800006(ad_admins) 1535800000(admins)
From IPA Client:
# id mspezie@example.org id: mspezie@example.org: no such user
# id freeipa@example.org uid=1070607388(freeipa@example.org) gid=1070607388(freeipa@example.org) groups=1070607388(freeipa@example.org) 1070600513(domain users@example.org) 1535800006(ad_admins) 1535800000(admins)
The only difference from these two accounts is that freeipa(a)example.org is present in cn=Users and mspezie(a)example.org not. All the AD groups associated to mspezie have a name
Ok, then please add debug_level=9 to the [domain/...] section of sssd.conf on the client restart SSSD, call 'id mspezie@example.org' and look for errors in the domain log file in /var/log/sssd. Feel free to send the log file here or to me directly.
bye, Sumit
FreeIPA-users mailing list -- freeipa-users@lists.fedorahosted.org To unsubscribe send an email to freeipa-users-leave@lists.fedorahosted.org Fedora Code of Conduct: https://getfedora.org/code-of-conduct.html List Guidelines: https://fedoraproject.org/wiki/Mailing_list_guidelines List Archives: https://lists.fedoraproject.org/archives/list/freeipa-users@lists.fedorahost...
Here the log from sssd:
(Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sbus_dispatch] (0x4000): dbus conn: 0x55ef74375140 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sbus_dispatch] (0x4000): Dispatching. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sbus_message_handler] (0x2000): Received SBUS method org.freedesktop.sssd.dataprovider.getAccountInfo on path /org/freedesktop/sssd/dataprovider (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_get_account_info_handler] (0x0200): Got request for [0x1][BE_REQ_USER][name=mspezie(a)example.org] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_attach_req] (0x0400): DP Request [Account #17]: New request. Flags [0x0001]. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_id_op_connect_step] (0x4000): reusing cached connection (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_id_op_connect_step] (0x4000): reusing cached connection (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_get_ad_override_connect_done] (0x4000): Searching for overrides in view [Default Trust View] with filter [(&(objectClass=ipaUserOverride)(uid=mspezie))]. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_print_server] (0x2000): Searching 192.168.5.21:389 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaUserOverride)(uid=mspezie))][cn=Default Trust View,cn=views,cn=accounts,dc=inetwk,dc=local]. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 34 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_op_add] (0x2000): New operation 34 timeout 6 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_result] (0x2000): Trace: sh[0x55ef7437a200], connected[1], ops[0x55ef743a2950], ldap[0x55ef7433a0a0] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_RESULT] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_op_destructor] (0x2000): Operation 34 finished (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_get_ad_override_done] (0x4000): No override found with filter [(&(objectClass=ipaUserOverride)(uid=mspezie))]. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_id_op_destroy] (0x4000): releasing operation connection (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_id_op_connect_step] (0x4000): reusing cached connection (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_s2n_get_acct_info_send] (0x0400): Sending request_type: [REQ_FULL_WITH_MEMBERS] for trust user [mspezie] to IPA server (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_s2n_exop_send] (0x0400): Executing extended operation (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_s2n_exop_send] (0x2000): ldap_extended_operation sent, msgid = 35 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_op_add] (0x2000): New operation 35 timeout 6 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_result] (0x2000): Trace: sh[0x55ef7437a200], connected[1], ops[0x55ef74395280], ldap[0x55ef7433a0a0] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_result] (0x2000): Trace: end of ldap_result list (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_result] (0x2000): Trace: sh[0x55ef7437a200], connected[1], ops[0x55ef74395280], ldap[0x55ef7433a0a0] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_EXTENDED] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_s2n_exop_done] (0x0400): ldap_extended_operation result: Success(0), (null). (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_op_destructor] (0x2000): Operation 35 finished (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [add_v1_user_data] (0x0040): find_domain_by_name failed. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [s2n_response_to_attrs] (0x0040): add_v1_user_data failed. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_s2n_get_user_done] (0x0040): s2n_response_to_attrs failed. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_id_op_done] (0x4000): releasing operation connection (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_subdomain_account_done] (0x0040): ipa_get_*_acct request failed: [12]: Cannot allocate memory. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_id_op_destroy] (0x4000): releasing operation connection (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_done] (0x0400): DP Request [Account #17]: Request handler finished [0]: Success (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [_dp_req_recv] (0x0400): DP Request [Account #17]: Receiving request data. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #17]: Finished. Success. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_reply_std] (0x1000): DP Request [Account #17]: Returning [Internal Error]: 3,12,Cannot allocate memory (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:1::intesys.it:name=mspezie(a)example.org] from reply table (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_destructor] (0x0400): DP Request [Account #17]: Request removed. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_result] (0x2000): Trace: sh[0x55ef7437a200], connected[1], ops[(nil)], ldap[0x55ef7433a0a0] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_result] (0x2000): Trace: end of ldap_result list (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sbus_dispatch] (0x4000): dbus conn: 0x55ef74375140 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sbus_dispatch] (0x4000): Dispatching. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sbus_message_handler] (0x2000): Received SBUS method org.freedesktop.sssd.dataprovider.getAccountInfo on path /org/freedesktop/sssd/dataprovider (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_get_account_info_handler] (0x0200): Got request for [0x1][BE_REQ_USER][name=mspezie(a)example.org] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_attach_req] (0x0400): DP Request [Account #18]: New request. Flags [0x0001]. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_id_op_connect_step] (0x4000): reusing cached connection (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_search_user_next_base] (0x0400): Searching for users with base [cn=accounts,dc=inetwk,dc=local] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_print_server] (0x2000): Searching 192.168.5.21:389 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(|(krbPrincipalName=mspezie(a)example.org)(mail=mspezie(a)example.org)(krbPrincipalName=mspezie\(a)example.org@INETWK.LOCAL))(objectclass=posixAccount)(uid=*)(&(uidNumber=*)(!(uidNumber=0))))][cn=accounts,dc=inetwk,dc=local]. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uid] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPassword] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uidNumber] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gecos] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [homeDirectory] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginShell] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbPrincipalName] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [cn] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [memberOf] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUniqueID] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaNTSecurityIdentifier] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [modifyTimestamp] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [entryUSN] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowLastChange] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowMin] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowMax] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowWarning] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowInactive] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowExpire] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [shadowFlag] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbLastPwdChange] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [krbPasswordExpiration] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [pwdAttribute] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [authorizedService] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [accountExpires] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userAccountControl] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [nsAccountLock] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [host] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginDisabled] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginExpirationTime] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginAllowedTimeMap] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaSshPubKey] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [ipaUserAuthType] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userCertificate;binary] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [mail] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 36 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_op_add] (0x2000): New operation 36 timeout 6 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_result] (0x2000): Trace: sh[0x55ef7437a200], connected[1], ops[0x55ef743a2950], ldap[0x55ef7433a0a0] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_RESULT] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_op_destructor] (0x2000): Operation 36 finished (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_search_user_process] (0x0400): Search for users, returned 0 results. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_search_user_process] (0x2000): Retrieved total 0 users (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_id_op_done] (0x4000): releasing operation connection (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55ef743b8930
(Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55ef743b89f0
(Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ldb] (0x4000): Running timer event 0x55ef743b8930 "ltdb_callback"
(Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ldb] (0x4000): Destroying timer event 0x55ef743b89f0 "ltdb_timeout"
(Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ldb] (0x4000): Ending timer event 0x55ef743b8930 "ltdb_callback"
(Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sysdb_search_by_name] (0x0400): No such entry (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sysdb_cache_search_groups] (0x2000): Search groups with filter: (&(objectCategory=group)(ghost=mspezie(a)example.org)) (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55ef743b8140
(Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55ef743b8200
(Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ldb] (0x4000): Running timer event 0x55ef743b8140 "ltdb_callback"
(Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ldb] (0x4000): Destroying timer event 0x55ef743b8200 "ltdb_timeout"
(Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ldb] (0x4000): Ending timer event 0x55ef743b8140 "ltdb_callback"
(Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sysdb_cache_search_groups] (0x2000): No such entry (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sysdb_delete_user] (0x0400): Error: 2 (No such file or directory) (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x55ef743b8700
(Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x55ef743b87c0
(Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ldb] (0x4000): Running timer event 0x55ef743b8700 "ltdb_callback"
(Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ldb] (0x4000): Destroying timer event 0x55ef743b87c0 "ltdb_timeout"
(Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ldb] (0x4000): Ending timer event 0x55ef743b8700 "ltdb_callback"
(Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sysdb_search_user_by_upn] (0x0400): No entry with upn [mspezie(a)example.org] found. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_id_get_account_info_orig_done] (0x0080): Object not found, ending request (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_done] (0x0400): DP Request [Account #18]: Request handler finished [0]: Success (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [_dp_req_recv] (0x0400): DP Request [Account #18]: Receiving request data. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #18]: Finished. Success. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_reply_std] (0x1000): DP Request [Account #18]: Returning [Success]: 0,0,Success (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:1:U:inetwk.local:name=mspezie(a)example.org] from reply table (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_destructor] (0x0400): DP Request [Account #18]: Request removed. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_result] (0x2000): Trace: sh[0x55ef7437a200], connected[1], ops[(nil)], ldap[0x55ef7433a0a0] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_result] (0x2000): Trace: end of ldap_result list (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sbus_dispatch] (0x4000): dbus conn: 0x55ef74375140 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sbus_dispatch] (0x4000): Dispatching. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sbus_message_handler] (0x2000): Received SBUS method org.freedesktop.sssd.dataprovider.getAccountInfo on path /org/freedesktop/sssd/dataprovider (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_get_account_info_handler] (0x0200): Got request for [0x1][BE_REQ_USER][name=mspezie(a)example.org] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_attach_req] (0x0400): DP Request [Account #19]: New request. Flags [0x0001]. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_id_op_connect_step] (0x4000): reusing cached connection (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_s2n_get_acct_info_send] (0x0400): Sending request_type: [REQ_FULL_WITH_MEMBERS] for trust user [mspezie(a)example.org] to IPA server (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_s2n_exop_send] (0x0400): Executing extended operation (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_s2n_exop_send] (0x2000): ldap_extended_operation sent, msgid = 37 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_op_add] (0x2000): New operation 37 timeout 6 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_result] (0x2000): Trace: sh[0x55ef7437a200], connected[1], ops[0x55ef74395690], ldap[0x55ef7433a0a0] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_EXTENDED] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_s2n_exop_done] (0x0400): ldap_extended_operation result: Success(0), (null). (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_op_destructor] (0x2000): Operation 37 finished (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [add_v1_user_data] (0x0040): find_domain_by_name failed. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [s2n_response_to_attrs] (0x0040): add_v1_user_data failed. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_s2n_get_user_done] (0x0040): s2n_response_to_attrs failed. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_id_op_done] (0x4000): releasing operation connection (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_subdomain_account_done] (0x0040): ipa_get_*_acct request failed: [12]: Cannot allocate memory. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_done] (0x0400): DP Request [Account #19]: Request handler finished [0]: Success (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [_dp_req_recv] (0x0400): DP Request [Account #19]: Receiving request data. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #19]: Finished. Success. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_reply_std] (0x1000): DP Request [Account #19]: Returning [Internal Error]: 3,12,Cannot allocate memory (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:1:U:intesys.it:name=mspezie(a)example.org] from reply table (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_destructor] (0x0400): DP Request [Account #19]: Request removed. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_result] (0x2000): Trace: sh[0x55ef7437a200], connected[1], ops[(nil)], ldap[0x55ef7433a0a0] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_result] (0x2000): Trace: end of ldap_result list (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sbus_dispatch] (0x4000): dbus conn: 0x55ef74375140 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sbus_dispatch] (0x4000): Dispatching. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sbus_message_handler] (0x2000): Received SBUS method org.freedesktop.sssd.dataprovider.getAccountInfo on path /org/freedesktop/sssd/dataprovider (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_get_account_info_handler] (0x0200): Got request for [0x1][BE_REQ_USER][name=mspezie(a)example.org] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_attach_req] (0x0400): DP Request [Account #20]: New request. Flags [0x0001]. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_id_op_connect_step] (0x4000): reusing cached connection (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_id_op_connect_step] (0x4000): reusing cached connection (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_get_ad_override_connect_done] (0x4000): Searching for overrides in view [Default Trust View] with filter [(&(objectClass=ipaUserOverride)(uid=mspezie))]. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_print_server] (0x2000): Searching 192.168.5.21:389 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaUserOverride)(uid=mspezie))][cn=Default Trust View,cn=views,cn=accounts,dc=inetwk,dc=local]. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 38 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_op_add] (0x2000): New operation 38 timeout 6 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_result] (0x2000): Trace: sh[0x55ef7437a200], connected[1], ops[0x55ef743b7bb0], ldap[0x55ef7433a0a0] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_RESULT] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_op_destructor] (0x2000): Operation 38 finished (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_get_ad_override_done] (0x4000): No override found with filter [(&(objectClass=ipaUserOverride)(uid=mspezie))]. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_id_op_destroy] (0x4000): releasing operation connection (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_id_op_connect_step] (0x4000): reusing cached connection (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_s2n_get_acct_info_send] (0x0400): Sending request_type: [REQ_FULL_WITH_MEMBERS] for trust user [mspezie] to IPA server (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_s2n_exop_send] (0x0400): Executing extended operation (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_s2n_exop_send] (0x2000): ldap_extended_operation sent, msgid = 39 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_op_add] (0x2000): New operation 39 timeout 6 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_result] (0x2000): Trace: sh[0x55ef7437a200], connected[1], ops[0x55ef74395690], ldap[0x55ef7433a0a0] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_result] (0x2000): Trace: end of ldap_result list (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_result] (0x2000): Trace: sh[0x55ef7437a200], connected[1], ops[0x55ef74395690], ldap[0x55ef7433a0a0] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_EXTENDED] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_s2n_exop_done] (0x0400): ldap_extended_operation result: Success(0), (null). (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_op_destructor] (0x2000): Operation 39 finished (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [add_v1_user_data] (0x0040): find_domain_by_name failed. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [s2n_response_to_attrs] (0x0040): add_v1_user_data failed. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_s2n_get_user_done] (0x0040): s2n_response_to_attrs failed. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_id_op_done] (0x4000): releasing operation connection (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_subdomain_account_done] (0x0040): ipa_get_*_acct request failed: [12]: Cannot allocate memory. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_id_op_destroy] (0x4000): releasing operation connection (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_done] (0x0400): DP Request [Account #20]: Request handler finished [0]: Success (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [_dp_req_recv] (0x0400): DP Request [Account #20]: Receiving request data. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #20]: Finished. Success. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_reply_std] (0x1000): DP Request [Account #20]: Returning [Internal Error]: 3,12,Cannot allocate memory (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:1::intesys.it:name=mspezie(a)example.org] from reply table (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_destructor] (0x0400): DP Request [Account #20]: Request removed. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_result] (0x2000): Trace: sh[0x55ef7437a200], connected[1], ops[(nil)], ldap[0x55ef7433a0a0] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_result] (0x2000): Trace: end of ldap_result list (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sbus_dispatch] (0x4000): dbus conn: 0x55ef74375140 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sbus_dispatch] (0x4000): Dispatching. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sbus_message_handler] (0x2000): Received SBUS method org.freedesktop.sssd.dataprovider.getAccountInfo on path /org/freedesktop/sssd/dataprovider (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_get_account_info_handler] (0x0200): Got request for [0x1][BE_REQ_USER][name=mspezie(a)example.org] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_attach_req] (0x0400): DP Request [Account #21]: New request. Flags [0x0001]. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_id_op_connect_step] (0x4000): reusing cached connection (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_s2n_get_acct_info_send] (0x0400): Sending request_type: [REQ_FULL_WITH_MEMBERS] for trust user [mspezie(a)example.org] to IPA server (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_s2n_exop_send] (0x0400): Executing extended operation (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_s2n_exop_send] (0x2000): ldap_extended_operation sent, msgid = 40 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_op_add] (0x2000): New operation 40 timeout 6 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_result] (0x2000): Trace: sh[0x55ef7437a200], connected[1], ops[0x55ef743b8510], ldap[0x55ef7433a0a0] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_EXTENDED] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_s2n_exop_done] (0x0400): ldap_extended_operation result: Success(0), (null). (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_op_destructor] (0x2000): Operation 40 finished (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [add_v1_user_data] (0x0040): find_domain_by_name failed. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [s2n_response_to_attrs] (0x0040): add_v1_user_data failed. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_s2n_get_user_done] (0x0040): s2n_response_to_attrs failed. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_id_op_done] (0x4000): releasing operation connection (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_subdomain_account_done] (0x0040): ipa_get_*_acct request failed: [12]: Cannot allocate memory. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_done] (0x0400): DP Request [Account #21]: Request handler finished [0]: Success (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [_dp_req_recv] (0x0400): DP Request [Account #21]: Receiving request data. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #21]: Finished. Success. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_reply_std] (0x1000): DP Request [Account #21]: Returning [Internal Error]: 3,12,Cannot allocate memory (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:1:U:intesys.it:name=mspezie(a)example.org] from reply table (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_destructor] (0x0400): DP Request [Account #21]: Request removed. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_result] (0x2000): Trace: sh[0x55ef7437a200], connected[1], ops[(nil)], ldap[0x55ef7433a0a0] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_result] (0x2000): Trace: end of ldap_result list (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sbus_dispatch] (0x4000): dbus conn: 0x55ef74375140 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sbus_dispatch] (0x4000): Dispatching. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sbus_message_handler] (0x2000): Received SBUS method org.freedesktop.sssd.dataprovider.getAccountInfo on path /org/freedesktop/sssd/dataprovider (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_get_account_info_handler] (0x0200): Got request for [0x1][BE_REQ_USER][name=mspezie(a)example.org] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_attach_req] (0x0400): DP Request [Account #22]: New request. Flags [0x0001]. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_id_op_connect_step] (0x4000): reusing cached connection (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_id_op_connect_step] (0x4000): reusing cached connection (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_get_ad_override_connect_done] (0x4000): Searching for overrides in view [Default Trust View] with filter [(&(objectClass=ipaUserOverride)(uid=mspezie))]. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_print_server] (0x2000): Searching 192.168.5.21:389 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaUserOverride)(uid=mspezie))][cn=Default Trust View,cn=views,cn=accounts,dc=inetwk,dc=local]. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 41 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_op_add] (0x2000): New operation 41 timeout 6 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_result] (0x2000): Trace: sh[0x55ef7437a200], connected[1], ops[0x55ef743b82e0], ldap[0x55ef7433a0a0] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_RESULT] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_op_destructor] (0x2000): Operation 41 finished (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_get_ad_override_done] (0x4000): No override found with filter [(&(objectClass=ipaUserOverride)(uid=mspezie))]. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_id_op_destroy] (0x4000): releasing operation connection (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_id_op_connect_step] (0x4000): reusing cached connection (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_s2n_get_acct_info_send] (0x0400): Sending request_type: [REQ_FULL_WITH_MEMBERS] for trust user [mspezie] to IPA server (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_s2n_exop_send] (0x0400): Executing extended operation (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_s2n_exop_send] (0x2000): ldap_extended_operation sent, msgid = 42 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_op_add] (0x2000): New operation 42 timeout 6 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_result] (0x2000): Trace: sh[0x55ef7437a200], connected[1], ops[0x55ef743b9230], ldap[0x55ef7433a0a0] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_result] (0x2000): Trace: end of ldap_result list (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_result] (0x2000): Trace: sh[0x55ef7437a200], connected[1], ops[0x55ef743b9230], ldap[0x55ef7433a0a0] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_EXTENDED] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_s2n_exop_done] (0x0400): ldap_extended_operation result: Success(0), (null). (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_op_destructor] (0x2000): Operation 42 finished (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [add_v1_user_data] (0x0040): find_domain_by_name failed. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [s2n_response_to_attrs] (0x0040): add_v1_user_data failed. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_s2n_get_user_done] (0x0040): s2n_response_to_attrs failed. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_id_op_done] (0x4000): releasing operation connection (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_subdomain_account_done] (0x0040): ipa_get_*_acct request failed: [12]: Cannot allocate memory. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_id_op_destroy] (0x4000): releasing operation connection (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_done] (0x0400): DP Request [Account #22]: Request handler finished [0]: Success (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [_dp_req_recv] (0x0400): DP Request [Account #22]: Receiving request data. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #22]: Finished. Success. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_reply_std] (0x1000): DP Request [Account #22]: Returning [Internal Error]: 3,12,Cannot allocate memory (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:1::intesys.it:name=mspezie(a)example.org] from reply table (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_destructor] (0x0400): DP Request [Account #22]: Request removed. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_result] (0x2000): Trace: sh[0x55ef7437a200], connected[1], ops[(nil)], ldap[0x55ef7433a0a0] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_result] (0x2000): Trace: end of ldap_result list (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sbus_dispatch] (0x4000): dbus conn: 0x55ef74375140 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sbus_dispatch] (0x4000): Dispatching. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sbus_message_handler] (0x2000): Received SBUS method org.freedesktop.sssd.dataprovider.getAccountInfo on path /org/freedesktop/sssd/dataprovider (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_get_account_info_handler] (0x0200): Got request for [0x1][BE_REQ_USER][name=mspezie(a)example.org] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_attach_req] (0x0400): DP Request [Account #23]: New request. Flags [0x0001]. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_attach_req] (0x0400): Number of active DP request: 1 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_id_op_connect_step] (0x4000): reusing cached connection (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_s2n_get_acct_info_send] (0x0400): Sending request_type: [REQ_FULL_WITH_MEMBERS] for trust user [mspezie(a)example.org] to IPA server (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_s2n_exop_send] (0x0400): Executing extended operation (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_s2n_exop_send] (0x2000): ldap_extended_operation sent, msgid = 43 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_op_add] (0x2000): New operation 43 timeout 6 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_result] (0x2000): Trace: sh[0x55ef7437a200], connected[1], ops[0x55ef743b92e0], ldap[0x55ef7433a0a0] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_EXTENDED] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_s2n_exop_done] (0x0400): ldap_extended_operation result: Success(0), (null). (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_op_destructor] (0x2000): Operation 43 finished (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain inetwk.local is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sss_domain_get_state] (0x1000): Domain intesys.it is Active (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [add_v1_user_data] (0x0040): find_domain_by_name failed. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [s2n_response_to_attrs] (0x0040): add_v1_user_data failed. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_s2n_get_user_done] (0x0040): s2n_response_to_attrs failed. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_id_op_done] (0x4000): releasing operation connection (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [ipa_subdomain_account_done] (0x0040): ipa_get_*_acct request failed: [12]: Cannot allocate memory. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_done] (0x0400): DP Request [Account #23]: Request handler finished [0]: Success (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [_dp_req_recv] (0x0400): DP Request [Account #23]: Receiving request data. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_reply_list_success] (0x0400): DP Request [Account #23]: Finished. Success. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_reply_std] (0x1000): DP Request [Account #23]: Returning [Internal Error]: 3,12,Cannot allocate memory (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_table_value_destructor] (0x0400): Removing [0:1:0x0001:1:U:intesys.it:name=mspezie(a)example.org] from reply table (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_destructor] (0x0400): DP Request [Account #23]: Request removed. (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [dp_req_destructor] (0x0400): Number of active DP request: 0 (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_result] (0x2000): Trace: sh[0x55ef7437a200], connected[1], ops[(nil)], ldap[0x55ef7433a0a0] (Thu Aug 9 12:57:51 2018) [sssd[be[inetwk.local]]] [sdap_process_result] (0x2000): Trace: end of ldap_result list
-----Messaggio originale----- Da: Sumit Bose via FreeIPA-users freeipa-users@lists.fedorahosted.org Inviato: giovedì 9 agosto 2018 12:52 A: freeipa-users@lists.fedorahosted.org Cc: Sumit Bose sbose@redhat.com Oggetto: [Freeipa-users] Re: IPA and AD basedn
On Thu, Aug 09, 2018 at 10:34:57AM +0000, Mirko Spezie via FreeIPA-users wrote:
This is the output from both IPA server and client:
From IPA Server:
# id mspezie@example.org uid=1070607073(mspezie@example.org) gid=1070607073(mspezie@example.org) groups=1070607073(mspezie@example.org) 1070603934(linux power users@example.org) 1070600512(domain admins@example.org) 1535800006(ad_admins) 1535800000(admins) .... 1070600513(domain users@example.org)
# id freeipa@example.org uid=1070607388(freeipa@example.org) gid=1070607388(freeipa@example.org) groups=1070607388(freeipa@example.org) 1070600513(domain users@example.org) 1535800006(ad_admins) 1535800000(admins)
From IPA Client:
# id mspezie@example.org id: mspezie@example.org: no such user
# id freeipa@example.org uid=1070607388(freeipa@example.org) gid=1070607388(freeipa@example.org) groups=1070607388(freeipa@example.org) 1070600513(domain users@example.org) 1535800006(ad_admins) 1535800000(admins)
The only difference from these two accounts is that freeipa(a)example.org is present in cn=Users and mspezie(a)example.org not. All the AD groups associated to mspezie have a name
Ok, then please add debug_level=9 to the [domain/...] section of sssd.conf on the client restart SSSD, call 'id mspezie@example.org' and look for errors in the domain log file in /var/log/sssd. Feel free to send the log file here or to me directly.
bye, Sumit
FreeIPA-users mailing list -- freeipa-users@lists.fedorahosted.org To unsubscribe send an email to freeipa-users-leave@lists.fedorahosted.org Fedora Code of Conduct: https://getfedora.org/code-of-conduct.html List Guidelines: https://fedoraproject.org/wiki/Mailing_list_guidelines List Archives: https://lists.fedoraproject.org/archives/list/freeipa-users@lists.fedo rahosted.org/message/DD3AQ25PRMF7AS6NCCRNBSVRXQVATGXY/
_______________________________________________ FreeIPA-users mailing list -- freeipa-users@lists.fedorahosted.org To unsubscribe send an email to freeipa-users-leave@lists.fedorahosted.org Fedora Code of Conduct: https://getfedora.org/code-of-conduct.html List Guidelines: https://fedoraproject.org/wiki/Mailing_list_guidelines List Archives: https://lists.fedoraproject.org/archives/list/freeipa-users@lists.fedorahost...
freeipa-users@lists.fedorahosted.org