[Freeipa-users] UPN suffixes in AD trust

Thu Jun 25 10:22:16 UTC 2015

On 06/24/2015 06:45 PM, Sumit Bose wrote:
> On Wed, Jun 24, 2015 at 05:11:07PM +0200, Giorgio Biacchi wrote:
>> Hi everybody,
>> I established a bidirectional trust between an IPA server (version 4.1.0 on
>> CentOS 7.1), ipa.mydomain.local and an AD (Windows 2012 r2), mydomain.local.
>> Everything is working fine, and I'm able to authenticate and logon on a linux
>> host joined to IPA server using AD credentials (username at mydomain.local).
>> But active directory is configured with two more UPN suffixes (otherdomain.com
>> and sub.otherdomain.com), and I cannot logon with credentials using alternative
>> UPN (example: john.doe at otherdomain.com).
>>
>> How can I make this possible? Another trust (ipa trust-add) with the same AD?
>> Manual configuration of krb5 and/or sssd?
> 
> Have you tried to login to an IPA client or the server? Please try with
> an IPA server first. If this does not work it would be nice if you can
> send the SSSD log files from the IPA server which are generated during
> the logon attempt. Please call 'sss_cache -E' before to invalidate all
> cached entries so that the logs will contain all needed calls to AD.
> 
> Using UPN suffixes were added to the AD provider some time ago and the
> code is available in the IPA provider as well, but I guess no one has
> actually tried this before.
> 
> bye,
> Sumit

First of all let me say that i feel like I'm missing some config somewhere..
Changes tried in krb5.conf to support UPN suffixes didn't helped.
I can only access the server vi ssh so I've attached the logs for a successful
login for account1 at mydomain.local and an unsuccessful login for
account2 at otherdomain.com done via ssh.

Bye and thanks for your help

> 
>>
>> Thanks in advance
>>
>> -- 
>> gb
>>
>> PGP Key: http://pgp.mit.edu/
>> Primary key fingerprint: C510 0765 943E EBED A4F2 69D3 16CC DC90 B9CB 0F34
>>
>> -- 
>> 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

-- 
gb

PGP Key: http://pgp.mit.edu/
Primary key fingerprint: C510 0765 943E EBED A4F2 69D3 16CC DC90 B9CB 0F34
-------------- next part --------------
(Thu Jun 25 11:38:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): The group has 1 members
(Thu Jun 25 11:38:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Group has 1 members
(Thu Jun 25 11:38:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Storing info for group admins
(Thu Jun 25 11:38:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object admins
(Thu Jun 25 11:38:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_grpmem] (0x0400): Processing group admins
(Thu Jun 25 11:38:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_grpmem] (0x0400): Adding member users to group [admins]
(Thu Jun 25 11:38:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:IPA:ipa.mydomain.local:b002c2bc-18dd-11e5-b692-005056a45723))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:38:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:38:18 2015) [sssd[be[ipa.mydomain.local]]] [acctinfo_callback] (0x0100): Request processed. Returned 0,0,Success
(Thu Jun 25 11:38:27 2015) [sssd[be[ipa.mydomain.local]]] [be_run_unconditional_online_cb] (0x0400): Running unconditional online callbacks.
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [be_run_unconditional_online_cb] (0x0400): Running unconditional online callbacks.
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [be_get_account_info] (0x0200): Got request for [0x1001][1][name=account1]
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [be_req_set_domain] (0x0400): Changing request domain from [ipa.mydomain.local] to [mydomain.local]
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaUserOverride)(uid=account1))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [fo_resolve_service_send] (0x0100): Trying to resolve service 'mydomain.local'
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [resolve_srv_send] (0x0200): The status of SRV lookup is resolved
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [be_resolve_server_process] (0x0200): Found address for server dc02.mydomain.local: [172.21.251.12] TTL 3600
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [ad_resolve_callback] (0x0100): Constructed uri 'ldap://dc02.mydomain.local'
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [ad_resolve_callback] (0x0100): Constructed GC uri 'ldap://dc02.mydomain.local'
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [sss_ldap_init_send] (0x0400): Setting 6 seconds timeout for connecting
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=*)][].
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_server_opts_from_rootdse] (0x0100): Setting AD compatibility level to [6]
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [sdap_kinit_send] (0x0400): Attempting kinit (default, host/idc01.ipa.mydomain.local, IPA.MYDOMAIN.LOCAL, 86400)
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [fo_resolve_service_send] (0x0100): Trying to resolve service 'mydomain.local'
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [resolve_srv_send] (0x0200): The status of SRV lookup is resolved
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [be_resolve_server_process] (0x0200): Found address for server dc02.mydomain.local: [172.21.251.12] TTL 3600
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [create_tgt_req_send_buffer] (0x0400): buffer size: 87
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [set_tgt_child_timeout] (0x0400): Setting 6 seconds timeout for tgt child
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [write_pipe_handler] (0x0400): All data has been sent!
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [child_sig_handler] (0x0100): child [6171] finished successfully.
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [read_pipe_handler] (0x0400): EOF received, client finished
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_tgt_recv] (0x0400): Child responded: 0 [FILE:/var/lib/sss/db/ccache_IPA.MYDOMAIN.LOCAL], expired on [1435311673]
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [sdap_cli_auth_step] (0x0100): expire timeout is 900
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [sasl_bind_send] (0x0100): Executing sasl bind mech: gssapi, user: host/idc01.ipa.mydomain.local
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [fo_set_port_status] (0x0100): Marking port 389 of server 'dc02.mydomain.local' as 'working'
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [set_server_common_status] (0x0100): Marking server 'dc02.mydomain.local' as 'working'
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [fo_set_port_status] (0x0400): Marking port 389 of duplicate server 'dc02.mydomain.local' as 'working'
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [sdap_search_user_next_base] (0x0400): Searching for users with base [dc=mydomain,dc=local]
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(sAMAccountName=account1)(objectclass=user)(sAMAccountName=*)(objectSID=*))][dc=mydomain,dc=local].
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [be_run_unconditional_online_cb] (0x0400): Running unconditional online callbacks.
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [sdap_search_user_process] (0x0400): Search for users, returned 1 results.
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Save user
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object account1 at mydomain.local
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Processing user account1 at mydomain.local
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Adding original memberOf attributes to [account1 at mydomain.local].
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Adding user principal [account1 at MYDOMAIN.LOCAL] to attributes of [account1 at mydomain.local].
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Storing info for user account1 at mydomain.local
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-1710311407-3537505305-1030735119-3800))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:13 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [objectClass=ipaexternalgroup][dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:14 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:14 2015) [sssd[be[ipa.mydomain.local]]] [ipa_get_ext_groups_done] (0x0400): [0] external groups found.
(Thu Jun 25 11:41:14 2015) [sssd[be[ipa.mydomain.local]]] [find_ipa_ext_memberships] (0x0400): No external groupmemberships found.
(Thu Jun 25 11:41:14 2015) [sssd[be[ipa.mydomain.local]]] [acctinfo_callback] (0x0100): Request processed. Returned 0,0,Success
(Thu Jun 25 11:41:14 2015) [sssd[be[ipa.mydomain.local]]] [be_get_account_info] (0x0200): Got request for [0x1003][1][name=nobody]
(Thu Jun 25 11:41:14 2015) [sssd[be[ipa.mydomain.local]]] [be_req_set_domain] (0x0400): Changing request domain from [ipa.mydomain.local] to [ipa.mydomain.local]
(Thu Jun 25 11:41:14 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_initgr_next_base] (0x0400): Searching for users with base [cn=accounts,dc=ipa,dc=mydomain,dc=local]
(Thu Jun 25 11:41:14 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(uid=nobody)(objectclass=posixAccount)(&(uidNumber=*)(!(uidNumber=0))))][cn=accounts,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:14 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:14 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_search_by_name] (0x0400): No such entry
(Thu Jun 25 11:41:14 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_delete_user] (0x0400): Error: 2 (No such file or directory)
(Thu Jun 25 11:41:14 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_search_by_name] (0x0400): No such entry
(Thu Jun 25 11:41:14 2015) [sssd[be[ipa.mydomain.local]]] [ipa_id_get_account_info_orig_done] (0x0080): Object not found, ending request
(Thu Jun 25 11:41:14 2015) [sssd[be[ipa.mydomain.local]]] [acctinfo_callback] (0x0100): Request processed. Returned 3,0,Account info lookup failed
(Thu Jun 25 11:41:14 2015) [sssd[be[ipa.mydomain.local]]] [be_get_account_info] (0x0200): Got request for [0x1][1][name=account1]
(Thu Jun 25 11:41:14 2015) [sssd[be[ipa.mydomain.local]]] [be_req_set_domain] (0x0400): Changing request domain from [ipa.mydomain.local] to [mydomain.local]
(Thu Jun 25 11:41:14 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaUserOverride)(uid=account1))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:14 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:14 2015) [sssd[be[ipa.mydomain.local]]] [sdap_search_user_next_base] (0x0400): Searching for users with base [dc=mydomain,dc=local]
(Thu Jun 25 11:41:14 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(sAMAccountName=account1)(objectclass=user)(sAMAccountName=*)(objectSID=*))][dc=mydomain,dc=local].
(Thu Jun 25 11:41:14 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:14 2015) [sssd[be[ipa.mydomain.local]]] [sdap_search_user_process] (0x0400): Search for users, returned 1 results.
(Thu Jun 25 11:41:14 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Save user
(Thu Jun 25 11:41:14 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object account1 at mydomain.local
(Thu Jun 25 11:41:14 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Processing user account1 at mydomain.local
(Thu Jun 25 11:41:14 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Adding original memberOf attributes to [account1 at mydomain.local].
(Thu Jun 25 11:41:14 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Adding user principal [account1 at MYDOMAIN.LOCAL] to attributes of [account1 at mydomain.local].
(Thu Jun 25 11:41:14 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Storing info for user account1 at mydomain.local
(Thu Jun 25 11:41:14 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-1710311407-3537505305-1030735119-3800))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:14 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:14 2015) [sssd[be[ipa.mydomain.local]]] [ipa_get_ad_memberships_send] (0x0400): External group information still valid.
(Thu Jun 25 11:41:14 2015) [sssd[be[ipa.mydomain.local]]] [find_ipa_ext_memberships] (0x0400): No external groupmemberships found.
(Thu Jun 25 11:41:14 2015) [sssd[be[ipa.mydomain.local]]] [acctinfo_callback] (0x0100): Request processed. Returned 0,0,Success
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [be_get_account_info] (0x0200): Got request for [0x3][1][name=account1]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [be_req_set_domain] (0x0400): Changing request domain from [ipa.mydomain.local] to [mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaUserOverride)(uid=account1))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [fo_resolve_service_send] (0x0100): Trying to resolve service 'gc_mydomain.local'
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [resolve_srv_send] (0x0200): The status of SRV lookup is resolved
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [be_resolve_server_process] (0x0200): Found address for server dc02.mydomain.local: [172.21.251.12] TTL 3600
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [ad_resolve_callback] (0x0100): Constructed uri 'ldap://dc02.mydomain.local'
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [ad_resolve_callback] (0x0100): Constructed GC uri 'ldap://dc02.mydomain.local:3268'
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sss_ldap_init_send] (0x0400): Setting 6 seconds timeout for connecting
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=*)][].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_server_opts_from_rootdse] (0x0100): Setting AD compatibility level to [6]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_kinit_send] (0x0400): Attempting kinit (default, host/idc01.ipa.mydomain.local, IPA.MYDOMAIN.LOCAL, 86400)
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [fo_resolve_service_send] (0x0100): Trying to resolve service 'mydomain.local'
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [resolve_srv_send] (0x0200): The status of SRV lookup is resolved
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [be_resolve_server_process] (0x0200): Found address for server dc02.mydomain.local: [172.21.251.12] TTL 3600
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [ad_resolve_callback] (0x0100): Constructed uri 'ldap://dc02.mydomain.local'
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [ad_resolve_callback] (0x0100): Constructed GC uri 'ldap://dc02.mydomain.local'
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [create_tgt_req_send_buffer] (0x0400): buffer size: 87
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [set_tgt_child_timeout] (0x0400): Setting 6 seconds timeout for tgt child
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [write_pipe_handler] (0x0400): All data has been sent!
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [child_sig_handler] (0x0100): child [6173] finished successfully.
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [read_pipe_handler] (0x0400): EOF received, client finished
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_tgt_recv] (0x0400): Child responded: 0 [FILE:/var/lib/sss/db/ccache_IPA.MYDOMAIN.LOCAL], expired on [1435311678]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_cli_auth_step] (0x0100): expire timeout is 900
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sasl_bind_send] (0x0100): Executing sasl bind mech: gssapi, user: host/idc01.ipa.mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [fo_set_port_status] (0x0100): Marking port 3268 of server 'dc02.mydomain.local' as 'working'
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [set_server_common_status] (0x0100): Marking server 'dc02.mydomain.local' as 'working'
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [fo_set_port_status] (0x0400): Marking port 3268 of duplicate server 'dc02.mydomain.local' as 'working'
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_initgr_next_base] (0x0400): Searching for users with base [dc=mydomain,dc=local]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(sAMAccountName=account1)(objectclass=user)(objectSID=*))][dc=mydomain,dc=local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [be_run_unconditional_online_cb] (0x0400): Running unconditional online callbacks.
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Save user
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object account1 at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Processing user account1 at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Adding original memberOf attributes to [account1 at mydomain.local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Adding user principal [account1 at MYDOMAIN.LOCAL] to attributes of [account1 at mydomain.local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Storing info for user account1 at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [no filter][CN=Giorgio Biacchi,OU=Gestori Aule,DC=mydomain,DC=local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_ad_tokengroups_get_posix_members] (0x0080): Domain not found for SID S-1-5-32-545
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_ad_tokengroups_get_posix_members] (0x0080): Domain not found for SID S-1-5-32-548
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_search_entry_by_sid_str] (0x0400): No such entry
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_ad_tokengroups_get_posix_members] (0x0400): Missing SID S-1-5-21-1710311407-3537505305-1030735119-1107 will be downloaded
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_search_entry_by_sid_str] (0x0400): No such entry
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_ad_tokengroups_get_posix_members] (0x0400): Missing SID S-1-5-21-1710311407-3537505305-1030735119-1608 will be downloaded
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=mydomain,dc=local]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-1710311407-3537505305-1030735119-1107)(objectClass=group)(name=*))][dc=mydomain,dc=local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results.
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_hash_group] (0x0400): Filtering AD group.
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=group)(name=*))][CN=Domain Users,CN=Users,DC=mydomain,DC=local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=user)][CN=Test Palo Alto,OU=Test,OU=Utenti Mydomain,DC=mydomain,DC=local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=user)][CN=test4,OU=Test,OU=Utenti Mydomain,DC=mydomain,DC=local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=user)][CN=test3,OU=Test,OU=Utenti Mydomain,DC=mydomain,DC=local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=user)][CN=test2,OU=Test,OU=Utenti Mydomain,DC=mydomain,DC=local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=user)][CN=test,OU=Test,OU=Utenti Mydomain,DC=mydomain,DC=local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_recv] (0x0400): 5 users found in the hash table
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_recv] (0x0400): 2 groups found in the hash table
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object test2 at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object test4 at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object test at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object test_pa at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object test3 at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object Domain Users at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Processing group Domain Users at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): The group has 5 members
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Group has 5 members
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Adding ghost member for group [test_pa at mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Adding ghost member for group [test4 at mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Adding ghost member for group [test3 at mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Adding ghost member for group [test2 at mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Adding ghost member for group [test at mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Storing info for group Domain Users at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object SophosUser at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Processing group SophosUser at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Filtering AD group [SophosUser at mydomain.local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): The group has 1 members
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Group has 1 members
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Storing info for group SophosUser at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_set_entry_attr] (0x0080): ldb_modify failed: [Attribute or value exists]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_set_entry_attr] (0x0040): Error: 17 (File exists)
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_store_group] (0x0400): Error: 17 (File exists)
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_store_group_with_gid] (0x0040): Could not store group SophosUser at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0080): Could not store group with GID: [File exists]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0080): Failed to save group [SophosUser at mydomain.local]: [File exists]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_groups] (0x0040): Failed to store group 1. Ignoring.
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object Domain Users at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_grpmem] (0x0400): Processing group Domain Users at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_grpmem] (0x0400): Adding member users to group [Domain Users at mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=mydomain,dc=local]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-1710311407-3537505305-1030735119-1608)(objectClass=group)(name=*))][dc=mydomain,dc=local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results.
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_hash_group] (0x0400): Filtering AD group.
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_recv] (0x0400): 0 users found in the hash table
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object SophosUserTmpRn
CNF:10eb1e79-892c-43ab-9735-0bf3cc30f264 at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Processing group SophosUserTmpRn
CNF:10eb1e79-892c-43ab-9735-0bf3cc30f264 at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Filtering AD group [SophosUserTmpRn
CNF:10eb1e79-892c-43ab-9735-0bf3cc30f264 at mydomain.local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): The group has 1 members
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Group has 1 members
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Storing info for group SophosUserTmpRn
CNF:10eb1e79-892c-43ab-9735-0bf3cc30f264 at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_set_entry_attr] (0x0080): ldb_modify failed: [Attribute or value exists]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_set_entry_attr] (0x0040): Error: 17 (File exists)
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_store_group] (0x0400): Error: 17 (File exists)
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_store_group_with_gid] (0x0040): Could not store group SophosUserTmpRn
CNF:10eb1e79-892c-43ab-9735-0bf3cc30f264 at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0080): Could not store group with GID: [File exists]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0080): Failed to save group [SophosUserTmpRn
CNF:10eb1e79-892c-43ab-9735-0bf3cc30f264 at mydomain.local]: [File exists]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_groups] (0x0040): Failed to store group 0. Ignoring.
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_search_entry_by_sid_str] (0x0400): No such entry
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_search_entry_by_sid_str] (0x0400): No such entry
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=mydomain,dc=local]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-1710311407-3537505305-1030735119-513)(objectClass=group)(name=*))][dc=mydomain,dc=local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results.
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=user)][CN=Test Palo Alto,OU=Test,OU=Utenti Mydomain,DC=mydomain,DC=local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=user)][CN=test4,OU=Test,OU=Utenti Mydomain,DC=mydomain,DC=local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=user)][CN=test3,OU=Test,OU=Utenti Mydomain,DC=mydomain,DC=local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=user)][CN=test2,OU=Test,OU=Utenti Mydomain,DC=mydomain,DC=local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=user)][CN=test,OU=Test,OU=Utenti Mydomain,DC=mydomain,DC=local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_recv] (0x0400): 5 users found in the hash table
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object test2 at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object test4 at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object test at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object test_pa at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object test3 at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object Domain Users at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Processing group Domain Users at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): The group has 5 members
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Group has 5 members
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Adding ghost member for group [test_pa at mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Adding ghost member for group [test4 at mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Adding ghost member for group [test3 at mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Adding ghost member for group [test2 at mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Adding ghost member for group [test at mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Storing info for group Domain Users at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object Domain Users at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_grpmem] (0x0400): Processing group Domain Users at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_grpmem] (0x0400): Adding member users to group [Domain Users at mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-1710311407-3537505305-1030735119-3800))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [ipa_get_ad_memberships_send] (0x0400): External group information still valid.
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [find_ipa_ext_memberships] (0x0400): No external groupmemberships found.
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [acctinfo_callback] (0x0100): Request processed. Returned 0,0,Success
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [be_req_set_domain] (0x0400): Changing request domain from [ipa.mydomain.local] to [mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [be_pam_handler] (0x0100): Got request with the following data
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): command: PAM_AUTHENTICATE
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): domain: mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): user: account1 at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): service: sshd
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): tty: ssh
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): ruser: 
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): rhost: xxx.xxx.xxx
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): authtok type: 1
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): newauthtok type: 0
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): priv: 1
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): cli_pid: 6169
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): logon name: not set
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [fo_resolve_service_send] (0x0100): Trying to resolve service 'IPA'
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [be_resolve_server_process] (0x0200): Found address for server idc01.ipa.mydomain.local: [172.21.251.9] TTL 7200
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [ipa_resolve_callback] (0x0400): Constructed uri 'ldap://idc01.ipa.mydomain.local'
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [write_pipe_handler] (0x0400): All data has been sent!
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [child_sig_handler] (0x0100): child [6174] finished successfully.
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [read_pipe_handler] (0x0400): EOF received, client finished
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [fo_set_port_status] (0x0100): Marking port 0 of server 'idc01.ipa.mydomain.local' as 'working'
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [set_server_common_status] (0x0100): Marking server 'idc01.ipa.mydomain.local' as 'working'
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [fo_set_port_status] (0x0400): Marking port 0 of duplicate server 'idc01.ipa.mydomain.local' as 'working'
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [be_pam_handler_callback] (0x0100): Backend returned: (0, 0, <NULL>) [Success]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [be_pam_handler_callback] (0x0100): Sending result [0][mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [be_pam_handler_callback] (0x0100): Sent result [0][mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [be_get_account_info] (0x0200): Got request for [0x3][1][name=account1]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [be_req_set_domain] (0x0400): Changing request domain from [ipa.mydomain.local] to [mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaUserOverride)(uid=account1))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_initgr_next_base] (0x0400): Searching for users with base [dc=mydomain,dc=local]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(sAMAccountName=account1)(objectclass=user)(objectSID=*))][dc=mydomain,dc=local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Save user
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object account1 at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Processing user account1 at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Adding original memberOf attributes to [account1 at mydomain.local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Adding user principal [account1 at MYDOMAIN.LOCAL] to attributes of [account1 at mydomain.local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Storing info for user account1 at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [no filter][CN=Giorgio Biacchi,OU=Gestori Aule,DC=mydomain,DC=local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_ad_tokengroups_get_posix_members] (0x0080): Domain not found for SID S-1-5-32-545
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_ad_tokengroups_get_posix_members] (0x0080): Domain not found for SID S-1-5-32-548
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_search_entry_by_sid_str] (0x0400): No such entry
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_ad_tokengroups_get_posix_members] (0x0400): Missing SID S-1-5-21-1710311407-3537505305-1030735119-1107 will be downloaded
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_search_entry_by_sid_str] (0x0400): No such entry
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_ad_tokengroups_get_posix_members] (0x0400): Missing SID S-1-5-21-1710311407-3537505305-1030735119-1608 will be downloaded
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=mydomain,dc=local]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-1710311407-3537505305-1030735119-1107)(objectClass=group)(name=*))][dc=mydomain,dc=local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results.
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_hash_group] (0x0400): Filtering AD group.
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_recv] (0x0400): 0 users found in the hash table
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object SophosUser at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Processing group SophosUser at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Filtering AD group [SophosUser at mydomain.local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): The group has 1 members
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Group has 1 members
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Storing info for group SophosUser at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_set_entry_attr] (0x0080): ldb_modify failed: [Attribute or value exists]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_set_entry_attr] (0x0040): Error: 17 (File exists)
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_store_group] (0x0400): Error: 17 (File exists)
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_store_group_with_gid] (0x0040): Could not store group SophosUser at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0080): Could not store group with GID: [File exists]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0080): Failed to save group [SophosUser at mydomain.local]: [File exists]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_groups] (0x0040): Failed to store group 0. Ignoring.
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=mydomain,dc=local]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-1710311407-3537505305-1030735119-1608)(objectClass=group)(name=*))][dc=mydomain,dc=local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results.
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_hash_group] (0x0400): Filtering AD group.
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_recv] (0x0400): 0 users found in the hash table
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object SophosUserTmpRn
CNF:10eb1e79-892c-43ab-9735-0bf3cc30f264 at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Processing group SophosUserTmpRn
CNF:10eb1e79-892c-43ab-9735-0bf3cc30f264 at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Filtering AD group [SophosUserTmpRn
CNF:10eb1e79-892c-43ab-9735-0bf3cc30f264 at mydomain.local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): The group has 1 members
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Group has 1 members
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Storing info for group SophosUserTmpRn
CNF:10eb1e79-892c-43ab-9735-0bf3cc30f264 at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_set_entry_attr] (0x0080): ldb_modify failed: [Attribute or value exists]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_set_entry_attr] (0x0040): Error: 17 (File exists)
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_store_group] (0x0400): Error: 17 (File exists)
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_store_group_with_gid] (0x0040): Could not store group SophosUserTmpRn
CNF:10eb1e79-892c-43ab-9735-0bf3cc30f264 at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0080): Could not store group with GID: [File exists]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0080): Failed to save group [SophosUserTmpRn
CNF:10eb1e79-892c-43ab-9735-0bf3cc30f264 at mydomain.local]: [File exists]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_groups] (0x0040): Failed to store group 0. Ignoring.
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_search_entry_by_sid_str] (0x0400): No such entry
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_search_entry_by_sid_str] (0x0400): No such entry
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=mydomain,dc=local]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-1710311407-3537505305-1030735119-513)(objectClass=group)(name=*))][dc=mydomain,dc=local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results.
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=user)][CN=Test Palo Alto,OU=Test,OU=Utenti Mydomain,DC=mydomain,DC=local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=user)][CN=test4,OU=Test,OU=Utenti Mydomain,DC=mydomain,DC=local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=user)][CN=test3,OU=Test,OU=Utenti Mydomain,DC=mydomain,DC=local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=user)][CN=test2,OU=Test,OU=Utenti Mydomain,DC=mydomain,DC=local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=user)][CN=test,OU=Test,OU=Utenti Mydomain,DC=mydomain,DC=local].
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_recv] (0x0400): 5 users found in the hash table
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object test2 at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object test4 at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object test at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object test_pa at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object test3 at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object Domain Users at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Processing group Domain Users at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): The group has 5 members
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Group has 5 members
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Adding ghost member for group [test_pa at mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Adding ghost member for group [test4 at mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Adding ghost member for group [test3 at mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Adding ghost member for group [test2 at mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Adding ghost member for group [test at mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Storing info for group Domain Users at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object Domain Users at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_grpmem] (0x0400): Processing group Domain Users at mydomain.local
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_grpmem] (0x0400): Adding member users to group [Domain Users at mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-1710311407-3537505305-1030735119-3800))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [ipa_get_ad_memberships_send] (0x0400): External group information still valid.
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [find_ipa_ext_memberships] (0x0400): No external groupmemberships found.
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [acctinfo_callback] (0x0100): Request processed. Returned 0,0,Success
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [be_req_set_domain] (0x0400): Changing request domain from [ipa.mydomain.local] to [mydomain.local]
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [be_pam_handler] (0x0100): Got request with the following data
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): command: PAM_ACCT_MGMT
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): domain: mydomain.local
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): user: account1 at mydomain.local
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): service: sshd
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): tty: ssh
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): ruser: 
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): rhost: xxx.xxx.xxx
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): authtok type: 0
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): newauthtok type: 0
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): priv: 1
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): cli_pid: 6169
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): logon name: not set
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [sdap_access_send] (0x0400): Performing access check for user [account1 at mydomain.local]
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [sdap_account_expired_rhds] (0x0400): Performing RHDS access check for user [account1 at mydomain.local]
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaHost)(fqdn=idc01.ipa.mydomain.local))][cn=accounts,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [sdap_has_deref_support] (0x0400): The server supports deref method OpenLDAP
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [sdap_x_deref_search_send] (0x0400): Dereferencing entry [fqdn=idc01.ipa.mydomain.local,cn=computers,cn=accounts,dc=ipa,dc=mydomain,dc=local] using OpenLDAP deref
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [no filter][fqdn=idc01.ipa.mydomain.local,cn=computers,cn=accounts,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [sdap_x_deref_parse_entry] (0x0400): Got deref control
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [sdap_x_deref_parse_entry] (0x0400): All deref results from a single control parsed
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [ipa_hostgroup_info_done] (0x0200): No host groups were dereferenced
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [ipa_hbac_service_info_next] (0x0400): Sending request for next search base: [cn=hbac,dc=ipa,dc=mydomain,dc=local][2][(objectClass=ipaHBACService)]
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectClass=ipaHBACService)][cn=hbac,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [ipa_hbac_servicegroup_info_next] (0x0400): Sending request for next search base: [cn=hbac,dc=ipa,dc=mydomain,dc=local][2][(objectClass=ipaHBACServiceGroup)]
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectClass=ipaHBACServiceGroup)][cn=hbac,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [ipa_hbac_rule_info_next] (0x0400): Sending request for next search base: [cn=hbac,dc=ipa,dc=mydomain,dc=local][2][(&(objectclass=ipaHBACRule)(ipaenabledflag=TRUE)(|(hostCategory=all)(memberHost=fqdn=idc01.ipa.mydomain.local,cn=computers,cn=accounts,dc=ipa,dc=mydomain,dc=local)))]
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectclass=ipaHBACRule)(ipaenabledflag=TRUE)(|(hostCategory=all)(memberHost=fqdn=idc01.ipa.mydomain.local,cn=computers,cn=accounts,dc=ipa,dc=mydomain,dc=local)))][cn=hbac,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [hbac_get_category] (0x0200): Category is set to 'all'.
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [hbac_get_category] (0x0200): Category is set to 'all'.
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [hbac_get_category] (0x0200): Category is set to 'all'.
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [hbac_shost_attrs_to_rule] (0x0400): Processing source hosts for rule [allow_all]
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [ipa_hbac_evaluate_rules] (0x0080): Access granted by HBAC rule [allow_all]
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [be_pam_handler_callback] (0x0100): Backend returned: (0, 0, <NULL>) [Success]
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [ipa_get_selinux_send] (0x0400): Retrieving SELinux user mapping
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(cn=ipaConfig)(objectClass=ipaGuiConfig))][cn=etc,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [ipa_selinux_get_maps_next] (0x0400): Trying to fetch SELinux maps with following parameters: [2][(&(objectclass=ipaselinuxusermap)(ipaEnabledFlag=TRUE))][cn=selinux,dc=ipa,dc=mydomain,dc=local]
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectclass=ipaselinuxusermap)(ipaEnabledFlag=TRUE))][cn=selinux,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [ipa_selinux_get_maps_done] (0x0400): No SELinux user maps found!
(Thu Jun 25 11:41:19 2015) [sssd[be[ipa.mydomain.local]]] [write_pipe_handler] (0x0400): All data has been sent!
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [be_get_account_info] (0x0200): Got request for [0x1003][1][name=polkitd]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [be_req_set_domain] (0x0400): Changing request domain from [ipa.mydomain.local] to [ipa.mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_initgr_next_base] (0x0400): Searching for users with base [cn=accounts,dc=ipa,dc=mydomain,dc=local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(uid=polkitd)(objectclass=posixAccount)(&(uidNumber=*)(!(uidNumber=0))))][cn=accounts,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_search_by_name] (0x0400): No such entry
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_delete_user] (0x0400): Error: 2 (No such file or directory)
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_search_by_name] (0x0400): No such entry
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [ipa_id_get_account_info_orig_done] (0x0080): Object not found, ending request
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [acctinfo_callback] (0x0100): Request processed. Returned 3,0,Account info lookup failed
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [child_sig_handler] (0x0100): child [6175] finished successfully.
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [read_pipe_handler] (0x0400): EOF received, client finished
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [be_pam_handler_callback] (0x0100): Backend returned: (0, 0, Success) [Success]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [be_pam_handler_callback] (0x0100): Sending result [0][mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [be_pam_handler_callback] (0x0100): Sent result [0][mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [be_get_account_info] (0x0200): Got request for [0x3][1][name=account1]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [be_req_set_domain] (0x0400): Changing request domain from [ipa.mydomain.local] to [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaUserOverride)(uid=account1))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_initgr_next_base] (0x0400): Searching for users with base [dc=mydomain,dc=local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(sAMAccountName=account1)(objectclass=user)(objectSID=*))][dc=mydomain,dc=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Save user
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object account1 at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Processing user account1 at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Adding original memberOf attributes to [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Adding user principal [account1 at MYDOMAIN.LOCAL] to attributes of [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Storing info for user account1 at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [no filter][CN=Giorgio Biacchi,OU=Gestori Aule,DC=mydomain,DC=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_ad_tokengroups_get_posix_members] (0x0080): Domain not found for SID S-1-5-32-545
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_ad_tokengroups_get_posix_members] (0x0080): Domain not found for SID S-1-5-32-548
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_search_entry_by_sid_str] (0x0400): No such entry
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_ad_tokengroups_get_posix_members] (0x0400): Missing SID S-1-5-21-1710311407-3537505305-1030735119-1107 will be downloaded
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_search_entry_by_sid_str] (0x0400): No such entry
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_ad_tokengroups_get_posix_members] (0x0400): Missing SID S-1-5-21-1710311407-3537505305-1030735119-1608 will be downloaded
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=mydomain,dc=local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-1710311407-3537505305-1030735119-1107)(objectClass=group)(name=*))][dc=mydomain,dc=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results.
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_hash_group] (0x0400): Filtering AD group.
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_recv] (0x0400): 0 users found in the hash table
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object SophosUser at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Processing group SophosUser at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Filtering AD group [SophosUser at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): The group has 1 members
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Group has 1 members
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Storing info for group SophosUser at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_set_entry_attr] (0x0080): ldb_modify failed: [Attribute or value exists]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_set_entry_attr] (0x0040): Error: 17 (File exists)
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_store_group] (0x0400): Error: 17 (File exists)
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_store_group_with_gid] (0x0040): Could not store group SophosUser at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0080): Could not store group with GID: [File exists]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0080): Failed to save group [SophosUser at mydomain.local]: [File exists]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_groups] (0x0040): Failed to store group 0. Ignoring.
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=mydomain,dc=local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-1710311407-3537505305-1030735119-1608)(objectClass=group)(name=*))][dc=mydomain,dc=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results.
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_hash_group] (0x0400): Filtering AD group.
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_recv] (0x0400): 0 users found in the hash table
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object SophosUserTmpRn
CNF:10eb1e79-892c-43ab-9735-0bf3cc30f264 at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Processing group SophosUserTmpRn
CNF:10eb1e79-892c-43ab-9735-0bf3cc30f264 at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Filtering AD group [SophosUserTmpRn
CNF:10eb1e79-892c-43ab-9735-0bf3cc30f264 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): The group has 1 members
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Group has 1 members
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Storing info for group SophosUserTmpRn
CNF:10eb1e79-892c-43ab-9735-0bf3cc30f264 at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_set_entry_attr] (0x0080): ldb_modify failed: [Attribute or value exists]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_set_entry_attr] (0x0040): Error: 17 (File exists)
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_store_group] (0x0400): Error: 17 (File exists)
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_store_group_with_gid] (0x0040): Could not store group SophosUserTmpRn
CNF:10eb1e79-892c-43ab-9735-0bf3cc30f264 at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0080): Could not store group with GID: [File exists]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0080): Failed to save group [SophosUserTmpRn
CNF:10eb1e79-892c-43ab-9735-0bf3cc30f264 at mydomain.local]: [File exists]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_groups] (0x0040): Failed to store group 0. Ignoring.
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_search_entry_by_sid_str] (0x0400): No such entry
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_search_entry_by_sid_str] (0x0400): No such entry
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=mydomain,dc=local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-1710311407-3537505305-1030735119-513)(objectClass=group)(name=*))][dc=mydomain,dc=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results.
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=user)][CN=Test Palo Alto,OU=Test,OU=Utenti Mydomain,DC=mydomain,DC=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=user)][CN=test4,OU=Test,OU=Utenti Mydomain,DC=mydomain,DC=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=user)][CN=test3,OU=Test,OU=Utenti Mydomain,DC=mydomain,DC=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=user)][CN=test2,OU=Test,OU=Utenti Mydomain,DC=mydomain,DC=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=user)][CN=test,OU=Test,OU=Utenti Mydomain,DC=mydomain,DC=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_recv] (0x0400): 5 users found in the hash table
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object test2 at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object test4 at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object test at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object test_pa at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object test3 at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object Domain Users at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Processing group Domain Users at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): The group has 5 members
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Group has 5 members
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Adding ghost member for group [test_pa at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Adding ghost member for group [test4 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Adding ghost member for group [test3 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Adding ghost member for group [test2 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Adding ghost member for group [test at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Storing info for group Domain Users at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object Domain Users at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_grpmem] (0x0400): Processing group Domain Users at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_grpmem] (0x0400): Adding member users to group [Domain Users at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-1710311407-3537505305-1030735119-3800))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [ipa_get_ad_memberships_send] (0x0400): External group information still valid.
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [find_ipa_ext_memberships] (0x0400): No external groupmemberships found.
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [acctinfo_callback] (0x0100): Request processed. Returned 0,0,Success
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [be_req_set_domain] (0x0400): Changing request domain from [ipa.mydomain.local] to [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [be_pam_handler] (0x0100): Got request with the following data
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): command: PAM_SETCRED
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): domain: mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): user: account1 at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): service: sshd
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): tty: ssh
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): ruser: 
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): rhost: xxx.xxx.xxx
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): authtok type: 0
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): newauthtok type: 0
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): priv: 1
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): cli_pid: 6169
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): logon name: not set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [be_pam_handler] (0x0100): Sending result [0][mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [be_get_account_info] (0x0200): Got request for [0x1001][1][idnumber=1539403800]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [be_req_set_domain] (0x0400): Changing request domain from [ipa.mydomain.local] to [ipa.mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_search_user_next_base] (0x0400): Searching for users with base [cn=accounts,dc=ipa,dc=mydomain,dc=local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(uidNumber=1539403800)(objectclass=posixAccount)(uid=*)(&(uidNumber=*)(!(uidNumber=0))))][cn=accounts,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_search_user_process] (0x0400): Search for users, returned 0 results.
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_users_done] (0x0040): Failed to retrieve users
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_search_user_by_uid] (0x0400): No such entry
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_delete_user] (0x0400): Error: 2 (No such file or directory)
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_search_user_by_uid] (0x0400): No such entry
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [ipa_id_get_account_info_orig_done] (0x0080): Object not found, ending request
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [acctinfo_callback] (0x0100): Request processed. Returned 3,0,Account info lookup failed
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [be_get_account_info] (0x0200): Got request for [0x3][1][name=account1]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [be_req_set_domain] (0x0400): Changing request domain from [ipa.mydomain.local] to [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaUserOverride)(uid=account1))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_initgr_next_base] (0x0400): Searching for users with base [dc=mydomain,dc=local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(sAMAccountName=account1)(objectclass=user)(objectSID=*))][dc=mydomain,dc=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Save user
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object account1 at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Processing user account1 at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Adding original memberOf attributes to [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Adding user principal [account1 at MYDOMAIN.LOCAL] to attributes of [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Storing info for user account1 at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [no filter][CN=Giorgio Biacchi,OU=Gestori Aule,DC=mydomain,DC=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_ad_tokengroups_get_posix_members] (0x0080): Domain not found for SID S-1-5-32-545
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_ad_tokengroups_get_posix_members] (0x0080): Domain not found for SID S-1-5-32-548
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_search_entry_by_sid_str] (0x0400): No such entry
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_ad_tokengroups_get_posix_members] (0x0400): Missing SID S-1-5-21-1710311407-3537505305-1030735119-1107 will be downloaded
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_search_entry_by_sid_str] (0x0400): No such entry
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_ad_tokengroups_get_posix_members] (0x0400): Missing SID S-1-5-21-1710311407-3537505305-1030735119-1608 will be downloaded
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=mydomain,dc=local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-1710311407-3537505305-1030735119-1107)(objectClass=group)(name=*))][dc=mydomain,dc=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results.
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_hash_group] (0x0400): Filtering AD group.
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_recv] (0x0400): 0 users found in the hash table
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object SophosUser at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Processing group SophosUser at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Filtering AD group [SophosUser at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): The group has 1 members
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Group has 1 members
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Storing info for group SophosUser at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_set_entry_attr] (0x0080): ldb_modify failed: [Attribute or value exists]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_set_entry_attr] (0x0040): Error: 17 (File exists)
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_store_group] (0x0400): Error: 17 (File exists)
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_store_group_with_gid] (0x0040): Could not store group SophosUser at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0080): Could not store group with GID: [File exists]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0080): Failed to save group [SophosUser at mydomain.local]: [File exists]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_groups] (0x0040): Failed to store group 0. Ignoring.
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=mydomain,dc=local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-1710311407-3537505305-1030735119-1608)(objectClass=group)(name=*))][dc=mydomain,dc=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results.
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_hash_group] (0x0400): Filtering AD group.
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_recv] (0x0400): 0 users found in the hash table
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object SophosUserTmpRn
CNF:10eb1e79-892c-43ab-9735-0bf3cc30f264 at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Processing group SophosUserTmpRn
CNF:10eb1e79-892c-43ab-9735-0bf3cc30f264 at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Filtering AD group [SophosUserTmpRn
CNF:10eb1e79-892c-43ab-9735-0bf3cc30f264 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): The group has 1 members
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Group has 1 members
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Storing info for group SophosUserTmpRn
CNF:10eb1e79-892c-43ab-9735-0bf3cc30f264 at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_set_entry_attr] (0x0080): ldb_modify failed: [Attribute or value exists]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_set_entry_attr] (0x0040): Error: 17 (File exists)
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_store_group] (0x0400): Error: 17 (File exists)
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_store_group_with_gid] (0x0040): Could not store group SophosUserTmpRn
CNF:10eb1e79-892c-43ab-9735-0bf3cc30f264 at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0080): Could not store group with GID: [File exists]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0080): Failed to save group [SophosUserTmpRn
CNF:10eb1e79-892c-43ab-9735-0bf3cc30f264 at mydomain.local]: [File exists]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_groups] (0x0040): Failed to store group 0. Ignoring.
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_search_entry_by_sid_str] (0x0400): No such entry
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_search_entry_by_sid_str] (0x0400): No such entry
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=mydomain,dc=local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-1710311407-3537505305-1030735119-513)(objectClass=group)(name=*))][dc=mydomain,dc=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results.
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=user)][CN=Test Palo Alto,OU=Test,OU=Utenti Mydomain,DC=mydomain,DC=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=user)][CN=test4,OU=Test,OU=Utenti Mydomain,DC=mydomain,DC=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=user)][CN=test3,OU=Test,OU=Utenti Mydomain,DC=mydomain,DC=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=user)][CN=test2,OU=Test,OU=Utenti Mydomain,DC=mydomain,DC=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=user)][CN=test,OU=Test,OU=Utenti Mydomain,DC=mydomain,DC=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_recv] (0x0400): 5 users found in the hash table
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object test2 at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object test4 at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object test at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object test_pa at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object test3 at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object Domain Users at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Processing group Domain Users at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): The group has 5 members
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Group has 5 members
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Adding ghost member for group [test_pa at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Adding ghost member for group [test4 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Adding ghost member for group [test3 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Adding ghost member for group [test2 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Adding ghost member for group [test at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Storing info for group Domain Users at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object Domain Users at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_grpmem] (0x0400): Processing group Domain Users at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_grpmem] (0x0400): Adding member users to group [Domain Users at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-1710311407-3537505305-1030735119-3800))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [ipa_get_ad_memberships_send] (0x0400): External group information still valid.
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [find_ipa_ext_memberships] (0x0400): No external groupmemberships found.
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [acctinfo_callback] (0x0100): Request processed. Returned 0,0,Success
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [be_req_set_domain] (0x0400): Changing request domain from [ipa.mydomain.local] to [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [be_pam_handler] (0x0100): Got request with the following data
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): command: PAM_OPEN_SESSION
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): domain: mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): user: account1 at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): service: sshd
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): tty: ssh
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): ruser: 
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): rhost: xxx.xxx.xxx
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): authtok type: 0
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): newauthtok type: 0
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): priv: 1
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): cli_pid: 6169
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): logon name: not set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [be_pam_handler] (0x0100): Sending result [0][mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [be_get_account_info] (0x0200): Got request for [0x3][1][name=account1]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [be_req_set_domain] (0x0400): Changing request domain from [ipa.mydomain.local] to [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaUserOverride)(uid=account1))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_initgr_next_base] (0x0400): Searching for users with base [dc=mydomain,dc=local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(sAMAccountName=account1)(objectclass=user)(objectSID=*))][dc=mydomain,dc=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Save user
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object account1 at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Processing user account1 at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Adding original memberOf attributes to [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Adding user principal [account1 at MYDOMAIN.LOCAL] to attributes of [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_user] (0x0400): Storing info for user account1 at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [no filter][CN=Giorgio Biacchi,OU=Gestori Aule,DC=mydomain,DC=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_ad_tokengroups_get_posix_members] (0x0080): Domain not found for SID S-1-5-32-545
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_ad_tokengroups_get_posix_members] (0x0080): Domain not found for SID S-1-5-32-548
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_search_entry_by_sid_str] (0x0400): No such entry
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_ad_tokengroups_get_posix_members] (0x0400): Missing SID S-1-5-21-1710311407-3537505305-1030735119-1107 will be downloaded
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_search_entry_by_sid_str] (0x0400): No such entry
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_ad_tokengroups_get_posix_members] (0x0400): Missing SID S-1-5-21-1710311407-3537505305-1030735119-1608 will be downloaded
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=mydomain,dc=local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-1710311407-3537505305-1030735119-1107)(objectClass=group)(name=*))][dc=mydomain,dc=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results.
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_hash_group] (0x0400): Filtering AD group.
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_recv] (0x0400): 0 users found in the hash table
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object SophosUser at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Processing group SophosUser at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Filtering AD group [SophosUser at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): The group has 1 members
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Group has 1 members
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Storing info for group SophosUser at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_set_entry_attr] (0x0080): ldb_modify failed: [Attribute or value exists]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_set_entry_attr] (0x0040): Error: 17 (File exists)
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_store_group] (0x0400): Error: 17 (File exists)
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_store_group_with_gid] (0x0040): Could not store group SophosUser at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0080): Could not store group with GID: [File exists]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0080): Failed to save group [SophosUser at mydomain.local]: [File exists]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_groups] (0x0040): Failed to store group 0. Ignoring.
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=mydomain,dc=local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-1710311407-3537505305-1030735119-1608)(objectClass=group)(name=*))][dc=mydomain,dc=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results.
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_hash_group] (0x0400): Filtering AD group.
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_recv] (0x0400): 0 users found in the hash table
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object SophosUserTmpRn
CNF:10eb1e79-892c-43ab-9735-0bf3cc30f264 at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Processing group SophosUserTmpRn
CNF:10eb1e79-892c-43ab-9735-0bf3cc30f264 at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Filtering AD group [SophosUserTmpRn
CNF:10eb1e79-892c-43ab-9735-0bf3cc30f264 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): The group has 1 members
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Group has 1 members
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Storing info for group SophosUserTmpRn
CNF:10eb1e79-892c-43ab-9735-0bf3cc30f264 at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_set_entry_attr] (0x0080): ldb_modify failed: [Attribute or value exists]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_set_entry_attr] (0x0040): Error: 17 (File exists)
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_store_group] (0x0400): Error: 17 (File exists)
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_store_group_with_gid] (0x0040): Could not store group SophosUserTmpRn
CNF:10eb1e79-892c-43ab-9735-0bf3cc30f264 at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0080): Could not store group with GID: [File exists]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0080): Failed to save group [SophosUserTmpRn
CNF:10eb1e79-892c-43ab-9735-0bf3cc30f264 at mydomain.local]: [File exists]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_groups] (0x0040): Failed to store group 0. Ignoring.
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_search_entry_by_sid_str] (0x0400): No such entry
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_search_entry_by_sid_str] (0x0400): No such entry
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [dc=mydomain,dc=local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-1710311407-3537505305-1030735119-513)(objectClass=group)(name=*))][dc=mydomain,dc=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results.
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=user)][CN=Test Palo Alto,OU=Test,OU=Utenti Mydomain,DC=mydomain,DC=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=user)][CN=test4,OU=Test,OU=Utenti Mydomain,DC=mydomain,DC=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=user)][CN=test3,OU=Test,OU=Utenti Mydomain,DC=mydomain,DC=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=user)][CN=test2,OU=Test,OU=Utenti Mydomain,DC=mydomain,DC=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=user)][CN=test,OU=Test,OU=Utenti Mydomain,DC=mydomain,DC=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_recv] (0x0400): 5 users found in the hash table
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object test2 at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object test4 at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object test at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object test_pa at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object test3 at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object Domain Users at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Processing group Domain Users at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): The group has 5 members
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Group has 5 members
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Adding ghost member for group [test_pa at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Adding ghost member for group [test4 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Adding ghost member for group [test3 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Adding ghost member for group [test2 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_process_ghost_members] (0x0400): Adding ghost member for group [test at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_group] (0x0400): Storing info for group Domain Users at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_primary_name] (0x0400): Processing object Domain Users at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_grpmem] (0x0400): Processing group Domain Users at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_save_grpmem] (0x0400): Adding member users to group [Domain Users at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectClass=ipaOverrideAnchor)(ipaAnchorUUID=:SID:S-1-5-21-1710311407-3537505305-1030735119-3800))][cn=Default Trust View,cn=views,cn=accounts,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [ipa_get_ad_memberships_send] (0x0400): External group information still valid.
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [find_ipa_ext_memberships] (0x0400): No external groupmemberships found.
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [acctinfo_callback] (0x0100): Request processed. Returned 0,0,Success
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [be_req_set_domain] (0x0400): Changing request domain from [ipa.mydomain.local] to [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [be_pam_handler] (0x0100): Got request with the following data
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): command: PAM_SETCRED
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): domain: mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): user: account1 at mydomain.local
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): service: sshd
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): tty: ssh
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): ruser: 
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): rhost: xxx.xxx.xxx
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): authtok type: 0
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): newauthtok type: 0
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): priv: 0
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): cli_pid: 6181
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [pam_print_data] (0x0100): logon name: not set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [be_pam_handler] (0x0100): Sending result [0][mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [be_get_account_info] (0x0200): Got request for [0x1002][1][idnumber=1539403800]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [be_req_set_domain] (0x0400): Changing request domain from [ipa.mydomain.local] to [ipa.mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [cn=accounts,dc=ipa,dc=mydomain,dc=local]
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(gidNumber=1539403800)(|(objectClass=ipaUserGroup)(objectClass=posixGroup))(cn=*)(&(gidNumber=*)(!(gidNumber=0))))][cn=accounts,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 0 results.
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_search_group_by_gid] (0x0400): No such entry
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_delete_group] (0x0400): Error: 2 (No such file or directory)
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_search_group_by_gid] (0x0400): No such entry
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [ipa_id_get_account_info_orig_done] (0x0080): Object not found, ending request
(Thu Jun 25 11:41:21 2015) [sssd[be[ipa.mydomain.local]]] [acctinfo_callback] (0x0100): Request processed. Returned 3,0,Account info lookup failed
-------------- next part --------------
(Thu Jun 25 11:38:18 2015) [sssd[nss]] [nss_cmd_getgrgid_search] (0x0080): No matching domain found for [1647000000]
(Thu Jun 25 11:38:18 2015) [sssd[nss]] [nss_cmd_getgrgid_search] (0x0100): Requesting info for [1647000000 at ipa.mydomain.local]
(Thu Jun 25 11:38:18 2015) [sssd[nss]] [nss_cmd_getgrgid_search] (0x0400): Returning info for gid [1647000000 at ipa.mydomain.local]
(Thu Jun 25 11:38:18 2015) [sssd[nss]] [nss_cmd_getgrgid_search] (0x0080): No matching domain found for [1647000000]
(Thu Jun 25 11:38:18 2015) [sssd[nss]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x7f36aadaee00:2:1647000000 at ipa.mydomain.local]
(Thu Jun 25 11:38:18 2015) [sssd[nss]] [client_recv] (0x0200): Client disconnected!
(Thu Jun 25 11:40:46 2015) [sssd[nss]] [nss_clear_memcache] (0x0400): Clearing memory caches.
(Thu Jun 25 11:40:46 2015) [sssd[nss]] [nss_orphan_netgroups] (0x0400): Removing netgroups from memory cache.
(Thu Jun 25 11:41:10 2015) [sssd[nss]] [nss_clear_memcache] (0x0400): Clearing memory caches.
(Thu Jun 25 11:41:10 2015) [sssd[nss]] [nss_orphan_netgroups] (0x0400): Removing netgroups from memory cache.
(Thu Jun 25 11:41:13 2015) [sssd[nss]] [accept_fd_handler] (0x0400): Client connected!
(Thu Jun 25 11:41:13 2015) [sssd[nss]] [sss_cmd_get_version] (0x0200): Received client version [1].
(Thu Jun 25 11:41:13 2015) [sssd[nss]] [sss_cmd_get_version] (0x0200): Offered version [1].
(Thu Jun 25 11:41:13 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:13 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:13 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:13 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:13 2015) [sssd[nss]] [sss_dp_issue_request] (0x0400): Issuing request for [0x7f36aadaee00:1:account1 at mydomain.local]
(Thu Jun 25 11:41:13 2015) [sssd[nss]] [sss_dp_get_account_msg] (0x0400): Creating request for [mydomain.local][4097][1][name=account1]
(Thu Jun 25 11:41:13 2015) [sssd[nss]] [sss_dp_internal_get_send] (0x0400): Entering request [0x7f36aadaee00:1:account1 at mydomain.local]
(Thu Jun 25 11:41:14 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:14 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:14 2015) [sssd[nss]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x7f36aadaee00:1:account1 at mydomain.local]
(Thu Jun 25 11:41:14 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [38] with input [nobody].
(Thu Jun 25 11:41:14 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'nobody' matched without domain, user is nobody
(Thu Jun 25 11:41:14 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [nobody] from [<ALL>]
(Thu Jun 25 11:41:14 2015) [sssd[nss]] [nss_cmd_initgroups_search] (0x0100): Requesting info for [nobody at ipa.mydomain.local]
(Thu Jun 25 11:41:14 2015) [sssd[nss]] [sss_dp_issue_request] (0x0400): Issuing request for [0x7f36aadaee00:3:nobody at ipa.mydomain.local]
(Thu Jun 25 11:41:14 2015) [sssd[nss]] [sss_dp_get_account_msg] (0x0400): Creating request for [ipa.mydomain.local][4099][1][name=nobody]
(Thu Jun 25 11:41:14 2015) [sssd[nss]] [sss_dp_internal_get_send] (0x0400): Entering request [0x7f36aadaee00:3:nobody at ipa.mydomain.local]
(Thu Jun 25 11:41:14 2015) [sssd[nss]] [nss_cmd_getby_dp_callback] (0x0040): Unable to get information from Data Provider
Error: 3, 0, Account info lookup failed
Will try to return what we have in cache
(Thu Jun 25 11:41:14 2015) [sssd[nss]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x7f36aadaee00:3:nobody at ipa.mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:18 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:18 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:18 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:18 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:18 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:18 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:18 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:18 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:18 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:18 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:18 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:18 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:18 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:18 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:18 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:18 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [accept_fd_handler] (0x0400): Client connected!
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_cmd_get_version] (0x0200): Received client version [1].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_cmd_get_version] (0x0200): Offered version [1].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [38] with input [root].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'root' matched without domain, user is root
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [root] from [<ALL>]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_initgroups_search] (0x0400): User [root] does not exist in [ipa.mydomain.local]! (negative cache)
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_initgroups_search] (0x0080): No matching domain found for [root], fail!
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [38] with input [polkitd].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'polkitd' matched without domain, user is polkitd
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [polkitd] from [<ALL>]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_initgroups_search] (0x0100): Requesting info for [polkitd at ipa.mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_dp_issue_request] (0x0400): Issuing request for [0x7f36aadaee00:3:polkitd at ipa.mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_dp_get_account_msg] (0x0400): Creating request for [ipa.mydomain.local][4099][1][name=polkitd]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_dp_internal_get_send] (0x0400): Entering request [0x7f36aadaee00:3:polkitd at ipa.mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getby_dp_callback] (0x0040): Unable to get information from Data Provider
Error: 3, 0, Account info lookup failed
Will try to return what we have in cache
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x7f36aadaee00:3:polkitd at ipa.mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [accept_fd_handler] (0x0400): Client connected!
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_cmd_get_version] (0x0200): Received client version [1].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_cmd_get_version] (0x0200): Offered version [1].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbyid] (0x0400): Running command [18] with id [1539403800].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwuid_search] (0x0100): Requesting info for [1539403800 at ipa.mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_dp_issue_request] (0x0400): Issuing request for [0x7f36aadaee00:1:1539403800 at ipa.mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_dp_get_account_msg] (0x0400): Creating request for [ipa.mydomain.local][4097][1][idnumber=1539403800]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_dp_internal_get_send] (0x0400): Entering request [0x7f36aadaee00:1:1539403800 at ipa.mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwuid_search] (0x0080): No matching domain found for [1539403800]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getby_dp_callback] (0x0040): Unable to get information from Data Provider
Error: 3, 0, Account info lookup failed
Will try to return what we have in cache
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwuid_search] (0x0100): Requesting info for [1539403800 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwuid_search] (0x0400): Returning info for uid [1539403800 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwuid_search] (0x0080): No matching domain found for [1539403800]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x7f36aadaee00:1:1539403800 at ipa.mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [accept_fd_handler] (0x0400): Client connected!
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_cmd_get_version] (0x0200): Received client version [1].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_cmd_get_version] (0x0200): Offered version [1].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [38] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_initgroups_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_initgroups_search] (0x0400): Initgroups for [account1 at mydomain.local] completed
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account1 at mydomain.local].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_parse_name_for_domains] (0x0200): name 'account1 at mydomain.local' matched expression for domain 'mydomain.local', user is account1
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0100): Requesting info for [account1] from [mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): Returning info for user [account1 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [accept_fd_handler] (0x0400): Client connected!
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_cmd_get_version] (0x0200): Received client version [1].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_cmd_get_version] (0x0200): Offered version [1].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getbyid] (0x0400): Running command [34] with id [1539403800].
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getgrgid_search] (0x0100): Requesting info for [1539403800 at ipa.mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_dp_issue_request] (0x0400): Issuing request for [0x7f36aadaee00:2:1539403800 at ipa.mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_dp_get_account_msg] (0x0400): Creating request for [ipa.mydomain.local][4098][1][idnumber=1539403800]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_dp_internal_get_send] (0x0400): Entering request [0x7f36aadaee00:2:1539403800 at ipa.mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getgrgid_search] (0x0080): No matching domain found for [1539403800]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getby_dp_callback] (0x0040): Unable to get information from Data Provider
Error: 3, 0, Account info lookup failed
Will try to return what we have in cache
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getgrgid_search] (0x0100): Requesting info for [1539403800 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [check_cache] (0x0400): Cached entry is valid, returning..
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getgrgid_search] (0x0400): Returning info for gid [1539403800 at mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [nss_cmd_getgrgid_search] (0x0080): No matching domain found for [1539403800]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x7f36aadaee00:2:1539403800 at ipa.mydomain.local]
(Thu Jun 25 11:41:21 2015) [sssd[nss]] [client_recv] (0x0200): Client disconnected!
-------------- next part --------------
(Thu Jun 25 11:41:39 2015) [sssd[be[ipa.mydomain.local]]] [sdap_search_user_next_base] (0x0400): Searching for users with base [cn=accounts,dc=ipa,dc=mydomain,dc=local]
(Thu Jun 25 11:41:39 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(krbPrincipalName=account2 at otherdomain.com)(objectclass=posixAccount)(uid=*)(&(uidNumber=*)(!(uidNumber=0))))][cn=accounts,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:39 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:39 2015) [sssd[be[ipa.mydomain.local]]] [sdap_search_user_process] (0x0400): Search for users, returned 0 results.
(Thu Jun 25 11:41:39 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_users_done] (0x0040): Failed to retrieve users
(Thu Jun 25 11:41:39 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_search_by_name] (0x0400): No such entry
(Thu Jun 25 11:41:39 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_delete_user] (0x0400): Error: 2 (No such file or directory)
(Thu Jun 25 11:41:39 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_search_by_name] (0x0400): No such entry
(Thu Jun 25 11:41:39 2015) [sssd[be[ipa.mydomain.local]]] [ipa_id_get_account_info_orig_done] (0x0080): Object not found, ending request
(Thu Jun 25 11:41:39 2015) [sssd[be[ipa.mydomain.local]]] [acctinfo_callback] (0x0100): Request processed. Returned 3,0,Account info lookup failed
(Thu Jun 25 11:41:50 2015) [sssd[be[ipa.mydomain.local]]] [be_get_subdomains] (0x0400): Got get subdomains [otherdomain.com]
(Thu Jun 25 11:41:50 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [objectclass=ipaIDRange][cn=ranges,cn=etc,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:50 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:50 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [objectclass=ipaNTTrustedDomain][cn=trusts,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:50 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:50 2015) [sssd[be[ipa.mydomain.local]]] [ipa_subdom_get_forest] (0x0400): 4th component is not 'trust', nothing to do.
(Thu Jun 25 11:41:50 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [objectclass=ipaNTDomainAttrs][cn=ad,cn=etc,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:50 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:50 2015) [sssd[be[ipa.mydomain.local]]] [get_subdomains_callback] (0x0400): Backend returned: (0, 0, <NULL>) [Success]
(Thu Jun 25 11:41:55 2015) [sssd[be[ipa.mydomain.local]]] [be_get_subdomains] (0x0400): Got get subdomains [otherdomain.com]
(Thu Jun 25 11:41:55 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [objectclass=ipaIDRange][cn=ranges,cn=etc,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:55 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:55 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [objectclass=ipaNTTrustedDomain][cn=trusts,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:55 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:55 2015) [sssd[be[ipa.mydomain.local]]] [ipa_subdom_get_forest] (0x0400): 4th component is not 'trust', nothing to do.
(Thu Jun 25 11:41:55 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [objectclass=ipaNTDomainAttrs][cn=ad,cn=etc,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:55 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:55 2015) [sssd[be[ipa.mydomain.local]]] [get_subdomains_callback] (0x0400): Backend returned: (0, 0, <NULL>) [Success]
(Thu Jun 25 11:41:55 2015) [sssd[be[ipa.mydomain.local]]] [be_get_account_info] (0x0200): Got request for [0x1001][1][name=account2 at otherdomain.com:U]
(Thu Jun 25 11:41:55 2015) [sssd[be[ipa.mydomain.local]]] [be_req_set_domain] (0x0400): Changing request domain from [ipa.mydomain.local] to [ipa.mydomain.local]
(Thu Jun 25 11:41:55 2015) [sssd[be[ipa.mydomain.local]]] [sdap_search_user_next_base] (0x0400): Searching for users with base [cn=accounts,dc=ipa,dc=mydomain,dc=local]
(Thu Jun 25 11:41:55 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(krbPrincipalName=account2 at otherdomain.com)(objectclass=posixAccount)(uid=*)(&(uidNumber=*)(!(uidNumber=0))))][cn=accounts,dc=ipa,dc=mydomain,dc=local].
(Thu Jun 25 11:41:55 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set
(Thu Jun 25 11:41:55 2015) [sssd[be[ipa.mydomain.local]]] [sdap_search_user_process] (0x0400): Search for users, returned 0 results.
(Thu Jun 25 11:41:55 2015) [sssd[be[ipa.mydomain.local]]] [sdap_get_users_done] (0x0040): Failed to retrieve users
(Thu Jun 25 11:41:55 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_search_by_name] (0x0400): No such entry
(Thu Jun 25 11:41:55 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_delete_user] (0x0400): Error: 2 (No such file or directory)
(Thu Jun 25 11:41:55 2015) [sssd[be[ipa.mydomain.local]]] [sysdb_search_by_name] (0x0400): No such entry
(Thu Jun 25 11:41:55 2015) [sssd[be[ipa.mydomain.local]]] [ipa_id_get_account_info_orig_done] (0x0080): Object not found, ending request
(Thu Jun 25 11:41:55 2015) [sssd[be[ipa.mydomain.local]]] [acctinfo_callback] (0x0100): Request processed. Returned 3,0,Account info lookup failed
(Thu Jun 25 11:41:55 2015) [sssd[be[ipa.mydomain.local]]] [be_get_subdomains] (0x0400): Got get subdomains [otherdomain.com]
(Thu Jun 25 11:41:55 2015) [sssd[be[ipa.mydomain.local]]] [get_subdomains_callback] (0x0400): Backend returned: (0, 0, <NULL>) [Success]
(Thu Jun 25 11:41:55 2015) [sssd[be[ipa.mydomain.local]]] [be_get_subdomains] (0x0400): Got get subdomains [otherdomain.com]
(Thu Jun 25 11:41:55 2015) [sssd[be[ipa.mydomain.local]]] [get_subdomains_callback] (0x0400): Backend returned: (0, 0, <NULL>) [Success]
-------------- next part --------------
(Thu Jun 25 11:41:39 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account2 at otherdomain.com@ipa.mydomain.local]
(Thu Jun 25 11:41:39 2015) [sssd[nss]] [sysdb_search_user_by_upn] (0x0400): No entry with upn [account2 at otherdomain.com] found.
(Thu Jun 25 11:41:39 2015) [sssd[nss]] [sss_ncache_set_str] (0x0400): Adding [NCE/USER/ipa.mydomain.local/account2 at otherdomain.com] to negative cache
(Thu Jun 25 11:41:39 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0040): No results for getpwnam call
(Thu Jun 25 11:41:39 2015) [sssd[nss]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x7f36aadaee00:1:account2 at otherdomain.com:U at ipa.mydomain.local]
(Thu Jun 25 11:41:41 2015) [sssd[nss]] [client_recv] (0x0200): Client disconnected!
(Thu Jun 25 11:41:44 2015) [sssd[nss]] [nss_clear_memcache] (0x0400): Clearing memory caches.
(Thu Jun 25 11:41:44 2015) [sssd[nss]] [nss_orphan_netgroups] (0x0400): Removing netgroups from memory cache.
(Thu Jun 25 11:41:45 2015) [sssd[nss]] [nss_clear_memcache] (0x0400): Clearing memory caches.
(Thu Jun 25 11:41:45 2015) [sssd[nss]] [nss_orphan_netgroups] (0x0400): Removing netgroups from memory cache.
(Thu Jun 25 11:41:50 2015) [sssd[nss]] [accept_fd_handler] (0x0400): Client connected!
(Thu Jun 25 11:41:50 2015) [sssd[nss]] [sss_cmd_get_version] (0x0200): Received client version [1].
(Thu Jun 25 11:41:50 2015) [sssd[nss]] [sss_cmd_get_version] (0x0200): Offered version [1].
(Thu Jun 25 11:41:50 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account2 at otherdomain.com].
(Thu Jun 25 11:41:50 2015) [sssd[nss]] [sss_dp_issue_request] (0x0400): Issuing request for [0x7f36aadb0670:domains at ipa.mydomain.local]
(Thu Jun 25 11:41:50 2015) [sssd[nss]] [sss_dp_get_domains_msg] (0x0400): Sending get domains request for [ipa.mydomain.local][otherdomain.com]
(Thu Jun 25 11:41:50 2015) [sssd[nss]] [sss_dp_internal_get_send] (0x0400): Entering request [0x7f36aadb0670:domains at ipa.mydomain.local]
(Thu Jun 25 11:41:50 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): User [account2 at otherdomain.com] does not exist in [ipa.mydomain.local]! (negative cache)
(Thu Jun 25 11:41:50 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0080): No matching domain found for [account2 at otherdomain.com], fail!
(Thu Jun 25 11:41:50 2015) [sssd[nss]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x7f36aadb0670:domains at ipa.mydomain.local]
(Thu Jun 25 11:41:55 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account2 at otherdomain.com].
(Thu Jun 25 11:41:55 2015) [sssd[nss]] [sss_dp_issue_request] (0x0400): Issuing request for [0x7f36aadb0670:domains at ipa.mydomain.local]
(Thu Jun 25 11:41:55 2015) [sssd[nss]] [sss_dp_get_domains_msg] (0x0400): Sending get domains request for [ipa.mydomain.local][otherdomain.com]
(Thu Jun 25 11:41:55 2015) [sssd[nss]] [sss_dp_internal_get_send] (0x0400): Entering request [0x7f36aadb0670:domains at ipa.mydomain.local]
(Thu Jun 25 11:41:55 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account2 at otherdomain.com@ipa.mydomain.local]
(Thu Jun 25 11:41:55 2015) [sssd[nss]] [sysdb_search_user_by_upn] (0x0400): No entry with upn [account2 at otherdomain.com] found.
(Thu Jun 25 11:41:55 2015) [sssd[nss]] [sss_dp_issue_request] (0x0400): Issuing request for [0x7f36aadaee00:1:account2 at otherdomain.com:U at ipa.mydomain.local]
(Thu Jun 25 11:41:55 2015) [sssd[nss]] [sss_dp_get_account_msg] (0x0400): Creating request for [ipa.mydomain.local][4097][1][name=account2 at otherdomain.com:U]
(Thu Jun 25 11:41:55 2015) [sssd[nss]] [sss_dp_internal_get_send] (0x0400): Entering request [0x7f36aadaee00:1:account2 at otherdomain.com:U at ipa.mydomain.local]
(Thu Jun 25 11:41:55 2015) [sssd[nss]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x7f36aadb0670:domains at ipa.mydomain.local]
(Thu Jun 25 11:41:55 2015) [sssd[nss]] [nss_cmd_getby_dp_callback] (0x0040): Unable to get information from Data Provider
Error: 3, 0, Account info lookup failed
Will try to return what we have in cache
(Thu Jun 25 11:41:55 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0100): Requesting info for [account2 at otherdomain.com@ipa.mydomain.local]
(Thu Jun 25 11:41:55 2015) [sssd[nss]] [sysdb_search_user_by_upn] (0x0400): No entry with upn [account2 at otherdomain.com] found.
(Thu Jun 25 11:41:55 2015) [sssd[nss]] [sss_ncache_set_str] (0x0400): Adding [NCE/USER/ipa.mydomain.local/account2 at otherdomain.com] to negative cache
(Thu Jun 25 11:41:55 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0040): No results for getpwnam call
(Thu Jun 25 11:41:55 2015) [sssd[nss]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x7f36aadaee00:1:account2 at otherdomain.com:U at ipa.mydomain.local]
(Thu Jun 25 11:41:55 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account2 at otherdomain.com].
(Thu Jun 25 11:41:55 2015) [sssd[nss]] [sss_dp_issue_request] (0x0400): Issuing request for [0x7f36aadb0670:domains at ipa.mydomain.local]
(Thu Jun 25 11:41:55 2015) [sssd[nss]] [sss_dp_get_domains_msg] (0x0400): Sending get domains request for [ipa.mydomain.local][otherdomain.com]
(Thu Jun 25 11:41:55 2015) [sssd[nss]] [sss_dp_internal_get_send] (0x0400): Entering request [0x7f36aadb0670:domains at ipa.mydomain.local]
(Thu Jun 25 11:41:55 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): User [account2 at otherdomain.com] does not exist in [ipa.mydomain.local]! (negative cache)
(Thu Jun 25 11:41:55 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0080): No matching domain found for [account2 at otherdomain.com], fail!
(Thu Jun 25 11:41:55 2015) [sssd[nss]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x7f36aadb0670:domains at ipa.mydomain.local]
(Thu Jun 25 11:41:55 2015) [sssd[nss]] [nss_cmd_getbynam] (0x0400): Running command [17] with input [account2 at otherdomain.com].
(Thu Jun 25 11:41:55 2015) [sssd[nss]] [sss_dp_issue_request] (0x0400): Issuing request for [0x7f36aadb0670:domains at ipa.mydomain.local]
(Thu Jun 25 11:41:55 2015) [sssd[nss]] [sss_dp_get_domains_msg] (0x0400): Sending get domains request for [ipa.mydomain.local][otherdomain.com]
(Thu Jun 25 11:41:55 2015) [sssd[nss]] [sss_dp_internal_get_send] (0x0400): Entering request [0x7f36aadb0670:domains at ipa.mydomain.local]
(Thu Jun 25 11:41:55 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0400): User [account2 at otherdomain.com] does not exist in [ipa.mydomain.local]! (negative cache)
(Thu Jun 25 11:41:55 2015) [sssd[nss]] [nss_cmd_getpwnam_search] (0x0080): No matching domain found for [account2 at otherdomain.com], fail!
(Thu Jun 25 11:41:55 2015) [sssd[nss]] [sss_dp_req_destructor] (0x0400): Deleting request: [0x7f36aadb0670:domains at ipa.mydomain.local]
(Thu Jun 25 11:42:01 2015) [sssd[nss]] [client_recv] (0x0200): Client disconnected!