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

Jakub Hrozek jhrozek at redhat.com
Wed Nov 23 08:58:51 UTC 2016 

On Wed, Nov 23, 2016 at 05:58:58PM +1100, Robert Sturrock wrote:
> 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.

The error happens here. Too bad we don't print the names of the group that
failed the find_domain_by_object_name() but I would guess a supplemetary
name is qualified with a domain that the client doesn't know for some reason.

The only suggestion I have is to run "id" for this user on the server
and check the domains of the supplementary groups that are returned.
Then check if the domains are expected. You can also run ldbsearch on
the client cache with objectclass=subdomain to check which trusted
domains the client knows about and whether they match the domains of the
supplementary groups on the server.

(This wouldn't fix the issue of course, but maybe help us understand
better where the error really is..)

Do you maybe use some non-default configuration on the server that
strips the domain names from the qualified name?

> > (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!
> 
> 
> -- 
> Manage your subscription for the Freeipa-users mailing list:
> https://www.redhat.com/mailman/listinfo/freeipa-users
> Go to http://freeipa.org for more info on the project

More information about the Freeipa-users mailing list