> -----Original Message-----
>
> There was an issue with build queue, so it took litle bit longer.
>
> The dependency problem should be fixed now.
Installed, restarted services sssd/realmd, problem replicated but log SEEMS the
same.
Log attached.
Thanks
--
(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 [[email protected]] 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, <NULL>) [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, <NULL>) [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]
_______________________________________________
sssd-users mailing list
[email protected]
https://lists.fedorahosted.org/mailman/listinfo/sssd-users
