Fedora 14: GDM, sssd and LDAP authentication

Bernd Nies bernd.nies at gmail.com
Wed Nov 10 15:18:04 UTC 2010


Hi Stephen,

Here's the log output of the various sssd logfiles.

[root at fedoraclient ~]# getent passwd bernd
bernd:*:3031:102:Bernd Nies:/home/bernd:/bin/bash


[root at fedoraclient ~]# ldapsearch -LLL -x -h ldap.example.com -b
ou=people,dc=example,dc=com uid=bernd
dn: uid=bernd,ou=people,dc=example,dc=com
sn: Nies
homeDirectory: /home/bernd
gecos: Bernd Nies
gidNumber: 102
loginShell: /bin/bash
cn: Bernd Nies
uid: bernd
uidNumber: 3031
shadowLastChange: 13411
objectClass: person
objectClass: organizationalPerson
objectClass: inetOrgPerson
objectClass: posixAccount
objectClass: top
objectClass: shadowaccount
givenName: Bernd
shadowExpire: -1
mail: bernd.nies at example.com


Contents of /var/log/secure:

Nov 10 16:32:22 fedoraclient pam: gdm-password[15744]:
pam_unix(gdm-password:auth): authentication failure; logname= uid=0 euid=0
tty=:0 ruser= rhost=  user=bernd
Nov 10 16:32:22 fedoraclient pam: gdm-password[15744]:
pam_sss(gdm-password:auth): authentication failure; logname= uid=0 euid=0
tty=:0 ruser= rhost= user=bernd
Nov 10 16:32:22 fedoraclient pam: gdm-password[15744]:
pam_sss(gdm-password:auth): received for user bernd: 6 (Permission denied)

/var/log/sssd/sssd_pam.log:

(Wed Nov 10 16:46:01 2010) [sssd[pam]] [sbus_dispatch] (9): dbus conn:
9E2D7F8
(Wed Nov 10 16:46:01 2010) [sssd[pam]] [sbus_dispatch] (9): Dispatching.
(Wed Nov 10 16:46:01 2010) [sssd[pam]] [sbus_message_handler] (9): Received
SBUS method [ping]
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [get_client_cred] (9): Client creds:
euid[0] egid[0] pid[16949].
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [accept_priv_fd_handler] (4): Client
connected to privileged pipe!
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [sss_cmd_get_version] (5): Received
client version [3].
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [sss_cmd_get_version] (5): Offered
version [3].
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_cmd_authenticate] (4): entering
pam_cmd_authenticate
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_print_data] (4): command:
PAM_AUTHENTICATE
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_print_data] (4): domain: (null)
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_print_data] (4): user: bernd
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_print_data] (4): service:
gdm-password
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_print_data] (4): tty: :0
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_print_data] (4): ruser: (null)
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_print_data] (4): rhost: (null)
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_print_data] (4): authtok type: 1
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_print_data] (4): authtok size: 7
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_print_data] (4): newauthtok
type: 0
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_print_data] (4): newauthtok
size: 0
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_print_data] (4): priv: 1
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_print_data] (4): cli_pid: 16949
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_check_user_search] (4):
Requesting info for [bernd at LOCAL]
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [ldb] (9): tevent: Added timed event
"ltdb_callback": 0x9e317e8
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [ldb] (9): tevent: Added timed event
"ltdb_timeout": 0x9e32138
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [ldb] (9): tevent: Destroying timer
event 0x9e32138 "ltdb_timeout"
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [ldb] (9): tevent: Ending timer event
0x9e317e8 "ltdb_callback"
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [sss_dp_send_acct_req_create] (4):
Sending request for [LDAP][1][1][name=bernd]
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [sbus_add_timeout] (8): 0x9e31448
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [sbus_remove_timeout] (8): 0x9e31448
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [sbus_dispatch] (9): dbus conn:
9E2EC18
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [sbus_dispatch] (9): Dispatching.
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [sss_dp_get_reply] (4): Got reply (0,
0, Success) from Data Provider
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_check_user_search] (4):
Requesting info for [bernd at LDAP]
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [ldb] (9): tevent: Added timed event
"ltdb_callback": 0x9e31540
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [ldb] (9): tevent: Added timed event
"ltdb_timeout": 0x9e31cb0
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [ldb] (9): tevent: Destroying timer
event 0x9e31cb0 "ltdb_timeout"
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [ldb] (9): tevent: Ending timer event
0x9e31540 "ltdb_callback"
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_check_user_search] (6):
Returning info for user [bernd at LDAP]
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_dp_send_req] (4): Sending
request with the following data:
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_print_data] (4): command:
PAM_AUTHENTICATE
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_print_data] (4): domain: LDAP
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_print_data] (4): user: bernd
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_print_data] (4): service:
gdm-password
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_print_data] (4): tty: :0
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_print_data] (4): ruser: (null)
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_print_data] (4): rhost: (null)
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_print_data] (4): authtok type: 1
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_print_data] (4): authtok size: 7
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_print_data] (4): newauthtok
type: 0
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_print_data] (4): newauthtok
size: 0
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_print_data] (4): priv: 1
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_print_data] (4): cli_pid: 16949
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [sbus_add_timeout] (8): 0x9e32010
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_dom_forwarder] (4):
pam_dp_send_req returned 0
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [sbus_remove_timeout] (8): 0x9e32010
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [sbus_dispatch] (9): dbus conn:
9E2EC18
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [sbus_dispatch] (9): Dispatching.
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_dp_process_reply] (4): received:
[6][LDAP]
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_reply] (4): pam_reply get
called.
(Wed Nov 10 16:46:03 2010) [sssd[pam]] [pam_reply] (4): blen: 21
(Wed Nov 10 16:46:05 2010) [sssd[pam]] [client_recv] (5): Client
disconnected!

/var/log/sssd/sssd_LDAP.log:

(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sbus_message_handler] (9):
Received SBUS method [getAccountInfo]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [be_get_account_info] (4): Got
request for [1][1][name=bernd]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_id_op_connect_step] (9):
reusing cached connection
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_get_generic_send] (6):
calling ldap_search_ext with
[(&(uid=bernd)(objectclass=posixAccount))][ou=people,dc=example,dc=com].
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_get_generic_send] (7):
Requesting attrs: [objectClass]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_get_generic_send] (7):
Requesting attrs: [uid]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_get_generic_send] (7):
Requesting attrs: [userPassword]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_get_generic_send] (7):
Requesting attrs: [uidNumber]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_get_generic_send] (7):
Requesting attrs: [gidNumber]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_get_generic_send] (7):
Requesting attrs: [gecos]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_get_generic_send] (7):
Requesting attrs: [homeDirectory]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_get_generic_send] (7):
Requesting attrs: [loginShell]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_get_generic_send] (7):
Requesting attrs: [krbPrincipalName]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_get_generic_send] (7):
Requesting attrs: [cn]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_get_generic_send] (7):
Requesting attrs: [modifyTimestamp]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_get_generic_send] (7):
Requesting attrs: [shadowLastChange]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_get_generic_send] (7):
Requesting attrs: [shadowMin]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_get_generic_send] (7):
Requesting attrs: [shadowMax]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_get_generic_send] (7):
Requesting attrs: [shadowWarning]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_get_generic_send] (7):
Requesting attrs: [shadowInactive]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_get_generic_send] (7):
Requesting attrs: [shadowExpire]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_get_generic_send] (7):
Requesting attrs: [shadowFlag]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_get_generic_send] (7):
Requesting attrs: [krbLastPwdChange]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_get_generic_send] (7):
Requesting attrs: [krbPasswordExpiration]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_get_generic_send] (7):
Requesting attrs: [pwdAttribute]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_get_generic_send] (8):
ldap_search_ext called, msgid = 7
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_process_result] (8):
Trace: sh[0x97e5648], connected[1], ops[0x99d3d40], ldap[0x97e5b30]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_parse_entry] (9):
OriginalDN: [uid=bernd,ou=people,dc=example,dc=com].
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_process_result] (8):
Trace: sh[0x97e5648], connected[1], ops[0x99d3d40], ldap[0x97e5b30]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_get_generic_done] (6):
Search result: Success(0), (null)
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_get_users_process] (6):
Search for users, returned 1 results.
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [ldb] (9): start ldb transaction
(nesting: 0)
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_save_user] (9): Save user
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_save_user] (7): Adding
original DN [uid=bernd,ou=people,dc=example,dc=com] to attributes of
[bernd].
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_save_user] (7): Original
memberOf is not available for [bernd].
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_save_user] (7): User
principal is not available for [bernd].
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_save_user] (9): Adding
[shadowLastChange]=[13411] to user attributes.
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_save_user] (9): Adding
[shadowExpire]=[-1] to user attributes.
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_save_user] (6): Storing
info for user bernd
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [ldb] (9): tevent: Added timed
event "ltdb_callback": 0x9842360
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [ldb] (9): tevent: Added timed
event "ltdb_timeout": 0x9968980
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [ldb] (9): tevent: Destroying
timer event 0x9968980 "ltdb_timeout"
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [ldb] (9): tevent: Ending timer
event 0x9842360 "ltdb_callback"
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [ldb] (9): start ldb transaction
(nesting: 1)
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [ldb] (9): tevent: Added timed
event "ltdb_callback": 0x993edf8
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [ldb] (9): tevent: Added timed
event "ltdb_timeout": 0x993eec0
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [ldb] (9): tevent: Destroying
timer event 0x993eec0 "ltdb_timeout"
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [ldb] (9): tevent: Ending timer
event 0x993edf8 "ltdb_callback"
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [ldb] (9): commit ldb
transaction (nesting: 1)
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_save_users] (9): User 0
processed!
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [ldb] (9): commit ldb
transaction (nesting: 0)
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_get_users_process] (9):
Saving 1 Users - Done
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_id_op_done] (9): releasing
operation connection
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [acctinfo_callback] (4): Request
processed. Returned 0,0,Success
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_process_result] (8):
Trace: sh[0x97e5648], connected[1], ops[(nil)], ldap[0x97e5b30]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_process_result] (8):
Trace: ldap_result found nothing!
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sbus_dispatch] (9): dbus conn:
97DEAC0
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sbus_dispatch] (9):
Dispatching.
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sbus_message_handler] (9):
Received SBUS method [pamHandler]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [be_pam_handler] (4): Got
request with the following data
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [pam_print_data] (4): command:
PAM_AUTHENTICATE
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [pam_print_data] (4): domain:
LDAP
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [pam_print_data] (4): user:
bernd
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [pam_print_data] (4): service:
gdm-password
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [pam_print_data] (4): tty: :0
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [pam_print_data] (4): ruser:
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [pam_print_data] (4): rhost:
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [pam_print_data] (4): authtok
type: 1
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [pam_print_data] (4): authtok
size: 7
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [pam_print_data] (4): newauthtok
type: 0
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [pam_print_data] (4): newauthtok
size: 0
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [pam_print_data] (4): priv: 0
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [pam_print_data] (4): cli_pid:
16949
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [fo_resolve_service_send] (4):
Trying to resolve service 'LDAP'
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [get_server_status] (7): Status
of server 'ldap.example.com' is 'working'
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [get_port_status] (7): Port
status of port 389 for server 'ldap.example.com' is 'working'
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [get_server_status] (7): Status
of server 'ldap.example.com' is 'working'
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [be_resolve_server_done] (4):
Found address for server ldap.example.com: [192.168.4.216]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_connect_send] (4):
Executing START TLS
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_ldap_connect_callback_add]
(9): New LDAP connection to [ldap://ldap.example.com:389] with fd [27].
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_process_result] (8):
Trace: sh[0x984d088], connected[1], ops[0x99d3d40], ldap[0x99689f0]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_connect_done] (3): START
TLS result: Success(0), Start TLS request accepted.Server willing to
negotiate SSL.
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [fo_set_port_status] (4):
Marking port 389 of server 'ldap.example.com' as 'working'
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [set_server_common_status] (4):
Marking server 'ldap.example.com' as 'working'
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [ldb] (9): tevent: Added timed
event "ltdb_callback": 0x99a51b0
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [ldb] (9): tevent: Added timed
event "ltdb_timeout": 0x99a5278
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [ldb] (9): tevent: Destroying
timer event 0x99a5278 "ltdb_timeout"
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [ldb] (9): tevent: Ending timer
event 0x99a51b0 "ltdb_callback"
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]]
[find_password_expiration_attributes] (9): No password policy requested.
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_control_create] (3):
Server does not support the requested control [1.3.6.1.4.1.42.2.27.8.5.1].
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [simple_bind_send] (4):
Executing simple bind as: uid=bernd,ou=people,dc=example,dc=com
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [simple_bind_send] (8): ldap
simple bind sent, msgid = 2
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_process_result] (8):
Trace: sh[0x984d088], connected[1], ops[0x97db8b0], ldap[0x99689f0]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_process_result] (8):
Trace: ldap_result found nothing!
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_process_result] (8):
Trace: sh[0x984d088], connected[1], ops[0x97db8b0], ldap[0x99689f0]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [simple_bind_done] (5): Server
returned no controls.
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [simple_bind_done] (3): Bind
result: Invalid credentials(49), (null)
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [sdap_handle_release] (8):
Trace: sh[0x984d088], connected[1], ops[(nil)], ldap[0x99689f0],
destructor_lock[0], release_memory[0]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [remove_connection_callback]
(9): Successfully removed connection callback.
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [be_pam_handler_callback] (4):
Backend returned: (0, 6, <NULL>) [Success]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [be_pam_handler_callback] (4):
Sending result [6][LDAP]
(Wed Nov 10 16:46:03 2010) [sssd[be[LDAP]]] [be_pam_handler_callback] (4):
Sent result [6][LDAP]



/var/log/sssd/sssd_nss.log

(Wed Nov 10 16:46:03 2010) [sssd[nss]] [nss_cmd_getpwnam] (4): Requesting
info for [bernd] from [<ALL>]
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [nss_cmd_getpwnam_search] (4):
Requesting info for [bernd at LOCAL]
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [ldb] (9): tevent: Added timed event
"ltdb_callback": 0x9ff79d0
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [ldb] (9): tevent: Added timed event
"ltdb_timeout": 0x9ffdb58
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [ldb] (9): tevent: Destroying timer
event 0x9ffdb58 "ltdb_timeout"
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [ldb] (9): tevent: Ending timer event
0x9ff79d0 "ltdb_callback"
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [nss_cmd_getpwnam_search] (4):
Requesting info for [bernd at LDAP]
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [ldb] (9): tevent: Added timed event
"ltdb_callback": 0x9ffd078
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [ldb] (9): tevent: Added timed event
"ltdb_timeout": 0x9ffdc78
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [ldb] (9): tevent: Destroying timer
event 0x9ffdc78 "ltdb_timeout"
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [ldb] (9): tevent: Ending timer event
0x9ffd078 "ltdb_callback"
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [nss_cmd_getpwnam_search] (6):
Returning info for user [bernd at LDAP]
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [nss_cmd_getpwnam] (4): Requesting
info for [bernd] from [<ALL>]
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [nss_cmd_getpwnam_search] (4):
Requesting info for [bernd at LOCAL]
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [ldb] (9): tevent: Added timed event
"ltdb_callback": 0x9ff79d0
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [ldb] (9): tevent: Added timed event
"ltdb_timeout": 0x9ffdb58
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [ldb] (9): tevent: Destroying timer
event 0x9ffdb58 "ltdb_timeout"
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [ldb] (9): tevent: Ending timer event
0x9ff79d0 "ltdb_callback"
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [nss_cmd_getpwnam_search] (4):
Requesting info for [bernd at LDAP]
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [ldb] (9): tevent: Added timed event
"ltdb_callback": 0x9ffd078
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [ldb] (9): tevent: Added timed event
"ltdb_timeout": 0x9ffdc78
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [ldb] (9): tevent: Destroying timer
event 0x9ffdc78 "ltdb_timeout"
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [ldb] (9): tevent: Ending timer event
0x9ffd078 "ltdb_callback"
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [nss_cmd_getpwnam_search] (6):
Returning info for user [bernd at LDAP]
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [nss_cmd_getpwnam] (4): Requesting
info for [bernd] from [<ALL>]
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [nss_cmd_getpwnam_search] (4):
Requesting info for [bernd at LOCAL]
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [ldb] (9): tevent: Added timed event
"ltdb_callback": 0x9ff79d0
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [ldb] (9): tevent: Added timed event
"ltdb_timeout": 0x9ffdb58
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [ldb] (9): tevent: Destroying timer
event 0x9ffdb58 "ltdb_timeout"
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [ldb] (9): tevent: Ending timer event
0x9ff79d0 "ltdb_callback"
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [nss_cmd_getpwnam_search] (4):
Requesting info for [bernd at LDAP]
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [ldb] (9): tevent: Added timed event
"ltdb_callback": 0x9ffd078
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [ldb] (9): tevent: Added timed event
"ltdb_timeout": 0x9ffdc78
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [ldb] (9): tevent: Destroying timer
event 0x9ffdc78 "ltdb_timeout"
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [ldb] (9): tevent: Ending timer event
0x9ffd078 "ltdb_callback"
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [nss_cmd_getpwnam_search] (6):
Returning info for user [bernd at LDAP]
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [nss_cmd_getpwnam] (4): Requesting
info for [bernd] from [<ALL>]
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [nss_cmd_getpwnam_search] (4):
Requesting info for [bernd at LOCAL]
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [ldb] (9): tevent: Added timed event
"ltdb_callback": 0x9ffe410
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [ldb] (9): tevent: Added timed event
"ltdb_timeout": 0x9ffe470
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [ldb] (9): tevent: Destroying timer
event 0x9ffe470 "ltdb_timeout"
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [ldb] (9): tevent: Ending timer event
0x9ffe410 "ltdb_callback"
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [nss_cmd_getpwnam_search] (4):
Requesting info for [bernd at LDAP]
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [ldb] (9): tevent: Added timed event
"ltdb_callback": 0x9ffd010
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [ldb] (9): tevent: Added timed event
"ltdb_timeout": 0x9ffd0d8
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [ldb] (9): tevent: Destroying timer
event 0x9ffd0d8 "ltdb_timeout"
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [ldb] (9): tevent: Ending timer event
0x9ffd010 "ltdb_callback"
(Wed Nov 10 16:46:03 2010) [sssd[nss]] [nss_cmd_getpwnam_search] (6):
Returning info for user [bernd at LDAP]
(Wed Nov 10 16:46:05 2010) [sssd[nss]] [nss_cmd_getpwnam] (4): Requesting
info for [bernd] from [<ALL>]
(Wed Nov 10 16:46:05 2010) [sssd[nss]] [nss_cmd_getpwnam_search] (4):
Requesting info for [bernd at LOCAL]
(Wed Nov 10 16:46:05 2010) [sssd[nss]] [ldb] (9): tevent: Added timed event
"ltdb_callback": 0x9ffe410
(Wed Nov 10 16:46:05 2010) [sssd[nss]] [ldb] (9): tevent: Added timed event
"ltdb_timeout": 0x9ffe470
(Wed Nov 10 16:46:05 2010) [sssd[nss]] [ldb] (9): tevent: Destroying timer
event 0x9ffe470 "ltdb_timeout"
(Wed Nov 10 16:46:05 2010) [sssd[nss]] [ldb] (9): tevent: Ending timer event
0x9ffe410 "ltdb_callback"
(Wed Nov 10 16:46:05 2010) [sssd[nss]] [nss_cmd_getpwnam_search] (4):
Requesting info for [bernd at LDAP]
(Wed Nov 10 16:46:05 2010) [sssd[nss]] [ldb] (9): tevent: Added timed event
"ltdb_callback": 0x9ffd010
(Wed Nov 10 16:46:05 2010) [sssd[nss]] [ldb] (9): tevent: Added timed event
"ltdb_timeout": 0x9ffd0d8
(Wed Nov 10 16:46:05 2010) [sssd[nss]] [ldb] (9): tevent: Destroying timer
event 0x9ffd0d8 "ltdb_timeout"
(Wed Nov 10 16:46:05 2010) [sssd[nss]] [ldb] (9): tevent: Ending timer event
0x9ffd010 "ltdb_callback"
(Wed Nov 10 16:46:05 2010) [sssd[nss]] [nss_cmd_getpwnam_search] (6):
Returning info for user [bernd at LDAP]
(Wed Nov 10 16:46:05 2010) [sssd[nss]] [client_recv] (5): Client
disconnected!
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.fedoraproject.org/pipermail/users/attachments/20101110/cfeaf0ae/attachment-0001.html 


More information about the users mailing list