[SSSD-users] Announcing SSSD 1.11.6

steve steve at steve-ss.com
Wed Jun 4 12:06:29 UTC 2014


On Wed, 2014-06-04 at 13:43 +0200, Sumit Bose wrote:
> On Wed, Jun 04, 2014 at 01:32:44PM +0200, steve wrote:
> > On Tue, 2014-06-03 at 19:50 +0200, Jakub Hrozek wrote:
> > >                        === SSSD 1.11.6 ===
> > 
> > Hi
> > Builds OK on openSUSE 13.1. Just a bit worried by:
> > 
> > (Wed Jun  4 13:25:47 2014) [sssd[be[hh3.site]]]
> > [sdap_ad_tokengroups_initgr_posix_tg_done] (0x0080): Domain not found
> > for SID S-1-5-32-545
> 
> an unresolved SID does not trigger an error. Can you send the full logs?

(Wed Jun  4 14:01:18 2014) [sssd[nss]] [accept_fd_handler] (0x0400):
Client connected!
(Wed Jun  4 14:01:18 2014) [sssd[nss]] [sss_cmd_get_version] (0x0200):
Received client version [1].
(Wed Jun  4 14:01:18 2014) [sssd[nss]] [sss_cmd_get_version] (0x0200):
Offered version [1].
(Wed Jun  4 14:01:18 2014) [sssd[nss]] [nss_cmd_getbynam] (0x0400):
Running command [17] with input [steve2].
(Wed Jun  4 14:01:18 2014) [sssd[nss]] [sss_parse_name_for_domains]
(0x0200): name 'steve2' matched without domain, user is steve2
(Wed Jun  4 14:01:18 2014) [sssd[nss]] [sss_parse_name_for_domains]
(0x0200): using default domain [(null)]
(Wed Jun  4 14:01:18 2014) [sssd[nss]] [nss_cmd_getbynam] (0x0100):
Requesting info for [steve2] from [<ALL>]
(Wed Jun  4 14:01:18 2014) [sssd[nss]] [nss_cmd_getpwnam_search]
(0x0100): Requesting info for [steve2 at hh3.site]
(Wed Jun  4 14:01:18 2014) [sssd[nss]] [check_cache] (0x0400): Cached
entry is valid, returning..
(Wed Jun  4 14:01:18 2014) [sssd[nss]] [nss_cmd_getpwnam_search]
(0x0400): Returning info for user [steve2 at hh3.site]
(Wed Jun  4 14:01:18 2014) [sssd[nss]] [nss_cmd_getbynam] (0x0400):
Running command [17] with input [steve2].
(Wed Jun  4 14:01:18 2014) [sssd[nss]] [sss_parse_name_for_domains]
(0x0200): name 'steve2' matched without domain, user is steve2
(Wed Jun  4 14:01:18 2014) [sssd[nss]] [sss_parse_name_for_domains]
(0x0200): using default domain [(null)]
(Wed Jun  4 14:01:18 2014) [sssd[nss]] [nss_cmd_getbynam] (0x0100):
Requesting info for [steve2] from [<ALL>]
(Wed Jun  4 14:01:18 2014) [sssd[nss]] [nss_cmd_getpwnam_search]
(0x0100): Requesting info for [steve2 at hh3.site]
(Wed Jun  4 14:01:18 2014) [sssd[nss]] [check_cache] (0x0400): Cached
entry is valid, returning..
(Wed Jun  4 14:01:18 2014) [sssd[nss]] [nss_cmd_getpwnam_search]
(0x0400): Returning info for user [steve2 at hh3.site]
(Wed Jun  4 14:01:18 2014) [sssd[be[hh3.site]]] [child_sig_handler]
(0x1000): Waiting for child [1670].
(Wed Jun  4 14:01:18 2014) [sssd[be[hh3.site]]] [child_sig_handler]
(0x0100): child [1670] finished successfully.
(Wed Jun  4 14:01:18 2014) [sssd[be[hh3.site]]] [be_nsupdate_done]
(0x0200): nsupdate child status: 0
(Wed Jun  4 14:01:18 2014) [sssd[be[hh3.site]]]
[ad_dyndns_nsupdate_done] (0x0040): DNS update finished
(Wed Jun  4 14:01:20 2014) [sssd[nss]] [nss_cmd_getbynam] (0x0400):
Running command [17] with input [steve2].
(Wed Jun  4 14:01:20 2014) [sssd[nss]] [sss_parse_name_for_domains]
(0x0200): name 'steve2' matched without domain, user is steve2
(Wed Jun  4 14:01:20 2014) [sssd[nss]] [sss_parse_name_for_domains]
(0x0200): using default domain [(null)]
(Wed Jun  4 14:01:20 2014) [sssd[nss]] [nss_cmd_getbynam] (0x0100):
Requesting info for [steve2] from [<ALL>]
(Wed Jun  4 14:01:20 2014) [sssd[nss]] [nss_cmd_getpwnam_search]
(0x0100): Requesting info for [steve2 at hh3.site]
(Wed Jun  4 14:01:20 2014) [sssd[nss]] [check_cache] (0x0400): Cached
entry is valid, returning..
(Wed Jun  4 14:01:20 2014) [sssd[nss]] [nss_cmd_getpwnam_search]
(0x0400): Returning info for user [steve2 at hh3.site]
(Wed Jun  4 14:01:20 2014) [sssd[pam]] [accept_fd_handler] (0x0400):
Client connected!
(Wed Jun  4 14:01:20 2014) [sssd[pam]] [sss_cmd_get_version] (0x0200):
Received client version [3].
(Wed Jun  4 14:01:20 2014) [sssd[pam]] [sss_cmd_get_version] (0x0200):
Offered version [3].
(Wed Jun  4 14:01:20 2014) [sssd[pam]] [pam_cmd_authenticate] (0x0100):
entering pam_cmd_authenticate
(Wed Jun  4 14:01:20 2014) [sssd[pam]] [sss_parse_name_for_domains]
(0x0200): name 'steve2' matched without domain, user is steve2
(Wed Jun  4 14:01:20 2014) [sssd[pam]] [sss_parse_name_for_domains]
(0x0200): using default domain [(null)]
(Wed Jun  4 14:01:20 2014) [sssd[pam]] [pam_print_data] (0x0100):
command: PAM_AUTHENTICATE
(Wed Jun  4 14:01:20 2014) [sssd[pam]] [pam_print_data] (0x0100):
domain: not set
(Wed Jun  4 14:01:20 2014) [sssd[pam]] [pam_print_data] (0x0100): user:
steve2
(Wed Jun  4 14:01:20 2014) [sssd[pam]] [pam_print_data] (0x0100):
service: su
(Wed Jun  4 14:01:20 2014) [sssd[pam]] [pam_print_data] (0x0100): tty:
pts/1
(Wed Jun  4 14:01:20 2014) [sssd[pam]] [pam_print_data] (0x0100): ruser:
steve
(Wed Jun  4 14:01:20 2014) [sssd[pam]] [pam_print_data] (0x0100): rhost:
not set
(Wed Jun  4 14:01:20 2014) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 1
(Wed Jun  4 14:01:20 2014) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Wed Jun  4 14:01:20 2014) [sssd[pam]] [pam_print_data] (0x0100): priv:
0
(Wed Jun  4 14:01:20 2014) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 1674
(Wed Jun  4 14:01:20 2014) [sssd[pam]] [sss_dp_issue_request] (0x0400):
Issuing request for [0x80532f0:3:steve2 at hh3.site]
(Wed Jun  4 14:01:20 2014) [sssd[pam]] [sss_dp_get_account_msg]
(0x0400): Creating request for [hh3.site][3][1][name=steve2]
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]] [be_get_account_info]
(0x0100): Got request for [3][1][name=steve2]
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]] [be_req_set_domain]
(0x0400): Changing request domain from [hh3.site] to [hh3.site]
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]]
[fo_resolve_service_send] (0x0100): (Wed Jun  4 14:01:20 2014)
[sssd[pam]] [sss_dp_internal_get_send] (0x0400): Entering request
[0x80532f0:3:steve2 at hh3.site]
Trying to resolve service 'AD_GC'
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]] [get_server_status]
(0x1000): Status of server 'hh16.hh3.site' is 'working'
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]] [get_port_status]
(0x1000): Port status of port 0 for server 'hh16.hh3.site' is 'neutral'
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]] [get_server_status]
(0x1000): Status of server 'hh16.hh3.site' is 'working'
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]]
[be_resolve_server_process] (0x1000): Saving the first resolved server
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]]
[be_resolve_server_process] (0x0200): Found address for server
hh16.hh3.site: [192.168.1.16] TTL 7200
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]] [ad_resolve_callback]
(0x0100): Constructed uri 'ldap://hh16.hh3.site'
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]] [ad_resolve_callback]
(0x0100): Constructed GC uri 'ldap://hh16.hh3.site:3268'
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]] [sss_ldap_init_send]
(0x0400): Setting 6 seconds timeout for connecting
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]]
[sdap_ldap_connect_callback_add] (0x1000): New LDAP connection to
[ldap://hh16.hh3.site:3268/??base] with fd [18].
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with
[(objectclass=*)][].
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [*]
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [altServer]
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [namingContexts]
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs:
[supportedControl]
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs:
[supportedExtension]
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs:
[supportedFeatures]
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs:
[supportedLDAPVersion]
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs:
[supportedSASLMechanisms]
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs:
[domainControllerFunctionality]
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs:
[defaultNamingContext]
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [lastUSN]
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs:
[highestCommittedUSN]
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_done] (0x0400): Search result: Success(0), no
errmsg set
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]]
[sdap_get_server_opts_from_rootdse] (0x0100): Setting AD compatibility
level to [4]
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]] [sdap_kinit_send]
(0x0400): Attempting kinit (/etc/krb5.keytab, CATRAL$, HH3.SITE, 86400)
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]] [sdap_kinit_next_kdc]
(0x1000): Resolving next KDC for service AD
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]]
[fo_resolve_service_send] (0x0100): Trying to resolve service 'AD'
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]] [get_server_status]
(0x1000): Status of server 'hh16.hh3.site' is 'working'
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]] [get_port_status]
(0x1000): Port status of port 0 for server 'hh16.hh3.site' is 'working'
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]] [get_server_status]
(0x1000): Status of server 'hh16.hh3.site' is 'working'
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]]
[be_resolve_server_process] (0x1000): Saving the first resolved server
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]]
[be_resolve_server_process] (0x0200): Found address for server
hh16.hh3.site: [192.168.1.16] TTL 7200
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]] [ad_resolve_callback]
(0x0100): Constructed uri 'ldap://hh16.hh3.site'
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]] [ad_resolve_callback]
(0x0100): Constructed GC uri 'ldap://hh16.hh3.site'
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]]
[sdap_kinit_kdc_resolved] (0x1000): KDC resolved, attempting to get
TGT...
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]]
[create_tgt_req_send_buffer] (0x0400): buffer size: 47
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]] [set_tgt_child_timeout]
(0x0400): Setting 6 seconds timeout for tgt child
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]] [write_pipe_handler]
(0x0400): All data has been sent!
(Wed Jun  4 14:01:20 2014) [[sssd[ldap_child[1675]]]] [main] (0x0400):
ldap_child started.
(Wed Jun  4 14:01:20 2014) [[sssd[ldap_child[1675]]]] [unpack_buffer]
(0x1000): total buffer size: 47
(Wed Jun  4 14:01:20 2014) [[sssd[ldap_child[1675]]]] [unpack_buffer]
(0x1000): realm_str size: 8
(Wed Jun  4 14:01:20 2014) [[sssd[ldap_child[1675]]]] [unpack_buffer]
(0x1000): got realm_str: HH3.SITE
(Wed Jun  4 14:01:20 2014) [[sssd[ldap_child[1675]]]] [unpack_buffer]
(0x1000): princ_str size: 7
(Wed Jun  4 14:01:20 2014) [[sssd[ldap_child[1675]]]] [unpack_buffer]
(0x1000): got princ_str: CATRAL$
(Wed Jun  4 14:01:20 2014) [[sssd[ldap_child[1675]]]] [unpack_buffer]
(0x1000): keytab_name size: 16
(Wed Jun  4 14:01:20 2014) [[sssd[ldap_child[1675]]]] [unpack_buffer]
(0x1000): got keytab_name: /etc/krb5.keytab
(Wed Jun  4 14:01:20 2014) [[sssd[ldap_child[1675]]]] [unpack_buffer]
(0x1000): lifetime: 86400
(Wed Jun  4 14:01:20 2014) [[sssd[ldap_child[1675]]]]
[ldap_child_get_tgt_sync] (0x0100): Principal name is: [CATRAL
$@HH3.SITE]
(Wed Jun  4 14:01:20 2014) [[sssd[ldap_child[1675]]]]
[ldap_child_get_tgt_sync] (0x0100): Using keytab [/etc/krb5.keytab]
(Wed Jun  4 14:01:20 2014) [[sssd[ldap_child[1675]]]] [prepare_response]
(0x0400): Building response for result [0]
(Wed Jun  4 14:01:20 2014) [[sssd[ldap_child[1675]]]] [pack_buffer]
(0x1000): result [0] krberr [0] msgsize [46] msg
[FILE:/usr/local/var/lib/sss/db/ccache_HH3.SITE]
(Wed Jun  4 14:01:20 2014) [[sssd[ldap_child[1675]]]] [main] (0x0400):
ldap_child completed successfully
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]] [read_pipe_handler]
(0x0400): EOF received, client finished
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]] [sdap_get_tgt_recv]
(0x0400): Child responded: 0
[FILE:/usr/local/var/lib/sss/db/ccache_HH3.SITE], expired on
[1401919280]
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]] [sdap_cli_auth_step]
(0x0100): expire timeout is 900
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]] [sdap_cli_auth_step]
(0x1000): the connection will expire at 1401884180
(Wed Jun  4 14:01:20 2014) [sssd[be[hh3.site]]] [sasl_bind_send]
(0x0100): Executing sasl bind mech: gssapi, user: CATRAL$
(Wed Jun  4 14:01:21 2014) [sssd] [service_send_ping] (0x0100): Pinging
hh3.site
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [child_sig_handler]
(0x1000): Waiting for child [1675].
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [child_sig_handler]
(0x0100): child [1675] finished successfully.
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [fo_set_port_status]
(0x0100): Marking port 0 of server 'hh16.hh3.site' as 'working'
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[set_server_common_status] (0x0100): Marking server 'hh16.hh3.site' as
'working'
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [ad_user_data_cmp]
(0x1000): Comparing GC with GC
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [fo_set_port_status]
(0x0400): Marking port 0 of duplicate server 'hh16.hh3.site' as
'working'
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_initgr_next_base] (0x0400): Searching for users with base
[DC=hh3,DC=site]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with
[(&(sAMAccountName=steve2)(objectclass=user)(&(uidNumber=*)(!(uidNumber=0))))][DC=hh3,DC=site].
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [sAMAccountName]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs:
[unixUserPassword]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uidNumber]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gecos]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs:
[unixHomeDirectory]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [loginShell]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs:
[userPrincipalName]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [name]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [memberOf]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectGUID]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectSID]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [primaryGroupID]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [whenChanged]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uSNChanged]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [accountExpires]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs:
[userAccountControl]
(Wed Jun  4 14:01:21 2014) [sssd] [ping_check] (0x0100): Service
hh3.site replied to ping
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_done] (0x0400): Search result: Success(0), no
errmsg set
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [sdap_save_user]
(0x0400): Save user
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [sdap_get_primary_name]
(0x0400): Processing object steve2
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [sdap_save_user]
(0x0400): Processing user steve2
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [sdap_save_user]
(0x0400): Adding original memberOf attributes to [steve2].
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [sdap_save_user]
(0x0400): Adding user principal [steve2 at HH3.SITE] to attributes of
[steve2].
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [sdap_save_user]
(0x0400): Storing info for user steve2
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with [no
filter][CN=steve2,CN=Users,DC=hh3,DC=site].
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [tokenGroups]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_done] (0x0400): Search result: Success(0), no
errmsg set
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_ad_tokengroups_initgr_posix_tg_done] (0x1000): Processing
membership SID [S-1-5-21-451355595-2219208293-2714859210-1111]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_ad_tokengroups_initgr_posix_tg_done] (0x1000): Processing
membership SID [S-1-5-21-451355595-2219208293-2714859210-513]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_ad_tokengroups_initgr_posix_tg_done] (0x1000): Processing
membership SID [S-1-5-32-545]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_ad_tokengroups_initgr_posix_tg_done] (0x0080): Domain not found
for SID S-1-5-32-545
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_ad_tokengroups_update_members] (0x1000): Updating memberships for
[steve2]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [sdap_posix_check_next]
(0x0400): Searching for POSIX attributes with base [DC=hh3,DC=site]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with
[(|(uidNumber=*)(gidNumber=*))][DC=hh3,DC=site].
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectclass]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uidNumber]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [sdap_posix_check_parse]
(0x1000): Found [CN=staff2,CN=Users,DC=hh3,DC=site] with POSIX
attributes
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [sdap_posix_check_parse]
(0x1000): Found [CN=julie2,CN=Users,DC=hh3,DC=site] with POSIX
attributes
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [sdap_posix_check_parse]
(0x1000): Found [CN=lynn2,CN=Users,DC=hh3,DC=site] with POSIX attributes
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [sdap_posix_check_parse]
(0x1000): Found [CN=cifsuser,CN=Users,DC=hh3,DC=site] with POSIX
attributes
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [sdap_posix_check_parse]
(0x1000): Found [CN=Administrator,CN=Users,DC=hh3,DC=site] with POSIX
attributes
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [sdap_posix_check_parse]
(0x1000): Found [CN=julie,CN=Users,DC=hh3,DC=site] with POSIX attributes
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [sdap_posix_check_parse]
(0x1000): Found [CN=Domain Users,CN=Users,DC=hh3,DC=site] with POSIX
attributes
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [sdap_posix_check_parse]
(0x1000): Found [CN=br2,CN=Users,DC=hh3,DC=site] with POSIX attributes
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [sdap_posix_check_parse]
(0x1000): Found [CN=steve2,CN=Users,DC=hh3,DC=site] with POSIX
attributes
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [sdap_posix_check_parse]
(0x1000): Found [CN=steve3,CN=Users,DC=hh3,DC=site] with POSIX
attributes
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [sdap_posix_check_parse]
(0x1000): Found [CN=steveu,CN=Users,DC=hh3,DC=site] with POSIX
attributes
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [sdap_posix_check_parse]
(0x1000): Found [CN=Administrators,CN=Builtin,DC=hh3,DC=site] with POSIX
attributes
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [sdap_posix_check_parse]
(0x1000): Found [CN=Domain Admins,CN=Users,DC=hh3,DC=site] with POSIX
attributes
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_done] (0x0400): Search result: Success(0), no
errmsg set
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [sdap_posix_check_done]
(0x0200): Server has POSIX attributes
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_groups_next_base] (0x0400): Searching for groups with base
[DC=hh3,DC=site]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x0400): calling ldap_search_ext with
[(&(gidNumber=20513)(objectclass=group)(name=*)(&(gidNumber=*)(!(gidNumber=0))))][DC=hh3,DC=site].
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectClass]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [name]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [gidNumber]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [member]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectGUID]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [objectSID]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [whenChanged]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [uSNChanged]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_step] (0x1000): Requesting attrs: [groupType]
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_generic_ext_done] (0x0400): Search result: Success(0), no
errmsg set
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_get_groups_process] (0x0400): Search for groups, returned 1
results.
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [sdap_has_deref_support]
(0x0400): The server supports deref method ASQ
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [sdap_nested_group_recv]
(0x0400): 0 users found in the hash table
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [sdap_nested_group_recv]
(0x0400): 1 groups found in the hash table
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [sdap_get_primary_name]
(0x0400): Processing object Domain Users
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [sdap_save_group]
(0x0400): Processing group Domain Users
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_process_ghost_members] (0x0400): The group has 0 members
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]]
[sdap_process_ghost_members] (0x0400): Group has 0 members
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [sdap_save_group]
(0x0400): Storing info for group Domain Users
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [sdap_get_primary_name]
(0x0400): Processing object Domain Users
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [sdap_save_grpmem]
(0x0400): Processing group Domain Users
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [sdap_save_grpmem]
(0x0400): Adding member users to group [Domain Users]
(Wed Jun  4 14:01:21 2014) [sssd[nss]] [nss_memcache_initgr_check]
(0x1000): Got request for [steve2 at hh3.site]
(Wed Jun  4 14:01:21 2014) [sssd[pam]] [sss_dp_get_reply] (0x1000): Got
reply from Data Provider - DP error code: 0 errno: 0 error message:
Success
(Wed Jun  4 14:01:21 2014) [sssd[pam]] [pam_check_user_search] (0x0100):
Requesting info for [steve2 at hh3.site]
(Wed Jun  4 14:01:21 2014) [sssd[pam]] [pam_check_user_search] (0x0400):
(Wed Jun  4 14:01:21 2014) [sssd[be[hh3.site]]] [acctinfo_callback]
(0x0100): Request processed. Returned 0,0,Success
Returning info for user [steve2 at hh3.site]
(Wed Jun  4 14:01:21 2014) [sssd[pam]] [pam_dp_send_req] (0x0100):
Sending request with the following data:
(Wed Jun  4 14:01:21 2014) [sssd[pam]] [pam_print_data] (0x0100):
command: PAM_AUTHENTICATE
(Wed Jun  4 14:01:21 2014) [sssd[pam]] [pam_print_data] (0x0100):
domain: hh3.site
(Wed Jun  4 14:01:21 2014) [sssd[pam]] [pam_print_data] (0x0100): user:
steve2
(Wed Jun  4 14:01:21 2014) [sssd[pam]] [pam_print_data] (0x0100):
service: su
(Wed Jun  4 14:01:21 2014) [sssd[pam]] [pam_print_data] (0x0100): tty:
pts/1
(Wed Jun  4 14:01:21 2014) [sssd[pam]] [pam_print_data] (0x0100): ruser:
steve
(Wed Jun  4 14:01:21 2014) [sssd[pam]] [pam_print_data] (0x0100): rhost:
not set
(Wed Jun  4 14:01:21 2014) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 1
(Wed Jun  4 14:01:21 2014) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Wed Jun  4 14:01:21 2014) [sssd[pam]] [pam_print_data] (0x0100): priv:
0
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 1674
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [be_req_set_domain]
(0x0400): Changing request domain from [hh3.site] to [hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [be_pam_handler]
(0x0100): Got request with the following data
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): command: PAM_AUTHENTICATE
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): domain: hh3.site
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): user: steve2
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): service: su
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): tty: pts/1
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): ruser: steve
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): rhost: 
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): authtok type: 1
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): newauthtok type: 0
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): priv: 0
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): cli_pid: 1674
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [krb5_pam_handler]
(0x1000): Wait queue of user [steve2] is empty, running request
immediately.
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [switch_creds] (0x0200):
Switch user to [3000021][20513].
(Wed Jun  4 14:01:22 2014) [sssd] [service_send_ping] (0x0100): Pinging
nss
(Wed Jun  4 14:01:22 2014) [sssd] [service_send_ping] (0x0100): Pinging
pam
(Wed Jun  4 14:01:22 2014) [sssd] [service_send_ping] (0x0100): Pinging
autofs
(Wed Jun  4 14:01:22 2014) [sssd] [ping_check] (0x0100): (Wed Jun  4
14:01:22 2014) [sssd[pam]] [pam_dom_forwarder] (0x0100): pam_dp_send_req
returned 0
Service autofs replied to ping
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [sss_dp_req_destructor] (0x0400):
(Wed Jun  4 14:01:22 2014) [sssd] [ping_check] (0x0100): Deleting
request: [0x80532f0:3:steve2 at hh3.site]
Service nss replied to ping
(Wed Jun  4 14:01:22 2014) [sssd] [ping_check] (0x0100): Service pam
replied to ping
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [switch_creds] (0x0200):
Switch user to [0][0].
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]]
[fo_resolve_service_send] (0x0100): Trying to resolve service 'AD'
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [get_server_status]
(0x1000): Status of server 'hh16.hh3.site' is 'working'
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [get_port_status]
(0x1000): Port status of port 0 for server 'hh16.hh3.site' is 'working'
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [get_server_status]
(0x1000): Status of server 'hh16.hh3.site' is 'working'
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]]
[be_resolve_server_process] (0x1000): Saving the first resolved server
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]]
[be_resolve_server_process] (0x0200): Found address for server
hh16.hh3.site: [192.168.1.16] TTL 7200
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [ad_resolve_callback]
(0x0100): Constructed uri 'ldap://hh16.hh3.site'
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [ad_resolve_callback]
(0x0100): Constructed GC uri 'ldap://hh16.hh3.site'
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [write_pipe_handler]
(0x0400): All data has been sent!
(Wed Jun  4 14:01:22 2014) [[sssd[krb5_child[1676]]]] [main] (0x0400):
krb5_child started.
(Wed Jun  4 14:01:22 2014) [[sssd[krb5_child[1676]]]] [unpack_buffer]
(0x1000): total buffer size: [106]
(Wed Jun  4 14:01:22 2014) [[sssd[krb5_child[1676]]]] [unpack_buffer]
(0x0100): cmd [241] uid [3000021] gid [20513] validate [true] enterprise
principal [true] offline [false] UPN [steve2 at HH3.SITE]
(Wed Jun  4 14:01:22 2014) [[sssd[krb5_child[1676]]]] [unpack_buffer]
(0x0100): ccname: [FILE:/tmp/krb5cc_3000021] keytab: [/etc/krb5.keytab]
(Wed Jun  4 14:01:22 2014) [[sssd[krb5_child[1676]]]]
[set_lifetime_options] (0x0100): Cannot read
[SSSD_KRB5_RENEWABLE_LIFETIME] from environment.
(Wed Jun  4 14:01:22 2014) [[sssd[krb5_child[1676]]]]
[set_lifetime_options] (0x0100): Cannot read [SSSD_KRB5_LIFETIME] from
environment.
(Wed Jun  4 14:01:22 2014) [[sssd[krb5_child[1676]]]]
[set_canonicalize_option] (0x0100): SSSD_KRB5_CANONICALIZE is set to
[true]
(Wed Jun  4 14:01:22 2014) [[sssd[krb5_child[1676]]]] [k5c_setup]
(0x0100): Not using FAST.
(Wed Jun  4 14:01:22 2014) [[sssd[krb5_child[1676]]]] [main] (0x0400):
Will perform online auth
(Wed Jun  4 14:01:22 2014) [[sssd[krb5_child[1676]]]] [tgt_req_child]
(0x1000): Attempting to get a TGT
(Wed Jun  4 14:01:22 2014) [[sssd[krb5_child[1676]]]] [get_and_save_tgt]
(0x0400): Attempting kinit for realm [HH3.SITE]
(Wed Jun  4 14:01:22 2014) [[sssd[krb5_child[1676]]]] [validate_tgt]
(0x0400): TGT verified using key for [host/catral.hh3.site at HH3.SITE].
(Wed Jun  4 14:01:22 2014) [[sssd[krb5_child[1676]]]] [sss_send_pac]
(0x0040): sss_pac_make_request failed [-1][2].
(Wed Jun  4 14:01:22 2014) [[sssd[krb5_child[1676]]]] [validate_tgt]
(0x0040): sss_send_pac failed, group membership for user with principal
[steve2\@HH3.SITE at HH3.SITE] might not be correct.
(Wed Jun  4 14:01:22 2014) [[sssd[krb5_child[1676]]]] [become_user]
(0x0200): Trying to become user [3000021][20513].
(Wed Jun  4 14:01:22 2014) [[sssd[krb5_child[1676]]]] [k5c_send_data]
(0x0200): Received error code 0
(Wed Jun  4 14:01:22 2014) [EEEEEEEEEEEEEEEEEEEEEEEE�] [main] (0x0400):
krb5_child completed successfully
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [read_pipe_handler]
(0x0400): EOF received, client finished
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]]
[parse_krb5_child_response] (0x1000): child response [0][3][36].
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]]
[parse_krb5_child_response] (0x1000): child response
[0][-1073741822][16].
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]]
[parse_krb5_child_response] (0x1000): child response
[0][-1073741823][32].
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]]
[parse_krb5_child_response] (0x1000): TGT times are
[1401883283][1401883283][1401919283][1401969682].
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [fo_set_port_status]
(0x0100): Marking port 0 of server 'hh16.hh3.site' as 'working'
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]]
[set_server_common_status] (0x0100): Marking server 'hh16.hh3.site' as
'working'
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [ad_user_data_cmp]
(0x1000): Comparing LDAP with LDAP
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [fo_set_port_status]
(0x0400): Marking port 0 of duplicate server 'hh16.hh3.site' as
'working'
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [switch_creds] (0x0200):
Switch user to [3000021][20513].
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [switch_creds] (0x0200):
Switch user to [0][0].
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]]
[safe_remove_old_ccache_file] (0x0400): New and old ccache file are the
same, none will be deleted.
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [check_wait_queue]
(0x1000): Wait queue for user [steve2] is empty.
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]]
[be_pam_handler_callback] (0x0100): Backend returned: (0, 0, <NULL>)
[Success]
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]]
[be_pam_handler_callback] (0x0100): Sending result [0][hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_dp_process_reply] (0x0100):
received: [0][hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_reply] (0x0200): pam_reply
called with result [0].
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]]
[be_pam_handler_callback] (0x0100): Sent result [0][hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [child_sig_handler]
(0x1000): Waiting for child [1676].
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [child_sig_handler]
(0x0100): child [1676] finished successfully.
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [be_ptask_execute]
(0x0400): (Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_reply] (0x0200):
Task [Cleanup of hh3.site]: executing task, timeout 10800 seconds
blen: 69
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getbynam] (0x0400):
Running command [17] with input [steve2].
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [sss_parse_name_for_domains]
(0x0200): name 'steve2' matched without domain, user is steve2
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [sss_parse_name_for_domains]
(0x0200): using default domain [(null)]
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getbynam] (0x0100):
Requesting info for [steve2] from [<ALL>]
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getpwnam_search]
(0x0100): Requesting info for [steve2 at hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [check_cache] (0x0400): Cached
entry is valid, returning..
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getpwnam_search]
(0x0400): Returning info for user [steve2 at hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_cmd_acct_mgmt] (0x0100):
entering pam_cmd_acct_mgmt
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [sss_parse_name_for_domains]
(0x0200): name 'steve2' matched without domain, user is steve2
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [sss_parse_name_for_domains]
(0x0200): using default domain [(null)]
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [be_ptask_done]
(0x0400): (Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data]
(0x0100): command: PAM_ACCT_MGMT
Task [Cleanup of hh3.site]: finished successfully
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100): (Wed
Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [be_ptask_schedule] (0x0400):
domain: not set
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100): Task
[Cleanup of hh3.site]: scheduling task 10800 seconds from last execution
time [1401894082]
user: steve2
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
service: su
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100): tty:
pts/1
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100): ruser:
steve
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100): rhost:
not set
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 0
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100): priv:
0
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 1674
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_check_user_search] (0x0100):
Requesting info for [steve2 at hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_check_user_search] (0x0400):
Returning info for user [steve2 at hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_dp_send_req] (0x0100):
Sending request with the following data:
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
command: PAM_ACCT_MGMT
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
domain: hh3.site
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100): user:
steve2
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
service: su
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100): tty:
pts/1
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100): ruser:
steve
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100): rhost:
not set
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 0
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100): priv:
0
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 1674
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [be_req_set_domain]
(0x0400): Changing request domain from [hh3.site] to [hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [be_pam_handler]
(0x0100): Got request with the following data
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): command: PAM_ACCT_MGMT
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): domain: hh3.site
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): user: steve2
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): service: su
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): tty: pts/1
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): ruser: steve
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): rhost: 
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): authtok type: 0
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): newauthtok type: 0
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): priv: 0
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): cli_pid: 1674
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [sdap_access_send]
(0x0400): Performing access check for user [steve2]
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]]
[sdap_account_expired_ad] (0x0400): Performing AD access check for user
[steve2]
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]]
[be_pam_handler_callback] (0x0100): Backend returned: (0, 0, <NULL>)
[Success]
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]]
[be_pam_handler_callback] (0x0400): SELinux provider doesn't exist, not
sending the request to it.
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]]
[be_pam_handler_callback] (0x0100): Sending result [0][hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]]
[be_pam_handler_callback] (0x0100): Sent result [0][hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_dom_forwarder] (0x0100):
pam_dp_send_req returned 0
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_dp_process_reply] (0x0100):
received: [0][hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_reply] (0x0200): pam_reply
called with result [0].
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_reply] (0x0200): blen: 25
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getbynam] (0x0400):
Running command [38] with input [steve2].
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [sss_parse_name_for_domains]
(0x0200): name 'steve2' matched without domain, user is steve2
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [sss_parse_name_for_domains]
(0x0200): using default domain [(null)]
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getbynam] (0x0100):
Requesting info for [steve2] from [<ALL>]
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_initgroups_search]
(0x0100): Requesting info for [steve2 at hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [check_cache] (0x0400): Cached
entry is valid, returning..
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_initgroups_search]
(0x0400): Initgroups for [steve2 at hh3.site] completed
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getbynam] (0x0400):
Running command [17] with input [steve2].
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [sss_parse_name_for_domains]
(0x0200): name 'steve2' matched without domain, user is steve2
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [sss_parse_name_for_domains]
(0x0200): using default domain [(null)]
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getbynam] (0x0100):
Requesting info for [steve2] from [<ALL>]
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getpwnam_search]
(0x0100): Requesting info for [steve2 at hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [check_cache] (0x0400): Cached
entry is valid, returning..
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getpwnam_search]
(0x0400): Returning info for user [steve2 at hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_cmd_setcred] (0x0100):
entering pam_cmd_setcred
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [sss_parse_name_for_domains]
(0x0200): name 'steve2' matched without domain, user is steve2
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [sss_parse_name_for_domains]
(0x0200): using default domain [(null)]
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
command: PAM_SETCRED
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
domain: not set
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100): user:
steve2
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
service: su
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100): tty:
pts/1
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100): ruser:
steve
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100): rhost:
not set
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 0
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100): priv:
0
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 1674
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_check_user_search] (0x0100):
Requesting info for [steve2 at hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_check_user_search] (0x0400):
Returning info for user [steve2 at hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_dp_send_req] (0x0100):
Sending request with the following data:
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
command: PAM_SETCRED
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
domain: hh3.site
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100): user:
steve2
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
service: su
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100): tty:
pts/1
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100): ruser:
steve
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100): rhost:
not set
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 0
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100): priv:
0
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 1674
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_dom_forwarder] (0x0100):
pam_dp_send_req returned 0
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [be_req_set_domain]
(0x0400): Changing request domain from [hh3.site] to [hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [be_pam_handler]
(0x0100): Got request with the following data
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): command: PAM_SETCRED
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): domain: hh3.site
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): user: steve2
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): service: su
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): tty: pts/1
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): ruser: steve
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): rhost: 
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): authtok type: 0
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): newauthtok type: 0
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): priv: 0
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): cli_pid: 1674
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [be_pam_handler]
(0x0100): Sending result [0][hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_dp_process_reply] (0x0100):
received: [0][hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_reply] (0x0200): pam_reply
called with result [0].
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_reply] (0x0200): blen: 25
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getbynam] (0x0400):
Running command [17] with input [steve2].
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [sss_parse_name_for_domains]
(0x0200): name 'steve2' matched without domain, user is steve2
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [sss_parse_name_for_domains]
(0x0200): using default domain [(null)]
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getbynam] (0x0100):
Requesting info for [steve2] from [<ALL>]
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getpwnam_search]
(0x0100): Requesting info for [steve2 at hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [check_cache] (0x0400): Cached
entry is valid, returning..
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getpwnam_search]
(0x0400): Returning info for user [steve2 at hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_cmd_open_session] (0x0100):
entering pam_cmd_open_session
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [sss_parse_name_for_domains]
(0x0200): name 'steve2' matched without domain, user is steve2
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [sss_parse_name_for_domains]
(0x0200): using default domain [(null)]
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
command: PAM_OPEN_SESSION
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
domain: not set
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100): user:
steve2
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
service: su
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100): tty:
pts/1
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100): ruser:
steve
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100): rhost:
not set
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 0
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100): priv:
0
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 1674
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_check_user_search] (0x0100):
Requesting info for [steve2 at hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_check_user_search] (0x0400):
Returning info for user [steve2 at hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_dp_send_req] (0x0100):
Sending request with the following data:
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
command: PAM_OPEN_SESSION
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
domain: hh3.site
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100): user:
steve2
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
service: su
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100): tty:
pts/1
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100): ruser:
steve
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100): rhost:
not set
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
authtok type: 0
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
newauthtok type: 0
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100): priv:
0
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_print_data] (0x0100):
cli_pid: 1674
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [be_req_set_domain]
(0x0400): Changing request domain from [hh3.site] to [hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [be_pam_handler]
(0x0100): Got request with the following data
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): command: PAM_OPEN_SESSION
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): domain: hh3.site
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): user: steve2
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): service: su
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): tty: pts/1
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): ruser: steve
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): rhost: 
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): authtok type: 0
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): newauthtok type: 0
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): priv: 0
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [pam_print_data]
(0x0100): cli_pid: 1674
(Wed Jun  4 14:01:22 2014) [sssd[be[hh3.site]]] [be_pam_handler]
(0x0100): Sending result [0][hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_dom_forwarder] (0x0100):
pam_dp_send_req returned 0
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_dp_process_reply] (0x0100):
received: [0][hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_reply] (0x0200): pam_reply
called with result [0].
(Wed Jun  4 14:01:22 2014) [sssd[pam]] [pam_reply] (0x0200): blen: 25
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getbynam] (0x0400):
Running command [17] with input [steve2].
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [sss_parse_name_for_domains]
(0x0200): name 'steve2' matched without domain, user is steve2
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [sss_parse_name_for_domains]
(0x0200): using default domain [(null)]
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getbynam] (0x0100):
Requesting info for [steve2] from [<ALL>]
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getpwnam_search]
(0x0100): Requesting info for [steve2 at hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [check_cache] (0x0400): Cached
entry is valid, returning..
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getpwnam_search]
(0x0400): Returning info for user [steve2 at hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getbynam] (0x0400):
Running command [17] with input [steve2].
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [sss_parse_name_for_domains]
(0x0200): name 'steve2' matched without domain, user is steve2
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [sss_parse_name_for_domains]
(0x0200): using default domain [(null)]
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getbynam] (0x0100):
Requesting info for [steve2] from [<ALL>]
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getpwnam_search]
(0x0100): Requesting info for [steve2 at hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [check_cache] (0x0400): Cached
entry is valid, returning..
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getpwnam_search]
(0x0400): Returning info for user [steve2 at hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getbynam] (0x0400):
Running command [17] with input [steve2].
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [sss_parse_name_for_domains]
(0x0200): name 'steve2' matched without domain, user is steve2
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [sss_parse_name_for_domains]
(0x0200): using default domain [(null)]
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getbynam] (0x0100):
Requesting info for [steve2] from [<ALL>]
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getpwnam_search]
(0x0100): Requesting info for [steve2 at hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [check_cache] (0x0400): Cached
entry is valid, returning..
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getpwnam_search]
(0x0400): Returning info for user [steve2 at hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getbynam] (0x0400):
Running command [17] with input [steve2].
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [sss_parse_name_for_domains]
(0x0200): name 'steve2' matched without domain, user is steve2
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [sss_parse_name_for_domains]
(0x0200): using default domain [(null)]
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getbynam] (0x0100):
Requesting info for [steve2] from [<ALL>]
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getpwnam_search]
(0x0100): Requesting info for [steve2 at hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [check_cache] (0x0400): Cached
entry is valid, returning..
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getpwnam_search]
(0x0400): Returning info for user [steve2 at hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getbynam] (0x0400):
Running command [17] with input [steve2].
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [sss_parse_name_for_domains]
(0x0200): name 'steve2' matched without domain, user is steve2
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [sss_parse_name_for_domains]
(0x0200): using default domain [(null)]
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getbynam] (0x0100):
Requesting info for [steve2] from [<ALL>]
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getpwnam_search]
(0x0100): Requesting info for [steve2 at hh3.site]
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [check_cache] (0x0400): Cached
entry is valid, returning..
(Wed Jun  4 14:01:22 2014) [sssd[nss]] [nss_cmd_getpwnam_search]
(0x0400): Returning info for user [steve2 at hh3.site]
^C(Wed Jun  4 14:01:28 2014) [sssd] [monitor_quit_signal] (0x0040):
Monitor received Interrupción: terminating children
(Wed Jun  4 14:01:28 2014) [sssd] [monitor_quit] (0x0040): Returned
with: 0
(Wed Jun  4 14:01:28 2014) [sssd] [monitor_quit] (0x0020): Terminating
[autofs][1663]
(Wed Jun  4 14:01:28 2014) [sssd[autofs]] [sss_responder_ctx_destructor]
(0x0400): Responder is being shut down
(Wed Jun  4 14:01:28 2014) [sssd[be[hh3.site]]] [sbus_dispatch]
(0x0080): Connection is not open for dispatching.
(Wed Jun  4 14:01:28 2014) [sssd[be[hh3.site]]] [be_client_destructor]
(0x0400): Removed autofs client
(Wed Jun  4 14:01:28 2014) [sssd] [monitor_quit] (0x0020): Child
[autofs] terminated with a signal
(Wed Jun  4 14:01:28 2014) [sssd] [monitor_quit] (0x0020): Terminating
[pam][1662]
(Wed Jun  4 14:01:28 2014) [sssd[pam]] [sss_responder_ctx_destructor]
(0x0400): Responder is being shut down
(Wed Jun  4 14:01:28 2014) [sssd[be[hh3.site]]] [sbus_dispatch]
(0x0080): Connection is not open for dispatching.
(Wed Jun  4 14:01:28 2014) [sssd[be[hh3.site]]] [be_client_destructor]
(0x0400): Removed PAM client
(Wed Jun  4 14:01:28 2014) [sssd] [monitor_quit] (0x0020): Child [pam]
exited gracefully
(Wed Jun  4 14:01:28 2014) [sssd] [monitor_quit] (0x0020): Terminating
[nss][1661]
(Wed Jun  4 14:01:28 2014) [sssd[nss]] [sss_responder_ctx_destructor]
(0x0400): Responder is being shut down
(Wed Jun  4 14:01:28 2014) [sssd[be[hh3.site]]] [sbus_dispatch]
(0x0080): Connection is not open for dispatching.
(Wed Jun  4 14:01:28 2014) [sssd[be[hh3.site]]] [be_client_destructor]
(0x0400): Removed NSS client
(Wed Jun  4 14:01:28 2014) [sssd] [monitor_quit] (0x0020): Child [nss]
terminated with a signal
(Wed Jun  4 14:01:28 2014) [sssd] [monitor_quit] (0x0020): Terminating
[hh3.site][1660]
(Wed Jun  4 14:01:28 2014) [sssd[be[hh3.site]]] [remove_krb5_info_files]
(0x0200): Could not remove
[/usr/local/var/lib/sss/pubconf/kpasswdinfo.HH3.SITE], [2][No existe el
fichero o el directorio]
(Wed Jun  4 14:01:28 2014) [sssd[be[hh3.site]]] [be_ptask_destructor]
(0x0400): (Wed Jun  4 14:01:28 2014) [sssd] [monitor_quit] (0x0020):
Child [hh3.site] terminated with a signal
catral:/usr/lib/systemd/system # 




More information about the sssd-users mailing list