[Freeipa-users] R: Re: IPA and AD basedn

[Mirko Spezie]

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)[e-mail]))(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(a)lists.fedorahosted.org&gt; 
Inviato: giovedì 9 agosto 2018 12:52
A: freeipa-users(a)lists.fedorahosted.org
Cc: Sumit Bose <sbose(a)redhat.com&gt;
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(a)example.org
>  uid=1070607073(mspezie(a)example.org) 
>  gid=1070607073(mspezie(a)example.org) 
>  groups=1070607073(mspezie(a)example.org)
>  1070603934(linux power users(a)example.org) 1070600512(domain 
>  admins(a)example.org)
>  1535800006(ad_admins)
>  1535800000(admins)
>  ....
>  1070600513(domain users(a)example.org)
>  
>  # id freeipa(a)example.org
>  uid=1070607388(freeipa(a)example.org) 
>  gid=1070607388(freeipa(a)example.org) 
>  groups=1070607388(freeipa(a)example.org)
>  1070600513(domain users(a)example.org)
>  1535800006(ad_admins)
>  1535800000(admins)
>  
>  
>  From IPA Client:
>  
>  # id mspezie(a)example.org
>  id: mspezie(a)example.org: no such user
>  
>  # id freeipa(a)example.org
>  uid=1070607388(freeipa(a)example.org) 
>  gid=1070607388(freeipa(a)example.org) 
>  groups=1070607388(freeipa(a)example.org)
>  1070600513(domain users(a)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(a)example.org&#39; 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(a)lists.fedorahosted.org
>  To unsubscribe send an email to 
>  freeipa-users-leave(a)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/[e-mail]
>  rahosted.org/message/DD3AQ25PRMF7AS6NCCRNBSVRXQVATGXY/
>  
_______________________________________________
FreeIPA-users mailing list -- freeipa-users(a)lists.fedorahosted.org
To unsubscribe send an email to freeipa-users-leave(a)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/[e-mail]...