(Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [be_get_account_info] (0x0200): Got request for [0x3][1][name=testuser] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [be_req_set_domain] (0x0400): Changing request domain from [mydomain.COM] to [mydomain.COM] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [fo_resolve_service_send] (0x0100): Trying to resolve service 'AD_GC' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [get_port_status] (0x1000): Port status of port 0 for server '(no name)' is 'neutral' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [resolve_srv_send] (0x0200): The status of SRV lookup is neutral (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_srv_plugin_send] (0x0400): About to find domain controllers (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_get_dc_servers_send] (0x0400): Looking up domain controllers in domain mydomain.COM (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [resolv_discover_srv_next_domain] (0x0400): SRV resolution of service 'ldap'. Will use DNS discovery domain 'mydomain.COM' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [resolv_getsrv_send] (0x0100): Trying to resolve SRV record of '_ldap._tcp.mydomain.COM' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [resolv_getsrv_done] (0x1000): Using TTL [600] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [request_watch_destructor] (0x0400): Deleting request watch (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [fo_discover_srv_done] (0x0400): Got answer. Processing... (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [fo_discover_srv_done] (0x0400): Got 12 servers (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_get_dc_servers_done] (0x0400): Found 12 domain controllers in domain mydomain.COM (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_srv_plugin_dcs_done] (0x0400): About to locate suitable site (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_connect_host_send] (0x0400): Resolving host condom02rm.mydomain.com (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [resolv_gethostbyname_files_send] (0x0100): Trying to resolve A record of 'condom02rm.mydomain.com' in files (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [resolv_gethostbyname_files_send] (0x0100): Trying to resolve AAAA record of 'condom02rm.mydomain.com' in files (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [resolv_gethostbyname_next] (0x0200): No more address families to retry (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [resolv_gethostbyname_dns_query] (0x0100): Trying to resolve A record of 'condom02rm.mydomain.com' in DNS (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [resolv_gethostbyname_dns_parse] (0x1000): Parsing an A reply (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [request_watch_destructor] (0x0400): Deleting request watch (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_connect_host_resolv_done] (0x0400): Connecting to ldap://condom02rm.mydomain.com:389 (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sss_ldap_init_send] (0x0400): Setting 6 seconds timeout for connecting (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ldap_connect_callback_add] (0x1000): New LDAP connection to [ldap://condom02rm.mydomain.com:389/??base] with fd [24]. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_connect_host_done] (0x0400): Successful connection to ldap://condom02rm.mydomain.com:389 (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(DnsDomain=mydomain.COM)(NtVer=\14\00\00\00))][]. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [netlogon] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_parse_entry] (0x1000): OriginalDN: []. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_get_client_site_done] (0x0400): Found site: mysite (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_srv_plugin_site_done] (0x0400): About to discover primary and backup servers (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [fo_discover_servers_send] (0x0400): Looking up primary servers (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [resolv_discover_srv_next_domain] (0x0400): SRV resolution of service 'gc'. Will use DNS discovery domain 'mysite._sites.mydomain.COM' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [resolv_getsrv_send] (0x0100): Trying to resolve SRV record of '_gc._tcp.mysite._sites.mydomain.COM' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [resolv_getsrv_done] (0x1000): Using TTL [600] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [request_watch_destructor] (0x0400): Deleting request watch (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [fo_discover_srv_done] (0x0400): Got answer. Processing... (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [fo_discover_srv_done] (0x0400): Got 2 servers (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [fo_discover_servers_primary_done] (0x0400): Looking up backup servers (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [resolv_discover_srv_next_domain] (0x0400): SRV resolution of service 'gc'. Will use DNS discovery domain 'mydomain.COM' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [resolv_getsrv_send] (0x0100): Trying to resolve SRV record of '_gc._tcp.mydomain.COM' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [resolv_getsrv_done] (0x1000): Using TTL [600] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [request_watch_destructor] (0x0400): Deleting request watch (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [fo_discover_srv_done] (0x0400): Got answer. Processing... (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [fo_discover_srv_done] (0x0400): Got 12 servers (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_srv_plugin_servers_done] (0x0400): Got 2 primary and 12 backup servers (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [fo_add_server_to_list] (0x0400): Inserted primary server 'condom01cs.mydomain.com:3268' to service 'AD_GC' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [fo_add_server_to_list] (0x0400): Inserted primary server 'condom03cs.mydomain.com:3268' to service 'AD_GC' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [fo_add_server_to_list] (0x0400): Inserted backup server 'condom01mi.mydomain.com:3268' to service 'AD_GC' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [fo_add_server_to_list] (0x0400): Inserted backup server 'condom01rm.mydomain.com:3268' to service 'AD_GC' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [fo_add_server_to_list] (0x0400): Inserted backup server 'condom02mi.mydomain.com:3268' to service 'AD_GC' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [fo_add_server_to_list] (0x0400): Inserted backup server 'condom02rm.mydomain.com:3268' to service 'AD_GC' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [fo_add_server_to_list] (0x0400): Inserted backup server 'condom02cs.mydomain.com:3268' to service 'AD_GC' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [fo_add_server_to_list] (0x0400): Inserted backup server 'condom02pd.mydomain.com:3268' to service 'AD_GC' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [fo_add_server_to_list] (0x0400): Server 'condom03cs.mydomain.com:3268' for service 'AD_GC' is already present (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [fo_add_server_to_list] (0x0400): Inserted backup server 'condom02to.mydomain.com:3268' to service 'AD_GC' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [fo_add_server_to_list] (0x0400): Inserted backup server 'condom03to.mydomain.com:3268' to service 'AD_GC' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [fo_add_server_to_list] (0x0400): Inserted backup server 'condomcsbck.mydomain.com:3268' to service 'AD_GC' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [fo_add_server_to_list] (0x0400): Server 'condom01cs.mydomain.com:3268' for service 'AD_GC' is already present (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [fo_add_server_to_list] (0x0400): Inserted backup server 'condom01pd.mydomain.com:3268' to service 'AD_GC' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [set_srv_data_status] (0x0100): Marking SRV lookup of service 'AD_GC' as 'resolved' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [get_server_status] (0x1000): Status of server 'condom01cs.mydomain.com' is 'name not resolved' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [resolv_gethostbyname_files_send] (0x0100): Trying to resolve A record of 'condom01cs.mydomain.com' in files (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [set_server_common_status] (0x0100): Marking server 'condom01cs.mydomain.com' as 'resolving name' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [resolv_gethostbyname_files_send] (0x0100): Trying to resolve AAAA record of 'condom01cs.mydomain.com' in files (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [resolv_gethostbyname_next] (0x0200): No more address families to retry (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [resolv_gethostbyname_dns_query] (0x0100): Trying to resolve A record of 'condom01cs.mydomain.com' in DNS (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [resolv_gethostbyname_dns_parse] (0x1000): Parsing an A reply (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [request_watch_destructor] (0x0400): Deleting request watch (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [set_server_common_status] (0x0100): Marking server 'condom01cs.mydomain.com' as 'name resolved' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [be_resolve_server_process] (0x1000): Saving the first resolved server (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [be_resolve_server_process] (0x0200): Found address for server condom01cs.mydomain.com: [10.52.1.37] TTL 600 (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_resolve_callback] (0x0100): Constructed uri 'ldap://condom01cs.mydomain.com' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_resolve_callback] (0x0100): Constructed GC uri 'ldap://condom01cs.mydomain.com:3268' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sss_ldap_init_send] (0x0400): Setting 6 seconds timeout for connecting (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ldap_connect_callback_add] (0x1000): New LDAP connection to [ldap://condom01cs.mydomain.com:3268/??base] with fd [24]. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(objectclass=*)][]. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [*] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [altServer] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [namingContexts] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedControl] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedExtension] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedFeatures] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedLDAPVersion] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [supportedSASLMechanisms] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [domainControllerFunctionality] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [defaultNamingContext] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [lastUSN] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [highestCommittedUSN] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_parse_entry] (0x1000): OriginalDN: []. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_server_opts_from_rootdse] (0x0100): Setting AD compatibility level to [4] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_kinit_send] (0x0400): Attempting kinit (default, GITCS01$, mydomain.COM, 86400) (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_kinit_next_kdc] (0x1000): Resolving next KDC for service AD (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [fo_resolve_service_send] (0x0100): Trying to resolve service 'AD' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [get_server_status] (0x1000): Status of server 'condom03cs.mydomain.com' is 'working' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [get_port_status] (0x1000): Port status of port 389 for server 'condom03cs.mydomain.com' is 'working' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [resolve_srv_send] (0x0200): The status of SRV lookup is resolved (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [get_server_status] (0x1000): Status of server 'condom03cs.mydomain.com' is 'working' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [be_resolve_server_process] (0x1000): Saving the first resolved server (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [be_resolve_server_process] (0x0200): Found address for server condom03cs.mydomain.com: [10.52.1.38] TTL 600 (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_kinit_kdc_resolved] (0x1000): KDC resolved, attempting to get TGT... (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [create_tgt_req_send_buffer] (0x0400): buffer size: 47 (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [set_tgt_child_timeout] (0x0400): Setting 6 seconds timeout for tgt child (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [write_pipe_handler] (0x0400): All data has been sent! (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [child_sig_handler] (0x1000): Waiting for child [13451]. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [child_sig_handler] (0x0100): child [13451] finished successfully. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [read_pipe_handler] (0x0400): EOF received, client finished (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_tgt_recv] (0x0400): Child responded: 0 [FILE:/var/lib/sss/db/ccache_mydomain.COM], expired on [1426806511] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_cli_auth_step] (0x0100): expire timeout is 900 (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_cli_auth_step] (0x1000): the connection will expire at 1426771411 (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sasl_bind_send] (0x0100): Executing sasl bind mech: gssapi, user: GITCS01$ (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [fo_set_port_status] (0x0100): Marking port 3268 of server 'condom01cs.mydomain.com' as 'working' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [set_server_common_status] (0x0100): Marking server 'condom01cs.mydomain.com' as 'working' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [fo_set_port_status] (0x0400): Marking port 3268 of duplicate server 'condom01cs.mydomain.com' as 'working' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing GC with GC (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_initgr_next_base] (0x0400): Searching for users with base [DC=mydomain,DC=COM] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(sAMAccountName=testuser)(objectclass=user)(objectSID=*))][DC=mydomain,DC=COM]. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sAMAccountName] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [unixUserPassword] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uidNumber] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gecos] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [unixHomeDirectory] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginShell] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userPrincipalName] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [name] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [memberOf] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectGUID] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectSID] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [primaryGroupID] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [whenChanged] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uSNChanged] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [accountExpires] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [userAccountControl] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_parse_entry] (0x1000): OriginalDN: [CN=Rossi Mario,OU=Utenti,OU=mysite,OU=SEDI2,DC=mydomain,DC=COM]. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_save_user] (0x0400): Save user (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_primary_name] (0x0400): Processing object testuser (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_save_user] (0x0400): Processing user testuser (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_save_user] (0x1000): Mapping user [testuser] objectSID [S-1-5-21-2248061571-2151176789-1472819363-5694] to unix ID (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_save_user] (0x0400): Adding original memberOf attributes to [testuser]. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_save_user] (0x0400): Adding user principal [testuser@mydomain.COM] to attributes of [testuser]. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_save_user] (0x0400): Storing info for user testuser (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [no filter][CN=Rossi Mario,OU=Utenti,OU=mysite,OU=SEDI2,DC=mydomain,DC=COM]. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [tokenGroups] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_parse_entry] (0x1000): OriginalDN: [CN=Rossi Mario,OU=Utenti,OU=mysite,OU=SEDI2,DC=mydomain,DC=COM]. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2072734008-185610687-561332275-11234] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x0080): Domain not found for SID S-1-5-21-2072734008-185610687-561332275-11234 (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2072734008-185610687-561332275-6180] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x0080): Domain not found for SID S-1-5-21-2072734008-185610687-561332275-6180 (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2072734008-185610687-561332275-11539] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x0080): Domain not found for SID S-1-5-21-2072734008-185610687-561332275-11539 (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-32-545] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_idmap_sid_to_unix] (0x0400): Object SID [S-1-5-32-545] is a built-in one. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x0400): Skipping built-in object. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-11539] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-11539] maps to GID [684011539] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-6711] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-6711] maps to GID [684006711] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-1899] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-1899] maps to GID [684001899] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-11458] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-11458] maps to GID [684011458] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-15193] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-15193] maps to GID [684015193] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-9133] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-9133] maps to GID [684009133] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-17975] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-17975] maps to GID [684017975] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-6734] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-6734] maps to GID [684006734] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-15338] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-15338] maps to GID [684015338] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-6789] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-6789] maps to GID [684006789] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-6712] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-6712] maps to GID [684006712] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-11215] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-11215] maps to GID [684011215] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-5629] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-5629] maps to GID [684005629] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-5862] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-5862] maps to GID [684005862] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-1519] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-1519] maps to GID [684001519] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-1927] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-1927] maps to GID [684001927] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-1510] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-1510] maps to GID [684001510] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-5877] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-5877] maps to GID [684005877] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-2887] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-2887] maps to GID [684002887] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-2985] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-2985] maps to GID [684002985] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-5867] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-5867] maps to GID [684005867] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-18052] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-18052] maps to GID [684018052] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-13916] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-13916] maps to GID [684013916] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-26603] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-26603] maps to GID [684026603] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-513] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-513] maps to GID [684000513] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-6710] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-6710] maps to GID [684006710] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-1898] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-1898] maps to GID [684001898] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-3919] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-3919] maps to GID [684003919] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-13860] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-13860] maps to GID [684013860] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-7017] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-7017] maps to GID [684007017] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-28159] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-28159] maps to GID [684028159] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-13745] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-13745] maps to GID [684013745] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-5637] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-5637] maps to GID [684005637] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-7520] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-7520] maps to GID [684007520] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-28172] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-28172] maps to GID [684028172] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-19329] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-19329] maps to GID [684019329] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-6656] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-6656] maps to GID [684006656] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-13742] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-13742] maps to GID [684013742] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-28039] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-28039] maps to GID [684028039] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-28166] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-28166] maps to GID [684028166] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-5329] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-5329] maps to GID [684005329] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-8042] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-8042] maps to GID [684008042] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-28167] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-28167] maps to GID [684028167] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): Processing membership SID [S-1-5-21-2248061571-2151176789-1472819363-6709] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_initgr_mapping_done] (0x1000): SID [S-1-5-21-2248061571-2151176789-1472819363-6709] maps to GID [684006709] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_ad_tokengroups_update_members] (0x1000): Updating memberships for [testuser] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sysdb_mod_group_member] (0x0080): ldb_modify failed: [No such attribute](16)[attribute 'member': no matching attribute value while deleting attribute on 'name=DGOP-Direzione Generale Operativa,cn=groups,cn=mydomain.COM,cn=sysdb'] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sysdb_error_to_errno] (0x0020): LDB returned unexpected error: [No such attribute] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sysdb_mod_group_member] (0x0400): Error: 14 (Bad address) (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sysdb_update_members_ex] (0x0020): Could not remove member [testuser] from group [name=DGOP-Direzione Generale Operativa,cn=groups,cn=mydomain.COM,cn=sysdb]. Skipping (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sysdb_mod_group_member] (0x0080): ldb_modify failed: [No such attribute](16)[attribute 'member': no matching attribute value while deleting attribute on 'name=Organigramma,cn=groups,cn=mydomain.COM,cn=sysdb'] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sysdb_error_to_errno] (0x0020): LDB returned unexpected error: [No such attribute] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sysdb_mod_group_member] (0x0400): Error: 14 (Bad address) (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sysdb_update_members_ex] (0x0020): Could not remove member [testuser] from group [name=Organigramma,cn=groups,cn=mydomain.COM,cn=sysdb]. Skipping (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sysdb_mod_group_member] (0x0080): ldb_modify failed: [No such attribute](16)[attribute 'member': no matching attribute value while deleting attribute on 'name=IT-Area IT,cn=groups,cn=mydomain.COM,cn=sysdb'] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sysdb_error_to_errno] (0x0020): LDB returned unexpected error: [No such attribute] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sysdb_mod_group_member] (0x0400): Error: 14 (Bad address) (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sysdb_update_members_ex] (0x0020): Could not remove member [testuser] from group [name=IT-Area IT,cn=groups,cn=mydomain.COM,cn=sysdb]. Skipping (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_initgr_done] (0x1000): Mapping primary group to unix ID (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [DC=mydomain,DC=COM] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-2248061571-2151176789-1472819363-513)(objectClass=group)(name=*))][DC=mydomain,DC=COM]. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [name] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [member] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectGUID] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectSID] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [whenChanged] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uSNChanged] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [groupType] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_parse_entry] (0x1000): OriginalDN: [CN=Domain Users,OU=Gruppi,DC=mydomain,DC=COM]. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_nested_group_recv] (0x0400): 0 users found in the hash table (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_primary_name] (0x0400): Processing object Domain Users (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_save_group] (0x0400): Processing group Domain Users (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_save_group] (0x1000): Mapping group [Domain Users] objectSID [S-1-5-21-2248061571-2151176789-1472819363-513] to unix ID (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_process_ghost_members] (0x0400): The group has 0 members (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_process_ghost_members] (0x0400): Group has 0 members (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_save_group] (0x0400): Storing info for group Domain Users (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_primary_name] (0x0400): Processing object Domain Users (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_save_grpmem] (0x0400): Processing group Domain Users (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_save_grpmem] (0x0400): Adding member users to group [Domain Users] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [acctinfo_callback] (0x0100): Request processed. Returned 0,0,Success (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [be_req_set_domain] (0x0400): Changing request domain from [mydomain.COM] to [mydomain.COM] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [be_pam_handler] (0x0100): Got request with the following data (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [pam_print_data] (0x0100): command: PAM_AUTHENTICATE (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [pam_print_data] (0x0100): domain: mydomain.COM (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [pam_print_data] (0x0100): user: testuser (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [pam_print_data] (0x0100): service: sshd (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [pam_print_data] (0x0100): tty: ssh (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [pam_print_data] (0x0100): ruser: (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [pam_print_data] (0x0100): rhost: Rossi-d.mydomain.com (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [pam_print_data] (0x0100): authtok type: 1 (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [pam_print_data] (0x0100): newauthtok type: 0 (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [pam_print_data] (0x0100): priv: 1 (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [pam_print_data] (0x0100): cli_pid: 13449 (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [pam_print_data] (0x0100): logon name: not set (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [krb5_pam_handler] (0x1000): Wait queue of user [testuser] is empty, running request immediately. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [krb5_auth_send] (0x0100): Home directory for user [testuser] not known. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [fo_resolve_service_send] (0x0100): Trying to resolve service 'AD' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [get_server_status] (0x1000): Status of server 'condom03cs.mydomain.com' is 'working' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [get_port_status] (0x1000): Port status of port 389 for server 'condom03cs.mydomain.com' is 'working' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [resolve_srv_send] (0x0200): The status of SRV lookup is resolved (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [get_server_status] (0x1000): Status of server 'condom03cs.mydomain.com' is 'working' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [be_resolve_server_process] (0x1000): Saving the first resolved server (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [be_resolve_server_process] (0x0200): Found address for server condom03cs.mydomain.com: [10.52.1.38] TTL 600 (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [write_pipe_handler] (0x0400): All data has been sent! (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [child_sig_handler] (0x1000): Waiting for child [13452]. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [child_sig_handler] (0x0100): child [13452] finished successfully. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [read_pipe_handler] (0x0400): EOF received, client finished (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [parse_krb5_child_response] (0x1000): child response [0][3][40]. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [parse_krb5_child_response] (0x1000): child response [0][-1073741822][24]. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [parse_krb5_child_response] (0x1000): child response [0][-1073741823][32]. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [parse_krb5_child_response] (0x1000): TGT times are [1426770511][1426770511][1426806511][1427375311]. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [parse_krb5_child_response] (0x1000): child response [0][6][8]. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [fo_set_port_status] (0x0100): Marking port 389 of server 'condom03cs.mydomain.com' as 'working' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [set_server_common_status] (0x0100): Marking server 'condom03cs.mydomain.com' as 'working' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing LDAP with LDAP (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [fo_set_port_status] (0x0400): Marking port 389 of duplicate server 'condom03cs.mydomain.com' as 'working' (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing LDAP with LDAP (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing LDAP with LDAP (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing LDAP with LDAP (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing LDAP with LDAP (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing LDAP with LDAP (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing LDAP with LDAP (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing LDAP with LDAP (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing LDAP with LDAP (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing LDAP with LDAP (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing LDAP with LDAP (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [ad_user_data_cmp] (0x1000): Comparing LDAP with LDAP (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [check_wait_queue] (0x1000): Wait queue for user [testuser] is empty. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [be_pam_handler_callback] (0x0100): Backend returned: (0, 0, ) [Success] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [be_pam_handler_callback] (0x0100): Sending result [0][mydomain.COM] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [be_pam_handler_callback] (0x0100): Sent result [0][mydomain.COM] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [be_req_set_domain] (0x0400): Changing request domain from [mydomain.COM] to [mydomain.COM] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [be_pam_handler] (0x0100): Got request with the following data (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [pam_print_data] (0x0100): command: PAM_ACCT_MGMT (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [pam_print_data] (0x0100): domain: mydomain.COM (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [pam_print_data] (0x0100): user: testuser (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [pam_print_data] (0x0100): service: sshd (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [pam_print_data] (0x0100): tty: ssh (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [pam_print_data] (0x0100): ruser: (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [pam_print_data] (0x0100): rhost: Rossi-d.mydomain.com (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [pam_print_data] (0x0100): authtok type: 0 (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [pam_print_data] (0x0100): newauthtok type: 0 (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [pam_print_data] (0x0100): priv: 1 (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [pam_print_data] (0x0100): cli_pid: 13449 (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [pam_print_data] (0x0100): logon name: not set (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [simple_access_obtain_filter_lists] (0x0200): Allow users list is empty. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [simple_access_obtain_filter_lists] (0x0200): Deny users list is empty. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [simple_access_obtain_filter_lists] (0x0200): Deny groups list is empty. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [simple_access_check_send] (0x0200): Simple access check for testuser (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [simple_check_get_groups_send] (0x1000): Looking up groups for user testuser (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [simple_check_get_groups_send] (0x0400): User testuser is a member of 47 supplemental groups (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [simple_check_get_groups_send] (0x0400): Need to resolve 3 groups (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [simple_resolve_group_check] (0x1000): The group is still non-POSIX (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [be_req_set_domain] (0x0400): Changing request domain from [mydomain.COM] to [mydomain.COM] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_groups_next_base] (0x0400): Searching for groups with base [DC=mydomain,DC=COM] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [(&(objectSID=S-1-5-21-2248061571-2151176789-1472819363-28039)(objectClass=group)(name=*))][DC=mydomain,DC=COM]. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [name] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [member] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectGUID] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectSID] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [whenChanged] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uSNChanged] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_ext_step] (0x1000): Requesting attrs: [groupType] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_parse_entry] (0x1000): OriginalDN: [CN=Organigramma,OU=Qualita,OU=Gruppi,DC=mydomain,DC=COM]. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_generic_op_finished] (0x0400): Search result: Success(0), no errmsg set (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_groups_process] (0x0400): Search for groups, returned 1 results. (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_has_deref_support] (0x0400): The server supports deref method ASQ (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_nested_group_recv] (0x0400): 0 users found in the hash table (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_nested_group_recv] (0x0400): 1 groups found in the hash table (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_primary_name] (0x0400): Processing object Organigramma (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_save_group] (0x0400): Processing group Organigramma (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_save_group] (0x1000): Mapping group [Organigramma] objectSID [S-1-5-21-2248061571-2151176789-1472819363-28039] to unix ID (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_process_ghost_members] (0x0400): The group has 11 members (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_process_ghost_members] (0x0400): Group has 11 members (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_save_group] (0x0400): Storing info for group Organigramma (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_get_primary_name] (0x0400): Processing object Organigramma (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_save_grpmem] (0x0400): Processing group Organigramma (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_save_grpmem] (0x0400): Adding member users to group [Organigramma] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_fill_memberships] (0x1000): member #0 (CN=AFLI-Affari Legali ed Istituzionali,OU=Qualita,OU=Gruppi,DC=mydomain,DC=COM): [name=AFLI-Affari Legali ed Istituzionali,cn=groups,cn=mydomain.COM,cn=sysdb] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_fill_memberships] (0x1000): member #1 (CN=Q-AMM-DELEGATO,OU=Qualita,OU=Gruppi,DC=mydomain,DC=COM): [name=Q-AMM-DELEGATO,cn=groups,cn=mydomain.COM,cn=sysdb] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_fill_memberships] (0x1000): member #2 (CN=DGOP-Direzione Generale Operativa,OU=Qualita,OU=Gruppi,DC=mydomain,DC=COM): [name=DGOP-Direzione Generale Operativa,cn=groups,cn=mydomain.COM,cn=sysdb] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_fill_memberships] (0x1000): member #3 (CN=Area Corporate Development e Investor Relations-Area Corporate D,OU=Qualita,OU=Gruppi,DC=mydomain,DC=COM): [name=Area Corporate Development e Investor Relations-Area Corporate D,cn=groups,cn=mydomain.COM,cn=sysdb] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_fill_memberships] (0x1000): member #4 (CN=CCMG-Area Cerved Credit Management Group,OU=Qualita,OU=Gruppi,DC=mydomain,DC=COM): [name=CCMG-Area Cerved Credit Management Group,cn=groups,cn=mydomain.COM,cn=sysdb] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_fill_memberships] (0x1000): member #5 (CN=INAU-Internal Audit,OU=Qualita,OU=Gruppi,DC=mydomain,DC=COM): [name=INAU-Internal Audit,cn=groups,cn=mydomain.COM,cn=sysdb] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_fill_memberships] (0x1000): member #6 (CN=CORP-Direzione Generale Commerciale,OU=Qualita,OU=Gruppi,DC=mydomain,DC=COM): [name=CORP-Direzione Generale Commerciale,cn=groups,cn=mydomain.COM,cn=sysdb] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_fill_memberships] (0x1000): member #7 (CN=HRCD-Area HR e Organizzazione,OU=Qualita,OU=Gruppi,DC=mydomain,DC=COM): [name=HRCD-Area HR e Organizzazione,cn=groups,cn=mydomain.COM,cn=sysdb] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_fill_memberships] (0x1000): member #8 (CN=BANC-Area Istituzioni Finanziarie,OU=Qualita,OU=Gruppi,DC=mydomain,DC=COM): [name=BANC-Area Istituzioni Finanziarie,cn=groups,cn=mydomain.COM,cn=sysdb] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_fill_memberships] (0x1000): member #9 (CN=AMFC-Area Amministrazione Finanza e Controllo,OU=Qualita,OU=Gruppi,DC=mydomain,DC=COM): [name=AMFC-Area Amministrazione Finanza e Controllo,cn=groups,cn=mydomain.COM,cn=sysdb] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [sdap_fill_memberships] (0x1000): member #10 (CN=Q-DIRETTORI,OU=Qualita,OU=Gruppi,DC=mydomain,DC=COM): [name=Q-DIRETTORI,cn=groups,cn=mydomain.COM,cn=sysdb] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [simple_resolve_group_check] (0x1000): The group is still non-POSIX (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [simple_resolve_group_done] (0x0040): Refresh failed (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [simple_check_get_groups_next] (0x0040): Could not resolve name of group with GID 684028039 (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [simple_access_check_done] (0x0040): Could not collect groups of user testuser (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [be_pam_handler_callback] (0x0100): Backend returned: (0, 4, ) [Success] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [be_pam_handler_callback] (0x0100): Sending result [4][mydomain.COM] (Thu Mar 19 14:08:31 2015) [sssd[be[mydomain.COM]]] [be_pam_handler_callback] (0x0100): Sent result [4][mydomain.COM]