[Freeipa-users] AD Trust users not resolving on clients: ipa_get_*_acct request failed

Wed Nov 23 06:58:58 UTC 2016

Hi All.

I’m having a problem getting trust users to resolve on *any* IPA client (this _was_ working well and I’m not sure what’s changed that may have caused it to start failing - although we have recently updated to IPA 4.4, plus IPA DNS enabled with delegation of ipa.example.com).

Whenever I try to lookup a trust user on a client (Ubuntu 16.04 with sssd-1.13.4-1ubuntu1.1) I see:

# id username at example.com
id: ‘username at example.com': no such user

The error message block in the sssd/domain log is:

> (Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [sysdb_search_by_name] (0x0400): No such entry
> (Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_group_dn_list] (0x0040): find_domain_by_object_name failed.
> (Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ipa_s2n_get_user_done] (0x0040): get_group_dn_list failed.
> (Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [sdap_id_op_done] (0x4000): releasing operation connection
> (Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ipa_subdomain_account_done] (0x0040): ipa_get_*_acct request failed: [12]: Cannot allocate memory.
> (Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [sdap_id_op_destroy] (0x4000): releasing operation connection
> (Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [acctinfo_callback] (0x0100): Request processed. Returned 3,12,Out of memory

A more complete log is below.

The domain users resolve fine on each of the three IPA servers (all RHEL7 with ipa-server-4.4.0-12.el7.x86_64).

IPA domain is ipa.example.com.  AD domain is example.com.

I have looked at https://fedorahosted.org/sssd/wiki/Troubleshooting, but no particular ideas are coming from that, so I guess I’m after some hints about what to check or any further tests to try.

Regards,

Robert.

(Wed Nov 23 17:24:03 2016) [sssd[be[ipa.example.com]]] [sbus_dispatch] (0x4000): dbus conn: 0xfd8480
(Wed Nov 23 17:24:03 2016) [sssd[be[ipa.example.com]]] [sbus_dispatch] (0x4000): Dispatching.
(Wed Nov 23 17:24:03 2016) [sssd[be[ipa.example.com]]] [sbus_message_handler] (0x2000): Received SBUS method org.freedesktop.sssd.dataprovider.getAccountInfo on path /org/freedesktop/sssd/dataprovider
(Wed Nov 23 17:24:03 2016) [sssd[be[ipa.example.com]]] [sbus_get_sender_id_send] (0x2000): Not a sysbus message, quit
(Wed Nov 23 17:24:03 2016) [sssd[be[ipa.example.com]]] [be_get_account_info] (0x0200): Got request for [0x1001][FAST BE_REQ_USER][1][name=mib]
(Wed Nov 23 17:24:03 2016) [sssd[be[ipa.example.com]]] [be_req_set_domain] (0x0400): Changing request domain from [ipa.example.com] to [example.com]
(Wed Nov 23 17:24:03 2016) [sssd[be[ipa.example.com]]] [sdap_id_op_connect_step] (0x4000): reusing cached connection
(Wed Nov 23 17:24:03 2016) [sssd[be[ipa.example.com]]] [sdap_id_op_connect_step] (0x4000): reusing cached connection
(Wed Nov 23 17:24:03 2016) [sssd[be[ipa.example.com]]] [ipa_get_ad_override_connect_done] (0x4000): Searching for overrides in view [Default Trust View] with filter [(&(objectClass=ipaUserOverride)(uid=mib))].
(Wed Nov 23 17:24:03 2016) [sssd[be[ipa.example.com]]] [sdap_print_server] (0x2000): Searching 172.25.180.53
(Wed Nov 23 17:24:03 2016) [sssd[be[ipa.example.com]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaUserOverride)(uid=mib))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=example,dc=com].
(Wed Nov 23 17:24:03 2016) [sssd[be[ipa.example.com]]] [sdap_get_generic_ext_step] (0x2000): ldap_search_ext called, msgid = 21
(Wed Nov 23 17:24:03 2016) [sssd[be[ipa.example.com]]] [sdap_op_add] (0x2000): New operation 21 timeout 6
(Wed Nov 23 17:24:03 2016) [sssd[be[ipa.example.com]]] [sdap_process_result] (0x2000): Trace: sh[0xfd76d0], connected[1], ops[0xff6420], ldap[0xfded70]
(Wed Nov 23 17:24:03 2016) [sssd[be[ipa.example.com]]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_SEARCH_RESULT]
(Wed Nov 23 17:24:03 2016) [sssd[be[ipa.example.com]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Wed Nov 23 17:24:03 2016) [sssd[be[ipa.example.com]]] [sdap_op_destructor] (0x2000): Operation 21 finished
(Wed Nov 23 17:24:03 2016) [sssd[be[ipa.example.com]]] [ipa_get_ad_override_done] (0x4000): No override found with filter [(&(objectClass=ipaUserOverride)(uid=mib))].
(Wed Nov 23 17:24:03 2016) [sssd[be[ipa.example.com]]] [sdap_id_op_destroy] (0x4000): releasing operation connection
(Wed Nov 23 17:24:03 2016) [sssd[be[ipa.example.com]]] [sdap_id_op_connect_step] (0x4000): reusing cached connection
(Wed Nov 23 17:24:03 2016) [sssd[be[ipa.example.com]]] [ipa_s2n_exop_send] (0x0400): Executing extended operation
(Wed Nov 23 17:24:03 2016) [sssd[be[ipa.example.com]]] [ipa_s2n_exop_send] (0x2000): ldap_extended_operation sent, msgid = 22
(Wed Nov 23 17:24:03 2016) [sssd[be[ipa.example.com]]] [sdap_op_add] (0x2000): New operation 22 timeout 6
(Wed Nov 23 17:24:03 2016) [sssd[be[ipa.example.com]]] [sdap_process_result] (0x2000): Trace: sh[0xfd76d0], connected[1], ops[0xff9310], ldap[0xfded70]
(Wed Nov 23 17:24:03 2016) [sssd[be[ipa.example.com]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing!
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [sdap_process_result] (0x2000): Trace: sh[0xfd76d0], connected[1], ops[0xff9310], ldap[0xfded70]
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [sdap_process_message] (0x4000): Message type: [LDAP_RES_EXTENDED]
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ipa_s2n_exop_done] (0x0400): ldap_extended_operation result: Success(0), (null).
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [sdap_op_destructor] (0x2000): Operation 22 finished
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [add_v1_user_data] (0x4000): BER tag is [48]
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Found new sequence.
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [objectSIDString].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [userPrincipalName].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [adAccountExpires].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [adUserAccountControl].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [mail].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [originalDN].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_extra_attrs] (0x4000): Extra attribute [originalMemberOf].
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0xff88e0

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0xff89a0

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Running timer event 0xff88e0 "ltdb_callback"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Destroying timer event 0xff89a0 "ltdb_timeout"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Ending timer event 0xff88e0 "ltdb_callback"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [sysdb_search_by_name] (0x0400): No such entry
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0xff7530

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0xff75f0

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Running timer event 0xff7530 "ltdb_callback"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Destroying timer event 0xff75f0 "ltdb_timeout"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Ending timer event 0xff7530 "ltdb_callback"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [sysdb_search_by_name] (0x0400): No such entry
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0xff72a0

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0xff7360

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Running timer event 0xff72a0 "ltdb_callback"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Destroying timer event 0xff7360 "ltdb_timeout"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Ending timer event 0xff72a0 "ltdb_callback"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [sysdb_search_by_name] (0x0400): No such entry
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0xff72a0

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0xff7360

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Running timer event 0xff72a0 "ltdb_callback"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Destroying timer event 0xff7360 "ltdb_timeout"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Ending timer event 0xff72a0 "ltdb_callback"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [sysdb_search_by_name] (0x0400): No such entry
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0xff7460

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0xff7520

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Running timer event 0xff7460 "ltdb_callback"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Destroying timer event 0xff7520 "ltdb_timeout"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Ending timer event 0xff7460 "ltdb_callback"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [sysdb_search_by_name] (0x0400): No such entry
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0xff7460

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0xff7520

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Running timer event 0xff7460 "ltdb_callback"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Destroying timer event 0xff7520 "ltdb_timeout"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Ending timer event 0xff7460 "ltdb_callback"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [sysdb_search_by_name] (0x0400): No such entry
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0xff75d0

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x1016050

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Running timer event 0xff75d0 "ltdb_callback"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Destroying timer event 0x1016050 "ltdb_timeout"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Ending timer event 0xff75d0 "ltdb_callback"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [sysdb_search_by_name] (0x0400): No such entry
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0xff72a0

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0xff7360

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Running timer event 0xff72a0 "ltdb_callback"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Destroying timer event 0xff7360 "ltdb_timeout"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Ending timer event 0xff72a0 "ltdb_callback"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [sysdb_search_by_name] (0x0400): No such entry
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x1016050

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x1016110

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Running timer event 0x1016050 "ltdb_callback"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Destroying timer event 0x1016110 "ltdb_timeout"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Ending timer event 0x1016050 "ltdb_callback"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [sysdb_search_by_name] (0x0400): No such entry
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x1016050

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x1016110

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Running timer event 0x1016050 "ltdb_callback"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Destroying timer event 0x1016110 "ltdb_timeout"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Ending timer event 0x1016050 "ltdb_callback"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [sysdb_search_by_name] (0x0400): No such entry
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x1016150

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x1016210

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Running timer event 0x1016150 "ltdb_callback"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Destroying timer event 0x1016210 "ltdb_timeout"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Ending timer event 0x1016150 "ltdb_callback"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [sysdb_search_by_name] (0x0400): No such entry
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x1016210

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x10162d0

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Running timer event 0x1016210 "ltdb_callback"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Destroying timer event 0x10162d0 "ltdb_timeout"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Ending timer event 0x1016210 "ltdb_callback"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [sysdb_search_by_name] (0x0400): No such entry
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x1016150

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x1016210

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Running timer event 0x1016150 "ltdb_callback"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Destroying timer event 0x1016210 "ltdb_timeout"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Ending timer event 0x1016150 "ltdb_callback"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [sysdb_search_by_name] (0x0400): No such entry
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Added timed event "ltdb_callback": 0x10162b0

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Added timed event "ltdb_timeout": 0x1016370

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Running timer event 0x10162b0 "ltdb_callback"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Destroying timer event 0x1016370 "ltdb_timeout"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ldb] (0x4000): Ending timer event 0x10162b0 "ltdb_callback"

(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [sysdb_search_by_name] (0x0400): No such entry
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [get_group_dn_list] (0x0040): find_domain_by_object_name failed.
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ipa_s2n_get_user_done] (0x0040): get_group_dn_list failed.
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [sdap_id_op_done] (0x4000): releasing operation connection
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [ipa_subdomain_account_done] (0x0040): ipa_get_*_acct request failed: [12]: Cannot allocate memory.
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [sdap_id_op_destroy] (0x4000): releasing operation connection
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [acctinfo_callback] (0x0100): Request processed. Returned 3,12,Out of memory
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [sdap_process_result] (0x2000): Trace: sh[0xfd76d0], connected[1], ops[(nil)], ldap[0xfded70]
(Wed Nov 23 17:24:04 2016) [sssd[be[ipa.example.com]]] [sdap_process_result] (0x2000): Trace: ldap_result found nothing!